[ 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 457105328 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: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001020] APIC: Switch to symmetric I/O mode setup [ 0.002615] x2apic enabled [ 0.003019] Switched APIC routing to physical x2apic. [ 0.004017] kvm-guest: setup PV IPIs [ 0.008000] ..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.008034] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009012] pid_max: default: 32768 minimum: 301 [ 0.010115] LSM: Security Framework initializing [ 0.011051] Yama: becoming mindful. [ 0.012035] SELinux: Initializing. [ 0.013075] *** VALIDATE selinux *** [ 0.022345] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.027798] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029032] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030137] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032129] *** VALIDATE tmpfs *** [ 0.034223] *** VALIDATE proc *** [ 0.035322] *** VALIDATE cgroup *** [ 0.036018] *** VALIDATE cgroup2 *** [ 0.037384] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.038246] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.039015] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.040043] Spectre V2 : User space: Vulnerable [ 0.041019] Speculative Store Bypass: Vulnerable [ 0.044506] debug: unmapping init [mem 0xffffffffb5a59000-0xffffffffb5a60fff] [ 0.046391] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.048213] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.049039] ... version: 2 [ 0.050027] ... bit width: 48 [ 0.051018] ... generic registers: 4 [ 0.052028] ... value mask: 0000ffffffffffff [ 0.053022] ... max period: 00007fffffffffff [ 0.054032] ... fixed-purpose events: 3 [ 0.055025] ... event mask: 000000070000000f [ 0.056401] rcu: Hierarchical SRCU implementation. [ 0.059505] smp: Bringing up secondary CPUs ... [ 0.060992] x86: Booting SMP configuration: [ 0.061076] .... node #0, CPUs: #1 #2 #3 [ 0.067047] smp: Brought up 1 node, 4 CPUs [ 0.069029] smpboot: Max logical packages: 1 [ 0.070031] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.144492] node 0 deferred pages initialised in 70ms [ 0.147399] devtmpfs: initialized [ 0.148240] x86/mm: Memory block size: 128MB [ 0.151093] gcov: version magic: 0x41383552 [ 0.153264] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.154098] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.155323] pinctrl core: initialized pinctrl subsystem [ 0.156236] [ 0.156935] ************************************************************* [ 0.157016] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.158012] ** ** [ 0.159028] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.160013] ** ** [ 0.161013] ** This means that this kernel is built to expose internal ** [ 0.162019] ** IOMMU data structures, which may compromise security on ** [ 0.163012] ** your system. ** [ 0.164014] ** ** [ 0.165014] ** If you see this message and you are not debugging the ** [ 0.166013] ** kernel, report this immediately to your vendor! ** [ 0.167010] ** ** [ 0.168018] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.169022] ************************************************************* [ 0.170734] NET: Registered protocol family 16 [ 0.171450] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.172086] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.173088] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.174472] cpuidle: using governor menu [ 0.177744] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.180581] PCI: Using configuration type 1 for base access [ 0.182130] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.191146] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.192025] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.193208] cryptd: max_cpu_qlen set to 1000 [ 0.197327] ACPI: Added _OSI(Module Device) [ 0.198015] ACPI: Added _OSI(Processor Device) [ 0.199014] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.200017] ACPI: Added _OSI(Processor Aggregator Device) [ 0.204324] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.209022] ACPI: Interpreter enabled [ 0.210073] ACPI: PM: (supports S0 S3 S4 S5) [ 0.211013] ACPI: Using IOAPIC for interrupt routing [ 0.212117] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.213383] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.221682] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.222053] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.223021] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.224127] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.226549] acpiphp: Slot [2] registered [ 0.227221] acpiphp: Slot [5] registered [ 0.228201] acpiphp: Slot [6] registered [ 0.229145] acpiphp: Slot [3] registered [ 0.230145] acpiphp: Slot [4] registered [ 0.231149] acpiphp: Slot [7] registered [ 0.232148] acpiphp: Slot [8] registered [ 0.234173] acpiphp: Slot [9] registered [ 0.236171] acpiphp: Slot [10] registered [ 0.238131] acpiphp: Slot [11] registered [ 0.240174] acpiphp: Slot [12] registered [ 0.241163] acpiphp: Slot [13] registered [ 0.243121] acpiphp: Slot [14] registered [ 0.245124] acpiphp: Slot [15] registered [ 0.247175] acpiphp: Slot [16] registered [ 0.248245] acpiphp: Slot [17] registered [ 0.250177] acpiphp: Slot [18] registered [ 0.251000] acpiphp: Slot [19] registered [ 0.251000] acpiphp: Slot [20] registered [ 0.253100] acpiphp: Slot [21] registered [ 0.255109] acpiphp: Slot [22] registered [ 0.256231] acpiphp: Slot [23] registered [ 0.258140] acpiphp: Slot [24] registered [ 0.260164] acpiphp: Slot [25] registered [ 0.262168] acpiphp: Slot [26] registered [ 0.264163] acpiphp: Slot [27] registered [ 0.266159] acpiphp: Slot [28] registered [ 0.267095] acpiphp: Slot [29] registered [ 0.269125] acpiphp: Slot [30] registered [ 0.270153] acpiphp: Slot [31] registered [ 0.272120] PCI host bridge to bus 0000:00 [ 0.274024] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.277029] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.279032] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.283028] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.286042] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.289038] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.290000] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.292326] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.296086] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.304019] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.307874] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.311020] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.313015] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.315016] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.318645] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.320909] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.324048] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.328311] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.333013] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.342017] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.345867] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.352701] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.356885] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.366023] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.389025] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.402000] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.409018] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.419018] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.440000] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.451916] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.454506] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.457474] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.460412] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.463224] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.467231] iommu: Default domain type: Passthrough [ 0.476583] SCSI subsystem initialized [ 0.478207] ACPI: bus type USB registered [ 0.480135] usbcore: registered new interface driver usbfs [ 0.482160] usbcore: registered new interface driver hub [ 0.484095] usbcore: registered new device driver usb [ 0.486210] pps_core: LinuxPPS API ver. 1 registered [ 0.490015] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.493073] PTP clock support registered [ 0.495144] EDAC MC: Ver: 3.0.0 [ 0.496522] PCI: Using ACPI for IRQ routing [ 0.498107] NetLabel: Initializing [ 0.499019] NetLabel: domain hash size = 128 [ 0.500018] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.501079] NetLabel: unlabeled traffic allowed by default [ 0.504129] vgaarb: loaded [ 0.505384] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.508016] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.514340] clocksource: Switched to clocksource kvm-clock [ 0.623638] VFS: Disk quotas dquot_6.6.0 [ 0.625476] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.628382] *** VALIDATE ramfs *** [ 0.629773] *** VALIDATE hugetlbfs *** [ 0.631573] pnp: PnP ACPI init [ 0.634297] pnp: PnP ACPI: found 6 devices [ 0.651401] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.655345] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.657825] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.660423] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.663159] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.666043] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.669456] NET: Registered protocol family 2 [ 0.672333] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.677926] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.681593] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.687322] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.691316] TCP: Hash tables configured (established 65536 bind 65536) [ 0.694660] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.698295] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.701597] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.704729] NET: Registered protocol family 1 [ 0.707338] RPC: Registered named UNIX socket transport module. [ 0.711530] RPC: Registered udp transport module. [ 0.713626] RPC: Registered tcp transport module. [ 0.715780] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.718871] NET: Registered protocol family 44 [ 0.721070] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.723904] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.726765] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.729849] PCI: CLS 0 bytes, default 64 [ 0.732342] Unpacking initramfs... [ 2.197161] debug: unmapping init [mem 0xffff91a0bcc64000-0xffff91a0bffcffff] [ 2.201151] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.205059] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.208276] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.733126] Initialise system trusted keyrings [ 2.734738] Key type blacklist registered [ 2.736976] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.746415] zbud: loaded [ 2.749725] *** VALIDATE nfs *** [ 2.751145] *** VALIDATE nfs4 *** [ 2.752889] pstore: using deflate compression [ 2.756758] Platform Keyring initialized [ 2.859484] NET: Registered protocol family 38 [ 2.862119] Key type asymmetric registered [ 2.864032] Asymmetric key parser 'x509' registered [ 2.866407] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.869758] io scheduler mq-deadline registered [ 2.871270] io scheduler kyber registered [ 2.872902] io scheduler bfq registered [ 2.874714] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.878651] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.881413] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.885478] ACPI: Power Button [PWRF] [ 2.890667] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.898070] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.914697] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.942645] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.973608] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.978812] Non-volatile memory driver v1.3 [ 2.980547] Linux agpgart interface v0.103 [ 3.019920] virtio_blk virtio1: [vda] 133936 512-byte logical blocks (68.6 MB/65.4 MiB) [ 3.023258] vda: detected capacity change from 0 to 68575232 [ 3.045314] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.051682] vdb: detected capacity change from 0 to 1073741824 [ 3.064628] libphy: Fixed MDIO Bus: probed [ 3.075095] usbcore: registered new interface driver usbserial_generic [ 3.077292] usbserial: USB Serial support registered for generic [ 3.079204] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.083072] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.084654] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.087334] mousedev: PS/2 mouse device common for all mice [ 3.090292] rtc_cmos 00:05: RTC can wake from S4 [ 3.096464] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.096652] rtc_cmos 00:05: registered as rtc0 [ 3.105256] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.111623] intel_pstate: CPU model not supported [ 3.116443] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.121569] hid: raw HID events driver (C) Jiri Kosina [ 3.126103] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.126217] usbcore: registered new interface driver usbhid [ 3.132523] usbhid: USB HID core driver [ 3.134135] drop_monitor: Initializing network drop monitor service [ 3.140334] Initializing XFRM netlink socket [ 3.144411] NET: Registered protocol family 10 [ 3.151455] Segment Routing with IPv6 [ 3.153145] NET: Registered protocol family 17 [ 3.155904] mpls_gso: MPLS GSO support [ 3.163804] RAS: Correctable Errors collector initialized. [ 3.166114] AVX version of gcm_enc/dec engaged. [ 3.167983] AES CTR mode by8 optimization enabled [ 3.255608] sched_clock: Marking stable (3255574441, 0)->(4491576122, -1236001681) [ 3.260139] registered taskstats version 1 [ 3.262459] Loading compiled-in X.509 certificates [ 3.264289] zswap: loaded using pool lzo/zbud [ 3.298189] Key type big_key registered [ 3.312784] Key type encrypted registered [ 3.314841] ima: No TPM chip found, activating TPM-bypass! [ 3.317266] ima: Allocated hash algorithm: sha1 [ 3.319467] ima: No architecture policies found [ 3.321417] evm: Initialising EVM extended attributes: [ 3.323975] evm: security.selinux [ 3.325527] evm: security.ima [ 3.326874] evm: security.capability [ 3.328440] evm: HMAC attrs: 0x1 [ 3.331386] rtc_cmos 00:05: setting system clock to 2026-04-03 11:39:44 UTC (1775216384) [ 3.344838] debug: unmapping init [mem 0xffffffffb6a03000-0xffffffffb6bfffff] [ 3.349613] debug: unmapping init [mem 0xffffffffb5782000-0xffffffffb5a58fff] [ 3.358091] Write protecting the kernel read-only data: 28672k [ 3.363798] debug: unmapping init [mem 0xffffffffb3e03000-0xffffffffb3ffffff] [ 3.369078] debug: unmapping init [mem 0xffffffffb4714000-0xffffffffb47fffff] [ 3.424270] 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.431420] systemd[1]: Detected virtualization kvm. [ 3.433914] systemd[1]: Detected architecture x86-64. [ 3.436048] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.461348] systemd[1]: No hostname configured. [ 3.463272] systemd[1]: Set hostname to . [ 3.465579] random: systemd: uninitialized urandom read (16 bytes read) [ 3.468364] systemd[1]: Initializing machine ID from random generator. [ 3.593198] random: systemd: uninitialized urandom read (16 bytes read) [ 3.596359] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 3.604222] random: systemd: uninitialized urandom read (16 bytes read) [ 3.607166] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 3.612335] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Timers. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Journal Socket. Starting Create Volatile Files and Directories... [ OK ] Started Memstrack Anylazing Service. Starting Setup Virtual Console... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Sockets. Starting Journal Service... [ OK ] Reached target Initrd Root Device. Starting Apply Kernel Variables... [ OK ] Reached target Swap. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.364738] device-mapper: uevent: version 1.0.3 [ 4.367563] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 5.171485] virtio_net virtio0 ens2: renamed from eth0 [ 5.274120] scsi host0: ata_piix [ 5.340394] scsi host1: ata_piix [ 5.342728] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.345605] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.856956] random: crng init done [ 9.865451] random: 7 urandom warning(s) missed due to ratelimiting [ 10.220521] dracut-initqueue[589]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 13.121588] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Timers. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ 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 Slices. [ OK ] Stopped target Sockets. [ 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 Swap. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ 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... [ 17.214273] printk: systemd: 25 output lines suppressed due to ratelimiting [ 18.497904] SELinux: Disabled at runtime. [ 18.671748] 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) [ 18.711550] systemd[1]: Detected virtualization kvm. [ 18.713797] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 21.247801] systemd[1]: initrd-switch-root.service: Succeeded. [ 21.253817] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 21.281812] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 21.288980] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 21.297848] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 21.349148] systemd[1]: Starting Journal Service... Starting Journal Service... [ 21.378275] systemd[1]: Created slice system-getty.slice. [ OK ] Created slice system-getty.slice. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Mounting Kernel Debug File System... [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Created slice User and Session Slice. Mounting Huge Pages File System... [ OK ] Listening on udev Control Socket. [ OK ] Reached target rpc_pipefs.target. Mounting POSIX Message Queue File System... Activating swap /dev/disk/by-label/SWAP... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. [ OK ] Reached target RPC Port Mapper. Starting Apply Kernel Variables... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ 21.991559] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Reached target Local Encrypted Volumes. Starting Remount Root and Kernel File Systems... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Reached target Slices. [ OK ] Listening on Process Core Dump Socket. [ OK ] Reached target Paths. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Started Journal Service. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Apply Kernel Variables. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started udev Coldplug all Devices. [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 23.389626] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 24.625405] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 24.646287] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 25.062167] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 25.162313] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (8s / no limit)[ 29.454337] Key type dns_resolver registered [** ] A start job is running for Configur…-only root support (8s / no limit) [*** ] A start job is running for Configur…-only root support (9s / no limit)[ 30.451433] NFS: Registering the id_resolver key type [ 30.459887] Key type id_resolver registered [ 30.462892] Key type id_legacy registered [ *** ] A start job is running for Configur…-only root support (9s / no limit) [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... Starting Mark the need to relabel after reboot... 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 Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Login Service... [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Reached target sshd-keygen.target. Starting Restore /run/initramfs on shutdown... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... [ OK ] Started Login Service. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... Starting Hostname Service... [ OK ] Started Permit User Sessions. [ OK ] Started OpenSSH server daemon. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... Starting System Logging Service... [ 39.396015] hrtimer: interrupt took 9998625 ns [ 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 oleg453-client login: [ 105.383684] libcfs: loading out-of-tree module taints kernel. [ 105.420196] Key type ._llcrypt registered [ 105.422913] Key type .llcrypt registered [ 106.172157] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 106.194463] alg: No test for adler32 (adler32-zlib) [ 107.819960] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 108.869114] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 110.687208] Key type lgssc registered [ 112.687970] Lustre: Echo OBD driver; http://www.lustre.org/ [ 285.222616] Lustre: Mounted lustre-client [ 290.449435] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 309.874374] Lustre: DEBUG MARKER: oleg453-client.virtnet: executing check_logdir /tmp/testlogs/ [ 310.755293] Lustre: lustre-OST0000-osc-ffff91a118b53000: disconnect after 23s idle [ 315.351236] Lustre: DEBUG MARKER: oleg453-client.virtnet: executing yml_node [ 321.138484] Lustre: DEBUG MARKER: Client: 2.17.51.74 [ 324.227702] Lustre: DEBUG MARKER: MDS: 2.17.51.74 [ 327.290641] Lustre: DEBUG MARKER: OSS: 2.17.51.74 [ 329.285230] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Fri Apr 3 07:45:08 EDT 2026 [ 349.503956] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 351.045737] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 352.724239] Lustre: DEBUG MARKER: === sanityn: start setup 07:45:32 (1775216732) === [ 353.470650] Lustre: Mounted lustre-client [ 357.394526] Lustre: DEBUG MARKER: oleg453-client.virtnet: executing check_config_client /mnt/lustre [ 377.692048] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 392.895851] Lustre: DEBUG MARKER: === sanityn: finish setup 07:46:12 (1775216772) === [ 395.243641] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 07:46:15 (1775216775) [ 403.886375] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 07:46:23 (1775216783) [ 411.329591] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 07:46:31 (1775216791) [ 420.286426] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 07:46:39 (1775216799) [ 428.526536] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 07:46:48 (1775216808) [ 434.888645] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 07:46:54 (1775216814) [ 441.932609] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 07:47:01 (1775216821) [ 448.382870] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 07:47:07 (1775216827) [ 456.811570] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 07:47:16 (1775216836) [ 465.406681] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 07:47:24 (1775216844) [ 473.347978] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 07:47:32 (1775216852) [ 481.432872] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 07:47:40 (1775216860) [ 481.759913] Lustre: lustre-OST0000-osc-ffff91a118b53000: disconnect after 24s idle [ 481.767630] Lustre: Skipped 1 previous similar message [ 488.941949] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 07:47:48 (1775216868) [ 495.246585] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 07:47:55 (1775216875) [ 497.129761] Lustre: lustre-OST0001-osc-ffff91a1031fe800: disconnect after 23s idle [ 502.100327] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 07:48:01 (1775216881) [ 508.714157] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 07:48:08 (1775216888) [ 515.721062] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 07:48:15 (1775216895) [ 523.096830] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 07:48:22 (1775216902) [ 532.222271] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 07:48:31 (1775216911) [ 541.176780] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 07:48:40 (1775216920) [ 550.793357] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 07:48:49 (1775216929) [ 551.571412] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279518 file: /mnt/lustre/lockdir/lockfile=144115205289279517 [ 694.288374] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 07:51:13 (1775217073) [ 703.782448] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 07:51:23 (1775217083) [ 711.030394] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 07:51:30 (1775217090) [ 718.615824] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 07:51:38 (1775217098) [ 724.756532] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 07:51:44 (1775217104) [ 731.248850] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 07:51:51 (1775217111) [ 733.471995] Lustre: DEBUG MARKER: chmod [ 739.964201] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 07:51:59 (1775217119) [ 1616.370453] Lustre: lustre-OST0000-osc-ffff91a1031fe800: disconnect after 20s idle [ 1617.366434] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 08:06:36 (1775217996) [ 1764.831496] Lustre: lustre-OST0001-osc-ffff91a1031fe800: disconnect after 23s idle [ 1764.839759] Lustre: Skipped 1 previous similar message [ 1834.701693] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 08:10:14 (1775218214) [ 1957.042110] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 08:12:16 (1775218336) [ 2102.797965] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 08:14:42 (1775218482) [ 2141.947302] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 08:15:20 (1775218520) [ 2143.714856] Lustre: lustre-OST0001-osc-ffff91a1031fe800: disconnect after 23s idle [ 2151.019680] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 08:15:30 (1775218530) [ 2152.264761] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2152.457497] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2152.594030] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2152.732326] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2152.856737] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2152.991984] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2153.090802] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2153.215099] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2153.335040] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2153.385362] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2153.450370] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2153.510167] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2153.570573] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2153.618374] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2153.654367] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2153.757789] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2153.913850] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2154.042984] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2154.198893] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2154.364487] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2154.423852] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2154.513987] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2154.675731] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2154.819837] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2154.946294] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2155.051986] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2155.161831] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2155.322467] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2155.410098] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2155.535254] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2155.649072] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2155.732223] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2155.852797] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2155.964251] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2156.067412] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2156.168976] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2156.259437] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2156.390394] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2156.516496] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2156.666442] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2156.793435] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2156.918467] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2157.083435] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2157.240362] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2157.340355] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2157.423575] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2157.525965] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2157.639862] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2157.768862] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2157.891908] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2157.966193] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2158.027984] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2158.103745] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2158.195702] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2158.284525] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2158.333444] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2158.382618] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2158.444896] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2158.538238] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2158.648269] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2158.759526] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2158.838180] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2158.925240] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2159.022444] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2159.112699] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2159.209691] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2159.282692] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2159.360475] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2159.484245] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2159.557807] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2159.639464] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2159.749369] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2159.836774] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2159.927422] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2160.019990] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2160.108777] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2160.204786] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2160.276832] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2160.348886] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2160.413858] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2160.487268] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2160.546678] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2160.637296] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2160.733839] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2160.804251] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2160.864162] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2160.948644] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2161.027487] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2161.101684] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2161.221981] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2161.354702] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2161.496647] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2161.582880] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2161.686733] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2161.761232] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2161.834973] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2161.905629] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2162.022909] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2162.140956] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2162.235828] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2162.344201] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2162.458474] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2162.561840] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2162.664258] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2162.783521] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2162.867401] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2162.991270] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2163.108546] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2163.245956] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2163.357724] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2163.449556] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2163.568567] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2163.671940] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2163.783303] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2163.935411] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2164.075745] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2164.183916] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2164.194583] Lustre: lustre-OST0000-osc-ffff91a118b53000: disconnect after 20s idle [ 2164.284250] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2164.393165] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2164.490639] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2164.591477] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2164.676484] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2164.779494] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2164.857598] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2164.942704] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2165.021332] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2165.126605] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2165.217205] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2165.320673] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2165.396754] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2165.460444] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2165.543608] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2165.634690] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2165.731705] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2165.822533] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2165.913598] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2165.966284] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2166.057691] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2166.138519] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2166.216550] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2166.283876] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2166.360238] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2166.429401] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2166.509042] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2166.578533] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2166.651786] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2166.744938] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2166.825257] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2166.894628] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2166.931628] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2166.994440] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2167.064044] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2167.153797] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2167.206946] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2167.271724] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2167.372661] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2167.511721] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2167.594633] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2167.664924] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2167.739755] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2167.826466] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2167.896000] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2167.970437] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2168.037549] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2168.076886] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2168.156352] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2168.205762] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2168.254230] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2168.362654] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2168.413021] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2168.471914] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2168.510820] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2168.591989] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2168.647639] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2168.725675] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2168.806202] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2168.882650] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2168.964573] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2169.040708] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2169.120546] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2169.214257] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2169.305514] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2169.411669] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2169.496696] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2169.576255] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2169.642897] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2169.721107] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2169.803754] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2169.883990] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2169.980701] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2170.071767] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2170.146803] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2170.226970] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2170.305873] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2170.394974] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2170.480251] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2170.557806] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2170.680634] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2170.785823] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2170.911744] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2171.027074] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2171.132258] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2171.206521] rw_seq_cst_vs_d (32325): drop_caches: 3 [ 2178.960569] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 08:15:58 (1775218558) [ 2179.394515] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2179.489815] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2179.613064] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2179.681272] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2179.752135] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2179.895446] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2179.973948] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2180.019842] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2180.080245] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2180.117806] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2180.140551] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2180.244621] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2180.348484] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2180.387595] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2180.421604] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2180.448035] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2180.542159] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2180.585401] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2180.692157] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2180.724369] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2180.945616] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2181.077352] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2181.224661] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2181.292633] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2181.416425] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2181.452719] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2181.483368] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2181.571671] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2181.740312] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2181.782672] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2181.870937] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2181.912627] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2182.061730] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2182.120885] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2182.255306] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2182.340738] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2182.532390] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2182.654726] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2182.790236] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2182.839480] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2182.985501] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2183.047195] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2183.168348] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2183.360305] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2183.452809] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2183.510943] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2183.576212] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2183.665221] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2183.824352] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2183.913970] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2183.974573] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2184.026821] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2184.149460] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2184.202647] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2184.395610] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2184.585306] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2184.704751] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2184.840942] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2184.939453] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2185.129218] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2185.310362] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2185.362885] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2185.398526] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2185.523283] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2185.621530] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2185.720847] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2185.769080] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2185.964577] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2186.102275] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2186.236585] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2186.327370] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2186.497532] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2186.576218] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2186.645171] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2186.698322] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2186.738672] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2186.825682] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2187.243086] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2187.311476] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2187.444343] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2187.512716] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2187.667428] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2187.781488] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2187.916653] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2187.972334] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2188.058686] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2188.131682] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2188.197620] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2188.355800] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2188.555154] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2188.695302] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2188.738414] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2188.878641] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2188.971801] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2189.149447] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2189.245750] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2189.345769] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2189.503777] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2189.745439] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2189.848512] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2189.941138] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2190.207043] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2190.283169] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2190.414579] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2190.609846] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2190.654943] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2190.747561] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2190.877567] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2190.936250] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2191.005569] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2191.150398] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2191.231586] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2191.312988] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2191.455571] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2191.490550] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2191.552807] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2191.682594] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2191.775967] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2191.838628] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2192.054432] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2192.226730] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2192.338856] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2192.433657] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2192.537580] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2192.684702] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2192.733398] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2192.862159] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2192.960246] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2193.097386] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2193.150250] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2193.308327] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2193.392253] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2193.659922] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2193.719666] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2193.824400] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2193.890392] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2193.926469] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2194.211967] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2194.267946] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2194.346584] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2194.398582] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2194.537829] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2194.675218] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2194.782652] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2194.821615] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2194.863338] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2194.907323] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2194.911823] Lustre: lustre-OST0001-osc-ffff91a1031fe800: disconnect after 24s idle [ 2194.926647] Lustre: Skipped 1 previous similar message [ 2195.022194] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2195.100057] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2195.188228] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2195.268896] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2195.396313] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2195.446170] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2195.538599] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2195.597229] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2195.649267] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2195.764738] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2195.841942] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2195.887884] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2196.031264] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2196.097075] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2196.194994] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2196.262097] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2196.329917] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2196.374772] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2196.431078] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2196.583165] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2196.695488] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2196.746384] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2196.842797] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2196.921252] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2197.027202] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2197.075913] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2197.165570] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2197.293542] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2197.341307] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2197.399345] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2197.431520] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2197.557456] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2197.612604] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2197.709728] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2197.826413] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2197.878555] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2197.917702] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2198.022581] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2198.064307] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2198.130499] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2198.162345] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2198.333289] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2198.543551] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2198.598591] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2198.707691] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2198.777462] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2198.912669] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2198.999525] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2199.043894] rw_seq_cst_vs_d (32906): drop_caches: 3 [ 2205.908399] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 08:16:25 (1775218585) [ 2212.773042] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 08:16:32 (1775218592) [ 2219.361678] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 08:16:39 (1775218599) [ 2220.511980] Lustre: lustre-OST0000-osc-ffff91a1031fe800: disconnect after 21s idle [ 2220.519089] Lustre: Skipped 1 previous similar message [ 2251.030630] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 08:17:10 (1775218630) [ 2251.594812] bash (35407): drop_caches: 3 [ 2254.846472] bash (35407): drop_caches: 3 [ 2257.990471] bash (35407): drop_caches: 3 [ 2261.184717] bash (35407): drop_caches: 3 [ 2264.303371] bash (35407): drop_caches: 3 [ 2267.510933] bash (35407): drop_caches: 3 [ 2270.688830] bash (35407): drop_caches: 3 [ 2273.845898] bash (35407): drop_caches: 3 [ 2276.831143] Lustre: lustre-OST0000-osc-ffff91a1031fe800: disconnect after 22s idle [ 2277.012393] bash (35407): drop_caches: 3 [ 2280.172648] bash (35407): drop_caches: 3 [ 2283.709408] bash (35407): drop_caches: 3 [ 2286.874992] bash (35407): drop_caches: 3 [ 2290.013833] bash (35407): drop_caches: 3 [ 2293.121424] bash (35407): drop_caches: 3 [ 2296.332147] bash (35407): drop_caches: 3 [ 2301.688367] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 08:18:00 (1775218680) [ 2312.734098] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 08:18:11 (1775218691) [ 2336.031319] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 08:18:36 (1775218716) [ 2342.678302] Lustre: DEBUG MARKER: loop 5 [ 2347.093506] Lustre: DEBUG MARKER: loop 10 [ 2348.512049] Lustre: lustre-OST0001-osc-ffff91a118b53000: disconnect after 20s idle [ 2351.508710] Lustre: DEBUG MARKER: loop 15 [ 2355.838824] Lustre: DEBUG MARKER: loop 20 [ 2363.460548] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 08:19:03 (1775218743) [ 2369.818847] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 08:19:09 (1775218749) [ 2377.809190] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 08:19:17 (1775218757) [ 2447.790753] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 08:20:27 (1775218827) [ 2455.047462] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 08:20:34 (1775218834) [ 2461.864432] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 08:20:41 (1775218841) [ 2469.203048] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 08:20:48 (1775218848) [ 2476.110087] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 08:20:55 (1775218855) [ 2483.469130] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 08:21:03 (1775218863) [ 2491.470434] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 08:21:11 (1775218871) [ 2491.881147] Lustre: lustre-OST0000-osc-ffff91a118b53000: disconnect after 23s idle [ 2491.896686] Lustre: Skipped 5 previous similar messages [ 2500.292985] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 2502.381639] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 08:21:21 (1775218881) [ 2511.612785] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 08:21:31 (1775218891) [ 2512.213599] Lustre: *** cfs_fail_loc=314, val=0*** [ 2513.247236] Lustre: *** cfs_fail_loc=314, val=0*** [ 2513.252401] Lustre: Skipped 2 previous similar messages [ 2519.890136] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 08:21:39 (1775218899) [ 2529.959665] Lustre: *** cfs_fail_loc=314, val=0*** [ 2532.838870] Lustre: lustre-OST0000-osc-ffff91a1031fe800: Connection to lustre-OST0000 (at 192.168.204.153@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2532.876377] LustreError: lustre-OST0000-osc-ffff91a1031fe800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2532.902126] Lustre: lustre-OST0000-osc-ffff91a1031fe800: Connection restored to 192.168.204.153@tcp (at 192.168.204.153@tcp) [ 2537.976772] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 08:21:57 (1775218917) [ 2538.266845] LustreError: 46908:0:(file.c:817:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 2541.297387] LustreError: 46908:0:(file.c:817:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 2547.551627] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 08:22:07 (1775218927) [ 2555.373950] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 08:22:15 (1775218935) [ 2562.346706] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 2563.861893] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 08:22:23 (1775218943) [ 2565.435447] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 2566.988378] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 08:22:27 (1775218947) [ 2573.812488] Lustre: lustre-MDT0000-mdc-ffff91a118b53000: Connection to lustre-MDT0000 (at 192.168.204.153@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2584.047371] LustreError: MGC192.168.204.153@tcp: Connection to MGS (at 192.168.204.153@tcp) was lost; in progress operations using this service will fail [ 2584.121591] Lustre: Evicted from MGS (at 192.168.204.153@tcp) after server handle changed from 0xacae83d289cf8453 to 0xacae83d289da257e [ 2584.142580] Lustre: MGC192.168.204.153@tcp: Connection restored to 192.168.204.153@tcp (at 192.168.204.153@tcp) [ 2621.191384] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 08:23:20 (1775219000) [ 2635.238251] Lustre: lustre-OST0000-osc-ffff91a1031fe800: disconnect after 22s idle [ 2635.247021] Lustre: Skipped 2 previous similar messages [ 2684.653996] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 08:24:24 (1775219064) [ 2704.838165] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 08:24:44 (1775219084) [ 2762.114154] Lustre: lustre-OST0001-osc-ffff91a118b53000: Connection to lustre-OST0001 (at 192.168.204.153@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2762.143991] Lustre: Skipped 1 previous similar message [ 2762.157304] LustreError: lustre-OST0001-osc-ffff91a118b53000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 2762.167556] Lustre: lustre-OST0001-osc-ffff91a118b53000: Connection restored to 192.168.204.153@tcp (at 192.168.204.153@tcp) [ 2762.182417] Lustre: Skipped 2 previous similar messages [ 2762.214376] LustreError: lustre-OST0001-osc-ffff91a1031fe800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 2773.482131] Lustre: lustre-OST0000-osc-ffff91a118b53000: Connection to lustre-OST0000 (at 192.168.204.153@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2773.499719] Lustre: Skipped 1 previous similar message [ 2773.508168] LustreError: lustre-OST0000-osc-ffff91a118b53000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2773.522737] Lustre: lustre-OST0000-osc-ffff91a118b53000: Connection restored to 192.168.204.153@tcp (at 192.168.204.153@tcp) [ 2773.527205] Lustre: Skipped 1 previous similar message [ 2792.103259] Lustre: DEBUG MARKER: oleg453-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff91a1031fe800.ost_server_uuid 50 [ 2793.650508] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff91a1031fe800.ost_server_uuid in FULL state after 0 sec [ 2797.258737] Lustre: DEBUG MARKER: oleg453-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff91a1031fe800.ost_server_uuid 50 [ 2799.274782] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff91a1031fe800.ost_server_uuid in IDLE state after 0 sec [ 2805.004297] Lustre: DEBUG MARKER: oleg453-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff91a1031fe800.ost_server_uuid 50 [ 2806.624890] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff91a1031fe800.ost_server_uuid in FULL state after 0 sec [ 2811.464962] Lustre: DEBUG MARKER: oleg453-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff91a1031fe800.ost_server_uuid 50 [ 2813.337312] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff91a1031fe800.ost_server_uuid in IDLE state after 0 sec [ 2823.186381] Lustre: DEBUG MARKER: oleg453-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff91a1031fe800.ost_server_uuid 50 [ 2824.894565] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff91a1031fe800.ost_server_uuid in FULL state after 0 sec [ 2828.628075] Lustre: DEBUG MARKER: oleg453-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff91a1031fe800.ost_server_uuid 50 [ 2830.299113] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff91a1031fe800.ost_server_uuid in IDLE state after 0 sec [ 2832.252505] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 08:26:51 (1775219211) [ 2834.872289] Lustre: DEBUG MARKER: Race attempt 0 [ 2837.708268] Lustre: DEBUG MARKER: Wait for 58271 58293 for 60 sec... [ 2905.743328] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 08:28:05 (1775219285) [ 2914.667579] Lustre: DEBUG MARKER: start test - cycle (0) [ 2941.529417] Lustre: DEBUG MARKER: start test - cycle (1) [ 2947.551323] Lustre: lustre-OST0001-osc-ffff91a1031fe800: disconnect after 24s idle [ 2947.570182] Lustre: Skipped 4 previous similar messages [ 2966.824995] Lustre: DEBUG MARKER: start test - cycle (2) [ 2992.417450] Lustre: DEBUG MARKER: start test - cycle (3) [ 3018.246241] Lustre: DEBUG MARKER: start test - cycle (4) [ 3043.617064] Lustre: DEBUG MARKER: start test - cycle (5) [ 3069.113762] Lustre: DEBUG MARKER: start test - cycle (6) [ 3094.118662] Lustre: DEBUG MARKER: start test - cycle (7) [ 3115.612084] Lustre: DEBUG MARKER: start test - cycle (8) [ 3141.567668] Lustre: DEBUG MARKER: start test - cycle (9) [ 3167.027063] Lustre: DEBUG MARKER: start test - cycle (10) [ 3196.374582] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 08:32:56 (1775219576) [ 3272.004470] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 08:34:11 (1775219651) [ 3278.547541] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 08:34:18 (1775219658) [ 3285.986970] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 08:34:25 (1775219665) [ 3292.673967] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 08:34:32 (1775219672) [ 3293.125596] Lustre: *** cfs_fail_loc=411, val=0*** [ 3298.099742] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 3299.393122] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 08:34:39 (1775219679) [ 3315.578923] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 08:34:55 (1775219695) [ 3331.543138] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 08:35:11 (1775219711) [ 3347.837600] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 08:35:27 (1775219727) [ 3363.756970] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 08:35:43 (1775219743) [ 3376.097445] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 08:35:56 (1775219756) [ 3387.038099] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 08:36:07 (1775219767) [ 3398.925994] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 08:36:18 (1775219778) [ 3410.857954] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 08:36:30 (1775219790) [ 3423.936609] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 08:36:43 (1775219803) [ 3437.896398] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 08:36:57 (1775219817) [ 3450.524588] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 08:37:10 (1775219830) [ 3463.532798] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 08:37:23 (1775219843) [ 3472.863408] Lustre: lustre-OST0000-osc-ffff91a118b53000: disconnect after 24s idle [ 3472.866226] Lustre: Skipped 13 previous similar messages [ 4082.143637] Lustre: lustre-OST0000-osc-ffff91a1031fe800: disconnect after 24s idle [ 4082.147090] Lustre: Skipped 1 previous similar message [ 4461.771371] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 08:54:02 (1775220842) [ 4470.554945] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 08:54:10 (1775220850) [ 4479.525153] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 08:54:19 (1775220859) [ 4487.797174] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 08:54:28 (1775220868) [ 4495.850264] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 08:54:36 (1775220876) [ 4503.712729] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 08:54:44 (1775220884) [ 4511.346585] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 08:54:51 (1775220891) [ 4519.247495] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 08:54:59 (1775220899) [ 4527.413281] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 08:55:07 (1775220907) [ 4590.685435] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 08:56:11 (1775220971) [ 4597.873285] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 08:56:18 (1775220978) [ 4605.283269] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 08:56:25 (1775220985) [ 4612.806547] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 08:56:33 (1775220993) [ 4620.555253] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 08:56:41 (1775221001) [ 4628.096729] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 08:56:48 (1775221008) [ 4636.121249] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 08:56:56 (1775221016) [ 4643.531335] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 08:57:03 (1775221023) [ 4651.336452] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 08:57:11 (1775221031) [ 4710.971893] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 08:58:11 (1775221091) [ 4742.623125] Lustre: lustre-OST0001-osc-ffff91a118b53000: disconnect after 20s idle [ 4742.628536] Lustre: Skipped 5 previous similar messages [ 5312.342994] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 09:08:12 (1775221692) [ 5317.788935] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 09:08:18 (1775221698) [ 5323.201424] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 09:08:23 (1775221703) [ 5328.668931] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 09:08:29 (1775221709) [ 5334.234745] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 09:08:34 (1775221714) [ 5340.034483] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 09:08:40 (1775221720) [ 5346.031234] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 09:08:46 (1775221726) [ 5351.720191] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 09:08:52 (1775221732) [ 5357.133125] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 09:08:57 (1775221737) [ 5362.470081] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 09:09:03 (1775221743) [ 5382.624149] Lustre: lustre-OST0000-osc-ffff91a118b53000: disconnect after 20s idle [ 5382.627433] Lustre: Skipped 6 previous similar messages [ 5403.649226] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 09:09:44 (1775221784) [ 5408.796412] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 09:09:49 (1775221789) [ 5414.196426] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 09:09:54 (1775221794) [ 5419.540941] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 09:10:00 (1775221800) [ 5424.753234] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 09:10:05 (1775221805) [ 5430.127536] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 09:10:10 (1775221810) [ 5435.664257] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 09:10:16 (1775221816) [ 5440.767634] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 09:10:21 (1775221821) [ 5446.373364] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 09:10:27 (1775221827) [ 6074.453653] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 09:20:54 (1775222454) [ 6082.113112] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 09:21:02 (1775222462) [ 6088.959423] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 09:21:09 (1775222469) [ 6094.304278] Lustre: lustre-OST0000-osc-ffff91a1031fe800: disconnect after 24s idle [ 6094.309801] Lustre: Skipped 2 previous similar messages [ 6096.993366] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 09:21:17 (1775222477) [ 6104.794967] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 09:21:25 (1775222485) [ 6112.859667] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 09:21:33 (1775222493) [ 6121.095618] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 09:21:41 (1775222501) [ 6129.392471] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 09:21:49 (1775222509) [ 6137.820902] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 09:21:58 (1775222518) [ 6146.011708] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 09:22:06 (1775222526) [ 6154.941516] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 09:22:15 (1775222535) [ 6164.792533] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 09:22:25 (1775222545) [ 6173.022769] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 09:22:33 (1775222553) [ 6181.653632] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 09:22:41 (1775222561) [ 6190.718358] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 09:22:51 (1775222571) [ 6199.423822] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 09:22:59 (1775222579) [ 6211.685755] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 09:23:11 (1775222591) [ 6211.950592] LustreError: 19716:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 6214.039749] LustreError: 19716:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 6221.155880] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 09:23:21 (1775222601) [ 6227.539569] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 09:23:27 (1775222607) [ 6227.712425] LustreError: 239603:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 6231.775187] LustreError: 239603:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 6231.801435] LustreError: 239603:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 6235.863142] LustreError: 239603:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 6235.891486] LustreError: 239609:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 6239.951175] LustreError: 239609:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 6244.266500] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 09:23:44 (1775222624) [ 6253.517197] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 09:23:53 (1775222633) [ 6258.719730] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 09:23:58 (1775222638) [ 6265.135460] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 09:24:05 (1775222645) [ 6293.260470] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 09:24:33 (1775222673) [ 6302.970787] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 09:24:43 (1775222683) [ 6312.390189] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 09:24:52 (1775222692) [ 6327.370501] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 09:25:07 (1775222707) [ 6338.834626] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 09:25:19 (1775222719) [ 6354.286640] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 09:25:34 (1775222734) [ 6370.188567] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 09:25:50 (1775222750) [ 6388.195306] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 09:26:08 (1775222768) [ 6401.700518] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 09:26:22 (1775222782) [ 6450.331940] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 09:27:10 (1775222830) [ 6454.390772] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 6459.060101] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 09:27:19 (1775222839) [ 6463.668694] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 09:27:23 (1775222843) [ 6467.975386] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 09:27:28 (1775222848) [ 6471.531521] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 09:27:31 (1775222851) [ 6498.046627] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 09:27:58 (1775222878) [ 6526.778440] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 09:28:27 (1775222907) [ 6530.616164] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 09:28:31 (1775222911) [ 6534.473724] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 09:28:34 (1775222914) [ 6537.679521] LustreError: lustre-MDT0000-mdc-ffff91a1031fe800: operation ldlm_enqueue to node 192.168.204.153@tcp failed: rc = -35 [ 6542.018727] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 09:28:42 (1775222922) [ 6542.345652] LustreError: 2417:0:(osc_request.c:3138:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 6544.431199] LustreError: 2417:0:(osc_request.c:3138:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 6550.659489] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 09:28:51 (1775222931) [ 6642.616751] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 09:30:22 (1775223022) [ 6647.964748] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 09:30:28 (1775223028) [ 6655.592199] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 09:30:36 (1775223036) [ 6663.908868] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 09:30:44 (1775223044) [ 6671.643253] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 09:30:52 (1775223052) [ 6683.602063] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 09:31:03 (1775223063) [ 6695.842053] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 09:31:16 (1775223076) [ 6701.601821] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 09:31:21 (1775223081) [ 6707.998458] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 09:31:28 (1775223088) [ 6713.823399] Lustre: lustre-OST0001-osc-ffff91a118b53000: disconnect after 21s idle [ 6713.828204] Lustre: Skipped 9 previous similar messages [ 6720.035490] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 09:31:40 (1775223100) [ 6780.181975] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 09:32:39 (1775223159) [ 6913.871758] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 09:34:53 (1775223293) [ 7044.196087] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 09:37:03 (1775223423) [ 7323.103303] Lustre: lustre-OST0001-osc-ffff91a118b53000: disconnect after 21s idle [ 7323.123623] Lustre: Skipped 16 previous similar messages [ 7390.873734] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 09:42:50 (1775223770) [ 7432.206964] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 09:43:32 (1775223812) [ 7472.748499] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 09:44:12 (1775223852) [ 7504.123430] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 09:44:43 (1775223883) [ 7580.909284] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 09:46:00 (1775223960) [ 7644.717741] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 09:47:04 (1775224024) [ 7748.216451] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 09:48:48 (1775224128) [ 7752.359682] LustreError: 284690:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a118b53000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7752.428447] Lustre: Unmounted lustre-client [ 7756.439870] LustreError: 284703:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a1031fe800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7756.470327] LustreError: 284703:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 7756.539198] Lustre: Unmounted lustre-client [ 7861.345991] Lustre: Mounted lustre-client [ 7863.755533] Lustre: Mounted lustre-client [ 7866.177593] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7950.815234] Lustre: lustre-OST0000-osc-ffff91a1063ff000: disconnect after 22s idle [ 7950.828964] Lustre: Skipped 12 previous similar messages [ 7955.669242] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 09:52:15 (1775224335) [ 7971.323553] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 09:52:30 (1775224350) [ 7980.831959] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 09:52:40 (1775224360) [ 8036.685703] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 09:53:36 (1775224416) [ 8096.599393] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 09:54:36 (1775224476) [ 8106.390173] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 09:54:46 (1775224486) [ 8198.564476] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 09:56:18 (1775224578) [ 8227.231476] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 09:56:47 (1775224607) [ 8273.482893] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 09:57:33 (1775224653) [ 8280.394477] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 09:57:40 (1775224660) [ 8296.477627] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 09:57:56 (1775224676) [ 8304.724493] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 09:58:04 (1775224684) [ 8306.192646] LustreError: 302160:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x1af:0x0]: rc = -5 [ 8306.203477] LustreError: 302160:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 8307.248146] LustreError: 302181:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x23:0x0]: rc = -5 [ 8307.263448] LustreError: 302181:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 1 previous similar message [ 8307.272101] LustreError: 302181:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 8307.279208] LustreError: 302181:0:(llite_lib.c:3704:ll_prep_inode()) Skipped 1 previous similar message [ 8308.248525] LustreError: 302201:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x1bb:0x0]: rc = -5 [ 8308.263133] LustreError: 302201:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 4 previous similar messages [ 8308.302821] LustreError: 301998:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 8308.314990] LustreError: 301998:0:(llite_lib.c:3704:ll_prep_inode()) Skipped 5 previous similar messages [ 8310.597053] LustreError: 302260:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x1d2:0x0]: rc = -5 [ 8310.610520] LustreError: 302260:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 16 previous similar messages [ 8310.621274] LustreError: 302260:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 8310.626814] LustreError: 302260:0:(llite_lib.c:3704:ll_prep_inode()) Skipped 15 previous similar messages [ 8314.732588] LustreError: 302352:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x46:0x0]: rc = -5 [ 8314.744281] LustreError: 302352:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 26 previous similar messages [ 8314.753276] LustreError: 302352:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 8314.761094] LustreError: 302352:0:(llite_lib.c:3704:ll_prep_inode()) Skipped 26 previous similar messages [ 8322.788677] LustreError: 302573:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x249:0x0]: rc = -5 [ 8322.801715] LustreError: 302573:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 65 previous similar messages [ 8322.810153] LustreError: 302573:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 8322.816146] LustreError: 302573:0:(llite_lib.c:3704:ll_prep_inode()) Skipped 65 previous similar messages [ 8339.249157] LustreError: 302945:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x2ab:0x0]: rc = -5 [ 8339.260098] LustreError: 302945:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 110 previous similar messages [ 8339.266253] LustreError: 302945:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 8339.271297] LustreError: 302945:0:(llite_lib.c:3704:ll_prep_inode()) Skipped 110 previous similar messages [ 8448.091723] LustreError: 301998:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x2b6:0x0]: rc = -5 [ 8448.100760] LustreError: 301998:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 40 previous similar messages [ 8448.114312] LustreError: 301998:0:(llite_lib.c:3704:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 8448.119579] LustreError: lustre-MDT0000-mdc-ffff91a1063ff000: operation ldlm_enqueue to node 192.168.204.153@tcp failed: rc = -107 [ 8448.120592] LustreError: 301998:0:(llite_lib.c:3704:ll_prep_inode()) Skipped 40 previous similar messages [ 8448.125891] Lustre: lustre-MDT0000-mdc-ffff91a1063ff000: Connection to lustre-MDT0000 (at 192.168.204.153@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8448.149315] LustreError: lustre-MDT0000-mdc-ffff91a1063ff000: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 8448.166609] LustreError: 303098:0:(file.c:251:ll_close_inode_openhandle()) lustre-clilmv-ffff91a1063ff000: inode [0x2000013a1:0x1a0:0x0] mdc close failed: rc = -108 [ 8448.184673] LustreError: 303099:0:(file.c:6097:ll_inode_revalidate_fini()) lustre: revalidate FID [0x200000007:0x1:0x0] error: rc = -108 [ 8448.193726] Lustre: lustre-MDT0000-mdc-ffff91a1063ff000: Connection restored to 192.168.204.153@tcp (at 192.168.204.153@tcp) [ 8452.629318] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 10:00:32 (1775224832) [ 8457.793760] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 10:00:37 (1775224837) [ 8644.206934] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 10:03:43 (1775225023) [ 8645.396464] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 8646.814373] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 10:03:46 (1775225026) [ 8818.257406] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 10:06:38 (1775225198) [ 8823.746936] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 10:06:43 (1775225203) [ 8836.575355] Lustre: lustre-OST0001-osc-ffff91a1037bb000: disconnect after 23s idle [ 8836.584600] Lustre: Skipped 6 previous similar messages [ 8947.905648] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 10:08:48 (1775225328) [ 8957.147255] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 10:08:57 (1775225337) [ 8962.906946] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 10:09:03 (1775225343) [ 9146.296673] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 10:12:06 (1775225526) [ 9329.408446] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 10:15:09 (1775225709) [ 9332.802918] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 10:15:13 (1775225713) [ 9343.662419] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 10:15:24 (1775225724) [ 9343.793745] Lustre: DEBUG MARKER: write [ 9343.827814] LustreError: 286416:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 9345.825301] Lustre: DEBUG MARKER: kill 341734 [ 9345.828544] LustreError: 341734:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 9348.927144] LustreError: 286416:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 9351.871521] LustreError: 341734:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 9354.609528] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 10:15:35 (1775225735) [ 9356.855124] LustreError: 342347:0:(rw.c:1868:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 9366.951295] LustreError: 342347:0:(rw.c:1868:ll_readpage()) cfs_fail_timeout id 1422 awake [ 9370.000576] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 10:15:50 (1775225750) [ 9370.213856] LustreError: 342934:0:(rw.c:2116:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 9372.303587] LustreError: 342934:0:(rw.c:2116:ll_readpage()) cfs_fail_timeout interrupted [ 9379.038474] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 10:15:59 (1775225759) [ 9379.755572] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 9380.504669] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 10:16:01 (1775225761) [ 9383.628927] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 10:16:04 (1775225764) [ 9386.993909] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 10:16:07 (1775225767) [ 9390.084422] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 10:16:10 (1775225770) [ 9393.429103] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 10:16:13 (1775225773) [ 9396.574657] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 10:16:16 (1775225776) [ 9399.834630] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 10:16:20 (1775225780) [ 9404.072196] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 10:16:24 (1775225784) [ 9409.209645] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 10:16:29 (1775225789) [ 9410.257818] Lustre: *** cfs_fail_loc=415, val=0*** [ 9418.135953] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 10:16:38 (1775225798) [ 9438.804660] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 10:16:59 (1775225819) [ 9438.923774] LustreError: 343820:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 sleeping for 5000ms [ 9438.927501] LustreError: 343820:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 3 previous similar messages [ 9443.927128] LustreError: 343820:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [ 9454.127142] LustreError: 343820:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [ 9454.131351] LustreError: 343820:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 5 previous similar messages [ 9462.181262] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 10:17:22 (1775225842) [ 9465.477667] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 10:17:25 (1775225845) [ 9468.900601] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 10:17:29 (1775225849) [ 9471.784274] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 10:17:32 (1775225852) [ 9476.590478] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 10:17:37 (1775225857) [ 9485.752713] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 10:17:46 (1775225866) [ 9486.041496] LustreError: 353668:0:(osc_request.c:2989:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 9486.045239] LustreError: 353668:0:(osc_request.c:2989:osc_build_rpc()) Skipped 6 previous similar messages [ 9490.103136] LustreError: 353668:0:(osc_request.c:2989:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 9490.107148] LustreError: 353668:0:(osc_request.c:2989:osc_build_rpc()) Skipped 2 previous similar messages [ 9492.953409] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 10:17:53 (1775225873) [ 9494.687466] LustreError: 354377:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a1063ff000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9494.691939] LustreError: 354377:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 9494.726440] Lustre: Unmounted lustre-client [ 9495.333068] LustreError: 354397:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a1037bb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9495.338286] LustreError: 354397:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 9495.380505] Lustre: Unmounted lustre-client [ 9495.978932] Lustre: DEBUG MARKER: Iteration 1 [ 9496.110893] LustreError: 354560:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9496.110922] LustreError: 354561:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9496.120386] LustreError: 354560:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 9496.186932] Lustre: Mounted lustre-client [ 9496.679778] LustreError: 354673:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a106a5d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9496.685469] LustreError: 354673:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 9496.758586] Lustre: Unmounted lustre-client [ 9497.850901] Key type lgssc unregistered [ 9497.978976] LNet: 354917:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9497.983615] LNetError: 354917:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9497.992721] LNet: Removed LNI 192.168.204.53@tcp [ 9498.338199] Key type .llcrypt unregistered [ 9498.340238] Key type ._llcrypt unregistered [ 9498.694099] Key type ._llcrypt registered [ 9498.697829] Key type .llcrypt registered [ 9499.037973] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9499.046067] alg: No test for adler32 (adler32-zlib) [ 9500.072205] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9500.401076] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9502.031243] Key type lgssc registered [ 9502.710710] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9508.055797] Lustre: DEBUG MARKER: Iteration 2 [ 9508.183862] LustreError: 355750:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9508.185208] LustreError: 355751:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9508.190758] LustreError: 355750:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9509.270108] Lustre: Mounted lustre-client [ 9509.271560] Lustre: Skipped 1 previous similar message [ 9509.777558] LustreError: 355862:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a118b51000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9509.783465] LustreError: 355862:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9509.834503] Lustre: Unmounted lustre-client [ 9511.050609] Key type lgssc unregistered [ 9511.186862] LNet: 356107:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9511.191704] LNetError: 356107:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9511.204985] LNet: Removed LNI 192.168.204.53@tcp [ 9511.499150] Key type .llcrypt unregistered [ 9511.501122] Key type ._llcrypt unregistered [ 9511.885767] Key type ._llcrypt registered [ 9511.887286] Key type .llcrypt registered [ 9512.114978] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9512.121613] alg: No test for adler32 (adler32-zlib) [ 9513.070690] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9513.185037] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9514.791205] Key type lgssc registered [ 9515.313553] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9520.042570] Lustre: DEBUG MARKER: Iteration 3 [ 9520.160760] LustreError: 356938:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9520.162076] LustreError: 356939:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9520.172072] LustreError: 356938:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 9521.287681] Lustre: Mounted lustre-client [ 9521.289950] Lustre: Skipped 1 previous similar message [ 9521.771676] LustreError: 357054:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a102e8d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9521.838773] Lustre: Unmounted lustre-client [ 9523.024782] Key type lgssc unregistered [ 9523.156507] LNet: 357298:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9523.160654] LNetError: 357298:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9523.169958] LNet: Removed LNI 192.168.204.53@tcp [ 9523.449237] Key type .llcrypt unregistered [ 9523.450899] Key type ._llcrypt unregistered [ 9523.783816] Key type ._llcrypt registered [ 9523.785510] Key type .llcrypt registered [ 9523.988211] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9523.996318] alg: No test for adler32 (adler32-zlib) [ 9524.888398] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9524.987549] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9526.583193] Key type lgssc registered [ 9527.076183] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9531.454764] Lustre: DEBUG MARKER: Iteration 4 [ 9531.566957] LustreError: 358130:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9531.567020] LustreError: 358131:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9531.573974] LustreError: 358130:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9532.648551] Lustre: Mounted lustre-client [ 9532.650221] Lustre: Skipped 1 previous similar message [ 9533.124315] LustreError: 358241:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a107c91800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9533.193946] Lustre: Unmounted lustre-client [ 9534.327472] Key type lgssc unregistered [ 9534.467545] LNet: 358485:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9534.472228] LNetError: 358485:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9534.481598] LNet: Removed LNI 192.168.204.53@tcp [ 9534.780217] Key type .llcrypt unregistered [ 9534.782317] Key type ._llcrypt unregistered [ 9535.110573] Key type ._llcrypt registered [ 9535.118992] Key type .llcrypt registered [ 9535.340864] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9535.348356] alg: No test for adler32 (adler32-zlib) [ 9536.256641] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9536.362779] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9537.967278] Key type lgssc registered [ 9538.516843] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9543.062154] Lustre: DEBUG MARKER: Iteration 5 [ 9543.219913] LustreError: 359317:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9543.220074] LustreError: 359318:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9543.229661] LustreError: 359317:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 9544.320564] Lustre: Mounted lustre-client [ 9544.323438] Lustre: Skipped 1 previous similar message [ 9544.845552] LustreError: 359433:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a106333000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9544.909134] Lustre: Unmounted lustre-client [ 9546.059896] Key type lgssc unregistered [ 9546.185144] LNet: 359679:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9546.188904] LNetError: 359679:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9546.198833] LNet: Removed LNI 192.168.204.53@tcp [ 9546.476182] Key type .llcrypt unregistered [ 9546.478181] Key type ._llcrypt unregistered [ 9546.771808] Key type ._llcrypt registered [ 9546.776855] Key type .llcrypt registered [ 9546.985718] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9546.993095] alg: No test for adler32 (adler32-zlib) [ 9547.889059] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9547.994732] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9549.599201] Key type lgssc registered [ 9550.122960] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9554.771561] Lustre: DEBUG MARKER: Iteration 6 [ 9554.901758] LustreError: 360511:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9554.901922] LustreError: 360512:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9554.913589] LustreError: 360511:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [ 9555.999203] Lustre: Mounted lustre-client [ 9556.632919] LustreError: 360627:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a1418aa000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9556.686110] Lustre: Unmounted lustre-client [ 9557.900387] Key type lgssc unregistered [ 9558.035461] LNet: 360871:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9558.040182] LNetError: 360871:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9558.052178] LNet: Removed LNI 192.168.204.53@tcp [ 9558.378158] Key type .llcrypt unregistered [ 9558.380070] Key type ._llcrypt unregistered [ 9558.654586] Key type ._llcrypt registered [ 9558.656584] Key type .llcrypt registered [ 9558.909235] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9558.925501] alg: No test for adler32 (adler32-zlib) [ 9559.816733] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9559.933476] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9561.543168] Key type lgssc registered [ 9562.084521] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9566.563251] Lustre: DEBUG MARKER: Iteration 7 [ 9566.690444] LustreError: 361702:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9566.691349] LustreError: 361703:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9566.697739] LustreError: 361702:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9567.787760] Lustre: Mounted lustre-client [ 9568.333407] LustreError: 361817:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a106953000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9568.340111] LustreError: 361817:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9568.389068] Lustre: Unmounted lustre-client [ 9569.652468] Key type lgssc unregistered [ 9569.804161] LNet: 362061:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9569.809921] LNetError: 362061:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9569.822806] LNet: Removed LNI 192.168.204.53@tcp [ 9570.109554] Key type .llcrypt unregistered [ 9570.111586] Key type ._llcrypt unregistered [ 9570.442833] Key type ._llcrypt registered [ 9570.444591] Key type .llcrypt registered [ 9570.692933] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9570.703447] alg: No test for adler32 (adler32-zlib) [ 9571.605690] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9571.726527] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9573.327205] Key type lgssc registered [ 9573.863974] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9578.170814] Lustre: DEBUG MARKER: Iteration 8 [ 9578.285492] LustreError: 362892:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9578.286753] LustreError: 362893:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9578.293103] LustreError: 362892:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9579.372601] Lustre: Mounted lustre-client [ 9579.376583] Lustre: Skipped 1 previous similar message [ 9579.865238] LustreError: 363006:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a106a58800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9579.872690] LustreError: 363006:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9579.934456] Lustre: Unmounted lustre-client [ 9581.048986] Key type lgssc unregistered [ 9581.180241] LNet: 363247:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9581.184252] LNetError: 363247:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9581.195493] LNet: Removed LNI 192.168.204.53@tcp [ 9581.483162] Key type .llcrypt unregistered [ 9581.484917] Key type ._llcrypt unregistered [ 9581.889437] Key type ._llcrypt registered [ 9581.892739] Key type .llcrypt registered [ 9582.007550] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9582.013837] alg: No test for adler32 (adler32-zlib) [ 9582.923793] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9583.024497] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9584.623215] Key type lgssc registered [ 9585.189453] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9589.865707] Lustre: DEBUG MARKER: Iteration 9 [ 9590.009053] LustreError: 364078:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9590.009084] LustreError: 364079:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9590.018202] LustreError: 364078:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 9591.114410] Lustre: Mounted lustre-client [ 9591.623644] LustreError: 364194:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a12213f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9591.629851] LustreError: 364194:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9591.691888] Lustre: Unmounted lustre-client [ 9592.955546] Key type lgssc unregistered [ 9593.087304] LNet: 364437:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9593.090924] LNetError: 364437:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9593.103913] LNet: Removed LNI 192.168.204.53@tcp [ 9593.386137] Key type .llcrypt unregistered [ 9593.387670] Key type ._llcrypt unregistered [ 9593.738923] Key type ._llcrypt registered [ 9593.742137] Key type .llcrypt registered [ 9593.949703] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9593.957386] alg: No test for adler32 (adler32-zlib) [ 9594.849567] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9594.948571] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9596.543150] Key type lgssc registered [ 9596.985560] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9601.498866] Lustre: DEBUG MARKER: Iteration 10 [ 9601.626875] LustreError: 365270:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9601.626925] LustreError: 365271:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9601.633519] LustreError: 365270:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9602.726276] Lustre: Mounted lustre-client [ 9602.729269] Lustre: Skipped 1 previous similar message [ 9603.234040] LustreError: 365384:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a104c76000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9603.241151] LustreError: 365384:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9603.299415] Lustre: Unmounted lustre-client [ 9604.510605] Key type lgssc unregistered [ 9604.663509] LNet: 365627:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9604.667184] LNetError: 365627:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9604.678808] LNet: Removed LNI 192.168.204.53@tcp [ 9604.976171] Key type .llcrypt unregistered [ 9604.978607] Key type ._llcrypt unregistered [ 9605.270585] Key type ._llcrypt registered [ 9605.272349] Key type .llcrypt registered [ 9605.490213] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9605.497840] alg: No test for adler32 (adler32-zlib) [ 9606.378180] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9606.492482] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9608.087166] Key type lgssc registered [ 9608.614583] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9613.334150] Lustre: DEBUG MARKER: Iteration 11 [ 9613.472960] LustreError: 366459:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9613.473030] LustreError: 366460:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9613.480154] LustreError: 366459:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9614.563961] Lustre: Mounted lustre-client [ 9615.149292] LustreError: 366569:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a12213d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9615.187337] Lustre: Unmounted lustre-client [ 9616.382730] Key type lgssc unregistered [ 9616.516118] LNet: 366820:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9616.523891] LNetError: 366820:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9616.536735] LNet: Removed LNI 192.168.204.53@tcp [ 9616.839172] Key type .llcrypt unregistered [ 9616.841715] Key type ._llcrypt unregistered [ 9617.158706] Key type ._llcrypt registered [ 9617.170797] Key type .llcrypt registered [ 9617.358702] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9617.366232] alg: No test for adler32 (adler32-zlib) [ 9618.258958] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9618.389124] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9619.999270] Key type lgssc registered [ 9620.602701] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9625.386568] Lustre: DEBUG MARKER: Iteration 12 [ 9625.500546] LustreError: 367652:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9625.502204] LustreError: 367653:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9625.507418] LustreError: 367652:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9626.590378] Lustre: Mounted lustre-client [ 9626.592715] Lustre: Skipped 1 previous similar message [ 9627.112718] LustreError: 367761:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a1041d1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9627.118788] LustreError: 367761:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9627.158683] Lustre: Unmounted lustre-client [ 9628.358833] Key type lgssc unregistered [ 9628.493834] LNet: 368004:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9628.502054] LNetError: 368004:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9628.512856] LNet: Removed LNI 192.168.204.53@tcp [ 9628.833146] Key type .llcrypt unregistered [ 9628.834625] Key type ._llcrypt unregistered [ 9629.110226] Key type ._llcrypt registered [ 9629.111885] Key type .llcrypt registered [ 9629.358349] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9629.367355] alg: No test for adler32 (adler32-zlib) [ 9630.303810] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9630.422835] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9632.039741] Key type lgssc registered [ 9632.597701] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9637.299971] Lustre: DEBUG MARKER: Iteration 13 [ 9637.430265] LustreError: 368836:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9637.430615] LustreError: 368837:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9637.438150] LustreError: 368836:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9638.523324] Lustre: Mounted lustre-client [ 9639.015703] LustreError: 368952:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a107fb3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9639.064466] Lustre: Unmounted lustre-client [ 9640.300690] Key type lgssc unregistered [ 9640.427669] LNet: 369195:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9640.431035] LNetError: 369195:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9640.444209] LNet: Removed LNI 192.168.204.53@tcp [ 9640.747290] Key type .llcrypt unregistered [ 9640.748824] Key type ._llcrypt unregistered [ 9641.068334] Key type ._llcrypt registered [ 9641.070871] Key type .llcrypt registered [ 9641.268669] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9641.277295] alg: No test for adler32 (adler32-zlib) [ 9642.186894] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9642.311381] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9643.927192] Key type lgssc registered [ 9644.459029] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9649.424437] Lustre: DEBUG MARKER: Iteration 14 [ 9649.581342] LustreError: 370027:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9649.583063] LustreError: 370028:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9649.590826] LustreError: 370027:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 9650.713934] Lustre: Mounted lustre-client [ 9651.500297] LustreError: 370136:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a129826800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9651.556964] Lustre: Unmounted lustre-client [ 9652.892343] Key type lgssc unregistered [ 9653.040581] LNet: 370387:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9653.046452] LNetError: 370387:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9653.059176] LNet: Removed LNI 192.168.204.53@tcp [ 9653.379177] Key type .llcrypt unregistered [ 9653.380824] Key type ._llcrypt unregistered [ 9653.808689] Key type ._llcrypt registered [ 9653.810187] Key type .llcrypt registered [ 9653.957853] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9653.964309] alg: No test for adler32 (adler32-zlib) [ 9654.857656] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9654.976112] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9656.591296] Key type lgssc registered [ 9657.112343] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9662.107267] Lustre: DEBUG MARKER: Iteration 15 [ 9662.261689] LustreError: 371219:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9662.262191] LustreError: 371220:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9662.271045] LustreError: 371219:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 9663.366484] Lustre: Mounted lustre-client [ 9663.368271] Lustre: Skipped 1 previous similar message [ 9663.864990] LustreError: 371334:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a108eef800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9663.916081] Lustre: Unmounted lustre-client [ 9665.157425] Key type lgssc unregistered [ 9665.309372] LNet: 371578:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9665.313158] LNetError: 371578:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9665.330609] LNet: Removed LNI 192.168.204.53@tcp [ 9665.663241] Key type .llcrypt unregistered [ 9665.665085] Key type ._llcrypt unregistered [ 9666.155234] Key type ._llcrypt registered [ 9666.162807] Key type .llcrypt registered [ 9666.274112] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9666.282138] alg: No test for adler32 (adler32-zlib) [ 9667.198176] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9667.311345] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9668.943204] Key type lgssc registered [ 9669.521329] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9674.393683] Lustre: DEBUG MARKER: Iteration 16 [ 9674.513925] LustreError: 372409:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9674.513960] LustreError: 372411:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9674.521241] LustreError: 372409:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9675.612945] Lustre: Mounted lustre-client [ 9675.617394] Lustre: Skipped 1 previous similar message [ 9676.426596] LustreError: 372519:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a122138000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9676.499323] Lustre: Unmounted lustre-client [ 9677.740720] Key type lgssc unregistered [ 9677.895571] LNet: 372770:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9677.899365] LNetError: 372770:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9677.910792] LNet: Removed LNI 192.168.204.53@tcp [ 9678.229123] Key type .llcrypt unregistered [ 9678.230491] Key type ._llcrypt unregistered [ 9678.552257] Key type ._llcrypt registered [ 9678.554127] Key type .llcrypt registered [ 9678.764994] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9678.772866] alg: No test for adler32 (adler32-zlib) [ 9679.668932] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9679.789963] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9681.391178] Key type lgssc registered [ 9681.963610] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9686.389083] Lustre: DEBUG MARKER: Iteration 17 [ 9686.507362] LustreError: 373601:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9686.507500] LustreError: 373602:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9686.514951] LustreError: 373601:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9687.610482] Lustre: Mounted lustre-client [ 9687.612858] Lustre: Skipped 1 previous similar message [ 9688.352988] LustreError: 373707:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a1093b7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9688.444352] Lustre: Unmounted lustre-client [ 9689.778295] Key type lgssc unregistered [ 9689.919201] LNet: 373957:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9689.923270] LNetError: 373957:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9689.941315] LNet: Removed LNI 192.168.204.53@tcp [ 9690.234132] Key type .llcrypt unregistered [ 9690.236654] Key type ._llcrypt unregistered [ 9690.587682] Key type ._llcrypt registered [ 9690.592077] Key type .llcrypt registered [ 9690.787380] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9690.795081] alg: No test for adler32 (adler32-zlib) [ 9691.687199] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9691.800712] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9693.407794] Key type lgssc registered [ 9694.006551] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9698.826355] Lustre: DEBUG MARKER: Iteration 18 [ 9698.968424] LustreError: 374788:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9698.968457] LustreError: 374791:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9698.977423] LustreError: 374788:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 9700.077905] Lustre: Mounted lustre-client [ 9700.079948] Lustre: Skipped 1 previous similar message [ 9700.625513] LustreError: 374905:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a1221ff000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9700.631386] LustreError: 374905:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9700.685468] Lustre: Unmounted lustre-client [ 9700.687432] Lustre: Skipped 1 previous similar message [ 9701.951727] Key type lgssc unregistered [ 9702.096588] LNet: 375149:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9702.100454] LNetError: 375149:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9702.109122] LNet: Removed LNI 192.168.204.53@tcp [ 9702.375155] Key type .llcrypt unregistered [ 9702.376652] Key type ._llcrypt unregistered [ 9702.709716] Key type ._llcrypt registered [ 9702.711785] Key type .llcrypt registered [ 9702.916101] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9702.924358] alg: No test for adler32 (adler32-zlib) [ 9703.812754] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9703.921360] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9705.519297] Key type lgssc registered [ 9706.003740] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9710.155401] Lustre: DEBUG MARKER: Iteration 19 [ 9710.300359] LustreError: 375981:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9710.301130] LustreError: 375982:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9710.306672] LustreError: 375981:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9711.377239] Lustre: Mounted lustre-client [ 9711.379099] Lustre: Skipped 1 previous similar message [ 9711.900932] LustreError: 376093:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a107c92000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9711.906854] LustreError: 376093:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9711.974995] Lustre: Unmounted lustre-client [ 9713.146811] Key type lgssc unregistered [ 9713.297560] LNet: 376337:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9713.301967] LNetError: 376337:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9713.315879] LNet: Removed LNI 192.168.204.53@tcp [ 9713.616473] Key type .llcrypt unregistered [ 9713.618762] Key type ._llcrypt unregistered [ 9714.065852] Key type ._llcrypt registered [ 9714.075901] Key type .llcrypt registered [ 9714.179386] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9714.186391] alg: No test for adler32 (adler32-zlib) [ 9715.112994] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9715.241965] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9716.847400] Key type lgssc registered [ 9717.349490] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9721.950078] Lustre: DEBUG MARKER: Iteration 20 [ 9722.081950] LustreError: 377167:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9722.090871] LustreError: 377169:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9722.094657] LustreError: 377167:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [ 9723.191591] Lustre: Mounted lustre-client [ 9723.193767] Lustre: Skipped 1 previous similar message [ 9723.672300] LustreError: 377280:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a1204a3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9723.678365] LustreError: 377280:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9723.720951] Lustre: Unmounted lustre-client [ 9724.826080] Key type lgssc unregistered [ 9724.954536] LNet: 377524:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9724.958247] LNetError: 377524:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9724.968898] LNet: Removed LNI 192.168.204.53@tcp [ 9725.256212] Key type .llcrypt unregistered [ 9725.257717] Key type ._llcrypt unregistered [ 9725.589280] Key type ._llcrypt registered [ 9725.597296] Key type .llcrypt registered [ 9725.796368] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9725.807088] alg: No test for adler32 (adler32-zlib) [ 9726.708925] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9726.814722] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9728.423173] Key type lgssc registered [ 9728.932380] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9733.735749] Lustre: DEBUG MARKER: Iteration 21 [ 9733.862870] LustreError: 378355:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9733.862897] LustreError: 378356:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9733.869897] LustreError: 378355:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9734.944939] Lustre: Mounted lustre-client [ 9735.470146] LustreError: 378470:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a1041b4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9735.475811] LustreError: 378470:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9735.518113] Lustre: Unmounted lustre-client [ 9736.665734] Key type lgssc unregistered [ 9736.794804] LNet: 378714:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9736.797928] LNetError: 378714:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9736.805652] LNet: Removed LNI 192.168.204.53@tcp [ 9737.099155] Key type .llcrypt unregistered [ 9737.100590] Key type ._llcrypt unregistered [ 9737.413263] Key type ._llcrypt registered [ 9737.414868] Key type .llcrypt registered [ 9737.601807] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9737.608766] alg: No test for adler32 (adler32-zlib) [ 9738.482416] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9738.589985] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9740.183313] Key type lgssc registered [ 9740.677381] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9744.947908] Lustre: DEBUG MARKER: Iteration 22 [ 9745.080603] LustreError: 379545:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9745.081075] LustreError: 379546:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9745.088620] LustreError: 379545:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9746.176068] Lustre: Mounted lustre-client [ 9746.179372] Lustre: Skipped 1 previous similar message [ 9746.659375] LustreError: 379658:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a1041b7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9746.714641] Lustre: Unmounted lustre-client [ 9747.841418] Key type lgssc unregistered [ 9747.970806] LNet: 379903:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9747.974458] LNetError: 379903:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9747.988751] LNet: Removed LNI 192.168.204.53@tcp [ 9748.266932] Key type .llcrypt unregistered [ 9748.268767] Key type ._llcrypt unregistered [ 9748.565289] Key type ._llcrypt registered [ 9748.567595] Key type .llcrypt registered [ 9748.775705] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9748.782086] alg: No test for adler32 (adler32-zlib) [ 9749.664956] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9749.761743] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9751.359158] Key type lgssc registered [ 9751.861795] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9756.408406] Lustre: DEBUG MARKER: Iteration 23 [ 9756.541124] LustreError: 380736:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9756.541202] LustreError: 380737:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9756.547549] LustreError: 380736:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9757.617606] Lustre: Mounted lustre-client [ 9758.116533] LustreError: 380848:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a119471800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9758.180799] Lustre: Unmounted lustre-client [ 9759.317679] Key type lgssc unregistered [ 9759.444116] LNet: 381092:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9759.447901] LNetError: 381092:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9759.459731] LNet: Removed LNI 192.168.204.53@tcp [ 9759.731152] Key type .llcrypt unregistered [ 9759.732657] Key type ._llcrypt unregistered [ 9760.008052] Key type ._llcrypt registered [ 9760.015280] Key type .llcrypt registered [ 9760.237117] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9760.243555] alg: No test for adler32 (adler32-zlib) [ 9761.131104] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9761.239517] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9762.839247] Key type lgssc registered [ 9763.367792] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9767.732583] Lustre: DEBUG MARKER: Iteration 24 [ 9767.850486] LustreError: 381925:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9767.850881] LustreError: 381926:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9767.857746] LustreError: 381925:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9768.924735] Lustre: Mounted lustre-client [ 9769.455588] LustreError: 382040:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a1031ff800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9769.462749] LustreError: 382040:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9769.505778] Lustre: Unmounted lustre-client [ 9770.639954] Key type lgssc unregistered [ 9770.775515] LNet: 382283:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9770.778912] LNetError: 382283:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9770.785815] LNet: Removed LNI 192.168.204.53@tcp [ 9771.068200] Key type .llcrypt unregistered [ 9771.069818] Key type ._llcrypt unregistered [ 9771.380668] Key type ._llcrypt registered [ 9771.382064] Key type .llcrypt registered [ 9771.652709] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9771.659241] alg: No test for adler32 (adler32-zlib) [ 9772.544066] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9772.649703] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9774.255259] Key type lgssc registered [ 9774.720199] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9779.016160] Lustre: DEBUG MARKER: Iteration 25 [ 9779.127167] LustreError: 383114:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9779.127273] LustreError: 383115:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9779.134789] LustreError: 383114:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9780.218738] Lustre: Mounted lustre-client [ 9780.702713] LustreError: 383226:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a1037bf000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9780.707555] LustreError: 383226:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9780.771782] Lustre: Unmounted lustre-client [ 9780.773571] Lustre: Skipped 1 previous similar message [ 9781.857177] Key type lgssc unregistered [ 9781.986452] LNet: 383470:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9781.990191] LNetError: 383470:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9782.000051] LNet: Removed LNI 192.168.204.53@tcp [ 9782.273182] Key type .llcrypt unregistered [ 9782.275498] Key type ._llcrypt unregistered [ 9782.609043] Key type ._llcrypt registered [ 9782.610530] Key type .llcrypt registered [ 9782.814860] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9782.822889] alg: No test for adler32 (adler32-zlib) [ 9783.719157] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9783.830696] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9785.439284] Key type lgssc registered [ 9785.942180] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9790.575471] Lustre: DEBUG MARKER: Iteration 26 [ 9790.703423] LustreError: 384301:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9790.703465] LustreError: 384302:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9790.711440] LustreError: 384301:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9791.801050] Lustre: Mounted lustre-client [ 9791.802668] Lustre: Skipped 1 previous similar message [ 9792.374649] LustreError: 384413:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a117e15000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9792.379585] LustreError: 384413:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9792.429717] Lustre: Unmounted lustre-client [ 9793.650691] Key type lgssc unregistered [ 9793.781665] LNet: 384658:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9793.786080] LNetError: 384658:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9793.796237] LNet: Removed LNI 192.168.204.53@tcp [ 9794.095150] Key type .llcrypt unregistered [ 9794.097398] Key type ._llcrypt unregistered [ 9794.418809] Key type ._llcrypt registered [ 9794.420880] Key type .llcrypt registered [ 9794.605243] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9794.613080] alg: No test for adler32 (adler32-zlib) [ 9795.499407] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9795.611138] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9797.207421] Key type lgssc registered [ 9797.723525] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9802.277516] Lustre: DEBUG MARKER: Iteration 27 [ 9802.417820] LustreError: 385488:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9802.424189] LustreError: 385490:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9802.427969] LustreError: 385488:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 9803.510748] Lustre: Mounted lustre-client [ 9803.997311] LustreError: 385603:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a12023b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9804.053217] Lustre: Unmounted lustre-client [ 9805.181746] Key type lgssc unregistered [ 9805.316218] LNet: 385847:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9805.320671] LNetError: 385847:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9805.331249] LNet: Removed LNI 192.168.204.53@tcp [ 9805.616227] Key type .llcrypt unregistered [ 9805.618066] Key type ._llcrypt unregistered [ 9805.978377] Key type ._llcrypt registered [ 9805.980391] Key type .llcrypt registered [ 9806.136842] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9806.147353] alg: No test for adler32 (adler32-zlib) [ 9807.041163] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9807.153969] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9808.759650] Key type lgssc registered [ 9809.298019] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9813.649340] Lustre: DEBUG MARKER: Iteration 28 [ 9813.771656] LustreError: 386678:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9813.771700] LustreError: 386680:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9813.778864] LustreError: 386678:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9814.848653] Lustre: Mounted lustre-client [ 9814.850590] Lustre: Skipped 1 previous similar message [ 9815.348612] LustreError: 386791:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a106337000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9815.354047] LustreError: 386791:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9815.405082] Lustre: Unmounted lustre-client [ 9816.530566] Key type lgssc unregistered [ 9816.663718] LNet: 387034:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9816.667496] LNetError: 387034:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9816.676687] LNet: Removed LNI 192.168.204.53@tcp [ 9816.943185] Key type .llcrypt unregistered [ 9816.945114] Key type ._llcrypt unregistered [ 9817.226603] Key type ._llcrypt registered [ 9817.227922] Key type .llcrypt registered [ 9817.429902] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9817.437128] alg: No test for adler32 (adler32-zlib) [ 9818.325288] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9818.433976] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9820.031184] Key type lgssc registered [ 9820.520271] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9824.819569] Lustre: DEBUG MARKER: Iteration 29 [ 9824.924536] LustreError: 387864:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9824.930639] LustreError: 387868:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9824.934207] LustreError: 387864:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 9826.003230] Lustre: Mounted lustre-client [ 9826.474406] LustreError: 387980:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a1031fb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9826.481491] LustreError: 387980:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9826.549105] Lustre: Unmounted lustre-client [ 9827.697544] Key type lgssc unregistered [ 9827.839872] LNet: 388224:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9827.842929] LNetError: 388224:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9827.851688] LNet: Removed LNI 192.168.204.53@tcp [ 9828.135167] Key type .llcrypt unregistered [ 9828.136982] Key type ._llcrypt unregistered [ 9828.479276] Key type ._llcrypt registered [ 9828.481318] Key type .llcrypt registered [ 9828.659369] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9828.666351] alg: No test for adler32 (adler32-zlib) [ 9829.545021] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9829.645136] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9831.247888] Key type lgssc registered [ 9831.762794] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9836.303603] Lustre: DEBUG MARKER: Iteration 30 [ 9836.414457] LustreError: 389055:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9836.414549] LustreError: 389056:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9836.420541] LustreError: 389055:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9837.479925] Lustre: Mounted lustre-client [ 9837.481770] Lustre: Skipped 1 previous similar message [ 9838.016566] LustreError: 389169:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a107c94800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9838.020613] LustreError: 389169:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9838.093030] Lustre: Unmounted lustre-client [ 9839.140465] Key type lgssc unregistered [ 9839.257750] LNet: 389413:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9839.261495] LNetError: 389413:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9839.271696] LNet: Removed LNI 192.168.204.53@tcp [ 9839.531135] Key type .llcrypt unregistered [ 9839.532893] Key type ._llcrypt unregistered [ 9839.828593] Key type ._llcrypt registered [ 9839.830093] Key type .llcrypt registered [ 9840.022270] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9840.028638] alg: No test for adler32 (adler32-zlib) [ 9840.906735] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9841.005244] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9842.599249] Key type lgssc registered [ 9843.028697] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9847.187714] Lustre: DEBUG MARKER: Iteration 31 [ 9847.323487] LustreError: 390244:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9847.323587] LustreError: 390245:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9847.329235] LustreError: 390244:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9848.398625] Lustre: Mounted lustre-client [ 9848.853903] LustreError: 390356:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a12e86a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9848.859983] LustreError: 390356:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9848.901841] Lustre: Unmounted lustre-client [ 9849.988384] Key type lgssc unregistered [ 9850.130435] LNet: 390600:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9850.134389] LNetError: 390600:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9850.144050] LNet: Removed LNI 192.168.204.53@tcp [ 9850.412206] Key type .llcrypt unregistered [ 9850.413622] Key type ._llcrypt unregistered [ 9850.745527] Key type ._llcrypt registered [ 9850.746608] Key type .llcrypt registered [ 9850.909293] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9850.916888] alg: No test for adler32 (adler32-zlib) [ 9851.781572] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9851.872730] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9853.463186] Key type lgssc registered [ 9853.908441] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9858.003050] Lustre: DEBUG MARKER: Iteration 32 [ 9858.124593] LustreError: 391431:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9858.124634] LustreError: 391432:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9858.131281] LustreError: 391431:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9859.202500] Lustre: Mounted lustre-client [ 9859.204380] Lustre: Skipped 1 previous similar message [ 9859.670381] LustreError: 391543:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a12213a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9859.675191] LustreError: 391543:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9859.736567] Lustre: Unmounted lustre-client [ 9860.737918] Key type lgssc unregistered [ 9860.860693] LNet: 391787:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9860.864248] LNetError: 391787:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9860.873374] LNet: Removed LNI 192.168.204.53@tcp [ 9861.124154] Key type .llcrypt unregistered [ 9861.126082] Key type ._llcrypt unregistered [ 9861.382743] Key type ._llcrypt registered [ 9861.384146] Key type .llcrypt registered [ 9861.592654] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9861.598922] alg: No test for adler32 (adler32-zlib) [ 9862.466000] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9862.570355] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9864.167129] Key type lgssc registered [ 9864.584493] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9868.578141] Lustre: DEBUG MARKER: Iteration 33 [ 9868.679496] LustreError: 392618:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9868.679539] LustreError: 392619:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9868.685611] LustreError: 392618:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9869.743946] Lustre: Mounted lustre-client [ 9869.745310] Lustre: Skipped 1 previous similar message [ 9870.195531] LustreError: 392733:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a102c9a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9870.199135] LustreError: 392733:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9870.292477] Lustre: Unmounted lustre-client [ 9871.312982] Key type lgssc unregistered [ 9871.433621] LNet: 392977:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9871.437677] LNetError: 392977:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9871.446877] LNet: Removed LNI 192.168.204.53@tcp [ 9871.709161] Key type .llcrypt unregistered [ 9871.710964] Key type ._llcrypt unregistered [ 9871.985682] Key type ._llcrypt registered [ 9871.986907] Key type .llcrypt registered [ 9872.172425] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9872.179204] alg: No test for adler32 (adler32-zlib) [ 9873.056791] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9873.158728] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9874.751204] Key type lgssc registered [ 9875.179779] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9879.335582] Lustre: DEBUG MARKER: Iteration 34 [ 9879.472498] LustreError: 393810:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9879.473138] LustreError: 393811:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9879.483180] LustreError: 393810:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9880.567357] Lustre: Mounted lustre-client [ 9880.569065] Lustre: Skipped 1 previous similar message [ 9881.106562] LustreError: 393925:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a12cdc9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9881.112262] LustreError: 393925:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9881.157795] Lustre: Unmounted lustre-client [ 9882.309789] Key type lgssc unregistered [ 9882.443873] LNet: 394168:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9882.447266] LNetError: 394168:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9882.461041] LNet: Removed LNI 192.168.204.53@tcp [ 9882.723233] Key type .llcrypt unregistered [ 9882.725248] Key type ._llcrypt unregistered [ 9883.004847] Key type ._llcrypt registered [ 9883.006271] Key type .llcrypt registered [ 9883.205331] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9883.211525] alg: No test for adler32 (adler32-zlib) [ 9884.088931] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9884.183737] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9885.775177] Key type lgssc registered [ 9886.215154] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9890.231067] Lustre: DEBUG MARKER: Iteration 35 [ 9890.329276] LustreError: 395000:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9890.329636] LustreError: 395001:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9890.335069] LustreError: 395000:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9891.388158] Lustre: Mounted lustre-client [ 9891.856659] LustreError: 395116:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a11893d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9891.963842] Lustre: Unmounted lustre-client [ 9893.033733] Key type lgssc unregistered [ 9893.155708] LNet: 395359:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9893.159279] LNetError: 395359:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9893.168442] LNet: Removed LNI 192.168.204.53@tcp [ 9893.444553] Key type .llcrypt unregistered [ 9893.446428] Key type ._llcrypt unregistered [ 9893.729742] Key type ._llcrypt registered [ 9893.731808] Key type .llcrypt registered [ 9893.930945] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9893.938098] alg: No test for adler32 (adler32-zlib) [ 9894.815133] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9894.908913] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9896.495152] Key type lgssc registered [ 9896.939551] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9901.284594] Lustre: DEBUG MARKER: Iteration 36 [ 9901.409298] LustreError: 396190:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9901.409704] LustreError: 396191:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9901.416861] LustreError: 396190:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9902.486054] Lustre: Mounted lustre-client [ 9902.997229] LustreError: 396308:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a12cdcb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9903.002363] LustreError: 396308:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9903.059077] Lustre: Unmounted lustre-client [ 9904.175675] Key type lgssc unregistered [ 9904.303527] LNet: 396552:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9904.307197] LNetError: 396552:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9904.317726] LNet: Removed LNI 192.168.204.53@tcp [ 9904.588929] Key type .llcrypt unregistered [ 9904.591294] Key type ._llcrypt unregistered [ 9904.848766] Key type ._llcrypt registered [ 9904.850187] Key type .llcrypt registered [ 9905.067823] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9905.074893] alg: No test for adler32 (adler32-zlib) [ 9905.956416] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9906.051530] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9907.639293] Key type lgssc registered [ 9908.103301] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9912.288763] Lustre: DEBUG MARKER: Iteration 37 [ 9912.393326] LustreError: 397383:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9912.393363] LustreError: 397384:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9912.400402] LustreError: 397383:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9913.460289] Lustre: Mounted lustre-client [ 9913.462028] Lustre: Skipped 1 previous similar message [ 9913.973778] LustreError: 397496:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a1082eb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9913.978616] LustreError: 397496:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9914.027893] Lustre: Unmounted lustre-client [ 9914.029264] Lustre: Skipped 1 previous similar message [ 9915.082506] Key type lgssc unregistered [ 9915.219555] LNet: 397739:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9915.222727] LNetError: 397739:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9915.231090] LNet: Removed LNI 192.168.204.53@tcp [ 9915.477205] Key type .llcrypt unregistered [ 9915.478724] Key type ._llcrypt unregistered [ 9915.769346] Key type ._llcrypt registered [ 9915.771106] Key type .llcrypt registered [ 9915.971145] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9915.977120] alg: No test for adler32 (adler32-zlib) [ 9916.849464] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9916.941143] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9918.535154] Key type lgssc registered [ 9919.019544] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9923.255265] Lustre: DEBUG MARKER: Iteration 38 [ 9923.400254] LustreError: 398572:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9923.400441] LustreError: 398571:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9923.408883] LustreError: 398572:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 9924.495120] Lustre: Mounted lustre-client [ 9924.496868] Lustre: Skipped 1 previous similar message [ 9924.992450] LustreError: 398685:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a12bc09000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9924.997614] LustreError: 398685:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9925.075320] Lustre: Unmounted lustre-client [ 9925.077244] Lustre: Skipped 1 previous similar message [ 9926.075976] Key type lgssc unregistered [ 9926.208504] LNet: 398928:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9926.211639] LNetError: 398928:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9926.222760] LNet: Removed LNI 192.168.204.53@tcp [ 9926.477135] Key type .llcrypt unregistered [ 9926.479096] Key type ._llcrypt unregistered [ 9926.785275] Key type ._llcrypt registered [ 9926.787037] Key type .llcrypt registered [ 9926.963503] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9926.969890] alg: No test for adler32 (adler32-zlib) [ 9927.857573] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9927.948796] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9929.535149] Key type lgssc registered [ 9930.018279] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9934.081407] Lustre: DEBUG MARKER: Iteration 39 [ 9934.204159] LustreError: 399760:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9934.204206] LustreError: 399761:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9934.211436] LustreError: 399760:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9935.275388] Lustre: Mounted lustre-client [ 9935.723210] LustreError: 399874:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a12bc0b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9935.729685] LustreError: 399874:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9935.774411] Lustre: Unmounted lustre-client [ 9936.787931] Key type lgssc unregistered [ 9936.899557] LNet: 400117:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9936.903287] LNetError: 400117:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9936.913769] LNet: Removed LNI 192.168.204.53@tcp [ 9937.163123] Key type .llcrypt unregistered [ 9937.164456] Key type ._llcrypt unregistered [ 9937.456424] Key type ._llcrypt registered [ 9937.458195] Key type .llcrypt registered [ 9937.637236] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9937.645447] alg: No test for adler32 (adler32-zlib) [ 9938.512172] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9938.604832] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9940.199176] Key type lgssc registered [ 9940.655089] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9944.968994] Lustre: DEBUG MARKER: Iteration 40 [ 9945.106266] LustreError: 400948:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9945.106301] LustreError: 400949:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9945.116996] LustreError: 400948:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9946.186847] Lustre: Mounted lustre-client [ 9946.188596] Lustre: Skipped 1 previous similar message [ 9946.651879] LustreError: 401063:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a107cd6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9946.657120] LustreError: 401063:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9946.704132] Lustre: Unmounted lustre-client [ 9947.716447] Key type lgssc unregistered [ 9947.832425] LNet: 401306:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9947.835547] LNetError: 401306:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9947.842718] LNet: Removed LNI 192.168.204.53@tcp [ 9948.079140] Key type .llcrypt unregistered [ 9948.080491] Key type ._llcrypt unregistered [ 9948.348058] Key type ._llcrypt registered [ 9948.349582] Key type .llcrypt registered [ 9948.590643] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9948.597270] alg: No test for adler32 (adler32-zlib) [ 9949.457207] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9949.547237] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9951.135155] Key type lgssc registered [ 9951.563854] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9955.507268] Lustre: DEBUG MARKER: Iteration 41 [ 9955.602793] LustreError: 402138:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9955.604047] LustreError: 402139:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9955.608716] LustreError: 402138:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9956.669861] Lustre: Mounted lustre-client [ 9956.671336] Lustre: Skipped 1 previous similar message [ 9957.168982] LustreError: 402253:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a106bf8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9957.174255] LustreError: 402253:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9957.243962] Lustre: Unmounted lustre-client [ 9958.322343] Key type lgssc unregistered [ 9958.443077] LNet: 402497:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9958.446614] LNetError: 402497:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9958.456838] LNet: Removed LNI 192.168.204.53@tcp [ 9958.724163] Key type .llcrypt unregistered [ 9958.725882] Key type ._llcrypt unregistered [ 9959.010974] Key type ._llcrypt registered [ 9959.012120] Key type .llcrypt registered [ 9959.208821] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9959.214908] alg: No test for adler32 (adler32-zlib) [ 9960.098123] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9960.196489] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9961.783220] Key type lgssc registered [ 9962.218198] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9966.293685] Lustre: DEBUG MARKER: Iteration 42 [ 9966.414727] LustreError: 403328:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9966.415775] LustreError: 403330:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9966.421420] LustreError: 403328:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9967.489498] Lustre: Mounted lustre-client [ 9967.490865] Lustre: Skipped 1 previous similar message [ 9967.970088] LustreError: 403442:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a1031fd000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9967.974718] LustreError: 403442:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9968.030949] Lustre: Unmounted lustre-client [ 9969.138396] Key type lgssc unregistered [ 9969.262723] LNet: 403685:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9969.266371] LNetError: 403685:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9969.273792] LNet: Removed LNI 192.168.204.53@tcp [ 9969.526175] Key type .llcrypt unregistered [ 9969.527975] Key type ._llcrypt unregistered [ 9969.786216] Key type ._llcrypt registered [ 9969.788399] Key type .llcrypt registered [ 9969.995117] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9970.002417] alg: No test for adler32 (adler32-zlib) [ 9970.881229] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9970.970262] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9972.567181] Key type lgssc registered [ 9973.004741] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9977.098436] Lustre: DEBUG MARKER: Iteration 43 [ 9977.208327] LustreError: 404516:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9977.209961] LustreError: 404517:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9977.216029] LustreError: 404516:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9978.297803] Lustre: Mounted lustre-client [ 9978.300107] Lustre: Skipped 1 previous similar message [ 9978.783183] LustreError: 404630:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a12023e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9978.787918] LustreError: 404630:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9978.866091] Lustre: Unmounted lustre-client [ 9979.945540] Key type lgssc unregistered [ 9980.066438] LNet: 404872:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9980.069242] LNetError: 404872:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9980.076823] LNet: Removed LNI 192.168.204.53@tcp [ 9980.353121] Key type .llcrypt unregistered [ 9980.354646] Key type ._llcrypt unregistered [ 9980.653555] Key type ._llcrypt registered [ 9980.655047] Key type .llcrypt registered [ 9980.868716] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9980.874830] alg: No test for adler32 (adler32-zlib) [ 9981.759875] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9981.863811] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9983.455535] Key type lgssc registered [ 9983.927724] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9988.306273] Lustre: DEBUG MARKER: Iteration 44 [ 9988.441785] LustreError: 405704:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9988.441842] LustreError: 405705:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9988.448793] LustreError: 405704:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9989.523602] Lustre: Mounted lustre-client [ 9989.525429] Lustre: Skipped 1 previous similar message [ 9990.018588] LustreError: 405817:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a10a70b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9990.024193] LustreError: 405817:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 9990.076522] Lustre: Unmounted lustre-client [ 9991.136326] Key type lgssc unregistered [ 9991.255561] LNet: 406060:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9991.258607] LNetError: 406060:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9991.271675] LNet: Removed LNI 192.168.204.53@tcp [ 9991.538119] Key type .llcrypt unregistered [ 9991.539785] Key type ._llcrypt unregistered [ 9991.893277] Key type ._llcrypt registered [ 9991.896178] Key type .llcrypt registered [ 9992.029402] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9992.036219] alg: No test for adler32 (adler32-zlib) [ 9992.907180] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [ 9992.992094] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 9994.583187] Key type lgssc registered [ 9995.005601] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9999.234339] Lustre: DEBUG MARKER: Iteration 45 [ 9999.351586] LustreError: 406892:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 9999.351997] LustreError: 406893:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 9999.357959] LustreError: 406892:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10000.435844] Lustre: Mounted lustre-client [10000.437497] Lustre: Skipped 1 previous similar message [10000.978204] LustreError: 407004:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a11893f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10001.043839] Lustre: Unmounted lustre-client [10002.079986] Key type lgssc unregistered [10002.203310] LNet: 407248:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10002.206362] LNetError: 407248:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10002.213713] LNet: Removed LNI 192.168.204.53@tcp [10002.487165] Key type .llcrypt unregistered [10002.488715] Key type ._llcrypt unregistered [10002.773848] Key type ._llcrypt registered [10002.776330] Key type .llcrypt registered [10002.956207] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10002.962880] alg: No test for adler32 (adler32-zlib) [10003.831766] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [10003.911807] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [10005.495153] Key type lgssc registered [10005.906561] Lustre: Echo OBD driver; http://www.lustre.org/ [10009.894029] Lustre: DEBUG MARKER: Iteration 46 [10010.003260] LustreError: 408080:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [10010.003566] LustreError: 408079:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [10010.007634] LustreError: 408080:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [10011.064311] Lustre: Mounted lustre-client [10011.591731] LustreError: 408187:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a10a70b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10011.596070] LustreError: 408187:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [10011.651800] Lustre: Unmounted lustre-client [10012.613160] Key type lgssc unregistered [10012.730450] LNet: 408430:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10012.733232] LNetError: 408430:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10012.747719] LNet: Removed LNI 192.168.204.53@tcp [10012.990157] Key type .llcrypt unregistered [10012.991466] Key type ._llcrypt unregistered [10013.258432] Key type ._llcrypt registered [10013.262153] Key type .llcrypt registered [10013.436410] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10013.442664] alg: No test for adler32 (adler32-zlib) [10014.303917] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [10014.381375] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [10015.967201] Key type lgssc registered [10016.415403] Lustre: Echo OBD driver; http://www.lustre.org/ [10020.120961] Lustre: DEBUG MARKER: Iteration 47 [10020.259504] LustreError: 409262:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [10020.259571] LustreError: 409263:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [10020.267299] LustreError: 409262:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10021.352495] Lustre: Mounted lustre-client [10021.882950] LustreError: 409375:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a1063be800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10021.887678] LustreError: 409375:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [10021.935918] Lustre: Unmounted lustre-client [10022.919872] Key type lgssc unregistered [10023.033369] LNet: 409620:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10023.036476] LNetError: 409620:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10023.044273] LNet: Removed LNI 192.168.204.53@tcp [10023.288103] Key type .llcrypt unregistered [10023.290105] Key type ._llcrypt unregistered [10023.548837] Key type ._llcrypt registered [10023.550291] Key type .llcrypt registered [10023.725830] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10023.738877] alg: No test for adler32 (adler32-zlib) [10024.608655] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [10024.691962] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [10026.279434] Key type lgssc registered [10026.670078] Lustre: Echo OBD driver; http://www.lustre.org/ [10030.510502] Lustre: DEBUG MARKER: Iteration 48 [10030.602043] LustreError: 410451:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [10030.604321] LustreError: 410452:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [10030.608483] LustreError: 410451:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10031.666379] Lustre: Mounted lustre-client [10031.667563] Lustre: Skipped 1 previous similar message [10032.117335] LustreError: 410565:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a1031f8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10032.122515] LustreError: 410565:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [10032.165076] Lustre: Unmounted lustre-client [10033.162642] Key type lgssc unregistered [10033.269516] LNet: 410808:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10033.272310] LNetError: 410808:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10033.281772] LNet: Removed LNI 192.168.204.53@tcp [10033.525094] Key type .llcrypt unregistered [10033.526157] Key type ._llcrypt unregistered [10033.745223] Key type ._llcrypt registered [10033.746541] Key type .llcrypt registered [10033.957543] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10033.964038] alg: No test for adler32 (adler32-zlib) [10034.840809] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [10034.934188] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [10036.527169] Key type lgssc registered [10036.902257] Lustre: Echo OBD driver; http://www.lustre.org/ [10040.573711] Lustre: DEBUG MARKER: Iteration 49 [10040.695173] LustreError: 411640:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [10040.695465] LustreError: 411639:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [10040.702014] LustreError: 411640:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10041.749672] Lustre: Mounted lustre-client [10041.751374] Lustre: Skipped 1 previous similar message [10042.212546] LustreError: 411753:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a1031fd800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10042.217079] LustreError: 411753:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [10042.275162] Lustre: Unmounted lustre-client [10043.275459] Key type lgssc unregistered [10043.395380] LNet: 411996:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10043.398838] LNetError: 411996:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10043.407699] LNet: Removed LNI 192.168.204.53@tcp [10043.640125] Key type .llcrypt unregistered [10043.641698] Key type ._llcrypt unregistered [10043.957147] Key type ._llcrypt registered [10043.958372] Key type .llcrypt registered [10044.106709] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10044.112422] alg: No test for adler32 (adler32-zlib) [10044.978238] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [10045.066612] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [10046.655170] Key type lgssc registered [10047.074422] Lustre: Echo OBD driver; http://www.lustre.org/ [10050.833323] Lustre: DEBUG MARKER: Iteration 50 [10050.957857] LustreError: 412828:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [10050.957892] LustreError: 412829:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [10050.964503] LustreError: 412828:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10052.019971] Lustre: Mounted lustre-client [10052.444052] LustreError: 412941:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a12ea50000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10052.447279] LustreError: 412941:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [10052.517286] Lustre: Unmounted lustre-client [10052.518542] Lustre: Skipped 1 previous similar message [10053.505669] Key type lgssc unregistered [10053.616425] LNet: 413185:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10053.618920] LNetError: 413185:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10053.626561] LNet: Removed LNI 192.168.204.53@tcp [10053.879121] Key type .llcrypt unregistered [10053.880286] Key type ._llcrypt unregistered [10054.166491] Key type ._llcrypt registered [10054.167936] Key type .llcrypt registered [10054.346853] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10054.352545] alg: No test for adler32 (adler32-zlib) [10055.221886] Lustre: Lustre: Build Version: 2.17.51_74_g8e7ba6b [10055.313285] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [10056.903172] Key type lgssc registered [10057.301253] Lustre: Echo OBD driver; http://www.lustre.org/ [10061.714419] Lustre: Mounted lustre-client [10064.095947] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 10:27:24 (1775226444) [10080.735175] Lustre: 414543:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775226445/real 1775226445] req@ffff91a131a0ad80 x1861459835365248/t0(0) o36->lustre-MDT0000-mdc-ffff91a104f93800@192.168.204.153@tcp:12/10 lens 496/440 e 0 to 1 dl 1775226461 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [10080.742986] Lustre: lustre-MDT0000-mdc-ffff91a104f93800: Connection to lustre-MDT0000 (at 192.168.204.153@tcp) was lost; in progress operations using this service will wait for recovery to complete [10080.754145] Lustre: lustre-MDT0000-mdc-ffff91a104f93800: Connection restored to 192.168.204.153@tcp (at 192.168.204.153@tcp) [10096.095878] Lustre: 414543:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775226461/real 1775226461] req@ffff91a131a0ad80 x1861459835365248/t0(0) o36->lustre-MDT0000-mdc-ffff91a104f93800@192.168.204.153@tcp:12/10 lens 496/440 e 0 to 1 dl 1775226477 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [10096.111323] Lustre: lustre-MDT0000-mdc-ffff91a104f93800: Connection to lustre-MDT0000 (at 192.168.204.153@tcp) was lost; in progress operations using this service will wait for recovery to complete [10096.126426] Lustre: lustre-MDT0000-mdc-ffff91a104f93800: Connection restored to 192.168.204.153@tcp (at 192.168.204.153@tcp) [10112.479228] Lustre: 414543:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775226477/real 1775226477] req@ffff91a131a0ad80 x1861459835365248/t0(0) o36->lustre-MDT0000-mdc-ffff91a104f93800@192.168.204.153@tcp:12/10 lens 496/440 e 0 to 1 dl 1775226493 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [10112.492371] Lustre: lustre-MDT0000-mdc-ffff91a104f93800: Connection to lustre-MDT0000 (at 192.168.204.153@tcp) was lost; in progress operations using this service will wait for recovery to complete [10112.510657] Lustre: lustre-MDT0000-mdc-ffff91a104f93800: Connection restored to 192.168.204.153@tcp (at 192.168.204.153@tcp) [10127.839133] Lustre: 414543:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775226493/real 1775226493] req@ffff91a131a0ad80 x1861459835365248/t0(0) o36->lustre-MDT0000-mdc-ffff91a104f93800@192.168.204.153@tcp:12/10 lens 496/440 e 0 to 1 dl 1775226509 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [10127.868654] Lustre: lustre-MDT0000-mdc-ffff91a104f93800: Connection to lustre-MDT0000 (at 192.168.204.153@tcp) was lost; in progress operations using this service will wait for recovery to complete [10127.897781] Lustre: lustre-MDT0000-mdc-ffff91a104f93800: Connection restored to 192.168.204.153@tcp (at 192.168.204.153@tcp) [10130.067599] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 10:28:30 (1775226510) [10138.372630] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 10:28:38 (1775226518) [10146.446971] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 10:28:46 (1775226526) [10151.472321] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 10:28:51 (1775226531) [10167.203286] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 10:29:07 (1775226547) [10191.740690] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 10:29:32 (1775226572) [10195.647154] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 10:29:36 (1775226576) [10195.864274] LustreError: 419318:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [10197.951110] LustreError: 419318:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [10201.351389] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 10:29:41 (1775226581) [10219.999185] Lustre: 413379:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775226585/real 1775226585] req@ffff91a13f288700 x1861459836406400/t0(0) o4->lustre-OST0000-osc-ffff91a104f93800@192.168.204.153@tcp:6/4 lens 4584/448 e 0 to 1 dl 1775226601 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [10220.030322] Lustre: lustre-OST0000-osc-ffff91a104f93800: Connection to lustre-OST0000 (at 192.168.204.153@tcp) was lost; in progress operations using this service will wait for recovery to complete [10236.383200] Lustre: 413378:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775226601/real 1775226601] req@ffff91a137b18000 x1861459836408320/t0(0) o4->lustre-OST0000-osc-ffff91a104f93800@192.168.204.153@tcp:6/4 lens 4584/448 e 0 to 1 dl 1775226617 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [10236.383252] Lustre: lustre-OST0000-osc-ffff91a104f93800: Connection to lustre-OST0000 (at 192.168.204.153@tcp) was lost; in progress operations using this service will wait for recovery to complete [10236.404777] Lustre: 413378:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [10236.437706] Lustre: lustre-OST0000-osc-ffff91a104f93800: Connection restored to 192.168.204.153@tcp (at 192.168.204.153@tcp) [10252.767184] Lustre: 413378:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775226617/real 1775226617] req@ffff91a137b18000 x1861459836408320/t0(0) o4->lustre-OST0000-osc-ffff91a104f93800@192.168.204.153@tcp:6/4 lens 4584/448 e 0 to 1 dl 1775226633 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [10252.767235] Lustre: lustre-OST0000-osc-ffff91a104f93800: Connection to lustre-OST0000 (at 192.168.204.153@tcp) was lost; in progress operations using this service will wait for recovery to complete [10252.784237] Lustre: 413378:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 5 previous similar messages [10252.802388] Lustre: lustre-OST0000-osc-ffff91a104f93800: Connection restored to 192.168.204.153@tcp (at 192.168.204.153@tcp) [10284.515388] Lustre: lustre-OST0000-osc-ffff91a104f93800: Connection restored to 192.168.204.153@tcp (at 192.168.204.153@tcp) [10284.520529] Lustre: Skipped 1 previous similar message [10299.871183] Lustre: 413378:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775226665/real 1775226665] req@ffff91a137b18000 x1861459836408320/t0(0) o4->lustre-OST0000-osc-ffff91a104f93800@192.168.204.153@tcp:6/4 lens 4584/448 e 0 to 1 dl 1775226681 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [10299.894714] Lustre: 413378:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 11 previous similar messages [10299.899623] Lustre: lustre-OST0000-osc-ffff91a104f93800: Connection to lustre-OST0000 (at 192.168.204.153@tcp) was lost; in progress operations using this service will wait for recovery to complete [10299.908432] Lustre: Skipped 2 previous similar messages [10325.194492] Lustre: DEBUG MARKER: oleg453-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff91a104f93800.ost_server_uuid 50 [10326.043440] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff91a104f93800.ost_server_uuid in FULL state after 0 sec [10326.958163] Lustre: DEBUG MARKER: cleanup: ====================================================== [10327.929863] Lustre: DEBUG MARKER: == sanityn test complete, duration 9998 sec ============== 10:31:48 (1775226708) [10328.868483] Lustre: DEBUG MARKER: === sanityn: start cleanup 10:31:49 (1775226709) === [10537.759736] LustreError: 421392:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a108d1b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10537.795704] Lustre: Unmounted lustre-client [10540.164821] Lustre: DEBUG MARKER: === sanityn: finish cleanup 10:35:20 (1775226920) === [10541.364352] LustreError: 421698:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff91a104f93800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10541.370613] LustreError: 421698:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [10541.420737] Lustre: Unmounted lustre-client [10584.315758] Key type lgssc unregistered [10584.449437] LNet: 422383:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10584.453336] LNetError: 422383:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [10584.465918] LNet: Removed LNI 192.168.204.53@tcp [10584.784267] Key type .llcrypt unregistered [10584.786577] Key type ._llcrypt unregistered