[ 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 407926490 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 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, 524584K 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.001017] APIC: Switch to symmetric I/O mode setup [ 0.003221] x2apic enabled [ 0.004012] Switched APIC routing to physical x2apic. [ 0.005020] kvm-guest: setup PV IPIs [ 0.007729] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008027] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009017] pid_max: default: 32768 minimum: 301 [ 0.010175] LSM: Security Framework initializing [ 0.011050] Yama: becoming mindful. [ 0.012061] SELinux: Initializing. [ 0.013102] *** VALIDATE selinux *** [ 0.021760] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026393] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027183] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028130] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029159] *** VALIDATE tmpfs *** [ 0.031232] *** VALIDATE proc *** [ 0.032325] *** VALIDATE cgroup *** [ 0.033016] *** VALIDATE cgroup2 *** [ 0.034333] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035187] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036000] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.036045] Spectre V2 : User space: Vulnerable [ 0.037017] Speculative Store Bypass: Vulnerable [ 0.040665] debug: unmapping init [mem 0xffffffffa8059000-0xffffffffa8060fff] [ 0.043298] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.044922] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.045031] ... version: 2 [ 0.046018] ... bit width: 48 [ 0.047022] ... generic registers: 4 [ 0.048021] ... value mask: 0000ffffffffffff [ 0.049025] ... max period: 00007fffffffffff [ 0.050022] ... fixed-purpose events: 3 [ 0.051022] ... event mask: 000000070000000f [ 0.053394] rcu: Hierarchical SRCU implementation. [ 0.055788] smp: Bringing up secondary CPUs ... [ 0.056785] x86: Booting SMP configuration: [ 0.057038] .... node #0, CPUs: #1 #2 #3 [ 0.063683] smp: Brought up 1 node, 4 CPUs [ 0.065027] smpboot: Max logical packages: 1 [ 0.066017] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.136617] node 0 deferred pages initialised in 68ms [ 0.140284] devtmpfs: initialized [ 0.141268] x86/mm: Memory block size: 128MB [ 0.143653] gcov: version magic: 0x41383552 [ 0.145262] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.146080] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.147350] pinctrl core: initialized pinctrl subsystem [ 0.148263] [ 0.148698] ************************************************************* [ 0.149017] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.150017] ** ** [ 0.151013] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.152019] ** ** [ 0.153014] ** This means that this kernel is built to expose internal ** [ 0.154020] ** IOMMU data structures, which may compromise security on ** [ 0.155018] ** your system. ** [ 0.156017] ** ** [ 0.157016] ** If you see this message and you are not debugging the ** [ 0.158017] ** kernel, report this immediately to your vendor! ** [ 0.159019] ** ** [ 0.160020] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.161018] ************************************************************* [ 0.162762] NET: Registered protocol family 16 [ 0.163460] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.164067] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.165077] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.167057] cpuidle: using governor menu [ 0.168332] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.170581] PCI: Using configuration type 1 for base access [ 0.173147] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.180158] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.182044] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.185150] cryptd: max_cpu_qlen set to 1000 [ 0.189318] ACPI: Added _OSI(Module Device) [ 0.190033] ACPI: Added _OSI(Processor Device) [ 0.191019] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.195022] ACPI: Added _OSI(Processor Aggregator Device) [ 0.200877] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.211640] ACPI: Interpreter enabled [ 0.212097] ACPI: PM: (supports S0 S3 S4 S5) [ 0.213053] ACPI: Using IOAPIC for interrupt routing [ 0.214599] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.219545] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.234230] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.236046] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.240028] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.242098] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.247055] acpiphp: Slot [2] registered [ 0.249198] acpiphp: Slot [5] registered [ 0.250659] acpiphp: Slot [6] registered [ 0.252127] acpiphp: Slot [3] registered [ 0.254227] acpiphp: Slot [4] registered [ 0.256129] acpiphp: Slot [7] registered [ 0.257124] acpiphp: Slot [8] registered [ 0.258114] acpiphp: Slot [9] registered [ 0.260199] acpiphp: Slot [10] registered [ 0.262123] acpiphp: Slot [11] registered [ 0.264181] acpiphp: Slot [12] registered [ 0.265076] acpiphp: Slot [13] registered [ 0.267137] acpiphp: Slot [14] registered [ 0.269136] acpiphp: Slot [15] registered [ 0.270223] acpiphp: Slot [16] registered [ 0.272111] acpiphp: Slot [17] registered [ 0.273117] acpiphp: Slot [18] registered [ 0.274102] acpiphp: Slot [19] registered [ 0.275133] acpiphp: Slot [20] registered [ 0.276207] acpiphp: Slot [21] registered [ 0.278219] acpiphp: Slot [22] registered [ 0.279187] acpiphp: Slot [23] registered [ 0.281114] acpiphp: Slot [24] registered [ 0.282097] acpiphp: Slot [25] registered [ 0.283135] acpiphp: Slot [26] registered [ 0.285109] acpiphp: Slot [27] registered [ 0.286131] acpiphp: Slot [28] registered [ 0.287195] acpiphp: Slot [29] registered [ 0.288107] acpiphp: Slot [30] registered [ 0.290139] acpiphp: Slot [31] registered [ 0.291178] PCI host bridge to bus 0000:00 [ 0.292023] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.294022] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.296025] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.298114] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.301022] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.303029] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.305365] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.308260] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.311400] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.318460] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.323053] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.325024] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.328022] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.330020] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.333310] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.335844] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.338043] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.340777] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.344032] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.353020] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.358016] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.362510] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.370022] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.376021] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.387022] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.395713] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.400016] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.404020] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.419018] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.431000] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.433336] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.435686] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.437766] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.439239] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.443142] iommu: Default domain type: Passthrough [ 0.445677] SCSI subsystem initialized [ 0.447200] ACPI: bus type USB registered [ 0.448114] usbcore: registered new interface driver usbfs [ 0.450127] usbcore: registered new interface driver hub [ 0.452079] usbcore: registered new device driver usb [ 0.453127] pps_core: LinuxPPS API ver. 1 registered [ 0.454008] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.457080] PTP clock support registered [ 0.460141] EDAC MC: Ver: 3.0.0 [ 0.463340] PCI: Using ACPI for IRQ routing [ 0.464609] NetLabel: Initializing [ 0.466009] NetLabel: domain hash size = 128 [ 0.466955] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.468075] NetLabel: unlabeled traffic allowed by default [ 0.469140] vgaarb: loaded [ 0.470250] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.472019] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.477558] clocksource: Switched to clocksource kvm-clock [ 0.572491] VFS: Disk quotas dquot_6.6.0 [ 0.573551] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.576298] *** VALIDATE ramfs *** [ 0.577167] *** VALIDATE hugetlbfs *** [ 0.578205] pnp: PnP ACPI init [ 0.579746] pnp: PnP ACPI: found 6 devices [ 0.603916] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.607941] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.609499] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.611382] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.613296] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.614813] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.616798] NET: Registered protocol family 2 [ 0.619407] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.623548] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.626818] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.631054] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.634552] TCP: Hash tables configured (established 65536 bind 65536) [ 0.638750] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.641449] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.643588] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.646138] NET: Registered protocol family 1 [ 0.652751] RPC: Registered named UNIX socket transport module. [ 0.654242] RPC: Registered udp transport module. [ 0.655467] RPC: Registered tcp transport module. [ 0.656589] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.658098] NET: Registered protocol family 44 [ 0.659543] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.660987] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.662291] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.663757] PCI: CLS 0 bytes, default 64 [ 0.665474] Unpacking initramfs... [ 2.019079] debug: unmapping init [mem 0xffff8972fcc64000-0xffff8972fffcffff] [ 2.022908] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.025043] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.027697] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.617643] Initialise system trusted keyrings [ 2.619624] Key type blacklist registered [ 2.622275] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.631664] zbud: loaded [ 2.635462] *** VALIDATE nfs *** [ 2.636872] *** VALIDATE nfs4 *** [ 2.668075] pstore: using deflate compression [ 2.673422] Platform Keyring initialized [ 2.796866] NET: Registered protocol family 38 [ 2.798188] Key type asymmetric registered [ 2.799630] Asymmetric key parser 'x509' registered [ 2.802670] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.805610] io scheduler mq-deadline registered [ 2.806915] io scheduler kyber registered [ 2.808718] io scheduler bfq registered [ 2.810579] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.813309] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.816404] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.819318] ACPI: Power Button [PWRF] [ 2.824913] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.832386] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.842610] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.871908] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.919354] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.927844] Non-volatile memory driver v1.3 [ 2.929294] Linux agpgart interface v0.103 [ 2.962554] virtio_blk virtio1: [vda] 134032 512-byte logical blocks (68.6 MB/65.4 MiB) [ 3.003286] vda: detected capacity change from 0 to 68624384 [ 3.020382] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.022850] vdb: detected capacity change from 0 to 1073741824 [ 3.155400] libphy: Fixed MDIO Bus: probed [ 3.172855] usbcore: registered new interface driver usbserial_generic [ 3.176518] usbserial: USB Serial support registered for generic [ 3.180854] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.240583] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.323645] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.325912] mousedev: PS/2 mouse device common for all mice [ 3.328872] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.332324] rtc_cmos 00:05: RTC can wake from S4 [ 3.447762] hpet1: lost 6 rtc interrupts [ 3.451753] rtc_cmos 00:05: registered as rtc0 [ 3.454885] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.461382] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.463446] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.465477] intel_pstate: CPU model not supported [ 3.474418] hid: raw HID events driver (C) Jiri Kosina [ 3.476545] usbcore: registered new interface driver usbhid [ 3.478552] usbhid: USB HID core driver [ 3.480274] drop_monitor: Initializing network drop monitor service [ 3.543238] Initializing XFRM netlink socket [ 3.549100] NET: Registered protocol family 10 [ 3.556191] Segment Routing with IPv6 [ 3.557560] NET: Registered protocol family 17 [ 3.562459] mpls_gso: MPLS GSO support [ 3.574471] RAS: Correctable Errors collector initialized. [ 3.578621] AVX version of gcm_enc/dec engaged. [ 3.581336] AES CTR mode by8 optimization enabled [ 3.710152] sched_clock: Marking stable (3710129120, 0)->(4544607251, -834478131) [ 3.716690] registered taskstats version 1 [ 3.718825] Loading compiled-in X.509 certificates [ 3.721132] zswap: loaded using pool lzo/zbud [ 3.748663] Key type big_key registered [ 3.759840] Key type encrypted registered [ 3.762782] ima: No TPM chip found, activating TPM-bypass! [ 3.766384] ima: Allocated hash algorithm: sha1 [ 3.768638] ima: No architecture policies found [ 3.770172] evm: Initialising EVM extended attributes: [ 3.771678] evm: security.selinux [ 3.772841] evm: security.ima [ 3.774447] evm: security.capability [ 3.775622] evm: HMAC attrs: 0x1 [ 3.777829] rtc_cmos 00:05: setting system clock to 2026-01-02 00:49:21 UTC (1767314961) [ 3.784910] debug: unmapping init [mem 0xffffffffa9003000-0xffffffffa91fffff] [ 3.787814] debug: unmapping init [mem 0xffffffffa7d82000-0xffffffffa8058fff] [ 3.796105] Write protecting the kernel read-only data: 28672k [ 3.798620] debug: unmapping init [mem 0xffffffffa6403000-0xffffffffa65fffff] [ 3.802206] debug: unmapping init [mem 0xffffffffa6d14000-0xffffffffa6dfffff] [ 3.976772] 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.988744] systemd[1]: Detected virtualization kvm. [ 3.991234] systemd[1]: Detected architecture x86-64. [ 3.993581] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 4.021715] systemd[1]: No hostname configured. [ 4.023380] systemd[1]: Set hostname to . [ 4.025562] random: systemd: uninitialized urandom read (16 bytes read) [ 4.028267] systemd[1]: Initializing machine ID from random generator. [ 4.417493] random: systemd: uninitialized urandom read (16 bytes read) [ 4.419439] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 4.425532] random: systemd: uninitialized urandom read (16 bytes read) [ 4.428558] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 4.434212] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ OK ] Listening on Journal Socket. Starting Create list of required st…ce nodes for the current kernel... Starting Journal Service... [ OK ] Started Memstrack Anylazing Service. Starting Apply Kernel Variables... Starting Create Volatile Files and Directories... [ OK ] Reached target Timers. [ OK ] Reached target Slices. Starting Setup Virtual Console... [ OK ] Reached target Initrd Root Device. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. [ OK ] Started Journal Service. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 5.381878] device-mapper: uevent: version 1.0.3 [ 5.384430] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 6.922628] virtio_net virtio0 ens2: renamed from eth0 [ 7.272227] scsi host0: ata_piix [ 7.282218] scsi host1: ata_piix [ 7.284414] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 7.286886] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 12.549596] random: crng init done [ 12.554279] random: 7 urandom warning(s) missed due to ratelimiting [ 15.439022] dracut-initqueue[586]: 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. [ 18.755623] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Swap. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 22.967580] printk: systemd: 23 output lines suppressed due to ratelimiting [ 23.741988] SELinux: Disabled at runtime. [ 23.813342] 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) [ 23.824595] systemd[1]: Detected virtualization kvm. [ 23.827863] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 24.707359] systemd[1]: initrd-switch-root.service: Succeeded. [ 24.710816] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 24.716335] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 24.719770] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 24.722572] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 24.730600] systemd[1]: Starting Journal Service... Starting Journal Service... [ 24.737379] systemd[1]: proc-sys-fs-binfmt_misc.automount: Refusing to start, unit to trigger not loaded. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Starting Remount Root and Kernel File Systems... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice system-serial\x2dgetty.slice. Activating swap /dev/disk/by-label/SWAP... Mounting POSIX Message Queue File System... [ OK ] Listening on RPCbind Server Activation Socket. [ 24.897218] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Reached target rpc_pipefs.target. [ OK ] Stopped target Switch Root. [ OK ] Started Dispatch Password Requests to Console Directory Watch. Mounting Kernel Debug File System... [ OK ] Created slice system-getty.slice. [ OK ] Created slice system-sshd\x2dkeygen.slice. Mounting Huge Pages File System... Starting Apply Kernel Variables... [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Listening on udev Control Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Stopped target Initrd Root File System. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Reached target RPC Port Mapper. [ OK ] Stopped target Initrd File Systems. [ OK ] Started Journal Service. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ 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. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 25.909123] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 26.811360] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 26.900828] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 28.819373] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 29.031131] EDAC sbridge: Ver: 1.1.2 [ 30.753356] Key type dns_resolver registered [ 31.338592] NFS: Registering the id_resolver key type [ 31.347992] Key type id_resolver registered [ 31.352432] Key type id_legacy registered [* ] A start job is running for Configur…-only root support (7s / no limit) [** ] A start job is running for Configur…-only root support (7s / no limit) [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... Starting Rebuild Dynamic Linker Cache... [ 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 ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting 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 oleg239-client login: [ 100.939725] hrtimer: interrupt took 18717174 ns [ 118.873112] libcfs: loading out-of-tree module taints kernel. [ 119.080465] Key type ._llcrypt registered [ 119.083067] Key type .llcrypt registered [ 119.819883] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 119.836340] alg: No test for adler32 (adler32-zlib) [ 121.421889] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [ 122.236631] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [ 124.042353] Key type lgssc registered [ 125.781886] Lustre: Echo OBD driver; http://www.lustre.org/ [ 533.803959] Lustre: Mounted lustre-client [ 539.093482] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 559.074389] Lustre: DEBUG MARKER: oleg239-client.virtnet: executing check_logdir /tmp/testlogs/ [ 559.592536] Lustre: lustre-OST0000-osc-ffff8973453c8000: disconnect after 24s idle [ 565.102083] Lustre: DEBUG MARKER: oleg239-client.virtnet: executing yml_node [ 575.757527] Lustre: DEBUG MARKER: Client: 2.17.0.RC4 [ 578.920316] Lustre: DEBUG MARKER: MDS: 2.17.0.RC4 [ 582.392111] Lustre: DEBUG MARKER: OSS: 2.17.0.RC4 [ 584.318753] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Thu Jan 1 19:59:00 EST 2026 [ 604.716950] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 606.856216] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 608.590897] Lustre: DEBUG MARKER: === sanityn: start setup 19:59:24 (1767315564) === [ 609.501345] Lustre: Mounted lustre-client [ 613.548128] Lustre: DEBUG MARKER: oleg239-client.virtnet: executing check_config_client /mnt/lustre [ 659.411869] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 682.417711] Lustre: DEBUG MARKER: === sanityn: finish setup 20:00:36 (1767315636) === [ 685.115627] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 20:00:41 (1767315641) [ 695.657662] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 20:00:51 (1767315651) [ 703.715778] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 20:00:59 (1767315659) [ 711.954659] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 20:01:08 (1767315668) [ 720.074130] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 20:01:15 (1767315675) [ 726.666887] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 20:01:23 (1767315683) [ 733.820045] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 20:01:30 (1767315690) [ 741.868458] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 20:01:37 (1767315697) [ 750.975084] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 20:01:46 (1767315706) [ 760.041222] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 20:01:56 (1767315716) [ 768.583459] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 20:02:04 (1767315724) [ 772.575278] Lustre: lustre-OST0000-osc-ffff8973453c8000: disconnect after 21s idle [ 772.588566] Lustre: Skipped 1 previous similar message [ 776.593489] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 20:02:12 (1767315732) [ 784.730314] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 20:02:20 (1767315740) [ 793.056307] Lustre: lustre-OST0001-osc-ffff89735119d800: disconnect after 23s idle [ 795.013584] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 20:02:30 (1767315750) [ 802.413664] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 20:02:38 (1767315758) [ 810.517479] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 20:02:46 (1767315766) [ 813.559491] Lustre: lustre-OST0000-osc-ffff8973453c8000: disconnect after 23s idle [ 818.720296] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 20:02:55 (1767315775) [ 826.492796] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 20:03:02 (1767315782) [ 835.036217] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 20:03:11 (1767315791) [ 841.684812] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 20:03:18 (1767315798) [ 848.378538] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 20:03:24 (1767315804) [ 849.390391] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279515 file: /mnt/lustre/lockdir/lockfile=144115205289279514 [ 989.226316] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 20:05:45 (1767315945) [ 1000.043262] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 20:05:56 (1767315956) [ 1007.585978] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 20:06:03 (1767315963) [ 1014.774699] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 20:06:11 (1767315971) [ 1023.136791] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 20:06:19 (1767315979) [ 1031.763293] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 20:06:27 (1767315987) [ 1034.092974] Lustre: DEBUG MARKER: chmod [ 1042.442482] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 20:06:38 (1767315998) [ 1992.206922] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 20:22:28 (1767316948) [ 2139.615208] Lustre: lustre-OST0001-osc-ffff89735119d800: disconnect after 21s idle [ 2200.350114] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 20:25:56 (1767317156) [ 2309.230546] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 20:27:45 (1767317265) [ 2448.632187] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 20:30:05 (1767317405) [ 2482.667132] Lustre: lustre-OST0001-osc-ffff89735119d800: disconnect after 20s idle [ 2488.266316] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 20:30:44 (1767317444) [ 2495.693443] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 20:30:51 (1767317451) [ 2496.867727] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2496.981035] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2497.121295] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2497.232732] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2497.351465] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2497.454717] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2497.557236] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2497.662446] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2497.767214] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2497.890117] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2498.057387] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2498.211064] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2498.327320] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2498.420303] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2498.504112] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2498.567854] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2498.656183] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2498.790874] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2498.907365] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2499.002063] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2499.088231] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2499.178898] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2499.296110] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2499.361462] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2499.460198] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2499.580115] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2499.760772] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2499.856087] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2499.959519] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2500.066678] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2500.169463] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2500.266502] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2500.383652] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2500.536038] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2500.640346] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2500.744510] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2500.818193] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2500.910684] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2501.013204] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2501.109787] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2501.210826] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2501.309646] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2501.396466] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2501.491466] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2501.582944] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2501.668174] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2501.779716] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2501.856866] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2502.002171] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2502.183576] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2502.302076] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2502.416270] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2502.524228] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2502.646461] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2502.765389] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2502.814191] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2502.863361] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2502.906218] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2502.961880] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2503.042844] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2503.114926] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2503.178731] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2503.235103] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2503.312715] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2503.436063] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2503.518593] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2503.607625] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2503.709115] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2503.786977] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2503.849642] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2503.915492] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2504.005810] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2504.082788] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2504.126962] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2504.177896] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2504.223816] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2504.284980] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2504.350252] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2504.405124] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2504.465692] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2504.532117] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2504.573890] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2504.640618] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2504.701185] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2504.775576] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2504.829039] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2504.878383] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2504.939230] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2505.011891] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2505.095974] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2505.178364] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2505.267987] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2505.359116] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2505.429633] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2505.503435] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2505.569365] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2505.645363] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2505.721929] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2505.786225] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2505.851637] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2505.917435] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2505.974123] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2506.086315] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2506.216577] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2506.312543] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2506.419333] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2506.489992] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2506.556688] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2506.617392] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2506.680330] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2506.741153] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2506.798299] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2506.850764] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2506.928124] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2506.983146] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2507.060295] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2507.134279] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2507.223054] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2507.288607] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2507.368385] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2507.441694] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2507.502682] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2507.592670] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2507.659816] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2507.751156] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2507.832849] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2507.922880] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2508.001545] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2508.077792] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2508.171990] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2508.237394] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2508.310354] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2508.380571] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2508.442236] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2508.515986] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2508.586368] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2508.647985] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2508.697260] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2508.781468] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2508.855815] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2508.920063] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2508.993517] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2509.057637] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2509.133991] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2509.201364] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2509.277204] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2509.404230] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2509.531832] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2509.593502] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2509.651932] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2509.724661] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2509.809610] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2509.891941] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2509.974284] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2510.090889] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2510.195338] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2510.319187] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2510.513149] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2510.643228] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2510.726704] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2510.839399] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2510.936747] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2511.013339] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2511.087845] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2511.160489] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2511.248348] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2511.338956] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2511.388587] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2511.505822] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2511.586783] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2511.686989] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2511.775200] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2511.889172] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2512.003152] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2512.059965] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2512.137173] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2512.249245] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2512.307750] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2512.390246] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2512.469255] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2512.553915] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2512.648819] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2512.748428] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2512.863370] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2513.002736] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2513.090183] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2513.163051] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2513.230383] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2513.296548] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2513.378287] Lustre: lustre-OST0000-osc-ffff8973453c8000: disconnect after 24s idle [ 2513.396361] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2513.462099] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2513.538699] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2513.609806] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2513.698583] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2513.801388] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2513.873708] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2513.942600] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2514.082712] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2514.195806] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2514.305584] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2514.396209] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2514.503085] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2514.594626] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2514.705815] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2514.859185] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2515.038068] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2515.237761] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2515.377069] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2515.479124] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2515.577311] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2515.693033] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2515.836620] rw_seq_cst_vs_d (34680): drop_caches: 3 [ 2524.349842] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 20:31:20 (1767317480) [ 2525.330261] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2525.507740] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2525.694283] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2525.838420] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2525.866677] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2526.080827] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2526.141272] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2526.182982] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2526.381879] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2526.440639] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2526.550101] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2526.668084] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2526.700961] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2526.730880] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2526.768866] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2526.789844] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2526.813173] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2526.978939] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2527.048904] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2527.282389] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2527.467458] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2527.580988] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2527.613892] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2527.649521] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2527.774778] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2527.910653] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2528.005493] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2528.191347] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2528.361532] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2528.486783] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2528.615832] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2528.819259] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2529.057050] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2529.121029] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2529.249829] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2529.516874] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2529.548781] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2529.683916] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2529.807823] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2530.016666] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2530.073232] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2530.195879] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2530.274651] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2530.387943] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2530.503327] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2530.640954] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2530.831976] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2530.967037] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2531.058708] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2531.236627] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2531.293922] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2531.369036] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2531.506707] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2531.550170] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2531.703291] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2531.818285] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2531.912729] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2532.066259] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2532.108646] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2532.152981] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2532.206572] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2532.254474] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2532.293207] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2532.374968] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2532.428229] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2532.618325] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2532.749590] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2532.788786] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2532.961894] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2533.049906] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2533.101372] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2533.285297] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2533.651578] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2533.728216] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2533.858307] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2533.962044] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2534.010761] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2534.081939] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2534.165553] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2534.250354] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2534.379670] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2534.437301] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2534.548497] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2534.656270] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2534.697432] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2534.862783] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2534.907398] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2534.960657] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2534.992021] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2535.141281] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2535.226168] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2535.377197] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2535.503212] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2535.635323] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2535.766130] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2535.840563] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2535.887872] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2535.991116] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2536.039299] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2536.112439] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2536.255211] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2536.391729] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2536.466550] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2536.571849] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2536.693950] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2536.789920] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2536.832199] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2536.941522] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2536.988251] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2537.134441] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2537.291844] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2537.343431] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2537.458201] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2537.489347] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2537.599196] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2537.717531] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2537.847633] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2537.888535] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2537.955721] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2538.019957] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2538.061848] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2538.141874] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2538.206179] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2538.312564] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2538.502625] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2538.539132] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2538.607831] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2538.689196] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2539.032944] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2539.230545] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2539.287398] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2539.363313] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2539.401974] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2539.544642] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2539.594602] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2539.734113] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2539.802990] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2540.019332] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2540.090774] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2540.155439] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2540.290448] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2540.527755] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2540.616311] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2540.671712] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2540.739738] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2540.808556] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2541.052231] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2541.187756] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2541.243429] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2541.339214] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2541.469023] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2541.521574] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2541.560350] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2541.600277] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2541.652827] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2541.719817] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2541.767092] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2541.827079] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2541.914953] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2542.056863] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2542.235036] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2542.484653] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2542.550964] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2542.757072] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2542.820985] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2542.970648] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2543.063407] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2543.112685] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2543.165850] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2543.267625] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2543.323828] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2543.409079] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2543.492942] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2543.639858] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2543.693655] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2543.764320] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2543.849697] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2543.933644] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2543.990348] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2544.095636] Lustre: lustre-OST0001-osc-ffff8973453c8000: disconnect after 22s idle [ 2544.103611] Lustre: Skipped 1 previous similar message [ 2544.125802] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2544.211110] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2544.289755] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2544.580523] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2544.816910] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2544.951758] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2545.061514] rw_seq_cst_vs_d (35270): drop_caches: 3 [ 2552.552756] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 20:31:48 (1767317508) [ 2560.064858] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 20:31:56 (1767317516) [ 2568.648526] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 20:32:05 (1767317525) [ 2603.237354] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 20:32:39 (1767317559) [ 2603.858225] bash (37761): drop_caches: 3 [ 2607.138154] bash (37761): drop_caches: 3 [ 2610.291490] bash (37761): drop_caches: 3 [ 2613.435394] bash (37761): drop_caches: 3 [ 2616.627728] bash (37761): drop_caches: 3 [ 2619.796455] bash (37761): drop_caches: 3 [ 2622.979975] bash (37761): drop_caches: 3 [ 2626.182150] bash (37761): drop_caches: 3 [ 2629.310518] bash (37761): drop_caches: 3 [ 2633.168355] bash (37761): drop_caches: 3 [ 2636.348946] bash (37761): drop_caches: 3 [ 2639.802559] bash (37761): drop_caches: 3 [ 2642.915507] bash (37761): drop_caches: 3 [ 2646.047488] bash (37761): drop_caches: 3 [ 2649.276544] bash (37761): drop_caches: 3 [ 2653.843523] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 20:33:30 (1767317610) [ 2665.587811] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 20:33:41 (1767317621) [ 2691.617774] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 20:34:08 (1767317648) [ 2699.970865] Lustre: DEBUG MARKER: loop 5 [ 2702.816442] Lustre: lustre-OST0000-osc-ffff8973453c8000: disconnect after 20s idle [ 2702.818448] Lustre: Skipped 1 previous similar message [ 2703.945491] Lustre: DEBUG MARKER: loop 10 [ 2708.924810] Lustre: DEBUG MARKER: loop 15 [ 2714.740946] Lustre: DEBUG MARKER: loop 20 [ 2724.042565] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 20:34:39 (1767317679) [ 2731.917891] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 20:34:48 (1767317688) [ 2739.120826] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 20:34:55 (1767317695) [ 2743.775226] Lustre: lustre-OST0001-osc-ffff89735119d800: disconnect after 21s idle [ 2743.778286] Lustre: Skipped 1 previous similar message [ 2764.256398] Lustre: lustre-OST0001-osc-ffff8973453c8000: disconnect after 23s idle [ 2764.265441] Lustre: Skipped 2 previous similar messages [ 2809.315952] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 20:36:05 (1767317765) [ 2816.523509] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 20:36:12 (1767317772) [ 2823.816781] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 20:36:19 (1767317779) [ 2834.359926] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 20:36:30 (1767317790) [ 2842.383135] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 20:36:38 (1767317798) [ 2850.594845] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 20:36:46 (1767317806) [ 2859.044617] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 20:36:55 (1767317815) [ 2866.353291] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 2868.168404] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 20:37:04 (1767317824) [ 2877.299902] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 20:37:13 (1767317833) [ 2877.749964] Lustre: *** cfs_fail_loc=314, val=0*** [ 2878.815879] Lustre: *** cfs_fail_loc=314, val=0*** [ 2878.829776] Lustre: Skipped 2 previous similar messages [ 2882.023595] Lustre: lustre-OST0001-osc-ffff8973453c8000: disconnect after 21s idle [ 2885.329458] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 20:37:21 (1767317841) [ 2896.373621] Lustre: *** cfs_fail_loc=314, val=0*** [ 2896.505149] LustreError: lustre-OST0000-osc-ffff89735119d800: operation ldlm_enqueue to node 192.168.202.139@tcp failed: rc = -107 [ 2896.513614] Lustre: lustre-OST0000-osc-ffff89735119d800: Connection to lustre-OST0000 (at 192.168.202.139@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2896.529110] LustreError: lustre-OST0000-osc-ffff89735119d800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2896.551855] LustreError: 48680:0:(ldlm_resource.c:981:ldlm_resource_complain()) lustre-OST0000-osc-ffff89735119d800: namespace resource [0x280000400:0x7:0x0].0x0 (ffff8973428a8a00) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 2896.568251] Lustre: lustre-OST0000-osc-ffff89735119d800: Connection restored to 192.168.202.139@tcp (at 192.168.202.139@tcp) [ 2905.795820] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 20:37:41 (1767317861) [ 2906.339426] LustreError: 49271:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 2909.383113] LustreError: 49271:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 2916.024493] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 20:37:52 (1767317872) [ 2925.117578] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 20:38:01 (1767317881) [ 2935.851459] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 2937.681260] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 20:38:13 (1767317893) [ 2939.506499] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 2940.786800] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 20:38:17 (1767317897) [ 2943.476749] Lustre: lustre-MDT0000-mdc-ffff8973453c8000: Connection to lustre-MDT0000 (at 192.168.202.139@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2958.844473] LustreError: MGC192.168.202.139@tcp: Connection to MGS (at 192.168.202.139@tcp) was lost; in progress operations using this service will fail [ 2958.865711] Lustre: Evicted from MGS (at 192.168.202.139@tcp) after server handle changed from 0xfc34c564807444a2 to 0xfc34c564807e4c67 [ 2958.894765] Lustre: MGC192.168.202.139@tcp: Connection restored to 192.168.202.139@tcp (at 192.168.202.139@tcp) [ 2959.996448] Lustre: lustre-MDT0000-mdc-ffff89735119d800: Connection restored to 192.168.202.139@tcp (at 192.168.202.139@tcp) [ 2994.435608] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 20:39:10 (1767317950) [ 3040.931378] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 20:39:57 (1767317997) [ 3064.262765] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 20:40:20 (1767318020) [ 3121.614214] Lustre: lustre-OST0000-osc-ffff8973453c8000: Connection to lustre-OST0000 (at 192.168.202.139@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3121.633653] Lustre: Skipped 1 previous similar message [ 3121.646420] LustreError: lustre-OST0000-osc-ffff8973453c8000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 3121.662580] LustreError: lustre-OST0000-osc-ffff89735119d800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 3121.663871] Lustre: lustre-OST0000-osc-ffff8973453c8000: Connection restored to 192.168.202.139@tcp (at 192.168.202.139@tcp) [ 3121.700487] Lustre: Skipped 2 previous similar messages [ 3138.039386] Lustre: lustre-OST0001-osc-ffff8973453c8000: Connection to lustre-OST0001 (at 192.168.202.139@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3138.051978] Lustre: Skipped 1 previous similar message [ 3138.069994] LustreError: lustre-OST0001-osc-ffff8973453c8000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 3138.081852] Lustre: lustre-OST0001-osc-ffff8973453c8000: Connection restored to 192.168.202.139@tcp (at 192.168.202.139@tcp) [ 3143.135609] Lustre: lustre-OST0000-osc-ffff8973453c8000: disconnect after 21s idle [ 3153.479464] Lustre: DEBUG MARKER: oleg239-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8973453c8000.ost_server_uuid 50 [ 3155.380028] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8973453c8000.ost_server_uuid in IDLE state after 0 sec [ 3159.644358] Lustre: DEBUG MARKER: oleg239-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8973453c8000.ost_server_uuid 50 [ 3161.216260] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8973453c8000.ost_server_uuid in IDLE state after 0 sec [ 3166.398164] Lustre: DEBUG MARKER: oleg239-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8973453c8000.ost_server_uuid 50 [ 3167.862486] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8973453c8000.ost_server_uuid in IDLE state after 0 sec [ 3171.259911] Lustre: DEBUG MARKER: oleg239-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8973453c8000.ost_server_uuid 50 [ 3173.039753] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8973453c8000.ost_server_uuid in IDLE state after 0 sec [ 3183.431847] Lustre: DEBUG MARKER: oleg239-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8973453c8000.ost_server_uuid 50 [ 3185.422040] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8973453c8000.ost_server_uuid in IDLE state after 0 sec [ 3189.758137] Lustre: DEBUG MARKER: oleg239-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8973453c8000.ost_server_uuid 50 [ 3191.445466] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8973453c8000.ost_server_uuid in IDLE state after 0 sec [ 3193.633873] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 20:42:29 (1767318149) [ 3196.398649] Lustre: DEBUG MARKER: Race attempt 0 [ 3199.538590] Lustre: DEBUG MARKER: Wait for 59785 59803 for 60 sec... [ 3267.462807] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 20:43:43 (1767318223) [ 3275.862454] Lustre: DEBUG MARKER: start test - cycle (0) [ 3301.877619] Lustre: DEBUG MARKER: start test - cycle (1) [ 3326.942286] Lustre: DEBUG MARKER: start test - cycle (2) [ 3332.575783] Lustre: lustre-OST0000-osc-ffff89735119d800: disconnect after 24s idle [ 3332.583757] Lustre: Skipped 2 previous similar messages [ 3346.523977] Lustre: DEBUG MARKER: start test - cycle (3) [ 3368.760104] Lustre: DEBUG MARKER: start test - cycle (4) [ 3393.716581] Lustre: DEBUG MARKER: start test - cycle (5) [ 3417.920534] Lustre: DEBUG MARKER: start test - cycle (6) [ 3439.266585] Lustre: DEBUG MARKER: start test - cycle (7) [ 3463.289326] Lustre: DEBUG MARKER: start test - cycle (8) [ 3487.024414] Lustre: DEBUG MARKER: start test - cycle (9) [ 3510.585151] Lustre: DEBUG MARKER: start test - cycle (10) [ 3539.197829] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 20:48:15 (1767318495) [ 3622.720268] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 20:49:39 (1767318579) [ 3631.005282] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 20:49:47 (1767318587) [ 3640.186910] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 20:49:56 (1767318596) [ 3648.236901] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 20:50:04 (1767318604) [ 3648.571676] Lustre: *** cfs_fail_loc=411, val=0*** [ 3655.485068] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 3657.605931] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 20:50:13 (1767318613) [ 3665.389749] Lustre: lustre-OST0001-osc-ffff8973453c8000: disconnect after 24s idle [ 3665.403542] Lustre: Skipped 9 previous similar messages [ 3675.366091] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 20:50:31 (1767318631) [ 3691.394246] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 20:50:48 (1767318648) [ 3708.804672] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 20:51:04 (1767318664) [ 3731.637149] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 20:51:27 (1767318687) [ 3746.445779] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 20:51:42 (1767318702) [ 3759.744747] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 20:51:56 (1767318716) [ 3772.491275] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 20:52:08 (1767318728) [ 3785.185543] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 20:52:21 (1767318741) [ 3799.090435] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 20:52:35 (1767318755) [ 3813.086460] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 20:52:49 (1767318769) [ 3827.041464] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 20:53:03 (1767318783) [ 3840.605947] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 20:53:17 (1767318797) [ 4458.975313] Lustre: lustre-OST0000-osc-ffff89735119d800: disconnect after 23s idle [ 4458.978068] Lustre: Skipped 8 previous similar messages [ 4873.326788] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 21:10:30 (1767319830) [ 4886.674662] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 21:10:42 (1767319842) [ 4898.409530] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 21:10:54 (1767319854) [ 4911.238384] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 21:11:07 (1767319867) [ 4926.461414] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 21:11:22 (1767319882) [ 4941.078804] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 21:11:37 (1767319897) [ 4954.355357] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 21:11:51 (1767319911) [ 4966.450253] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 21:12:02 (1767319922) [ 4980.269490] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 21:12:16 (1767319936) [ 5078.900241] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 21:13:55 (1767320035) [ 5091.309841] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 21:14:07 (1767320047) [ 5103.908835] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 21:14:20 (1767320060) [ 5115.748668] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 21:14:32 (1767320072) [ 5127.325589] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 21:14:43 (1767320083) [ 5138.487623] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 21:14:54 (1767320094) [ 5153.395737] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 21:15:09 (1767320109) [ 5155.296823] Lustre: lustre-OST0001-osc-ffff8973453c8000: disconnect after 20s idle [ 5155.303103] Lustre: Skipped 4 previous similar messages [ 5168.350506] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 21:15:24 (1767320124) [ 5181.366809] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 21:15:37 (1767320137) [ 5292.525097] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 21:17:28 (1767320248) [ 5759.455347] Lustre: lustre-OST0000-osc-ffff8973453c8000: disconnect after 23s idle [ 5759.461298] Lustre: Skipped 9 previous similar messages [ 6483.174226] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 21:37:19 (1767321439) [ 6486.495538] Lustre: lustre-OST0000-osc-ffff89735119d800: disconnect after 24s idle [ 6486.502153] Lustre: Skipped 2 previous similar messages [ 6495.956943] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 21:37:32 (1767321452) [ 6508.613456] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 21:37:44 (1767321464) [ 6520.578127] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 21:37:56 (1767321476) [ 6532.432986] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 21:38:09 (1767321489) [ 6543.792837] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 21:38:20 (1767321500) [ 6555.270878] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 21:38:31 (1767321511) [ 6566.263546] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 21:38:42 (1767321522) [ 6578.145365] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 21:38:54 (1767321534) [ 6590.471971] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 21:39:06 (1767321546) [ 6744.939664] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 21:41:41 (1767321701) [ 6756.742746] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 21:41:53 (1767321713) [ 6769.355500] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 21:42:05 (1767321725) [ 6781.856331] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 21:42:18 (1767321738) [ 6793.887434] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 21:42:30 (1767321750) [ 6805.694502] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 21:42:42 (1767321762) [ 6817.765287] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 21:42:54 (1767321774) [ 6829.113633] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 21:43:05 (1767321785) [ 6840.897797] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 21:43:17 (1767321797) [ 7960.865546] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 22:01:57 (1767322917) [ 7970.878188] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 22:02:07 (1767322927) [ 7971.310616] Lustre: lustre-OST0001-osc-ffff89735119d800: disconnect after 21s idle [ 7971.314258] Lustre: Skipped 4 previous similar messages [ 7982.607504] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 22:02:18 (1767322938) [ 7995.723639] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 22:02:31 (1767322951) [ 8008.438588] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 22:02:45 (1767322965) [ 8018.987427] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 22:02:55 (1767322975) [ 8030.275123] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 22:03:06 (1767322986) [ 8040.945512] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 22:03:17 (1767322997) [ 8052.589960] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 22:03:29 (1767323009) [ 8063.535453] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 22:03:39 (1767323019) [ 8068.575837] Lustre: lustre-OST0000-osc-ffff8973453c8000: disconnect after 20s idle [ 8068.584151] Lustre: Skipped 2 previous similar messages [ 8074.389912] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 22:03:51 (1767323031) [ 8085.528824] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 22:04:02 (1767323042) [ 8095.765188] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 22:04:12 (1767323052) [ 8104.453334] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 22:04:21 (1767323061) [ 8113.632639] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 22:04:30 (1767323070) [ 8122.837772] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 22:04:39 (1767323079) [ 8133.379892] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 22:04:50 (1767323090) [ 8133.634822] LustreError: 8098:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 8135.711119] LustreError: 8098:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 8142.786260] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 22:04:59 (1767323099) [ 8149.013945] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 22:05:05 (1767323105) [ 8149.264713] LustreError: 240342:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 8153.328615] LustreError: 240342:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 8153.357533] LustreError: 240342:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 8157.434276] LustreError: 240342:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 8157.490776] LustreError: 240349:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 8161.551152] LustreError: 240349:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 8167.124269] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 22:05:23 (1767323123) [ 8178.018638] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 22:05:34 (1767323134) [ 8185.710625] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 22:05:42 (1767323142) [ 8193.244821] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 22:05:49 (1767323149) [ 8223.035344] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 22:06:19 (1767323179) [ 8234.193423] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 22:06:30 (1767323190) [ 8244.507439] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 22:06:41 (1767323201) [ 8260.144989] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 22:06:56 (1767323216) [ 8273.137625] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 22:07:09 (1767323229) [ 8290.255573] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 22:07:26 (1767323246) [ 8307.513205] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 22:07:44 (1767323264) [ 8326.786963] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 22:08:03 (1767323283) [ 8340.442882] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 22:08:17 (1767323297) [ 8404.929523] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 22:09:21 (1767323361) [ 8409.483916] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 8414.754274] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 22:09:31 (1767323371) [ 8420.044040] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 22:09:36 (1767323376) [ 8424.342799] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 22:09:41 (1767323381) [ 8428.881620] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 22:09:45 (1767323385) [ 8461.240045] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 22:10:17 (1767323417) [ 8483.296353] Lustre: lustre-OST0001-osc-ffff8973453c8000: disconnect after 24s idle [ 8483.299986] Lustre: Skipped 4 previous similar messages [ 8496.714084] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 22:10:53 (1767323453) [ 8501.890546] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 22:10:58 (1767323458) [ 8505.821379] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 22:11:02 (1767323462) [ 8509.049974] LustreError: lustre-MDT0000-mdc-ffff89735119d800: operation ldlm_enqueue to node 192.168.202.139@tcp failed: rc = -35 [ 8513.786193] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 22:11:10 (1767323470) [ 8514.129470] LustreError: 2478:0:(osc_request.c:3093:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 8516.215165] LustreError: 2478:0:(osc_request.c:3093:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 8523.748715] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 22:11:20 (1767323480) [ 8631.726411] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 22:13:08 (1767323588) [ 8638.317669] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 22:13:14 (1767323594) [ 8647.347153] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 22:13:24 (1767323604) [ 8658.241356] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 22:13:34 (1767323614) [ 8669.065598] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 22:13:45 (1767323625) [ 8681.670891] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 22:13:58 (1767323638) [ 8694.213955] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 22:14:11 (1767323651) [ 8699.962556] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 22:14:16 (1767323656) [ 8705.707659] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 22:14:22 (1767323662) [ 8718.601673] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 22:14:35 (1767323675) [ 8765.114739] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 22:15:21 (1767323721) [ 8795.615936] Lustre: lustre-OST0001-osc-ffff8973453c8000: disconnect after 24s idle [ 8795.620341] Lustre: Skipped 7 previous similar messages [ 8883.907804] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 22:17:20 (1767323840) [ 9006.309573] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 22:19:23 (1767323963) [ 9305.568582] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 22:24:22 (1767324262) [ 9338.698420] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 22:24:55 (1767324295) [ 9370.644417] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 22:25:27 (1767324327) [ 9397.919996] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 22:25:54 (1767324354) [ 9425.375624] Lustre: lustre-OST0001-osc-ffff8973453c8000: disconnect after 21s idle [ 9425.378766] Lustre: Skipped 11 previous similar messages [ 9466.793654] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 22:27:03 (1767324423) [ 9523.962325] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 22:28:00 (1767324480) [ 9621.616430] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 22:29:38 (1767324578) [ 9623.857537] LustreError: 286181:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8973453c8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9623.864097] LustreError: 286181:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 9623.905137] Lustre: Unmounted lustre-client [ 9625.143506] LustreError: 286194:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89735119d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9625.150222] LustreError: 286194:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 9625.155583] LustreError: 286194:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 9625.158187] LustreError: 286194:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 9625.194612] Lustre: Unmounted lustre-client [ 9725.300499] Lustre: Mounted lustre-client [ 9727.421327] Lustre: Mounted lustre-client [ 9729.399908] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9799.434365] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 22:32:36 (1767324756) [ 9810.651375] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 22:32:47 (1767324767) [ 9816.375602] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 22:32:53 (1767324773) [ 9868.941765] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 22:33:45 (1767324825) [ 9927.172135] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 22:34:44 (1767324884) [ 9934.235812] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 22:34:51 (1767324891) [10000.470214] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 22:35:57 (1767324957) [10017.002720] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 22:36:13 (1767324973) [10059.246672] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 22:36:56 (1767325016) [10063.434219] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 22:37:00 (1767325020) [10077.275182] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 22:37:14 (1767325034) [10080.735464] Lustre: lustre-OST0000-osc-ffff89736b924800: disconnect after 22s idle [10080.739464] Lustre: Skipped 9 previous similar messages [10082.329880] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 22:37:19 (1767325039) [10082.875350] LustreError: 305330:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x279:0x0]: rc = -5 [10082.881156] LustreError: 305330:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10083.380303] LustreError: 305352:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x24:0x0]: rc = -5 [10083.385748] LustreError: 305352:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 1 previous similar message [10083.389209] LustreError: 305352:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10083.392084] LustreError: 305352:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 1 previous similar message [10084.476592] LustreError: 305402:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x28c:0x0]: rc = -5 [10084.481044] LustreError: 305402:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 9 previous similar messages [10084.484314] LustreError: 305402:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10084.487188] LustreError: 305402:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 9 previous similar messages [10084.943339] LustreError: 305420:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff89736b924800: inode [0x2000013a1:0x294:0x0] mdc close failed: rc = -2 [10086.572560] LustreError: 305503:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x2ba:0x0]: rc = -5 [10086.577713] LustreError: 305503:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 21 previous similar messages [10086.582993] LustreError: 305503:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10086.586573] LustreError: 305503:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 21 previous similar messages [10090.758725] LustreError: 305739:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x30d:0x0]: rc = -5 [10090.763443] LustreError: 305739:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 68 previous similar messages [10090.767465] LustreError: 305739:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10090.772663] LustreError: 305739:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 68 previous similar messages [10098.909847] LustreError: 306143:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x38d:0x0]: rc = -5 [10098.914090] LustreError: 306143:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 119 previous similar messages [10098.916487] LustreError: 306143:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10098.918689] LustreError: 306143:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 119 previous similar messages [10108.501384] LustreError: 306565:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff89736b924800: inode [0x2000013a1:0x405:0x0] mdc close failed: rc = -2 [10114.952776] LustreError: 306849:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x459:0x0]: rc = -5 [10114.957564] LustreError: 306849:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 209 previous similar messages [10114.961530] LustreError: 306849:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10114.964555] LustreError: 306849:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 209 previous similar messages [10231.392864] LustreError: 305169:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x2ac:0x0]: rc = -5 [10231.399522] LustreError: 305169:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 226 previous similar messages [10231.402492] LustreError: 305169:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10231.405762] LustreError: 305169:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 226 previous similar messages [10231.423598] LustreError: lustre-MDT0001-mdc-ffff897345da1000: operation mds_getattr_lock to node 192.168.202.139@tcp failed: rc = -107 [10231.427854] Lustre: lustre-MDT0001-mdc-ffff897345da1000: Connection to lustre-MDT0001 (at 192.168.202.139@tcp) was lost; in progress operations using this service will wait for recovery to complete [10231.443626] LustreError: lustre-MDT0001-mdc-ffff897345da1000: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [10231.470527] Lustre: lustre-MDT0001-mdc-ffff897345da1000: Connection restored to 192.168.202.139@tcp (at 192.168.202.139@tcp) [10234.932834] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 22:39:51 (1767325191) [10238.534645] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 22:39:55 (1767325195) [10335.369653] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 22:41:32 (1767325292) [10336.053994] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [10336.798529] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 22:41:33 (1767325293) [10443.401722] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 22:43:20 (1767325400) [10446.379965] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 22:43:23 (1767325403) [10569.677594] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 22:45:26 (1767325526) [10578.619900] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 22:45:35 (1767325535) [10583.243447] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 22:45:40 (1767325540) [10751.460254] Lustre: lustre-OST0000-osc-ffff89736b924800: disconnect after 20s idle [10751.467060] Lustre: Skipped 2 previous similar messages [10766.364361] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 22:48:43 (1767325723) [10949.412564] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 22:51:46 (1767325906) [10952.565404] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 22:51:49 (1767325909) [10962.336809] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 22:51:59 (1767325919) [10962.452085] Lustre: DEBUG MARKER: write [10962.476965] LustreError: 289447:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [10964.482735] Lustre: DEBUG MARKER: kill 349050 [10964.485188] LustreError: 349050:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [10967.577129] LustreError: 289447:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [10970.530375] LustreError: 349050:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [10973.100288] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 22:52:10 (1767325930) [10975.372909] LustreError: 349663:0:(rw.c:1957:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [10985.376184] LustreError: 349663:0:(rw.c:1957:ll_readpage()) cfs_fail_timeout id 1422 awake [10988.608087] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 22:52:25 (1767325945) [10988.772250] LustreError: 350253:0:(rw.c:2202:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [10990.855132] LustreError: 350253:0:(rw.c:2202:ll_readpage()) cfs_fail_timeout interrupted [10997.502543] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 22:52:34 (1767325954) [10998.104512] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [10998.906590] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 22:52:35 (1767325955) [11001.713284] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 22:52:38 (1767325958) [11004.445354] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 22:52:41 (1767325961) [11007.336091] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 22:52:44 (1767325964) [11010.465259] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 22:52:47 (1767325967) [11013.752467] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 22:52:50 (1767325970) [11016.792778] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 22:52:53 (1767325973) [11021.092545] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 22:52:58 (1767325978) [11024.918123] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 22:53:01 (1767325981) [11025.763217] Lustre: *** cfs_fail_loc=415, val=0*** [11033.168065] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 22:53:10 (1767325990) [11053.580884] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 22:53:30 (1767326010) [11053.713340] LustreError: 288299:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [11053.716737] LustreError: 288299:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [11058.807092] LustreError: 288835:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [11058.807092] LustreError: 289447:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [11058.807110] LustreError: 289447:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 1 previous similar message [11058.809673] LustreError: 288835:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 1 previous similar message [11069.015120] LustreError: 288299:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [11069.019058] LustreError: 288299:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 4 previous similar messages [11076.518115] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 22:53:53 (1767326033) [11079.696680] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 22:53:56 (1767326036) [11082.173901] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 22:53:59 (1767326039) [11084.448990] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 22:54:01 (1767326041) [11088.793828] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 22:54:06 (1767326046) [11097.255586] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 22:54:14 (1767326054) [11097.447783] LustreError: 361005:0:(osc_request.c:2944:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [11097.450352] LustreError: 361005:0:(osc_request.c:2944:osc_build_rpc()) Skipped 6 previous similar messages [11101.511130] LustreError: 361005:0:(osc_request.c:2944:osc_build_rpc()) cfs_fail_timeout id 414 awake [11101.513697] LustreError: 361005:0:(osc_request.c:2944:osc_build_rpc()) Skipped 1 previous similar message [11104.100352] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 22:54:21 (1767326061) [11105.204386] LustreError: 361714:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897345da1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11105.208628] LustreError: 361714:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [11105.213888] LustreError: 361714:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [11105.216475] LustreError: 361714:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [11105.237088] Lustre: Unmounted lustre-client [11105.716919] LustreError: 361734:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [11105.718929] LustreError: 361734:0:(obd_class.h:479:obd_check_dev()) Skipped 11 previous similar messages [11106.256223] Lustre: DEBUG MARKER: Iteration 1 [11106.350914] LustreError: 361897:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11106.350956] LustreError: 361899:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11106.356811] LustreError: 361897:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11106.411349] Lustre: Mounted lustre-client [11106.855869] LustreError: 362004:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897351cbe000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11106.860569] LustreError: 362005:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11106.860678] LustreError: 362004:0:(lov_obd.c:783:lov_cleanup()) Skipped 5 previous similar messages [11106.862614] LustreError: 362005:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [11106.893060] Lustre: Unmounted lustre-client [11106.894231] Lustre: Skipped 1 previous similar message [11107.944466] Key type lgssc unregistered [11108.082635] LNet: 362246:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11108.087332] LNetError: 362246:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11108.096758] LNet: Removed LNI 192.168.202.39@tcp [11108.422118] Key type .llcrypt unregistered [11108.423572] Key type ._llcrypt unregistered [11108.800288] Key type ._llcrypt registered [11108.808288] Key type .llcrypt registered [11108.983618] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11108.989833] alg: No test for adler32 (adler32-zlib) [11110.003466] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11110.318152] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11111.951258] Key type lgssc registered [11112.589909] Lustre: Echo OBD driver; http://www.lustre.org/ [11117.617558] Lustre: DEBUG MARKER: Iteration 2 [11117.778046] LustreError: 363075:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11117.778065] LustreError: 363076:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11117.784349] LustreError: 363075:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [11118.893779] Lustre: Mounted lustre-client [11119.380031] LustreError: 363191:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89736b6ff000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11119.387355] LustreError: 363191:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11119.424657] Lustre: Unmounted lustre-client [11120.548267] Key type lgssc unregistered [11120.698719] LNet: 363434:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11120.702050] LNetError: 363434:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11120.721553] LNet: Removed LNI 192.168.202.39@tcp [11120.976130] Key type .llcrypt unregistered [11120.977687] Key type ._llcrypt unregistered [11121.254409] Key type ._llcrypt registered [11121.255490] Key type .llcrypt registered [11121.457256] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11121.464570] alg: No test for adler32 (adler32-zlib) [11122.342624] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11122.448093] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11124.047773] Key type lgssc registered [11124.519588] Lustre: Echo OBD driver; http://www.lustre.org/ [11128.876500] Lustre: DEBUG MARKER: Iteration 3 [11129.005580] LustreError: 364262:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11129.005625] LustreError: 364263:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11129.013362] LustreError: 364262:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11130.107354] Lustre: Mounted lustre-client [11130.594269] LustreError: 364376:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897360fe1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11130.601609] LustreError: 364376:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11130.604050] LustreError: 364376:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [11130.628355] Lustre: Unmounted lustre-client [11131.728127] Key type lgssc unregistered [11131.855483] LNet: 364618:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11131.858415] LNetError: 364618:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11131.869942] LNet: Removed LNI 192.168.202.39@tcp [11132.128392] Key type .llcrypt unregistered [11132.130266] Key type ._llcrypt unregistered [11132.445840] Key type ._llcrypt registered [11132.446880] Key type .llcrypt registered [11132.628127] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11132.634545] alg: No test for adler32 (adler32-zlib) [11133.521057] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11133.618996] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11135.207174] Key type lgssc registered [11135.710522] Lustre: Echo OBD driver; http://www.lustre.org/ [11140.369953] Lustre: DEBUG MARKER: Iteration 4 [11140.496606] LustreError: 365447:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11140.497243] LustreError: 365449:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11140.504833] LustreError: 365447:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [11141.634030] Lustre: Mounted lustre-client [11142.232330] LustreError: 365562:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89736770d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11142.241776] LustreError: 365562:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11142.282962] Lustre: Unmounted lustre-client [11143.540425] Key type lgssc unregistered [11143.689093] LNet: 365806:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11143.694817] LNetError: 365806:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11143.702883] LNet: Removed LNI 192.168.202.39@tcp [11143.964116] Key type .llcrypt unregistered [11143.965298] Key type ._llcrypt unregistered [11144.254771] Key type ._llcrypt registered [11144.256562] Key type .llcrypt registered [11144.477064] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11144.494605] alg: No test for adler32 (adler32-zlib) [11145.424369] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11145.530259] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11147.135253] Key type lgssc registered [11147.657369] Lustre: Echo OBD driver; http://www.lustre.org/ [11152.572768] Lustre: DEBUG MARKER: Iteration 5 [11152.734730] LustreError: 366634:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11152.735506] LustreError: 366635:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11152.740916] LustreError: 366634:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11153.841978] Lustre: Mounted lustre-client [11153.846877] Lustre: Skipped 1 previous similar message [11154.681971] LustreError: 366743:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897342d1b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11154.686964] LustreError: 366743:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11154.692379] LustreError: 366748:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11154.696612] LustreError: 366748:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11154.724807] Lustre: Unmounted lustre-client [11156.000664] Key type lgssc unregistered [11156.143417] LNet: 366989:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11156.147089] LNetError: 366989:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11156.158091] LNet: Removed LNI 192.168.202.39@tcp [11156.497143] Key type .llcrypt unregistered [11156.498477] Key type ._llcrypt unregistered [11156.857990] Key type ._llcrypt registered [11156.861796] Key type .llcrypt registered [11157.112343] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11157.120443] alg: No test for adler32 (adler32-zlib) [11158.040162] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11158.166110] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11159.775167] Key type lgssc registered [11160.379454] Lustre: Echo OBD driver; http://www.lustre.org/ [11165.363183] Lustre: DEBUG MARKER: Iteration 6 [11165.542516] LustreError: 367818:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11165.543087] LustreError: 367819:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11165.550054] LustreError: 367818:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11166.645533] Lustre: Mounted lustre-client [11166.650057] Lustre: Skipped 1 previous similar message [11167.163044] LustreError: 367932:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897351149000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11167.167076] LustreError: 367933:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11167.168771] LustreError: 367932:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11167.206448] Lustre: Unmounted lustre-client [11168.254840] Key type lgssc unregistered [11168.378566] LNet: 368173:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11168.382573] LNetError: 368173:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11168.393675] LNet: Removed LNI 192.168.202.39@tcp [11168.627136] Key type .llcrypt unregistered [11168.628561] Key type ._llcrypt unregistered [11168.932453] Key type ._llcrypt registered [11168.943890] Key type .llcrypt registered [11169.116732] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11169.122407] alg: No test for adler32 (adler32-zlib) [11170.013835] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11170.103305] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11171.703167] Key type lgssc registered [11172.374406] Lustre: Echo OBD driver; http://www.lustre.org/ [11176.766446] Lustre: DEBUG MARKER: Iteration 7 [11176.941278] LustreError: 369001:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11176.943105] LustreError: 369002:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11176.948694] LustreError: 369001:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [11178.067776] Lustre: Mounted lustre-client [11178.565584] LustreError: 369116:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897351cba800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11178.570069] LustreError: 369116:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11178.573199] LustreError: 369114:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11178.638357] Lustre: Unmounted lustre-client [11179.692857] Key type lgssc unregistered [11179.826704] LNet: 369357:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11179.829324] LNetError: 369357:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11179.841737] LNet: Removed LNI 192.168.202.39@tcp [11180.089151] Key type .llcrypt unregistered [11180.090684] Key type ._llcrypt unregistered [11180.377492] Key type ._llcrypt registered [11180.379015] Key type .llcrypt registered [11180.552496] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11180.558136] alg: No test for adler32 (adler32-zlib) [11181.459906] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11181.591761] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11183.191225] Key type lgssc registered [11183.731770] Lustre: Echo OBD driver; http://www.lustre.org/ [11188.295389] Lustre: DEBUG MARKER: Iteration 8 [11188.447753] LustreError: 370186:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11188.447782] LustreError: 370185:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11188.455035] LustreError: 370186:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [11189.552585] Lustre: Mounted lustre-client [11190.358953] LustreError: 370299:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897346621000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11190.368934] LustreError: 370300:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11190.372911] LustreError: 370300:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [11190.400107] Lustre: Unmounted lustre-client [11191.729750] Key type lgssc unregistered [11191.868698] LNet: 370542:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11191.871982] LNetError: 370542:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11191.884821] LNet: Removed LNI 192.168.202.39@tcp [11192.189311] Key type .llcrypt unregistered [11192.190864] Key type ._llcrypt unregistered [11192.595264] Key type ._llcrypt registered [11192.608691] Key type .llcrypt registered [11192.764788] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11192.773242] alg: No test for adler32 (adler32-zlib) [11193.666592] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11193.783926] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11195.375180] Key type lgssc registered [11195.933507] Lustre: Echo OBD driver; http://www.lustre.org/ [11200.834268] Lustre: DEBUG MARKER: Iteration 9 [11201.014846] LustreError: 371370:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11201.014898] LustreError: 371371:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11201.019870] LustreError: 371370:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [11202.184390] Lustre: Mounted lustre-client [11202.189702] Lustre: Skipped 1 previous similar message [11202.729758] LustreError: 371480:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897351cbc800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11202.735794] LustreError: 371480:0:(obd_class.h:479:obd_check_dev()) Device 7 not setup [11202.783270] Lustre: Unmounted lustre-client [11204.020272] Key type lgssc unregistered [11204.159655] LNet: 371725:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11204.163381] LNetError: 371725:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11204.171963] LNet: Removed LNI 192.168.202.39@tcp [11204.515158] Key type .llcrypt unregistered [11204.518064] Key type ._llcrypt unregistered [11204.872944] Key type ._llcrypt registered [11204.874364] Key type .llcrypt registered [11205.103440] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11205.113581] alg: No test for adler32 (adler32-zlib) [11206.038790] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11206.164496] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11207.775207] Key type lgssc registered [11208.429673] Lustre: Echo OBD driver; http://www.lustre.org/ [11213.500691] Lustre: DEBUG MARKER: Iteration 10 [11213.644256] LustreError: 372554:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11213.644700] LustreError: 372555:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11213.652190] LustreError: 372554:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11214.758288] Lustre: Mounted lustre-client [11214.762613] Lustre: Skipped 1 previous similar message [11215.266834] LustreError: 372661:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897345da7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11215.275816] LustreError: 372661:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11215.304261] Lustre: Unmounted lustre-client [11216.536592] Key type lgssc unregistered [11216.673991] LNet: 372911:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11216.677204] LNetError: 372911:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11216.685212] LNet: Removed LNI 192.168.202.39@tcp [11216.948135] Key type .llcrypt unregistered [11216.949746] Key type ._llcrypt unregistered [11217.217828] Key type ._llcrypt registered [11217.220513] Key type .llcrypt registered [11217.486035] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11217.497481] alg: No test for adler32 (adler32-zlib) [11218.409339] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11218.503718] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11220.119176] Key type lgssc registered [11220.568717] Lustre: Echo OBD driver; http://www.lustre.org/ [11224.875866] Lustre: DEBUG MARKER: Iteration 11 [11225.021207] LustreError: 373739:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11225.021304] LustreError: 373740:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11225.026632] LustreError: 373739:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [11226.133566] Lustre: Mounted lustre-client [11226.134700] Lustre: Skipped 1 previous similar message [11226.609318] LustreError: 373851:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89734799c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11226.615679] LustreError: 373851:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11226.659559] Lustre: Unmounted lustre-client [11227.813422] Key type lgssc unregistered [11227.961694] LNet: 374095:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11227.965179] LNetError: 374095:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11227.972537] LNet: Removed LNI 192.168.202.39@tcp [11228.286130] Key type .llcrypt unregistered [11228.287433] Key type ._llcrypt unregistered [11228.668167] Key type ._llcrypt registered [11228.669580] Key type .llcrypt registered [11228.817984] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11228.824799] alg: No test for adler32 (adler32-zlib) [11229.705590] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11229.801635] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11231.391146] Key type lgssc registered [11231.900475] Lustre: Echo OBD driver; http://www.lustre.org/ [11235.901545] Lustre: DEBUG MARKER: Iteration 12 [11236.008617] LustreError: 374924:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11236.008688] LustreError: 374925:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11236.015596] LustreError: 374924:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11237.117196] Lustre: Mounted lustre-client [11237.121551] Lustre: Skipped 1 previous similar message [11237.648692] LustreError: 375039:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897351fae000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11237.658245] LustreError: 375039:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11237.661161] LustreError: 375039:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11237.703833] Lustre: Unmounted lustre-client [11238.861894] Key type lgssc unregistered [11238.982351] LNet: 375282:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11238.985418] LNetError: 375282:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11238.993674] LNet: Removed LNI 192.168.202.39@tcp [11239.257143] Key type .llcrypt unregistered [11239.258314] Key type ._llcrypt unregistered [11239.594207] Key type ._llcrypt registered [11239.595538] Key type .llcrypt registered [11239.759707] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11239.766585] alg: No test for adler32 (adler32-zlib) [11240.644435] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11240.732990] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11242.320103] Key type lgssc registered [11242.857276] Lustre: Echo OBD driver; http://www.lustre.org/ [11247.457561] Lustre: DEBUG MARKER: Iteration 13 [11247.612626] LustreError: 376110:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11247.622959] LustreError: 376112:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11247.626095] LustreError: 376110:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [11248.770514] Lustre: Mounted lustre-client [11248.772889] Lustre: Skipped 1 previous similar message [11249.238533] LustreError: 376228:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897377108800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11249.242539] LustreError: 376229:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11249.243914] LustreError: 376228:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11249.291023] Lustre: Unmounted lustre-client [11250.545321] Key type lgssc unregistered [11250.695859] LNet: 376470:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11250.699148] LNetError: 376470:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11250.711959] LNet: Removed LNI 192.168.202.39@tcp [11251.007124] Key type .llcrypt unregistered [11251.008914] Key type ._llcrypt unregistered [11251.370833] Key type ._llcrypt registered [11251.374300] Key type .llcrypt registered [11251.583311] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11251.593737] alg: No test for adler32 (adler32-zlib) [11252.490722] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11252.593522] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11254.183172] Key type lgssc registered [11254.726135] Lustre: Echo OBD driver; http://www.lustre.org/ [11258.978334] Lustre: DEBUG MARKER: Iteration 14 [11259.090856] LustreError: 377298:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11259.090930] LustreError: 377299:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11259.096047] LustreError: 377298:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [11260.192050] Lustre: Mounted lustre-client [11260.645398] LustreError: 377413:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897347998000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11260.657415] LustreError: 377414:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11260.662365] LustreError: 377414:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [11260.710249] Lustre: Unmounted lustre-client [11261.803149] Key type lgssc unregistered [11261.936778] LNet: 377656:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11261.940289] LNetError: 377656:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11261.951168] LNet: Removed LNI 192.168.202.39@tcp [11262.229109] Key type .llcrypt unregistered [11262.230716] Key type ._llcrypt unregistered [11262.529457] Key type ._llcrypt registered [11262.537414] Key type .llcrypt registered [11262.743897] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11262.750157] alg: No test for adler32 (adler32-zlib) [11263.636062] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11263.734498] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11265.328624] Key type lgssc registered [11265.792808] Lustre: Echo OBD driver; http://www.lustre.org/ [11269.886824] Lustre: DEBUG MARKER: Iteration 15 [11270.028674] LustreError: 378486:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11270.029593] LustreError: 378485:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11270.035442] LustreError: 378486:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11272.196478] Lustre: Mounted lustre-client [11272.198099] Lustre: Skipped 1 previous similar message [11272.695932] LustreError: 378594:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897351faa000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11272.709250] LustreError: 378594:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11272.743075] Lustre: Unmounted lustre-client [11273.798326] Key type lgssc unregistered [11273.927301] LNet: 378844:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11273.929997] LNetError: 378844:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11273.939661] LNet: Removed LNI 192.168.202.39@tcp [11274.192266] Key type .llcrypt unregistered [11274.193738] Key type ._llcrypt unregistered [11274.453551] Key type ._llcrypt registered [11274.458139] Key type .llcrypt registered [11274.652877] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11274.658449] alg: No test for adler32 (adler32-zlib) [11275.540991] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11275.649601] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11277.239136] Key type lgssc registered [11277.689106] Lustre: Echo OBD driver; http://www.lustre.org/ [11281.762945] Lustre: DEBUG MARKER: Iteration 16 [11281.871776] LustreError: 379673:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11281.871821] LustreError: 379674:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11281.877454] LustreError: 379673:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11282.953495] Lustre: Mounted lustre-client [11282.955033] Lustre: Skipped 1 previous similar message [11283.417424] LustreError: 379787:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897352b52800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11283.422406] LustreError: 379787:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11283.424084] LustreError: 379788:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11283.465923] Lustre: Unmounted lustre-client [11284.580738] Key type lgssc unregistered [11284.694903] LNet: 380028:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11284.699029] LNetError: 380028:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11284.708041] LNet: Removed LNI 192.168.202.39@tcp [11284.995112] Key type .llcrypt unregistered [11284.996640] Key type ._llcrypt unregistered [11285.290704] Key type ._llcrypt registered [11285.293830] Key type .llcrypt registered [11285.476402] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11285.483331] alg: No test for adler32 (adler32-zlib) [11286.359045] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11286.457549] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11288.047284] Key type lgssc registered [11288.497511] Lustre: Echo OBD driver; http://www.lustre.org/ [11292.346756] Lustre: DEBUG MARKER: Iteration 17 [11292.453693] LustreError: 380857:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11292.453732] LustreError: 380858:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11292.459756] LustreError: 380857:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11293.545738] Lustre: Mounted lustre-client [11293.547256] Lustre: Skipped 1 previous similar message [11294.003720] LustreError: 380973:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89735119a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11294.009862] LustreError: 380973:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11294.089360] Lustre: Unmounted lustre-client [11295.043782] Key type lgssc unregistered [11295.157483] LNet: 381216:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11295.160319] LNetError: 381216:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11295.169688] LNet: Removed LNI 192.168.202.39@tcp [11295.411131] Key type .llcrypt unregistered [11295.412673] Key type ._llcrypt unregistered [11295.698708] Key type ._llcrypt registered [11295.700304] Key type .llcrypt registered [11295.895385] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11295.902340] alg: No test for adler32 (adler32-zlib) [11296.768513] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11296.856699] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11298.439243] Key type lgssc registered [11298.864434] Lustre: Echo OBD driver; http://www.lustre.org/ [11302.927704] Lustre: DEBUG MARKER: Iteration 18 [11303.041276] LustreError: 382045:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11303.044330] LustreError: 382046:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11303.048832] LustreError: 382045:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11304.120811] Lustre: Mounted lustre-client [11304.122338] Lustre: Skipped 1 previous similar message [11304.592432] LustreError: 382161:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89735119f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11304.594318] LustreError: 382162:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11304.595758] LustreError: 382161:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11304.629400] Lustre: Unmounted lustre-client [11305.602792] Key type lgssc unregistered [11305.724495] LNet: 382405:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11305.727412] LNetError: 382405:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11305.736812] LNet: Removed LNI 192.168.202.39@tcp [11305.988183] Key type .llcrypt unregistered [11305.989639] Key type ._llcrypt unregistered [11306.262030] Key type ._llcrypt registered [11306.263678] Key type .llcrypt registered [11306.446790] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11306.452694] alg: No test for adler32 (adler32-zlib) [11307.329615] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11307.420620] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11309.008374] Key type lgssc registered [11309.479748] Lustre: Echo OBD driver; http://www.lustre.org/ [11314.167608] Lustre: DEBUG MARKER: Iteration 19 [11314.313655] LustreError: 383233:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11314.314911] LustreError: 383234:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11314.321086] LustreError: 383233:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11315.424378] Lustre: Mounted lustre-client [11315.936160] LustreError: 383347:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897352b55000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11315.940833] LustreError: 383348:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11315.941253] LustreError: 383347:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11315.982195] Lustre: Unmounted lustre-client [11317.013705] Key type lgssc unregistered [11317.141724] LNet: 383590:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11317.145461] LNetError: 383590:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11317.153768] LNet: Removed LNI 192.168.202.39@tcp [11317.401133] Key type .llcrypt unregistered [11317.402619] Key type ._llcrypt unregistered [11317.721449] Key type ._llcrypt registered [11317.722976] Key type .llcrypt registered [11317.899651] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11317.904551] alg: No test for adler32 (adler32-zlib) [11318.765334] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11318.851486] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11320.439188] Key type lgssc registered [11320.848776] Lustre: Echo OBD driver; http://www.lustre.org/ [11324.512355] Lustre: DEBUG MARKER: Iteration 20 [11324.646727] LustreError: 384420:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11324.646801] LustreError: 384419:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11324.652074] LustreError: 384420:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11325.716521] Lustre: Mounted lustre-client [11325.717605] Lustre: Skipped 1 previous similar message [11326.159695] LustreError: 384532:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897343c84800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11326.163448] LustreError: 384532:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11326.166561] LustreError: 384531:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11326.168980] LustreError: 384531:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11326.201173] Lustre: Unmounted lustre-client [11327.205907] Key type lgssc unregistered [11327.327390] LNet: 384773:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11327.329581] LNetError: 384773:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11327.337646] LNet: Removed LNI 192.168.202.39@tcp [11327.595124] Key type .llcrypt unregistered [11327.596480] Key type ._llcrypt unregistered [11327.861143] Key type ._llcrypt registered [11327.862339] Key type .llcrypt registered [11328.070274] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11328.076841] alg: No test for adler32 (adler32-zlib) [11328.938081] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11329.026933] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11330.615195] Key type lgssc registered [11331.101773] Lustre: Echo OBD driver; http://www.lustre.org/ [11335.134703] Lustre: DEBUG MARKER: Iteration 21 [11335.254475] LustreError: 385602:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11335.254506] LustreError: 385603:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11335.261275] LustreError: 385602:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [11336.326086] Lustre: Mounted lustre-client [11336.787466] LustreError: 385716:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8973503d3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11336.792184] LustreError: 385716:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11336.794569] LustreError: 385717:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11336.797709] LustreError: 385717:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11336.835230] Lustre: Unmounted lustre-client [11337.857180] Key type lgssc unregistered [11337.971458] LNet: 385958:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11337.974522] LNetError: 385958:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11337.982941] LNet: Removed LNI 192.168.202.39@tcp [11338.240148] Key type .llcrypt unregistered [11338.241724] Key type ._llcrypt unregistered [11338.533431] Key type ._llcrypt registered [11338.534797] Key type .llcrypt registered [11338.725913] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11338.731545] alg: No test for adler32 (adler32-zlib) [11339.598507] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11339.680269] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11341.271146] Key type lgssc registered [11341.661317] Lustre: Echo OBD driver; http://www.lustre.org/ [11345.470199] Lustre: DEBUG MARKER: Iteration 22 [11345.571316] LustreError: 386787:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11345.575619] LustreError: 386789:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11345.578798] LustreError: 386787:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [11346.645080] Lustre: Mounted lustre-client [11347.113458] LustreError: 386903:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897351198000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11347.120189] LustreError: 386903:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11347.173804] Lustre: Unmounted lustre-client [11348.195509] Key type lgssc unregistered [11348.326807] LNet: 387146:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11348.330347] LNetError: 387146:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11348.339970] LNet: Removed LNI 192.168.202.39@tcp [11348.602129] Key type .llcrypt unregistered [11348.605078] Key type ._llcrypt unregistered [11348.890831] Key type ._llcrypt registered [11348.898254] Key type .llcrypt registered [11349.099522] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11349.104396] alg: No test for adler32 (adler32-zlib) [11349.973756] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11350.061177] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11351.655144] Key type lgssc registered [11352.113858] Lustre: Echo OBD driver; http://www.lustre.org/ [11356.436387] Lustre: DEBUG MARKER: Iteration 23 [11356.563494] LustreError: 387975:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11356.564867] LustreError: 387976:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11356.570177] LustreError: 387975:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11357.647421] Lustre: Mounted lustre-client [11358.101069] LustreError: 388090:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89734799a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11358.104257] LustreError: 388090:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11358.107704] LustreError: 388089:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11358.110303] LustreError: 388089:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11358.150893] Lustre: Unmounted lustre-client [11359.215268] Key type lgssc unregistered [11359.335364] LNet: 388333:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11359.338284] LNetError: 388333:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11359.345723] LNet: Removed LNI 192.168.202.39@tcp [11359.581130] Key type .llcrypt unregistered [11359.582613] Key type ._llcrypt unregistered [11359.827981] Key type ._llcrypt registered [11359.829363] Key type .llcrypt registered [11360.062463] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11360.068253] alg: No test for adler32 (adler32-zlib) [11360.944283] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11361.039447] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11362.631237] Key type lgssc registered [11363.117604] Lustre: Echo OBD driver; http://www.lustre.org/ [11367.288443] Lustre: DEBUG MARKER: Iteration 24 [11367.431580] LustreError: 389164:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11367.432273] LustreError: 389163:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11367.440191] LustreError: 389164:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11368.578502] Lustre: Mounted lustre-client [11369.047222] LustreError: 389275:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8973453cf000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11369.060508] LustreError: 389275:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11369.102087] Lustre: Unmounted lustre-client [11370.138413] Key type lgssc unregistered [11370.274644] LNet: 389520:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11370.277301] LNetError: 389520:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11370.289215] LNet: Removed LNI 192.168.202.39@tcp [11370.571173] Key type .llcrypt unregistered [11370.573091] Key type ._llcrypt unregistered [11370.855289] Key type ._llcrypt registered [11370.857756] Key type .llcrypt registered [11371.066700] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11371.073378] alg: No test for adler32 (adler32-zlib) [11371.994758] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11372.146150] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11373.767196] Key type lgssc registered [11374.306086] Lustre: Echo OBD driver; http://www.lustre.org/ [11378.859350] Lustre: DEBUG MARKER: Iteration 25 [11378.972786] LustreError: 390348:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11378.976679] LustreError: 390354:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11378.979618] LustreError: 390348:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11380.054148] Lustre: Mounted lustre-client [11380.055673] Lustre: Skipped 1 previous similar message [11380.502849] LustreError: 390466:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89735d546000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11380.507489] LustreError: 390467:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11380.508240] LustreError: 390466:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11380.560064] Lustre: Unmounted lustre-client [11381.588099] Key type lgssc unregistered [11381.699565] LNet: 390709:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11381.702487] LNetError: 390709:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11381.712599] LNet: Removed LNI 192.168.202.39@tcp [11381.950192] Key type .llcrypt unregistered [11381.951951] Key type ._llcrypt unregistered [11382.240817] Key type ._llcrypt registered [11382.242296] Key type .llcrypt registered [11382.435841] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11382.441513] alg: No test for adler32 (adler32-zlib) [11383.314851] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11383.407891] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11385.007152] Key type lgssc registered [11385.431319] Lustre: Echo OBD driver; http://www.lustre.org/ [11389.100250] Lustre: DEBUG MARKER: Iteration 26 [11389.210345] LustreError: 391539:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11389.211082] LustreError: 391540:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11389.215624] LustreError: 391539:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [11390.260564] Lustre: Mounted lustre-client [11390.712311] LustreError: 391651:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897360cf9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11390.718082] LustreError: 391650:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11390.718580] LustreError: 391651:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11390.755090] Lustre: Unmounted lustre-client [11391.741853] Key type lgssc unregistered [11391.858494] LNet: 391892:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11391.861292] LNetError: 391892:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11391.869646] LNet: Removed LNI 192.168.202.39@tcp [11392.117105] Key type .llcrypt unregistered [11392.118186] Key type ._llcrypt unregistered [11392.406575] Key type ._llcrypt registered [11392.407951] Key type .llcrypt registered [11392.586553] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11392.592528] alg: No test for adler32 (adler32-zlib) [11393.459933] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11393.553267] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11395.143168] Key type lgssc registered [11395.572272] Lustre: Echo OBD driver; http://www.lustre.org/ [11399.519566] Lustre: DEBUG MARKER: Iteration 27 [11399.648851] LustreError: 392721:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11399.648916] LustreError: 392722:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11399.653809] LustreError: 392721:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [11400.725756] Lustre: Mounted lustre-client [11400.728376] Lustre: Skipped 1 previous similar message [11401.184148] LustreError: 392834:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8973453cd000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11401.188157] LustreError: 392834:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11401.191341] LustreError: 392836:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11401.231155] Lustre: Unmounted lustre-client [11402.292337] Key type lgssc unregistered [11402.418404] LNet: 393077:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11402.421403] LNetError: 393077:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11402.429888] LNet: Removed LNI 192.168.202.39@tcp [11402.715142] Key type .llcrypt unregistered [11402.716640] Key type ._llcrypt unregistered [11403.039887] Key type ._llcrypt registered [11403.041526] Key type .llcrypt registered [11403.198111] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11403.203512] alg: No test for adler32 (adler32-zlib) [11404.079270] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11404.165754] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11405.751161] Key type lgssc registered [11406.205591] Lustre: Echo OBD driver; http://www.lustre.org/ [11410.166700] Lustre: DEBUG MARKER: Iteration 28 [11410.321149] LustreError: 393905:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11410.321395] LustreError: 393906:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11410.326405] LustreError: 393905:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [11411.391190] Lustre: Mounted lustre-client [11411.393768] Lustre: Skipped 1 previous similar message [11411.932648] LustreError: 394014:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8973453c8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11411.942370] LustreError: 394014:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [11411.946096] LustreError: 394014:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11411.987561] Lustre: Unmounted lustre-client [11413.114831] Key type lgssc unregistered [11413.237631] LNet: 394258:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11413.240287] LNetError: 394258:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11413.247774] LNet: Removed LNI 192.168.202.39@tcp [11413.494170] Key type .llcrypt unregistered [11413.495936] Key type ._llcrypt unregistered [11413.750415] Key type ._llcrypt registered [11413.762882] Key type .llcrypt registered [11413.980311] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11413.987586] alg: No test for adler32 (adler32-zlib) [11414.879078] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11414.979371] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11416.567163] Key type lgssc registered [11416.990993] Lustre: Echo OBD driver; http://www.lustre.org/ [11420.946292] Lustre: DEBUG MARKER: Iteration 29 [11421.068942] LustreError: 395087:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11421.069040] LustreError: 395086:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11421.075436] LustreError: 395087:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [11422.149925] Lustre: Mounted lustre-client [11422.591956] LustreError: 395201:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89736d1b1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11422.594507] LustreError: 395200:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [11422.596152] LustreError: 395201:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11422.639963] Lustre: Unmounted lustre-client [11423.672570] Key type lgssc unregistered [11423.787131] LNet: 395443:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11423.790081] LNetError: 395443:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11423.797556] LNet: Removed LNI 192.168.202.39@tcp [11424.041126] Key type .llcrypt unregistered [11424.042416] Key type ._llcrypt unregistered [11424.292122] Key type ._llcrypt registered [11424.293330] Key type .llcrypt registered [11424.500716] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11424.506588] alg: No test for adler32 (adler32-zlib) [11425.373213] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11425.473405] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11427.071121] Key type lgssc registered [11427.487488] Lustre: Echo OBD driver; http://www.lustre.org/ [11431.259768] Lustre: DEBUG MARKER: Iteration 30 [11431.373547] LustreError: 396281:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11431.373712] LustreError: 396284:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11431.380213] LustreError: 396281:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [11432.456836] Lustre: Mounted lustre-client [11432.458146] Lustre: Skipped 1 previous similar message [11432.936044] LustreError: 396395:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897342d1f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11432.940862] LustreError: 396395:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11432.941537] LustreError: 396396:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11432.997758] Lustre: Unmounted lustre-client [11434.066773] Key type lgssc unregistered [11434.186161] LNet: 396637:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11434.189327] LNetError: 396637:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11434.203058] LNet: Removed LNI 192.168.202.39@tcp [11434.446103] Key type .llcrypt unregistered [11434.447271] Key type ._llcrypt unregistered [11434.735316] Key type ._llcrypt registered [11434.736392] Key type .llcrypt registered [11434.935704] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11434.944971] alg: No test for adler32 (adler32-zlib) [11435.813250] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11435.907794] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11437.495176] Key type lgssc registered [11437.993640] Lustre: Echo OBD driver; http://www.lustre.org/ [11442.928386] Lustre: DEBUG MARKER: Iteration 31 [11443.067666] LustreError: 397478:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11443.067802] LustreError: 397477:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11443.074853] LustreError: 397478:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11445.196539] Lustre: Mounted lustre-client [11445.720991] LustreError: 397594:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897360c99800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11445.725795] LustreError: 397594:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11445.728969] LustreError: 397595:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11445.768386] Lustre: Unmounted lustre-client [11446.843552] Key type lgssc unregistered [11446.972340] LNet: 397838:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11446.974923] LNetError: 397838:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11446.982546] LNet: Removed LNI 192.168.202.39@tcp [11447.242189] Key type .llcrypt unregistered [11447.244810] Key type ._llcrypt unregistered [11447.521862] Key type ._llcrypt registered [11447.524133] Key type .llcrypt registered [11447.699069] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11447.705698] alg: No test for adler32 (adler32-zlib) [11448.570982] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11448.660505] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11450.247175] Key type lgssc registered [11450.672373] Lustre: Echo OBD driver; http://www.lustre.org/ [11454.330966] Lustre: DEBUG MARKER: Iteration 32 [11454.441271] LustreError: 398666:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11454.444846] LustreError: 398667:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11454.448595] LustreError: 398666:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [11455.525923] Lustre: Mounted lustre-client [11455.974974] LustreError: 398781:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897360c9d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11455.980771] LustreError: 398781:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11456.012080] Lustre: Unmounted lustre-client [11457.038253] Key type lgssc unregistered [11457.143269] LNet: 399023:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11457.145442] LNetError: 399023:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11457.152409] LNet: Removed LNI 192.168.202.39@tcp [11457.398120] Key type .llcrypt unregistered [11457.399505] Key type ._llcrypt unregistered [11457.675547] Key type ._llcrypt registered [11457.677283] Key type .llcrypt registered [11457.869796] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11457.876342] alg: No test for adler32 (adler32-zlib) [11458.751928] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11458.851950] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11460.455328] Key type lgssc registered [11460.878516] Lustre: Echo OBD driver; http://www.lustre.org/ [11465.010390] Lustre: DEBUG MARKER: Iteration 33 [11465.136394] LustreError: 399852:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11465.136465] LustreError: 399853:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11465.142918] LustreError: 399852:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [11466.218063] Lustre: Mounted lustre-client [11466.716602] LustreError: 399967:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89736d1b1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11466.726801] LustreError: 399967:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11466.779098] Lustre: Unmounted lustre-client [11467.909376] Key type lgssc unregistered [11468.020350] LNet: 400209:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11468.023412] LNetError: 400209:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11468.033598] LNet: Removed LNI 192.168.202.39@tcp [11468.283703] Key type .llcrypt unregistered [11468.285107] Key type ._llcrypt unregistered [11468.527204] Key type ._llcrypt registered [11468.528989] Key type .llcrypt registered [11468.750070] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11468.764513] alg: No test for adler32 (adler32-zlib) [11469.638567] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11469.738760] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11471.343714] Key type lgssc registered [11471.854906] Lustre: Echo OBD driver; http://www.lustre.org/ [11475.818273] Lustre: DEBUG MARKER: Iteration 34 [11475.933195] LustreError: 401046:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11475.934651] LustreError: 401047:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11475.937311] LustreError: 401046:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [11477.002729] Lustre: Mounted lustre-client [11477.487840] LustreError: 401164:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897351fac000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11477.490398] LustreError: 401163:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11477.491846] LustreError: 401164:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11477.557056] Lustre: Unmounted lustre-client [11478.697952] Key type lgssc unregistered [11478.832473] LNet: 401406:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11478.835537] LNetError: 401406:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11478.843734] LNet: Removed LNI 192.168.202.39@tcp [11479.099132] Key type .llcrypt unregistered [11479.100886] Key type ._llcrypt unregistered [11479.384593] Key type ._llcrypt registered [11479.388182] Key type .llcrypt registered [11479.613660] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11479.621727] alg: No test for adler32 (adler32-zlib) [11480.492686] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11480.593265] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11482.191117] Key type lgssc registered [11482.708360] Lustre: Echo OBD driver; http://www.lustre.org/ [11486.906797] Lustre: DEBUG MARKER: Iteration 35 [11487.021100] LustreError: 402234:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11487.024102] LustreError: 402235:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11487.027440] LustreError: 402234:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [11488.106666] Lustre: Mounted lustre-client [11488.576766] LustreError: 402342:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897352b49800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11488.583804] LustreError: 402342:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11488.622083] Lustre: Unmounted lustre-client [11489.693247] Key type lgssc unregistered [11489.824495] LNet: 402594:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11489.828735] LNetError: 402594:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11489.841426] LNet: Removed LNI 192.168.202.39@tcp [11490.105851] Key type .llcrypt unregistered [11490.107080] Key type ._llcrypt unregistered [11490.388422] Key type ._llcrypt registered [11490.389831] Key type .llcrypt registered [11490.564063] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11490.572391] alg: No test for adler32 (adler32-zlib) [11491.453673] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11491.546785] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11493.151180] Key type lgssc registered [11493.609689] Lustre: Echo OBD driver; http://www.lustre.org/ [11497.469017] Lustre: DEBUG MARKER: Iteration 36 [11497.675541] LustreError: 403423:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11497.676228] LustreError: 403424:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11497.681155] LustreError: 403423:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11498.767642] Lustre: Mounted lustre-client [11498.768947] Lustre: Skipped 1 previous similar message [11499.247127] LustreError: 403539:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897342d1a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11499.250265] LustreError: 403538:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11499.251814] LustreError: 403539:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11499.297051] Lustre: Unmounted lustre-client [11500.354800] Key type lgssc unregistered [11500.475508] LNet: 403781:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11500.477976] LNetError: 403781:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11500.488645] LNet: Removed LNI 192.168.202.39@tcp [11500.727124] Key type .llcrypt unregistered [11500.728588] Key type ._llcrypt unregistered [11501.014711] Key type ._llcrypt registered [11501.016020] Key type .llcrypt registered [11501.190646] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11501.195654] alg: No test for adler32 (adler32-zlib) [11502.066914] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11502.171508] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11503.762398] Key type lgssc registered [11504.183449] Lustre: Echo OBD driver; http://www.lustre.org/ [11508.818060] Lustre: DEBUG MARKER: Iteration 37 [11508.933169] LustreError: 404621:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11508.933296] LustreError: 404620:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11508.938854] LustreError: 404621:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11510.014106] Lustre: Mounted lustre-client [11510.504793] LustreError: 404734:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897372d1f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11510.522273] LustreError: 404734:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11510.524500] LustreError: 404734:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11510.558567] Lustre: Unmounted lustre-client [11511.640763] Key type lgssc unregistered [11511.771483] LNet: 404978:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11511.774343] LNetError: 404978:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11511.784271] LNet: Removed LNI 192.168.202.39@tcp [11512.066144] Key type .llcrypt unregistered [11512.067937] Key type ._llcrypt unregistered [11512.377546] Key type ._llcrypt registered [11512.381307] Key type .llcrypt registered [11512.586968] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11512.593893] alg: No test for adler32 (adler32-zlib) [11513.457365] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11513.547332] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11515.135116] Key type lgssc registered [11515.531094] Lustre: Echo OBD driver; http://www.lustre.org/ [11519.422262] Lustre: DEBUG MARKER: Iteration 38 [11519.541548] LustreError: 405806:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11519.541586] LustreError: 405807:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11519.546954] LustreError: 405806:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11521.623906] Lustre: Mounted lustre-client [11521.625764] Lustre: Skipped 1 previous similar message [11522.094205] LustreError: 405925:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897352b53000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11522.098740] LustreError: 405926:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11522.100975] LustreError: 405925:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11522.133372] Lustre: Unmounted lustre-client [11523.222836] Key type lgssc unregistered [11523.354485] LNet: 406167:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11523.357097] LNetError: 406167:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11523.365632] LNet: Removed LNI 192.168.202.39@tcp [11523.654201] Key type .llcrypt unregistered [11523.655342] Key type ._llcrypt unregistered [11523.986866] Key type ._llcrypt registered [11523.988062] Key type .llcrypt registered [11524.232870] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11524.237229] alg: No test for adler32 (adler32-zlib) [11525.098899] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11525.189810] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11526.775182] Key type lgssc registered [11527.158060] Lustre: Echo OBD driver; http://www.lustre.org/ [11530.824185] Lustre: DEBUG MARKER: Iteration 39 [11530.931779] LustreError: 406997:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11530.932736] LustreError: 406996:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11530.935985] LustreError: 406997:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [11531.993738] Lustre: Mounted lustre-client [11531.994850] Lustre: Skipped 1 previous similar message [11532.413686] LustreError: 407108:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897344fbb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11532.418731] LustreError: 407108:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11532.422667] LustreError: 407108:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11532.464066] Lustre: Unmounted lustre-client [11533.406223] Key type lgssc unregistered [11533.522383] LNet: 407350:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11533.524462] LNetError: 407350:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11533.530745] LNet: Removed LNI 192.168.202.39@tcp [11533.759132] Key type .llcrypt unregistered [11533.760427] Key type ._llcrypt unregistered [11534.023851] Key type ._llcrypt registered [11534.025194] Key type .llcrypt registered [11534.199191] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11534.204669] alg: No test for adler32 (adler32-zlib) [11535.065759] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11535.153059] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11536.735156] Key type lgssc registered [11537.136279] Lustre: Echo OBD driver; http://www.lustre.org/ [11540.976682] Lustre: DEBUG MARKER: Iteration 40 [11541.098767] LustreError: 408179:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11541.098810] LustreError: 408180:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11541.104675] LustreError: 408179:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11542.161502] Lustre: Mounted lustre-client [11542.622924] LustreError: 408288:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8973754fa800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11542.626570] LustreError: 408289:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11542.627751] LustreError: 408288:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11542.662366] Lustre: Unmounted lustre-client [11543.903850] Key type lgssc unregistered [11544.032744] LNet: 408530:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11544.035762] LNetError: 408530:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11544.045160] LNet: Removed LNI 192.168.202.39@tcp [11544.315200] Key type .llcrypt unregistered [11544.317724] Key type ._llcrypt unregistered [11544.675201] Key type ._llcrypt registered [11544.676314] Key type .llcrypt registered [11544.862127] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11544.871296] alg: No test for adler32 (adler32-zlib) [11545.748419] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11545.844397] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11547.431149] Key type lgssc registered [11547.881240] Lustre: Echo OBD driver; http://www.lustre.org/ [11551.722680] Lustre: DEBUG MARKER: Iteration 41 [11551.852737] LustreError: 409359:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11551.853055] LustreError: 409360:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11551.860080] LustreError: 409359:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [11552.929941] Lustre: Mounted lustre-client [11553.379537] LustreError: 409474:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897343c83800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11553.381089] LustreError: 409473:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11553.383479] LustreError: 409474:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11553.428128] Lustre: Unmounted lustre-client [11554.383130] Key type lgssc unregistered [11554.509454] LNet: 409715:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11554.512226] LNetError: 409715:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11554.523414] LNet: Removed LNI 192.168.202.39@tcp [11554.762094] Key type .llcrypt unregistered [11554.763308] Key type ._llcrypt unregistered [11555.047097] Key type ._llcrypt registered [11555.048207] Key type .llcrypt registered [11555.227136] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11555.233698] alg: No test for adler32 (adler32-zlib) [11556.097216] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11556.182115] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11557.767130] Key type lgssc registered [11558.153494] Lustre: Echo OBD driver; http://www.lustre.org/ [11561.686636] Lustre: DEBUG MARKER: Iteration 42 [11561.790519] LustreError: 410544:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11561.790553] LustreError: 410545:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11561.795876] LustreError: 410544:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [11562.850180] Lustre: Mounted lustre-client [11562.853769] Lustre: Skipped 1 previous similar message [11563.306686] LustreError: 410658:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897352b4a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11563.310119] LustreError: 410658:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11563.312752] LustreError: 410659:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11563.314975] LustreError: 410659:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11563.354090] Lustre: Unmounted lustre-client [11564.319678] Key type lgssc unregistered [11564.436085] LNet: 410900:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11564.439546] LNetError: 410900:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11564.451770] LNet: Removed LNI 192.168.202.39@tcp [11564.684103] Key type .llcrypt unregistered [11564.685431] Key type ._llcrypt unregistered [11564.955831] Key type ._llcrypt registered [11564.957418] Key type .llcrypt registered [11565.149691] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11565.155347] alg: No test for adler32 (adler32-zlib) [11566.038358] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11566.127271] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11567.712599] Key type lgssc registered [11568.198253] Lustre: Echo OBD driver; http://www.lustre.org/ [11571.943668] Lustre: DEBUG MARKER: Iteration 43 [11572.046086] LustreError: 411741:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11572.046442] LustreError: 411742:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11572.051168] LustreError: 411741:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11573.108218] Lustre: Mounted lustre-client [11573.109527] Lustre: Skipped 1 previous similar message [11573.564260] LustreError: 411855:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8973754f8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11573.568066] LustreError: 411855:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11573.571898] LustreError: 411854:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11573.626103] Lustre: Unmounted lustre-client [11574.625103] Key type lgssc unregistered [11574.734371] LNet: 412095:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11574.736699] LNetError: 412095:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11574.743654] LNet: Removed LNI 192.168.202.39@tcp [11574.975121] Key type .llcrypt unregistered [11574.976222] Key type ._llcrypt unregistered [11575.197701] Key type ._llcrypt registered [11575.198923] Key type .llcrypt registered [11575.407963] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11575.413835] alg: No test for adler32 (adler32-zlib) [11576.271203] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11576.355233] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11577.943159] Key type lgssc registered [11578.330198] Lustre: Echo OBD driver; http://www.lustre.org/ [11581.881722] Lustre: DEBUG MARKER: Iteration 44 [11581.975590] LustreError: 412924:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11581.976904] LustreError: 412925:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11581.980521] LustreError: 412924:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [11583.035579] Lustre: Mounted lustre-client [11583.460732] LustreError: 413036:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897342d18800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11583.465224] LustreError: 413037:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11583.465350] LustreError: 413036:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11583.505553] Lustre: Unmounted lustre-client [11584.430488] Key type lgssc unregistered [11584.556495] LNet: 413278:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11584.558650] LNetError: 413278:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11584.570584] LNet: Removed LNI 192.168.202.39@tcp [11584.790111] Key type .llcrypt unregistered [11584.791243] Key type ._llcrypt unregistered [11585.033695] Key type ._llcrypt registered [11585.034840] Key type .llcrypt registered [11585.232108] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11585.238216] alg: No test for adler32 (adler32-zlib) [11586.083834] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11586.158412] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11587.735159] Key type lgssc registered [11588.152613] Lustre: Echo OBD driver; http://www.lustre.org/ [11591.674619] Lustre: DEBUG MARKER: Iteration 45 [11591.772374] LustreError: 414107:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11591.777092] LustreError: 414108:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11591.779078] LustreError: 414107:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [11592.852970] Lustre: Mounted lustre-client [11593.291204] LustreError: 414220:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89734818f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11593.298327] LustreError: 414221:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11593.300798] LustreError: 414221:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11593.341871] Lustre: Unmounted lustre-client [11594.261419] Key type lgssc unregistered [11594.386667] LNet: 414463:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11594.389862] LNetError: 414463:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11594.396415] LNet: Removed LNI 192.168.202.39@tcp [11594.634128] Key type .llcrypt unregistered [11594.635528] Key type ._llcrypt unregistered [11594.871530] Key type ._llcrypt registered [11594.873903] Key type .llcrypt registered [11595.070538] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11595.075324] alg: No test for adler32 (adler32-zlib) [11595.926734] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11596.005311] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11597.591139] Key type lgssc registered [11597.977663] Lustre: Echo OBD driver; http://www.lustre.org/ [11601.497036] Lustre: DEBUG MARKER: Iteration 46 [11601.586801] LustreError: 415291:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11601.587727] LustreError: 415292:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11601.591651] LustreError: 415291:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [11602.642413] Lustre: Mounted lustre-client [11602.643652] Lustre: Skipped 1 previous similar message [11603.113231] LustreError: 415408:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897348256000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11603.115675] LustreError: 415409:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11603.117249] LustreError: 415408:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11603.171056] Lustre: Unmounted lustre-client [11604.091844] Key type lgssc unregistered [11604.209400] LNet: 415651:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11604.211968] LNetError: 415651:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11604.219731] LNet: Removed LNI 192.168.202.39@tcp [11604.452122] Key type .llcrypt unregistered [11604.453383] Key type ._llcrypt unregistered [11604.723642] Key type ._llcrypt registered [11604.726616] Key type .llcrypt registered [11604.901963] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11604.907230] alg: No test for adler32 (adler32-zlib) [11605.770489] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11605.847905] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11607.423154] Key type lgssc registered [11607.803727] Lustre: Echo OBD driver; http://www.lustre.org/ [11611.237259] Lustre: DEBUG MARKER: Iteration 47 [11611.347913] LustreError: 416478:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11611.356277] LustreError: 416480:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11611.359172] LustreError: 416478:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [11612.418607] Lustre: Mounted lustre-client [11612.419667] Lustre: Skipped 1 previous similar message [11612.825823] LustreError: 416596:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897343c9f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11612.830282] LustreError: 416596:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11612.880349] Lustre: Unmounted lustre-client [11612.881927] Lustre: Skipped 1 previous similar message [11613.772465] Key type lgssc unregistered [11613.879401] LNet: 416838:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11613.882309] LNetError: 416838:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11613.895477] LNet: Removed LNI 192.168.202.39@tcp [11614.114136] Key type .llcrypt unregistered [11614.115310] Key type ._llcrypt unregistered [11614.357679] Key type ._llcrypt registered [11614.359135] Key type .llcrypt registered [11614.536971] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11614.541746] alg: No test for adler32 (adler32-zlib) [11615.393711] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11615.469403] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11617.055132] Key type lgssc registered [11617.407296] Lustre: Echo OBD driver; http://www.lustre.org/ [11620.807910] Lustre: DEBUG MARKER: Iteration 48 [11620.927437] LustreError: 417667:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11620.927490] LustreError: 417668:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11620.933759] LustreError: 417667:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11621.996303] Lustre: Mounted lustre-client [11621.997918] Lustre: Skipped 1 previous similar message [11622.494933] LustreError: 417779:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89735114e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11622.498257] LustreError: 417780:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11622.499864] LustreError: 417779:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11622.527310] Lustre: Unmounted lustre-client [11623.417458] Key type lgssc unregistered [11623.523448] LNet: 418022:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11623.526264] LNetError: 418022:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11623.535588] LNet: Removed LNI 192.168.202.39@tcp [11623.757153] Key type .llcrypt unregistered [11623.758039] Key type ._llcrypt unregistered [11623.999360] Key type ._llcrypt registered [11624.004818] Key type .llcrypt registered [11624.186166] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11624.191265] alg: No test for adler32 (adler32-zlib) [11625.043425] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11625.117443] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11626.695154] Key type lgssc registered [11627.090787] Lustre: Echo OBD driver; http://www.lustre.org/ [11630.622463] Lustre: DEBUG MARKER: Iteration 49 [11630.716159] LustreError: 418851:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11630.716204] LustreError: 418850:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11630.720131] LustreError: 418851:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [11631.764144] Lustre: Mounted lustre-client [11631.766106] Lustre: Skipped 1 previous similar message [11632.180832] LustreError: 418963:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897348250000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11632.184411] LustreError: 418963:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11632.187240] LustreError: 418964:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [11632.190706] LustreError: 418964:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [11632.232128] Lustre: Unmounted lustre-client [11633.102871] Key type lgssc unregistered [11633.217403] LNet: 419206:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11633.219550] LNetError: 419206:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11633.227617] LNet: Removed LNI 192.168.202.39@tcp [11633.428099] Key type .llcrypt unregistered [11633.429191] Key type ._llcrypt unregistered [11633.679262] Key type ._llcrypt registered [11633.680385] Key type .llcrypt registered [11633.850278] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11633.855710] alg: No test for adler32 (adler32-zlib) [11634.711432] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11634.788069] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11636.375133] Key type lgssc registered [11636.734882] Lustre: Echo OBD driver; http://www.lustre.org/ [11640.215620] Lustre: DEBUG MARKER: Iteration 50 [11640.307805] LustreError: 420034:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11640.307839] LustreError: 420035:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11640.312519] LustreError: 420034:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [11641.374001] Lustre: Mounted lustre-client [11641.375776] Lustre: Skipped 1 previous similar message [11641.817714] LustreError: 420145:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897352b4c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11641.819677] LustreError: 420146:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11641.821412] LustreError: 420145:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11641.855099] Lustre: Unmounted lustre-client [11642.746092] Key type lgssc unregistered [11642.862644] LNet: 420388:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11642.865425] LNetError: 420388:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11642.877706] LNet: Removed LNI 192.168.202.39@tcp [11643.100095] Key type .llcrypt unregistered [11643.101165] Key type ._llcrypt unregistered [11643.319966] Key type ._llcrypt registered [11643.321498] Key type .llcrypt registered [11643.481074] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11643.486829] alg: No test for adler32 (adler32-zlib) [11644.346767] Lustre: Lustre: Build Version: 2.17.0_RC4_1_g3742a8b [11644.429828] LNet: Added LNI 192.168.202.39@tcp [8/256/0/180] [11646.007131] Key type lgssc registered [11646.357954] Lustre: Echo OBD driver; http://www.lustre.org/ [11650.470911] Lustre: Mounted lustre-client [11652.853108] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 23:03:30 (1767326610) [11668.447165] Lustre: 421738:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767326610/real 1767326610] req@ffff897367dea680 x1853176259028864/t0(0) o36->lustre-MDT0000-mdc-ffff897347999000@192.168.202.139@tcp:12/10 lens 496/440 e 0 to 1 dl 1767326626 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [11668.454855] Lustre: lustre-MDT0000-mdc-ffff897347999000: Connection to lustre-MDT0000 (at 192.168.202.139@tcp) was lost; in progress operations using this service will wait for recovery to complete [11668.463939] Lustre: lustre-MDT0000-mdc-ffff897347999000: Connection restored to 192.168.202.139@tcp (at 192.168.202.139@tcp) [11684.831131] Lustre: 421738:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767326626/real 1767326626] req@ffff897367dea680 x1853176259028864/t0(0) o36->lustre-MDT0000-mdc-ffff897347999000@192.168.202.139@tcp:12/10 lens 496/440 e 0 to 1 dl 1767326642 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [11684.838472] Lustre: lustre-MDT0000-mdc-ffff897347999000: Connection to lustre-MDT0000 (at 192.168.202.139@tcp) was lost; in progress operations using this service will wait for recovery to complete [11684.848444] Lustre: lustre-MDT0000-mdc-ffff897347999000: Connection restored to 192.168.202.139@tcp (at 192.168.202.139@tcp) [11701.215135] Lustre: 421738:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767326642/real 1767326642] req@ffff897367dea680 x1853176259028864/t0(0) o36->lustre-MDT0000-mdc-ffff897347999000@192.168.202.139@tcp:12/10 lens 496/440 e 0 to 1 dl 1767326658 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [11701.224339] Lustre: lustre-MDT0000-mdc-ffff897347999000: Connection to lustre-MDT0000 (at 192.168.202.139@tcp) was lost; in progress operations using this service will wait for recovery to complete [11701.238607] Lustre: lustre-MDT0000-mdc-ffff897347999000: Connection restored to 192.168.202.139@tcp (at 192.168.202.139@tcp) [11716.575127] Lustre: 421738:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767326658/real 1767326658] req@ffff897367dea680 x1853176259028864/t0(0) o36->lustre-MDT0000-mdc-ffff897347999000@192.168.202.139@tcp:12/10 lens 496/440 e 0 to 1 dl 1767326674 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [11716.586135] Lustre: lustre-MDT0000-mdc-ffff897347999000: Connection to lustre-MDT0000 (at 192.168.202.139@tcp) was lost; in progress operations using this service will wait for recovery to complete [11716.596630] Lustre: lustre-MDT0000-mdc-ffff897347999000: Connection restored to 192.168.202.139@tcp (at 192.168.202.139@tcp) [11732.959147] Lustre: 421738:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767326674/real 1767326674] req@ffff897367dea680 x1853176259028864/t0(0) o36->lustre-MDT0000-mdc-ffff897347999000@192.168.202.139@tcp:12/10 lens 496/440 e 0 to 1 dl 1767326690 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [11732.968268] Lustre: lustre-MDT0000-mdc-ffff897347999000: Connection to lustre-MDT0000 (at 192.168.202.139@tcp) was lost; in progress operations using this service will wait for recovery to complete [11732.977730] Lustre: lustre-MDT0000-mdc-ffff897347999000: Connection restored to 192.168.202.139@tcp (at 192.168.202.139@tcp) [11733.478406] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 23:04:50 (1767326690) [11739.223469] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 23:04:56 (1767326696) [11742.369403] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 23:04:59 (1767326699) [11744.491194] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 23:05:01 (1767326701) [11751.520487] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 23:05:08 (1767326708) [11763.612317] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 23:05:20 (1767326720) [11765.825311] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 23:05:23 (1767326723) [11765.916413] LustreError: 426523:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [11767.999093] LustreError: 426523:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [11769.914436] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 23:05:27 (1767326727) [11788.191125] Lustre: 420576:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767326729/real 1767326729] req@ffff897345df1f80 x1853176260068736/t0(0) o4->lustre-OST0000-osc-ffff897347999000@192.168.202.139@tcp:6/4 lens 4584/448 e 0 to 1 dl 1767326745 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [11788.191178] Lustre: lustre-OST0000-osc-ffff897347999000: Connection to lustre-OST0000 (at 192.168.202.139@tcp) was lost; in progress operations using this service will wait for recovery to complete [11788.198141] Lustre: 420576:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 1 previous similar message [11803.617305] Lustre: lustre-OST0000-osc-ffff897347999000: Connection restored to 192.168.202.139@tcp (at 192.168.202.139@tcp) [11819.999153] Lustre: 420579:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767326761/real 1767326761] req@ffff8973507a1180 x1853176260067584/t0(0) o4->lustre-OST0000-osc-ffff897347999000@192.168.202.139@tcp:6/4 lens 4584/448 e 0 to 1 dl 1767326777 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [11819.999192] Lustre: lustre-OST0000-osc-ffff897347999000: Connection to lustre-OST0000 (at 192.168.202.139@tcp) was lost; in progress operations using this service will wait for recovery to complete [11820.008553] Lustre: 420579:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 9 previous similar messages [11820.015680] Lustre: Skipped 1 previous similar message [11820.024413] Lustre: lustre-OST0000-osc-ffff897347999000: Connection restored to 192.168.202.139@tcp (at 192.168.202.139@tcp) [11858.813482] Lustre: DEBUG MARKER: oleg239-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff897347999000.ost_server_uuid 50 [11859.323642] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff897347999000.ost_server_uuid in FULL state after 0 sec [11859.890064] Lustre: DEBUG MARKER: cleanup: ====================================================== [11860.482649] Lustre: DEBUG MARKER: == sanityn test complete, duration 11275 sec ============= 23:06:57 (1767326817) [11861.056838] Lustre: DEBUG MARKER: === sanityn: start cleanup 23:06:58 (1767326818) === [11916.807769] LustreError: 428569:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff89735119c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11916.812357] LustreError: 428569:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11916.832089] Lustre: Unmounted lustre-client [11918.158188] Lustre: DEBUG MARKER: === sanityn: finish cleanup 23:07:55 (1767326875) === [11918.497839] LustreError: 428874:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff897347999000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11918.501913] LustreError: 428874:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [11918.507883] LustreError: 428874:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [11918.509972] LustreError: 428874:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [11918.534135] Lustre: Unmounted lustre-client [11955.974503] Key type lgssc unregistered [11956.097535] LNet: 429613:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11956.099937] LNetError: 429613:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11956.110853] LNet: Removed LNI 192.168.202.39@tcp [11956.367156] Key type .llcrypt unregistered [11956.368566] Key type ._llcrypt unregistered