[ 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 456021087 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, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001010] APIC: Switch to symmetric I/O mode setup [ 0.003272] x2apic enabled [ 0.005005] Switched APIC routing to physical x2apic. [ 0.007012] kvm-guest: setup PV IPIs [ 0.010000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.010000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.010024] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.011014] pid_max: default: 32768 minimum: 301 [ 0.013150] LSM: Security Framework initializing [ 0.014057] Yama: becoming mindful. [ 0.016028] SELinux: Initializing. [ 0.017076] *** VALIDATE selinux *** [ 0.025504] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.030021] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.031186] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032118] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.033124] *** VALIDATE tmpfs *** [ 0.034499] *** VALIDATE proc *** [ 0.035271] *** VALIDATE cgroup *** [ 0.036012] *** VALIDATE cgroup2 *** [ 0.038090] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.039195] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.040015] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.041046] Spectre V2 : User space: Vulnerable [ 0.042017] Speculative Store Bypass: Vulnerable [ 0.045363] debug: unmapping init [mem 0xffffffffb3059000-0xffffffffb3060fff] [ 0.048000] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.048871] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.049036] ... version: 2 [ 0.050021] ... bit width: 48 [ 0.051016] ... generic registers: 4 [ 0.052015] ... value mask: 0000ffffffffffff [ 0.053029] ... max period: 00007fffffffffff [ 0.054030] ... fixed-purpose events: 3 [ 0.055021] ... event mask: 000000070000000f [ 0.057312] rcu: Hierarchical SRCU implementation. [ 0.059632] smp: Bringing up secondary CPUs ... [ 0.060773] x86: Booting SMP configuration: [ 0.061030] .... node #0, CPUs: #1 #2 #3 [ 0.065076] smp: Brought up 1 node, 4 CPUs [ 0.067015] smpboot: Max logical packages: 1 [ 0.068031] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.134660] node 0 deferred pages initialised in 65ms [ 0.138262] devtmpfs: initialized [ 0.139323] x86/mm: Memory block size: 128MB [ 0.141725] gcov: version magic: 0x41383552 [ 0.146291] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.147097] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.148273] pinctrl core: initialized pinctrl subsystem [ 0.149311] [ 0.149902] ************************************************************* [ 0.150014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.151015] ** ** [ 0.152013] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.153015] ** ** [ 0.154012] ** This means that this kernel is built to expose internal ** [ 0.155014] ** IOMMU data structures, which may compromise security on ** [ 0.156013] ** your system. ** [ 0.157014] ** ** [ 0.158012] ** If you see this message and you are not debugging the ** [ 0.159017] ** kernel, report this immediately to your vendor! ** [ 0.160014] ** ** [ 0.161013] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.162010] ************************************************************* [ 0.163704] NET: Registered protocol family 16 [ 0.165442] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.169067] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.172067] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.176660] cpuidle: using governor menu [ 0.179619] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.180434] PCI: Using configuration type 1 for base access [ 0.183128] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.194067] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.197103] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.202175] cryptd: max_cpu_qlen set to 1000 [ 0.205250] ACPI: Added _OSI(Module Device) [ 0.207019] ACPI: Added _OSI(Processor Device) [ 0.209015] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.211015] ACPI: Added _OSI(Processor Aggregator Device) [ 0.216392] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.224412] ACPI: Interpreter enabled [ 0.226053] ACPI: PM: (supports S0 S3 S4 S5) [ 0.227011] ACPI: Using IOAPIC for interrupt routing [ 0.229213] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.233501] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.244040] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.246048] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.248017] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.252081] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.256635] acpiphp: Slot [2] registered [ 0.259186] acpiphp: Slot [5] registered [ 0.261172] acpiphp: Slot [6] registered [ 0.262299] acpiphp: Slot [3] registered [ 0.265158] acpiphp: Slot [4] registered [ 0.267249] acpiphp: Slot [7] registered [ 0.269123] acpiphp: Slot [8] registered [ 0.270132] acpiphp: Slot [9] registered [ 0.272114] acpiphp: Slot [10] registered [ 0.273098] acpiphp: Slot [11] registered [ 0.275130] acpiphp: Slot [12] registered [ 0.277103] acpiphp: Slot [13] registered [ 0.278110] acpiphp: Slot [14] registered [ 0.280118] acpiphp: Slot [15] registered [ 0.282125] acpiphp: Slot [16] registered [ 0.284195] acpiphp: Slot [17] registered [ 0.286116] acpiphp: Slot [18] registered [ 0.288085] acpiphp: Slot [19] registered [ 0.290121] acpiphp: Slot [20] registered [ 0.292102] acpiphp: Slot [21] registered [ 0.294119] acpiphp: Slot [22] registered [ 0.296162] acpiphp: Slot [23] registered [ 0.298109] acpiphp: Slot [24] registered [ 0.299218] acpiphp: Slot [25] registered [ 0.301208] acpiphp: Slot [26] registered [ 0.303114] acpiphp: Slot [27] registered [ 0.305114] acpiphp: Slot [28] registered [ 0.306150] acpiphp: Slot [29] registered [ 0.308118] acpiphp: Slot [30] registered [ 0.309099] acpiphp: Slot [31] registered [ 0.311064] PCI host bridge to bus 0000:00 [ 0.313021] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.315025] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.318034] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.322016] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.325042] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.328030] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.331204] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.334084] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.338472] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.347786] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.353009] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.356025] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.359026] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.361019] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.365609] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.368811] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.371041] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.373897] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.377851] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.389018] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.392973] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.401055] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.408016] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.415015] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.433016] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.443918] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.451020] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.456019] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.468022] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.480397] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.483391] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.486381] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.488461] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.491262] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.497042] iommu: Default domain type: Passthrough [ 0.499794] SCSI subsystem initialized [ 0.501226] ACPI: bus type USB registered [ 0.503193] usbcore: registered new interface driver usbfs [ 0.506156] usbcore: registered new interface driver hub [ 0.508176] usbcore: registered new device driver usb [ 0.510217] pps_core: LinuxPPS API ver. 1 registered [ 0.513087] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.516099] PTP clock support registered [ 0.519123] EDAC MC: Ver: 3.0.0 [ 0.521182] PCI: Using ACPI for IRQ routing [ 0.522559] NetLabel: Initializing [ 0.524019] NetLabel: domain hash size = 128 [ 0.527015] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.530095] NetLabel: unlabeled traffic allowed by default [ 0.532149] vgaarb: loaded [ 0.533379] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.535032] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.540337] clocksource: Switched to clocksource kvm-clock [ 0.666587] VFS: Disk quotas dquot_6.6.0 [ 0.668240] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.671748] *** VALIDATE ramfs *** [ 0.673258] *** VALIDATE hugetlbfs *** [ 0.675314] pnp: PnP ACPI init [ 0.678325] pnp: PnP ACPI: found 6 devices [ 0.699153] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.702758] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.705278] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.707555] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.709996] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.712759] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.716230] NET: Registered protocol family 2 [ 0.719803] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.727188] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.731770] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.737911] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.741989] TCP: Hash tables configured (established 65536 bind 65536) [ 0.745410] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.749172] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.753535] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.756789] NET: Registered protocol family 1 [ 0.759715] RPC: Registered named UNIX socket transport module. [ 0.762787] RPC: Registered udp transport module. [ 0.764774] RPC: Registered tcp transport module. [ 0.766520] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.769607] NET: Registered protocol family 44 [ 0.771364] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.773783] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.776487] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.779806] PCI: CLS 0 bytes, default 64 [ 0.782307] Unpacking initramfs... [ 2.287460] debug: unmapping init [mem 0xffff8a7ebcc64000-0xffff8a7ebffcffff] [ 2.291597] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.294407] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.297038] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.800449] Initialise system trusted keyrings [ 2.801965] Key type blacklist registered [ 2.804182] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.818692] zbud: loaded [ 2.823589] *** VALIDATE nfs *** [ 2.825050] *** VALIDATE nfs4 *** [ 2.826819] pstore: using deflate compression [ 2.830568] Platform Keyring initialized [ 2.942263] NET: Registered protocol family 38 [ 2.944467] Key type asymmetric registered [ 2.946506] Asymmetric key parser 'x509' registered [ 2.950169] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.954098] io scheduler mq-deadline registered [ 2.955810] io scheduler kyber registered [ 2.957767] io scheduler bfq registered [ 2.959812] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.963285] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.965940] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.969098] ACPI: Power Button [PWRF] [ 2.974879] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.981081] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.994586] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.024174] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.055158] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.060775] Non-volatile memory driver v1.3 [ 3.062732] Linux agpgart interface v0.103 [ 3.103605] virtio_blk virtio1: [vda] 133944 512-byte logical blocks (68.6 MB/65.4 MiB) [ 3.107594] vda: detected capacity change from 0 to 68579328 [ 3.125336] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.128810] vdb: detected capacity change from 0 to 1073741824 [ 3.136896] libphy: Fixed MDIO Bus: probed [ 3.142887] usbcore: registered new interface driver usbserial_generic [ 3.145588] usbserial: USB Serial support registered for generic [ 3.148237] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.153492] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.155833] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.158901] mousedev: PS/2 mouse device common for all mice [ 3.163826] rtc_cmos 00:05: RTC can wake from S4 [ 3.167519] rtc_cmos 00:05: registered as rtc0 [ 3.170166] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.170566] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.173147] intel_pstate: CPU model not supported [ 3.182040] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.183312] hid: raw HID events driver (C) Jiri Kosina [ 3.189361] usbcore: registered new interface driver usbhid [ 3.190781] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.191690] usbhid: USB HID core driver [ 3.191883] drop_monitor: Initializing network drop monitor service [ 3.197781] Initializing XFRM netlink socket [ 3.200068] NET: Registered protocol family 10 [ 3.203579] Segment Routing with IPv6 [ 3.205310] NET: Registered protocol family 17 [ 3.207642] mpls_gso: MPLS GSO support [ 3.214433] RAS: Correctable Errors collector initialized. [ 3.216454] AVX version of gcm_enc/dec engaged. [ 3.218174] AES CTR mode by8 optimization enabled [ 3.298748] sched_clock: Marking stable (3298724512, 0)->(4260324529, -961600017) [ 3.303500] registered taskstats version 1 [ 3.307266] Loading compiled-in X.509 certificates [ 3.309395] zswap: loaded using pool lzo/zbud [ 3.366071] Key type big_key registered [ 3.382248] Key type encrypted registered [ 3.383642] ima: No TPM chip found, activating TPM-bypass! [ 3.385950] ima: Allocated hash algorithm: sha1 [ 3.389627] ima: No architecture policies found [ 3.392294] evm: Initialising EVM extended attributes: [ 3.395314] evm: security.selinux [ 3.396899] evm: security.ima [ 3.398791] evm: security.capability [ 3.400614] evm: HMAC attrs: 0x1 [ 3.404821] rtc_cmos 00:05: setting system clock to 2026-04-01 11:05:48 UTC (1775041548) [ 3.410881] debug: unmapping init [mem 0xffffffffb4003000-0xffffffffb41fffff] [ 3.413860] debug: unmapping init [mem 0xffffffffb2d82000-0xffffffffb3058fff] [ 3.427176] Write protecting the kernel read-only data: 28672k [ 3.430685] debug: unmapping init [mem 0xffffffffb1403000-0xffffffffb15fffff] [ 3.433561] debug: unmapping init [mem 0xffffffffb1d14000-0xffffffffb1dfffff] [ 3.467996] 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.479422] systemd[1]: Detected virtualization kvm. [ 3.482292] systemd[1]: Detected architecture x86-64. [ 3.484461] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.514558] systemd[1]: No hostname configured. [ 3.516417] systemd[1]: Set hostname to . [ 3.518810] random: systemd: uninitialized urandom read (16 bytes read) [ 3.521777] systemd[1]: Initializing machine ID from random generator. [ 3.712813] random: systemd: uninitialized urandom read (16 bytes read) [ 3.715903] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 3.722251] random: systemd: uninitialized urandom read (16 bytes read) [ 3.724964] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 3.732690] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Setup Virtual Console... Starting Apply Kernel Variables... [ OK ] Reached target Timers. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Swap. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. 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.495676] device-mapper: uevent: version 1.0.3 [ 4.498465] 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... [ 4.853804] random: fast init done [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 5.246539] virtio_net virtio0 ens2: renamed from eth0 [ 5.315474] scsi host0: ata_piix [ 5.330436] scsi host1: ata_piix [ 5.332228] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.335281] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.043665] dracut-initqueue[577]: RTNETLINK answers: File exists [ 9.913500] random: crng init done [ 9.915426] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 11.703208] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Timers. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 15.039741] printk: systemd: 25 output lines suppressed due to ratelimiting [ 16.036596] SELinux: Disabled at runtime. [ 16.223570] 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) [ 16.249536] systemd[1]: Detected virtualization kvm. [ 16.253512] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 18.372365] systemd[1]: initrd-switch-root.service: Succeeded. [ 18.381180] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 18.397872] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 18.403992] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 18.411317] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 18.433370] systemd[1]: Starting Journal Service... Starting Journal Service... [ 18.446684] systemd[1]: Reached target rpc_pipefs.target. [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Forward Password Requests to Wall Directory Watch. Mounting Kernel Debug File System... [ OK ] Listening on udev Kernel Socket. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on initctl Compatibility Named Pipe. Starting Remount Root and Kernel File Systems... [ OK ] Stopped target Initrd Root File System. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... Mounting Huge Pages File System... [ OK ] Created slice system-getty.slice. Starting Apply Kernel Variables... Activating swap /dev/disk/by-label/SWAP... Mounting POSIX Message Queue File System... [ 19.163157] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Kernel Debug File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Reached target Swap. Starting Configure read-only root support... 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. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Mounted /mnt. [ 20.035518] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 21.419543] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 21.449084] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 22.339330] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 22.485180] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (7s / no limit) [** ] A start job is running for Configur…-only root support (8s / no limit) [*** ] A start job is running for Configur…-only root support (8s / no limit)[ 27.602033] Key type dns_resolver registered [ *** ] A start job is running for Configur…-only root support (9s / no limit) [ *** ] A start job is running for Configur…-only root support (9s / no limit) [ ***] A start job is running for Configur…only root support (10s / no limit)[ 28.907575] NFS: Registering the id_resolver key type [ 28.922583] Key type id_resolver registered [ 28.933791] Key type id_legacy registered [ **] A start job is running for Configur…only root support (10s / no limit) [ *] A start job is running for Configur…only root support (11s / no limit) [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Load/Save Random Seed... Starting Create Volatile Files and Directories... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting Network Manager Wait Online... [ OK ] Started Login Service. Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg453-client login: [ 71.487868] hrtimer: interrupt took 3011922 ns [ 97.831884] libcfs: loading out-of-tree module taints kernel. [ 97.992497] Key type ._llcrypt registered [ 97.995345] Key type .llcrypt registered [ 98.550571] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 98.576532] alg: No test for adler32 (adler32-zlib) [ 100.083026] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 100.997975] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 102.728284] Key type lgssc registered [ 105.248569] Lustre: Echo OBD driver; http://www.lustre.org/ [ 258.068836] Lustre: Mounted lustre-client [ 263.732758] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 283.616791] Lustre: lustre-OST0000-osc-ffff8a7f130cf000: disconnect after 23s idle [ 285.988310] Lustre: DEBUG MARKER: oleg453-client.virtnet: executing check_logdir /tmp/testlogs/ [ 292.021413] Lustre: DEBUG MARKER: oleg453-client.virtnet: executing yml_node [ 296.747620] Lustre: DEBUG MARKER: Client: 2.17.51.74 [ 299.829306] Lustre: DEBUG MARKER: MDS: 2.17.51.74 [ 303.398431] Lustre: DEBUG MARKER: OSS: 2.17.51.74 [ 305.564244] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Wed Apr 1 07:10:49 EDT 2026 [ 327.156834] Lustre: DEBUG MARKER: excepting tests: 27 40a 102 [ 329.678682] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 331.792835] Lustre: DEBUG MARKER: === sanityn: start setup 07:11:14 (1775041874) === [ 332.989645] Lustre: Mounted lustre-client [ 338.381782] Lustre: DEBUG MARKER: oleg453-client.virtnet: executing check_config_client /mnt/lustre [ 358.539344] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 371.466659] Lustre: DEBUG MARKER: === sanityn: finish setup 07:11:54 (1775041914) === [ 374.051316] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 07:11:57 (1775041917) [ 383.306040] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 07:12:06 (1775041926) [ 390.661527] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 07:12:14 (1775041934) [ 399.090514] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 07:12:22 (1775041942) [ 407.076374] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 07:12:30 (1775041950) [ 414.343434] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 07:12:37 (1775041957) [ 421.371741] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 07:12:45 (1775041965) [ 428.531979] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 07:12:52 (1775041972) [ 430.090408] Lustre: DEBUG MARKER: SKIP: sanityn test_2f needs >= 2 MDTs [ 432.020546] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 07:12:55 (1775041975) [ 440.209253] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 07:13:03 (1775041983) [ 446.958135] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 07:13:10 (1775041990) [ 454.112834] Lustre: lustre-OST0001-osc-ffff8a7f130cf000: disconnect after 22s idle [ 454.119167] Lustre: Skipped 1 previous similar message [ 455.400638] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 07:13:18 (1775041998) [ 462.897360] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 07:13:26 (1775042006) [ 469.473558] Lustre: lustre-OST0000-osc-ffff8a7f085c6800: disconnect after 21s idle [ 470.726844] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 07:13:34 (1775042014) [ 478.980836] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 07:13:41 (1775042021) [ 487.463112] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 07:13:50 (1775042030) [ 489.953493] Lustre: lustre-OST0001-osc-ffff8a7f130cf000: disconnect after 22s idle [ 496.186846] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 07:13:59 (1775042039) [ 504.699343] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 07:14:08 (1775042048) [ 514.408336] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 07:14:17 (1775042057) [ 522.753711] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 07:14:26 (1775042066) [ 530.642580] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 07:14:34 (1775042074) [ 531.553789] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205272502301 file: /mnt/lustre/lockdir/lockfile=144115205272502299 [ 678.858047] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 07:17:02 (1775042222) [ 688.280463] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 07:17:12 (1775042232) [ 695.744914] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 07:17:19 (1775042239) [ 703.264891] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 07:17:26 (1775042246) [ 709.795776] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 07:17:33 (1775042253) [ 716.609116] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 07:17:40 (1775042260) [ 718.736466] Lustre: DEBUG MARKER: chmod [ 725.583345] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 07:17:49 (1775042269) [ 759.764733] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7531520KB free > 800000KB, increase MAXFREE (or reduce fs size) [ 774.156031] Lustre: DEBUG MARKER: == sanityn test 16a: 500 iterations of dual-mount fsx ==== 07:18:37 (1775042317) [ 831.634001] Lustre: DEBUG MARKER: == sanityn test 16b: 500 iterations of dual-mount fsx at small size ========================================================== 07:19:35 (1775042375) [ 862.162449] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 07:20:06 (1775042406) [ 864.799174] Lustre: DEBUG MARKER: SKIP: sanityn test_16c dio on ldiskfs only [ 866.478952] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 07:20:10 (1775042410) [ 909.794319] Lustre: lustre-OST0000-osc-ffff8a7f085c6800: disconnect after 21s idle [ 912.857833] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 07:20:56 (1775042456) [ 920.229584] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 07:21:03 (1775042463) [ 921.384351] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 921.485761] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 921.597964] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 921.680483] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 921.749226] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 921.821086] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 921.893661] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 921.992291] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 922.068606] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 922.153067] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 922.239631] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 922.347443] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 922.439995] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 922.524370] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 922.643459] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 922.742565] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 922.832563] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 922.912972] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 923.015389] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 923.127517] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 923.222656] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 923.291181] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 923.393136] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 923.530338] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 923.633640] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 923.721732] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 923.828441] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 923.935791] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 924.077020] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 924.196829] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 924.326444] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 924.446202] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 924.548435] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 924.661987] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 924.747300] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 924.838734] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 924.939230] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 925.030419] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 925.094737] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 925.171572] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 925.277062] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 925.381461] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 925.466775] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 925.575534] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 925.695578] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 925.826417] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 925.918757] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 926.031855] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 926.145889] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 926.263900] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 926.347256] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 926.458979] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 926.549398] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 926.635628] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 926.724471] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 926.861899] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 926.979094] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 927.105373] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 927.203388] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 927.308080] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 927.433357] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 927.533648] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 927.617948] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 927.697494] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 927.773116] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 927.890510] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 927.997617] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 928.073790] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 928.175139] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 928.279275] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 928.375502] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 928.483358] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 928.617553] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 928.724281] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 928.818819] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 928.896812] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 929.002576] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 929.108723] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 929.226821] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 929.334919] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 929.421809] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 929.510374] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 929.599699] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 929.709571] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 929.836534] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 929.947086] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 930.049457] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 930.120058] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 930.222491] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 930.306232] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 930.384361] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 930.499254] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 930.605480] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 930.694956] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 930.776943] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 930.851418] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 930.966400] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 931.088145] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 931.193592] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 931.251852] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 931.328757] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 931.402730] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 931.528744] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 931.636642] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 931.717656] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 931.862596] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 931.963348] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 932.047492] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 932.183084] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 932.288113] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 932.396582] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 932.495567] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 932.589773] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 932.643363] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 932.737774] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 932.868848] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 932.996088] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 933.114196] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 933.222626] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 933.315073] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 933.419051] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 933.510820] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 933.588418] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 933.694194] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 933.788115] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 933.871773] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 933.954428] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 934.022506] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 934.105015] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 934.196806] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 934.295707] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 934.404826] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 934.471221] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 934.580226] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 934.687593] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 934.769304] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 934.863061] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 934.978519] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 935.150568] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 935.286744] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 935.392664] Lustre: lustre-OST0001-osc-ffff8a7f085c6800: disconnect after 21s idle [ 935.410797] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 935.511985] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 935.595525] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 935.711222] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 935.812732] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 935.918846] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 936.066613] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 936.141369] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 936.205964] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 936.287290] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 936.398283] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 936.480925] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 936.523237] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 936.593977] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 936.654047] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 936.739962] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 936.833751] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 936.911428] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 937.012277] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 937.095547] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 937.184793] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 937.264444] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 937.381896] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 937.511304] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 937.606173] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 937.708557] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 937.798550] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 937.901081] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 938.011837] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 938.067555] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 938.115661] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 938.182522] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 938.252738] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 938.331903] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 938.406676] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 938.459706] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 938.623931] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 938.750777] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 938.891754] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 939.004091] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 939.144923] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 939.267083] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 939.364021] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 939.481710] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 939.587733] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 939.689814] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 939.814936] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 939.992634] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 940.115483] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 940.237473] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 940.309225] rw_seq_cst_vs_d (29508): drop_caches: 3 [ 950.576072] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 07:21:33 (1775042493) [ 950.905976] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 950.933193] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 951.011918] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 951.054443] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 951.099238] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 951.133047] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 951.176684] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 951.322448] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 951.651394] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 951.711969] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 951.815057] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 951.944713] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 952.051858] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 952.150555] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 952.198546] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 952.368281] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 952.458921] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 952.560152] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 952.717268] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 952.897730] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 953.037090] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 953.104083] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 953.158253] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 953.235046] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 953.385578] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 953.541646] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 953.664025] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 953.735711] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 953.904113] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 954.030324] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 954.103220] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 954.241485] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 954.302226] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 954.404747] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 954.495571] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 954.629837] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 954.753895] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 954.901068] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 955.127256] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 955.152872] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 955.301648] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 955.548664] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 955.681859] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 955.860400] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 956.104254] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 956.285743] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 956.416977] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 956.597752] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 956.680516] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 956.818364] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 956.855513] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 957.035990] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 957.109375] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 957.197167] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 957.383110] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 957.593751] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 957.645643] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 957.753521] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 957.927226] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 958.069818] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 958.372947] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 958.451124] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 958.714520] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 958.841167] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 958.905157] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 959.009553] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 959.158905] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 959.280706] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 959.340126] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 959.581897] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 959.751057] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 959.786741] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 959.853932] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 959.943120] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 960.053982] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 960.193578] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 960.281483] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 960.703244] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 960.915694] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 960.992368] Lustre: lustre-OST0000-osc-ffff8a7f085c6800: disconnect after 21s idle [ 961.004978] Lustre: Skipped 1 previous similar message [ 961.032535] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 961.273289] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 961.383562] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 961.422319] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 961.616971] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 961.687691] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 961.885986] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 961.910397] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 962.007241] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 962.094239] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 962.202413] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 962.270483] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 962.374377] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 962.530351] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 962.652654] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 962.724739] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 962.858889] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 962.948652] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 963.022329] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 963.120960] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 963.313302] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 963.524194] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 963.589503] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 963.776250] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 963.835107] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 963.895047] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 963.922813] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 964.164827] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 964.216665] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 964.474871] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 964.632120] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 964.841605] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 964.905165] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 965.049901] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 965.235303] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 965.385385] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 965.631041] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 965.860590] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 966.001104] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 966.108180] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 966.196308] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 966.260845] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 966.385458] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 966.430194] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 966.557095] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 966.741871] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 966.891837] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 967.139771] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 967.245536] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 967.364721] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 967.391514] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 967.736701] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 967.917283] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 968.049958] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 968.221396] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 968.358480] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 968.499444] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 968.675643] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 968.760379] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 968.835345] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 968.897548] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 969.066774] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 969.122659] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 969.307262] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 969.369860] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 969.470424] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 969.573455] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 969.637887] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 969.792522] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 969.916747] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 970.078405] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 970.152944] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 970.218753] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 970.330909] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 970.428205] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 970.567595] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 970.664131] rw_seq_cst_vs_d (30084): drop_caches: 3 [ 978.203977] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 07:22:02 (1775042522) [ 985.170471] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 07:22:08 (1775042528) [ 991.754256] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 07:22:15 (1775042535) [ 1032.438647] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 07:22:56 (1775042576) [ 1032.976415] bash (32545): drop_caches: 3 [ 1036.167504] bash (32545): drop_caches: 3 [ 1039.573831] bash (32545): drop_caches: 3 [ 1043.340932] bash (32545): drop_caches: 3 [ 1046.468833] bash (32545): drop_caches: 3 [ 1049.600264] bash (32545): drop_caches: 3 [ 1052.746904] bash (32545): drop_caches: 3 [ 1055.842388] bash (32545): drop_caches: 3 [ 1058.951558] bash (32545): drop_caches: 3 [ 1062.070698] bash (32545): drop_caches: 3 [ 1065.174485] bash (32545): drop_caches: 3 [ 1068.336477] bash (32545): drop_caches: 3 [ 1071.480556] bash (32545): drop_caches: 3 [ 1074.613554] bash (32545): drop_caches: 3 [ 1077.783908] bash (32545): drop_caches: 3 [ 1080.973967] bash (32545): drop_caches: 3 [ 1085.504515] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 07:23:49 (1775042629) [ 1095.560568] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 07:23:59 (1775042639) [ 1136.517476] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 07:24:40 (1775042680) [ 1139.016542] Lustre: DEBUG MARKER: SKIP: sanityn test_19 not cache-capable obdfilter [ 1140.611257] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 07:24:44 (1775042684) [ 1147.148467] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 07:24:50 (1775042690) [ 1148.391534] Lustre: lustre-OST0001-osc-ffff8a7f130cf000: disconnect after 20s idle [ 1148.397135] Lustre: Skipped 1 previous similar message [ 1152.957748] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 07:24:56 (1775042696) [ 1220.718061] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 07:26:04 (1775042764) [ 1226.450325] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 07:26:10 (1775042770) [ 1231.698048] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 07:26:15 (1775042775) [ 1237.725997] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 07:26:21 (1775042781) [ 1239.221872] Lustre: DEBUG MARKER: SKIP: sanityn test_25b needs >= 2 MDTs [ 1240.656240] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 07:26:24 (1775042784) [ 1246.964481] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 07:26:31 (1775042791) [ 1253.724718] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 07:26:37 (1775042797) [ 1259.738313] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1260.998099] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 07:26:45 (1775042805) [ 1268.031546] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 07:26:52 (1775042812) [ 1268.486310] Lustre: *** cfs_fail_loc=314, val=0*** [ 1269.537075] Lustre: *** cfs_fail_loc=314, val=0*** [ 1269.542886] Lustre: Skipped 2 previous similar messages [ 1274.762137] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 07:26:58 (1775042818) [ 1287.229259] Lustre: *** cfs_fail_loc=314, val=0*** [ 1287.286236] LustreError: lustre-OST0000-osc-ffff8a7f085c6800: operation ldlm_enqueue to node 192.168.204.153@tcp failed: rc = -107 [ 1287.292240] Lustre: lustre-OST0000-osc-ffff8a7f085c6800: Connection to lustre-OST0000 (at 192.168.204.153@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1287.304819] LustreError: lustre-OST0000-osc-ffff8a7f085c6800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1287.314542] Lustre: 2358:0:(llite_lib.c:4149:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.153@tcp:/lustre/fid: [0x200000402:0x22:0x0]// may get corrupted (rc -108) [ 1287.351541] LustreError: 41783:0:(ldlm_resource.c:1170:ldlm_resource_complain()) lustre-OST0000-osc-ffff8a7f085c6800: namespace resource [0x240000400:0x35:0x0].0x0 (ffff8a7f127d1000) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 1287.376245] Lustre: lustre-OST0000-osc-ffff8a7f085c6800: Connection restored to 192.168.204.153@tcp (at 192.168.204.153@tcp) [ 1292.808754] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 07:27:17 (1775042837) [ 1293.164445] LustreError: 42365:0:(file.c:817:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 1296.200129] LustreError: 42365:0:(file.c:817:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 1300.785292] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 07:27:24 (1775042844) [ 1307.041234] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 07:27:31 (1775042851) [ 1313.002405] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 1314.349562] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 07:27:38 (1775042858) [ 1315.564331] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 1317.215781] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 07:27:41 (1775042861) [ 1318.422794] Lustre: DEBUG MARKER: SKIP: sanityn test_33c needs >= 2 MDTs [ 1319.955207] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 07:27:43 (1775042863) [ 1321.485378] Lustre: DEBUG MARKER: SKIP: sanityn test_33d needs >= 2 MDTs [ 1323.033622] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 07:27:46 (1775042866) [ 1324.231879] Lustre: DEBUG MARKER: SKIP: sanityn test_33e needs >= 2 MDTs [ 1325.851649] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 07:27:49 (1775042869) [ 1332.704232] Lustre: lustre-OST0000-osc-ffff8a7f085c6800: disconnect after 24s idle [ 1332.712559] Lustre: Skipped 4 previous similar messages [ 1378.788954] Lustre: lustre-OST0001-osc-ffff8a7f130cf000: Connection to lustre-OST0001 (at 192.168.204.153@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1378.806686] LustreError: lustre-OST0001-osc-ffff8a7f130cf000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1378.815886] LustreError: lustre-OST0001-osc-ffff8a7f085c6800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1378.821445] Lustre: lustre-OST0001-osc-ffff8a7f130cf000: Connection restored to 192.168.204.153@tcp (at 192.168.204.153@tcp) [ 1378.833732] Lustre: Skipped 1 previous similar message [ 1398.223444] Lustre: lustre-OST0000-osc-ffff8a7f130cf000: Connection to lustre-OST0000 (at 192.168.204.153@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1398.232087] Lustre: Skipped 1 previous similar message [ 1398.241855] LustreError: lustre-OST0000-osc-ffff8a7f130cf000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1398.257114] Lustre: lustre-OST0000-osc-ffff8a7f130cf000: Connection restored to 192.168.204.153@tcp (at 192.168.204.153@tcp) [ 1412.428200] Lustre: DEBUG MARKER: oleg453-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8a7f085c6800.ost_server_uuid 50 [ 1413.390764] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8a7f085c6800.ost_server_uuid in FULL state after 0 sec [ 1415.832546] Lustre: DEBUG MARKER: oleg453-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8a7f085c6800.ost_server_uuid 50 [ 1416.961175] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8a7f085c6800.ost_server_uuid in IDLE state after 0 sec [ 1420.315777] Lustre: DEBUG MARKER: oleg453-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8a7f085c6800.ost_server_uuid 50 [ 1421.368288] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8a7f085c6800.ost_server_uuid in FULL state after 0 sec [ 1424.022446] Lustre: DEBUG MARKER: oleg453-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8a7f085c6800.ost_server_uuid 50 [ 1425.008572] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8a7f085c6800.ost_server_uuid in IDLE state after 0 sec [ 1431.151224] Lustre: DEBUG MARKER: oleg453-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8a7f085c6800.ost_server_uuid 50 [ 1432.088982] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8a7f085c6800.ost_server_uuid in FULL state after 0 sec [ 1434.509381] Lustre: DEBUG MARKER: oleg453-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8a7f085c6800.ost_server_uuid 50 [ 1435.497838] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8a7f085c6800.ost_server_uuid in IDLE state after 0 sec [ 1436.666199] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 07:29:40 (1775042980) [ 1438.531810] Lustre: DEBUG MARKER: Race attempt 0 [ 1440.718080] Lustre: DEBUG MARKER: Wait for 48991 49071 for 60 sec... [ 1506.026917] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 07:30:50 (1775043050) [ 1513.242528] Lustre: DEBUG MARKER: start test - cycle (0) [ 1539.258530] Lustre: DEBUG MARKER: start test - cycle (1) [ 1542.624222] Lustre: lustre-OST0001-osc-ffff8a7f085c6800: disconnect after 20s idle [ 1542.627257] Lustre: Skipped 3 previous similar messages [ 1564.615251] Lustre: DEBUG MARKER: start test - cycle (2) [ 1589.996519] Lustre: DEBUG MARKER: start test - cycle (3) [ 1615.080690] Lustre: DEBUG MARKER: start test - cycle (4) [ 1641.082897] Lustre: DEBUG MARKER: start test - cycle (5) [ 1666.548937] Lustre: DEBUG MARKER: start test - cycle (6) [ 1692.480854] Lustre: DEBUG MARKER: start test - cycle (7) [ 1717.738357] Lustre: DEBUG MARKER: start test - cycle (8) [ 1742.584410] Lustre: DEBUG MARKER: start test - cycle (9) [ 1764.968141] Lustre: DEBUG MARKER: start test - cycle (10) [ 1793.110686] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 07:35:37 (1775043337) [ 1808.864282] Lustre: lustre-OST0000-osc-ffff8a7f130cf000: disconnect after 20s idle [ 1808.874753] Lustre: Skipped 9 previous similar messages [ 1875.241792] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 07:36:59 (1775043419) [ 1880.996848] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 07:37:05 (1775043425) [ 1886.843408] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 07:37:11 (1775043431) [ 1892.541241] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 07:37:16 (1775043436) [ 1892.798785] Lustre: *** cfs_fail_loc=411, val=0*** [ 1897.311588] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 1898.412805] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 07:37:22 (1775043442) [ 1911.683954] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 07:37:35 (1775043455) [ 1925.058762] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 07:37:49 (1775043469) [ 1938.840556] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 07:38:02 (1775043482) [ 1951.916719] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 07:38:16 (1775043496) [ 1962.334805] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 07:38:26 (1775043506) [ 1972.663200] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 07:38:36 (1775043516) [ 1982.318235] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 07:38:46 (1775043526) [ 1990.823390] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 07:38:55 (1775043535) [ 1999.512766] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 07:39:03 (1775043543) [ 2008.687488] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 07:39:12 (1775043552) [ 2017.802937] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 07:39:22 (1775043562) [ 2027.903584] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 07:39:32 (1775043572) [ 2648.544683] Lustre: lustre-OST0000-osc-ffff8a7f085c6800: disconnect after 24s idle [ 2648.548452] Lustre: Skipped 8 previous similar messages [ 2872.019431] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 07:53:36 (1775044416) [ 2879.014567] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 07:53:43 (1775044423) [ 2886.396754] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 07:53:50 (1775044430) [ 2893.819963] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 07:53:58 (1775044438) [ 2901.222830] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 07:54:05 (1775044445) [ 2908.624338] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 07:54:12 (1775044452) [ 2915.747184] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 07:54:20 (1775044460) [ 2923.199356] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 07:54:27 (1775044467) [ 2930.938924] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 07:54:35 (1775044475) [ 2968.840918] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 07:55:13 (1775044513) [ 2976.106935] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 07:55:20 (1775044520) [ 2982.937579] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 07:55:27 (1775044527) [ 2989.090109] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 07:55:33 (1775044533) [ 2995.489491] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 07:55:39 (1775044539) [ 3002.180464] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 07:55:46 (1775044546) [ 3009.044840] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 07:55:53 (1775044553) [ 3015.855733] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 07:56:00 (1775044560) [ 3016.568661] Lustre: DEBUG MARKER: SKIP: sanityn test_43i needs >= 2 MDTs [ 3017.295210] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 07:56:01 (1775044561) [ 3071.224373] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 07:56:55 (1775044615) [ 3319.265558] Lustre: lustre-OST0001-osc-ffff8a7f130cf000: disconnect after 24s idle [ 3319.269655] Lustre: Skipped 5 previous similar messages [ 3568.507714] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 08:05:13 (1775045113) [ 3573.682151] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 08:05:18 (1775045118) [ 3578.850458] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 08:05:23 (1775045123) [ 3584.175852] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 08:05:28 (1775045128) [ 3589.578096] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 08:05:34 (1775045134) [ 3594.923460] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 08:05:39 (1775045139) [ 3600.228699] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 08:05:44 (1775045144) [ 3605.641702] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 08:05:50 (1775045150) [ 3610.774461] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 08:05:55 (1775045155) [ 3611.247647] Lustre: DEBUG MARKER: SKIP: sanityn test_44i needs >= 2 MDTs [ 3611.757940] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 08:05:56 (1775045156) [ 3644.321833] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 08:06:28 (1775045188) [ 3649.813714] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 08:06:34 (1775045194) [ 3655.183027] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 08:06:39 (1775045199) [ 3660.518207] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 08:06:45 (1775045205) [ 3665.761225] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 08:06:50 (1775045210) [ 3670.959798] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 08:06:55 (1775045215) [ 3676.142612] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 08:07:00 (1775045220) [ 3680.875700] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 08:07:05 (1775045225) [ 3681.433662] Lustre: DEBUG MARKER: SKIP: sanityn test_45i needs >= 2 MDTs [ 3682.012391] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 08:07:06 (1775045226) [ 3923.424169] Lustre: lustre-OST0001-osc-ffff8a7f130cf000: disconnect after 23s idle [ 3923.426374] Lustre: Skipped 2 previous similar messages [ 4115.228294] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 08:14:19 (1775045659) [ 4120.602498] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 08:14:25 (1775045665) [ 4125.706123] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 08:14:30 (1775045670) [ 4130.871755] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 08:14:35 (1775045675) [ 4136.120530] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 08:14:40 (1775045680) [ 4141.270697] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 08:14:45 (1775045685) [ 4146.497678] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 08:14:51 (1775045691) [ 4151.802510] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 08:14:56 (1775045696) [ 4157.168770] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 08:15:01 (1775045701) [ 4157.678383] Lustre: DEBUG MARKER: SKIP: sanityn test_46i needs >= 2 MDTs [ 4158.238765] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 08:15:02 (1775045702) [ 4158.748198] Lustre: DEBUG MARKER: SKIP: sanityn test_47a needs >= 2 MDTs [ 4159.310649] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 08:15:03 (1775045703) [ 4159.826990] Lustre: DEBUG MARKER: SKIP: sanityn test_47b needs >= 2 MDTs [ 4160.407175] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 08:15:05 (1775045705) [ 4160.913035] Lustre: DEBUG MARKER: SKIP: sanityn test_47c needs >= 2 MDTs [ 4161.438650] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 08:15:06 (1775045706) [ 4161.946599] Lustre: DEBUG MARKER: SKIP: sanityn test_47d needs >= 2 MDTs [ 4162.537454] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 08:15:07 (1775045707) [ 4163.074972] Lustre: DEBUG MARKER: SKIP: sanityn test_47e needs >= 2 MDTs [ 4163.655345] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 08:15:08 (1775045708) [ 4164.159483] Lustre: DEBUG MARKER: SKIP: sanityn test_47f needs >= 2 MDTs [ 4164.733422] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 08:15:09 (1775045709) [ 4165.213450] Lustre: DEBUG MARKER: SKIP: sanityn test_47g needs >= 2 MDTs [ 4165.768159] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 08:15:10 (1775045710) [ 4165.859186] LustreError: 19024:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 4167.944110] LustreError: 19024:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 4173.122034] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 08:15:17 (1775045717) [ 4177.174356] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 08:15:21 (1775045721) [ 4177.265457] LustreError: 217222:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4181.328141] LustreError: 217222:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 4181.335807] LustreError: 217222:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4185.392111] LustreError: 217222:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 4185.405640] LustreError: 217229:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4189.464068] LustreError: 217229:0:(glimpse.c:206:__cl_glimpse_size()) cfs_fail_timeout id 1404 awake [ 4191.609673] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 08:15:36 (1775045736) [ 4198.390922] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 08:15:43 (1775045743) [ 4201.739387] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 08:15:46 (1775045746) [ 4206.091993] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 08:15:50 (1775045750) [ 4230.428075] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 08:16:15 (1775045775) [ 4238.068419] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 08:16:22 (1775045782) [ 4245.530974] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 08:16:30 (1775045790) [ 4258.384304] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 08:16:43 (1775045803) [ 4268.009530] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 08:16:52 (1775045812) [ 4268.525718] Lustre: DEBUG MARKER: SKIP: sanityn test_55e needs >= 2 MDTs [ 4269.082381] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 08:16:53 (1775045813) [ 4281.988303] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 08:17:06 (1775045826) [ 4296.037435] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 08:17:20 (1775045840) [ 4327.144854] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 08:17:51 (1775045871) [ 4367.861991] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 08:18:32 (1775045912) [ 4370.240582] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 4373.151736] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 08:18:37 (1775045917) [ 4376.729268] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 08:18:41 (1775045921) [ 4377.589823] Lustre: DEBUG MARKER: SKIP: sanityn test_71a ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 4378.307021] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 08:18:42 (1775045922) [ 4378.943696] Lustre: DEBUG MARKER: SKIP: sanityn test_71b ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 4379.625388] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 08:18:44 (1775045924) [ 4380.211589] Lustre: DEBUG MARKER: SKIP: sanityn test_71c support only ldiskfs ost [ 4380.863239] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 08:18:45 (1775045925) [ 4381.472083] Lustre: DEBUG MARKER: SKIP: sanityn test_71d ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 4382.141870] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 08:18:46 (1775045926) [ 4384.570898] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 08:18:49 (1775045929) [ 4386.892419] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 08:18:51 (1775045931) [ 4389.984899] LustreError: lustre-MDT0000-mdc-ffff8a7f085c6800: operation ldlm_enqueue to node 192.168.204.153@tcp failed: rc = -35 [ 4389.988047] LustreError: Skipped 1 previous similar message [ 4393.001965] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 08:18:57 (1775045937) [ 4393.138767] LustreError: 2358:0:(osc_request.c:3138:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 4395.192120] LustreError: 2358:0:(osc_request.c:3138:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 4400.402143] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 08:19:04 (1775045944) [ 4422.556986] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 08:19:27 (1775045967) [ 4425.603455] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 08:19:30 (1775045970) [ 4429.681146] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 08:19:34 (1775045974) [ 4434.983220] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 08:19:39 (1775045979) [ 4439.931418] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 08:19:44 (1775045984) [ 4447.597385] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 08:19:52 (1775045992) [ 4455.138309] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 08:19:59 (1775045999) [ 4458.530085] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 08:20:03 (1775046003) [ 4462.346961] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 08:20:07 (1775046007) [ 4469.504733] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 08:20:14 (1775046014) [ 4518.045018] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 08:21:02 (1775046062) [ 4542.944144] Lustre: lustre-OST0001-osc-ffff8a7f130cf000: disconnect after 22s idle [ 4542.946796] Lustre: Skipped 5 previous similar messages [ 4651.170630] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 08:23:15 (1775046195) [ 4783.726761] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 08:25:28 (1775046328) [ 5137.998483] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 08:31:22 (1775046682) [ 5162.464120] Lustre: lustre-OST0001-osc-ffff8a7f130cf000: disconnect after 23s idle [ 5162.467359] Lustre: Skipped 15 previous similar messages [ 5175.387552] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 08:32:00 (1775046720) [ 5211.726186] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 08:32:36 (1775046756) [ 5242.953358] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 08:33:07 (1775046787) [ 5323.905957] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 08:34:28 (1775046868) [ 5389.394640] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 08:35:34 (1775046934) [ 5503.428034] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 08:37:28 (1775047048) [ 5504.353522] LustreError: 258676:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f130cf000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5504.366365] Lustre: Unmounted lustre-client [ 5505.228681] LustreError: 258689:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f085c6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5505.231892] LustreError: 258689:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 5505.244675] Lustre: Unmounted lustre-client [ 5546.934130] Lustre: Mounted lustre-client [ 5548.450782] Lustre: Mounted lustre-client [ 5549.451373] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 5636.143291] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 08:39:40 (1775047180) [ 5643.761427] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 08:39:48 (1775047188) [ 5646.935991] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 08:39:51 (1775047191) [ 5697.125346] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 08:40:41 (1775047241) [ 5763.034973] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 08:41:47 (1775047307) [ 5763.552208] Lustre: lustre-OST0000-osc-ffff8a7f069e9800: disconnect after 24s idle [ 5763.555430] Lustre: Skipped 17 previous similar messages [ 5766.630209] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 08:41:51 (1775047311) [ 5803.217081] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 08:42:27 (1775047347) [ 5814.162319] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 08:42:38 (1775047358) [ 5854.427796] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 08:43:19 (1775047399) [ 5857.115400] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 08:43:21 (1775047401) [ 5869.658171] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 08:43:34 (1775047414) [ 5870.138487] Lustre: DEBUG MARKER: SKIP: sanityn test_80a needs >= 2 MDTs [ 5870.657347] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 08:43:35 (1775047415) [ 5871.109495] Lustre: DEBUG MARKER: SKIP: sanityn test_80b needs >= 2 MDTs [ 5871.650254] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 08:43:36 (1775047416) [ 5872.119944] Lustre: DEBUG MARKER: SKIP: sanityn test_81a needs >= 2 MDTs [ 5872.654244] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 08:43:37 (1775047417) [ 5873.166150] Lustre: DEBUG MARKER: SKIP: sanityn test_81b We need at least 2 MDTs for this test [ 5873.680901] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 08:43:38 (1775047418) [ 5874.158772] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 5874.671893] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 08:43:39 (1775047419) [ 5899.037940] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 08:44:03 (1775047443) [ 5901.422930] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 08:44:06 (1775047446) [ 5901.989169] Lustre: DEBUG MARKER: SKIP: sanityn test_83 needs >= 2 MDTs [ 5902.582711] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 08:44:07 (1775047447) [ 5910.128810] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 08:44:14 (1775047454) [ 5913.159579] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 08:44:17 (1775047457) [ 5913.660118] Lustre: DEBUG MARKER: SKIP: sanityn test_90 needs >= 2 MDTs [ 5914.209375] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 08:44:18 (1775047458) [ 5914.708227] Lustre: DEBUG MARKER: SKIP: sanityn test_91 needs >= 2 MDTs [ 5915.264234] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 08:44:19 (1775047459) [ 5915.719205] Lustre: DEBUG MARKER: SKIP: sanityn test_92 needs >= 2 MDTs [ 5916.248126] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 08:44:20 (1775047460) [ 5924.814820] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 08:44:29 (1775047469) [ 5924.890680] Lustre: DEBUG MARKER: write [ 5924.907357] LustreError: 260759:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 5926.911365] Lustre: DEBUG MARKER: kill 288699 [ 5926.913232] LustreError: 288699:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 5930.008050] LustreError: 260759:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 5932.952086] LustreError: 288699:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 5934.876403] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 08:44:39 (1775047479) [ 5937.016516] LustreError: 289305:0:(rw.c:1868:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 5947.112086] LustreError: 289305:0:(rw.c:1868:ll_readpage()) cfs_fail_timeout id 1422 awake [ 5949.226312] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 08:44:53 (1775047493) [ 5949.308280] LustreError: 289884:0:(rw.c:2116:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 5951.392087] LustreError: 289884:0:(rw.c:2116:ll_readpage()) cfs_fail_timeout interrupted [ 5957.185244] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 08:45:01 (1775047501) [ 5957.645979] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 5958.153913] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 08:45:02 (1775047502) [ 5960.170604] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 08:45:04 (1775047504) [ 5962.124598] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 08:45:06 (1775047506) [ 5964.094991] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 08:45:08 (1775047508) [ 5966.045170] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 08:45:10 (1775047510) [ 5968.095061] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 08:45:12 (1775047512) [ 5970.122724] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 08:45:14 (1775047514) [ 5973.045122] Lustre: DEBUG MARKER: SKIP: sanityn test_102 skipping ALWAYS excluded test 102 [ 5973.553494] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 08:45:18 (1775047518) [ 5974.132426] Lustre: *** cfs_fail_loc=415, val=0*** [ 5980.544280] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 08:45:25 (1775047525) [ 5981.008095] Lustre: DEBUG MARKER: SKIP: sanityn test_104 ldiskfs only test [ 5981.519433] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 08:45:26 (1775047526) [ 5981.585743] LustreError: 260759:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 sleeping for 5000ms [ 5981.589753] LustreError: 260759:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 3 previous similar messages [ 5986.680076] LustreError: 264923:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [ 5986.682305] LustreError: 264923:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 2 previous similar messages [ 5996.872067] LustreError: 260087:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) cfs_fail_timeout id 416 awake [ 5996.874232] LustreError: 260087:0:(osc_lock.c:435:__osc_dlm_blocking_ast()) Skipped 4 previous similar messages [ 6004.017603] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 08:45:48 (1775047548) [ 6004.506411] Lustre: DEBUG MARKER: SKIP: sanityn test_106a Test only for ldiskfs and statx() supported [ 6005.019900] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 08:45:49 (1775047549) [ 6007.269273] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 08:45:51 (1775047551) [ 6009.302384] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 08:45:53 (1775047553) [ 6013.332774] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 08:45:58 (1775047558) [ 6021.542455] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 08:46:06 (1775047566) [ 6021.669493] LustreError: 258476:0:(osc_request.c:2989:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 6021.673541] LustreError: 258476:0:(osc_request.c:2989:osc_build_rpc()) Skipped 5 previous similar messages [ 6025.736100] LustreError: 258476:0:(osc_request.c:2989:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 6025.738198] LustreError: 258476:0:(osc_request.c:2989:osc_build_rpc()) Skipped 1 previous similar message [ 6027.818256] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 08:46:12 (1775047572) [ 6028.533863] LustreError: 299824:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f069e9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6028.536760] LustreError: 299824:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 6028.549778] Lustre: Unmounted lustre-client [ 6029.278749] Lustre: DEBUG MARKER: Iteration 1 [ 6029.366141] LustreError: 300004:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6029.366352] LustreError: 300005:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6029.370214] LustreError: 300004:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6029.414883] Lustre: Mounted lustre-client [ 6029.416671] Lustre: Skipped 1 previous similar message [ 6030.821323] Key type lgssc unregistered [ 6030.942492] LNet: 300349:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6030.944886] LNetError: 300349:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6030.953891] LNet: Removed LNI 192.168.204.53@tcp [ 6031.250136] Key type .llcrypt unregistered [ 6031.251951] Key type ._llcrypt unregistered [ 6031.538578] Key type ._llcrypt registered [ 6031.539703] Key type .llcrypt registered [ 6031.781379] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6031.786913] alg: No test for adler32 (adler32-zlib) [ 6032.746244] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6032.998913] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6034.600119] Key type lgssc registered [ 6035.101417] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6038.915369] Lustre: DEBUG MARKER: Iteration 2 [ 6039.025139] LustreError: 301169:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6039.026419] LustreError: 301170:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6039.029433] LustreError: 301169:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6039.824331] Lustre: Mounted lustre-client [ 6039.826184] Lustre: Skipped 1 previous similar message [ 6040.278164] LustreError: 301276:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f11ca3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6040.281915] LustreError: 301276:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6040.316595] Lustre: Unmounted lustre-client [ 6041.224200] Key type lgssc unregistered [ 6041.333328] LNet: 301518:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6041.335542] LNetError: 301518:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6041.341768] LNet: Removed LNI 192.168.204.53@tcp [ 6041.563082] Key type .llcrypt unregistered [ 6041.564203] Key type ._llcrypt unregistered [ 6041.829216] Key type ._llcrypt registered [ 6041.830219] Key type .llcrypt registered [ 6041.988942] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6041.994329] alg: No test for adler32 (adler32-zlib) [ 6042.857956] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6042.938320] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6044.520082] Key type lgssc registered [ 6044.893220] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6048.048608] Lustre: DEBUG MARKER: Iteration 3 [ 6048.149103] LustreError: 302339:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6048.149168] LustreError: 302340:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6048.154900] LustreError: 302339:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6048.946167] Lustre: Mounted lustre-client [ 6048.948291] Lustre: Skipped 1 previous similar message [ 6049.376162] LustreError: 302448:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f0600c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6049.380942] LustreError: 302448:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6049.392124] Lustre: Unmounted lustre-client [ 6050.223878] Key type lgssc unregistered [ 6050.340494] LNet: 302690:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6050.343902] LNetError: 302690:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6050.355624] LNet: Removed LNI 192.168.204.53@tcp [ 6050.584094] Key type .llcrypt unregistered [ 6050.585066] Key type ._llcrypt unregistered [ 6050.877629] Key type ._llcrypt registered [ 6050.878510] Key type .llcrypt registered [ 6051.037821] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6051.042441] alg: No test for adler32 (adler32-zlib) [ 6051.894834] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6051.974923] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6053.560107] Key type lgssc registered [ 6053.918675] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6057.161483] Lustre: DEBUG MARKER: Iteration 4 [ 6057.254623] LustreError: 303510:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6057.255721] LustreError: 303511:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6057.259989] LustreError: 303510:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6058.062541] Lustre: Mounted lustre-client [ 6058.063801] Lustre: Skipped 1 previous similar message [ 6058.514440] LustreError: 303619:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f10beb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6058.518635] LustreError: 303619:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6058.546416] Lustre: Unmounted lustre-client [ 6059.387043] Key type lgssc unregistered [ 6059.493443] LNet: 303862:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6059.495852] LNetError: 303862:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6059.506717] LNet: Removed LNI 192.168.204.53@tcp [ 6059.730095] Key type .llcrypt unregistered [ 6059.731223] Key type ._llcrypt unregistered [ 6059.980329] Key type ._llcrypt registered [ 6059.981353] Key type .llcrypt registered [ 6060.185558] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6060.191242] alg: No test for adler32 (adler32-zlib) [ 6061.047556] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6061.121616] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6062.696129] Key type lgssc registered [ 6063.042612] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6066.100427] Lustre: DEBUG MARKER: Iteration 5 [ 6066.196655] LustreError: 304682:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6066.196707] LustreError: 304683:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6066.200667] LustreError: 304682:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6066.990468] Lustre: Mounted lustre-client [ 6067.428950] LustreError: 304791:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f20ed7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6067.432243] LustreError: 304791:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6067.450159] Lustre: Unmounted lustre-client [ 6068.338087] Key type lgssc unregistered [ 6068.456451] LNet: 305033:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6068.460192] LNetError: 305033:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6068.467664] LNet: Removed LNI 192.168.204.53@tcp [ 6068.680118] Key type .llcrypt unregistered [ 6068.681730] Key type ._llcrypt unregistered [ 6068.914256] Key type ._llcrypt registered [ 6068.917144] Key type .llcrypt registered [ 6069.120134] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6069.125484] alg: No test for adler32 (adler32-zlib) [ 6069.982011] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6070.069154] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6071.648148] Key type lgssc registered [ 6072.018143] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6075.072688] Lustre: DEBUG MARKER: Iteration 6 [ 6075.164779] LustreError: 305853:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6075.165812] LustreError: 305854:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6075.169162] LustreError: 305853:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6075.966795] Lustre: Mounted lustre-client [ 6076.410879] LustreError: 305960:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f03971800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6076.414201] LustreError: 305960:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6076.441784] Lustre: Unmounted lustre-client [ 6077.368613] Key type lgssc unregistered [ 6077.481490] LNet: 306202:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6077.485761] LNetError: 306202:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6077.494306] LNet: Removed LNI 192.168.204.53@tcp [ 6077.763098] Key type .llcrypt unregistered [ 6077.764100] Key type ._llcrypt unregistered [ 6078.066221] Key type ._llcrypt registered [ 6078.068587] Key type .llcrypt registered [ 6078.300019] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6078.305339] alg: No test for adler32 (adler32-zlib) [ 6079.190588] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6079.277030] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6080.864204] Key type lgssc registered [ 6081.244851] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6084.362506] Lustre: DEBUG MARKER: Iteration 7 [ 6084.478619] LustreError: 307022:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6084.478669] LustreError: 307023:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6084.485354] LustreError: 307022:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6085.286732] Lustre: Mounted lustre-client [ 6085.905704] LustreError: 307130:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f18233800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6085.909144] LustreError: 307130:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6085.949107] Lustre: Unmounted lustre-client [ 6086.948506] Key type lgssc unregistered [ 6087.065838] LNet: 307373:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6087.068462] LNetError: 307373:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6087.077654] LNet: Removed LNI 192.168.204.53@tcp [ 6087.347135] Key type .llcrypt unregistered [ 6087.348837] Key type ._llcrypt unregistered [ 6087.701439] Key type ._llcrypt registered [ 6087.720051] Key type .llcrypt registered [ 6087.897722] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6087.901945] alg: No test for adler32 (adler32-zlib) [ 6088.763287] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6088.844550] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6090.432109] Key type lgssc registered [ 6090.806164] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6093.991126] Lustre: DEBUG MARKER: Iteration 8 [ 6094.087509] LustreError: 308195:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6094.087560] LustreError: 308196:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6094.091211] LustreError: 308195:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6094.884543] Lustre: Mounted lustre-client [ 6095.329429] LustreError: 308301:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f084a2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6095.334177] LustreError: 308301:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6095.376150] Lustre: Unmounted lustre-client [ 6096.279443] Key type lgssc unregistered [ 6096.397481] LNet: 308544:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6096.399817] LNetError: 308544:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6096.408767] LNet: Removed LNI 192.168.204.53@tcp [ 6096.638082] Key type .llcrypt unregistered [ 6096.639122] Key type ._llcrypt unregistered [ 6096.884412] Key type ._llcrypt registered [ 6096.885326] Key type .llcrypt registered [ 6097.060614] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6097.065061] alg: No test for adler32 (adler32-zlib) [ 6097.921106] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6098.001823] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6099.592103] Key type lgssc registered [ 6099.967606] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6103.156564] Lustre: DEBUG MARKER: Iteration 9 [ 6103.248392] LustreError: 309364:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6103.252714] LustreError: 309366:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6103.254895] LustreError: 309364:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6104.057843] Lustre: Mounted lustre-client [ 6104.059479] Lustre: Skipped 1 previous similar message [ 6104.521325] LustreError: 309466:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f0544f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6104.524425] LustreError: 309466:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6104.549483] Lustre: Unmounted lustre-client [ 6105.474098] Key type lgssc unregistered [ 6105.593463] LNet: 309708:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6105.597129] LNetError: 309708:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6105.605676] LNet: Removed LNI 192.168.204.53@tcp [ 6105.805112] Key type .llcrypt unregistered [ 6105.806753] Key type ._llcrypt unregistered [ 6106.052388] Key type ._llcrypt registered [ 6106.053929] Key type .llcrypt registered [ 6106.235129] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6106.240825] alg: No test for adler32 (adler32-zlib) [ 6107.088859] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6107.168419] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6108.752147] Key type lgssc registered [ 6109.158054] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6112.386928] Lustre: DEBUG MARKER: Iteration 10 [ 6112.483584] LustreError: 310530:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6112.483620] LustreError: 310529:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6112.488611] LustreError: 310530:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6113.286594] Lustre: Mounted lustre-client [ 6113.287964] Lustre: Skipped 1 previous similar message [ 6113.726088] LustreError: 310638:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f2dcf2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6113.730817] LustreError: 310638:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6113.768133] Lustre: Unmounted lustre-client [ 6114.655639] Key type lgssc unregistered [ 6114.773483] LNet: 310881:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6114.775643] LNetError: 310881:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6114.786739] LNet: Removed LNI 192.168.204.53@tcp [ 6115.004083] Key type .llcrypt unregistered [ 6115.005128] Key type ._llcrypt unregistered [ 6115.233130] Key type ._llcrypt registered [ 6115.234181] Key type .llcrypt registered [ 6115.404346] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6115.422173] alg: No test for adler32 (adler32-zlib) [ 6116.267466] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6116.343109] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6117.920178] Key type lgssc registered [ 6118.271501] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6121.430863] Lustre: DEBUG MARKER: Iteration 11 [ 6121.528486] LustreError: 311700:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6121.528790] LustreError: 311701:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6121.534347] LustreError: 311700:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6122.331680] Lustre: Mounted lustre-client [ 6122.333270] Lustre: Skipped 1 previous similar message [ 6122.772220] LustreError: 311809:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f130ca000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6122.776127] LustreError: 311809:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6122.806120] Lustre: Unmounted lustre-client [ 6123.672955] Key type lgssc unregistered [ 6123.779294] LNet: 312052:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6123.781551] LNetError: 312052:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6123.787705] LNet: Removed LNI 192.168.204.53@tcp [ 6123.983097] Key type .llcrypt unregistered [ 6123.984076] Key type ._llcrypt unregistered [ 6124.198641] Key type ._llcrypt registered [ 6124.200620] Key type .llcrypt registered [ 6124.407049] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6124.412742] alg: No test for adler32 (adler32-zlib) [ 6125.255233] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6125.328645] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6126.904104] Key type lgssc registered [ 6127.247746] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6130.286745] Lustre: DEBUG MARKER: Iteration 12 [ 6130.377351] LustreError: 312873:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6130.378055] LustreError: 312874:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6130.381458] LustreError: 312873:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6131.184839] Lustre: Mounted lustre-client [ 6131.185907] Lustre: Skipped 1 previous similar message [ 6131.622877] LustreError: 312979:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f10bed000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6131.670546] Lustre: Unmounted lustre-client [ 6132.531372] Key type lgssc unregistered [ 6132.629473] LNet: 313221:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6132.631539] LNetError: 313221:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6132.638614] LNet: Removed LNI 192.168.204.53@tcp [ 6132.850192] Key type .llcrypt unregistered [ 6132.851720] Key type ._llcrypt unregistered [ 6133.140633] Key type ._llcrypt registered [ 6133.142119] Key type .llcrypt registered [ 6133.319198] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6133.325948] alg: No test for adler32 (adler32-zlib) [ 6134.171816] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6134.244893] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6135.832106] Key type lgssc registered [ 6136.186700] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6139.236595] Lustre: DEBUG MARKER: Iteration 13 [ 6139.329614] LustreError: 314043:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6139.329647] LustreError: 314044:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6139.333747] LustreError: 314043:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6140.124177] Lustre: Mounted lustre-client [ 6140.125707] Lustre: Skipped 1 previous similar message [ 6140.553906] LustreError: 314149:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f069eb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6140.558127] LustreError: 314149:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6140.591881] Lustre: Unmounted lustre-client [ 6141.453212] Key type lgssc unregistered [ 6141.566421] LNet: 314393:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6141.568493] LNetError: 314393:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6141.576715] LNet: Removed LNI 192.168.204.53@tcp [ 6141.789090] Key type .llcrypt unregistered [ 6141.790734] Key type ._llcrypt unregistered [ 6142.010975] Key type ._llcrypt registered [ 6142.011993] Key type .llcrypt registered [ 6142.207815] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6142.212653] alg: No test for adler32 (adler32-zlib) [ 6143.073200] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6143.157643] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6144.736112] Key type lgssc registered [ 6145.093070] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6148.305587] Lustre: DEBUG MARKER: Iteration 14 [ 6148.402570] LustreError: 315213:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6148.402600] LustreError: 315214:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6148.406801] LustreError: 315213:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6149.202376] Lustre: Mounted lustre-client [ 6149.204499] Lustre: Skipped 1 previous similar message [ 6149.663339] LustreError: 315316:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f18233800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6149.666837] LustreError: 315316:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6149.695818] Lustre: Unmounted lustre-client [ 6150.593234] Key type lgssc unregistered [ 6150.708762] LNet: 315559:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6150.711190] LNetError: 315559:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6150.717738] LNet: Removed LNI 192.168.204.53@tcp [ 6150.938131] Key type .llcrypt unregistered [ 6150.940195] Key type ._llcrypt unregistered [ 6151.188071] Key type ._llcrypt registered [ 6151.189098] Key type .llcrypt registered [ 6151.377759] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6151.382699] alg: No test for adler32 (adler32-zlib) [ 6152.237291] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6152.318624] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6153.904141] Key type lgssc registered [ 6154.273106] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6157.455339] Lustre: DEBUG MARKER: Iteration 15 [ 6157.563201] LustreError: 316380:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6157.563446] LustreError: 316381:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6157.567305] LustreError: 316380:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6158.362753] Lustre: Mounted lustre-client [ 6158.364105] Lustre: Skipped 1 previous similar message [ 6158.813594] LustreError: 316487:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f108a3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6158.818197] LustreError: 316487:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6158.840327] Lustre: Unmounted lustre-client [ 6159.713274] Key type lgssc unregistered [ 6159.820463] LNet: 316730:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6159.823787] LNetError: 316730:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6159.836789] LNet: Removed LNI 192.168.204.53@tcp [ 6160.079137] Key type .llcrypt unregistered [ 6160.080866] Key type ._llcrypt unregistered [ 6160.371954] Key type ._llcrypt registered [ 6160.375670] Key type .llcrypt registered [ 6160.627078] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6160.632246] alg: No test for adler32 (adler32-zlib) [ 6161.482993] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6161.572412] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6163.160223] Key type lgssc registered [ 6163.571531] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6167.011477] Lustre: DEBUG MARKER: Iteration 16 [ 6167.110665] LustreError: 317550:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6167.110725] LustreError: 317551:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6167.115147] LustreError: 317550:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6167.911900] Lustre: Mounted lustre-client [ 6167.913613] Lustre: Skipped 1 previous similar message [ 6168.372165] LustreError: 317658:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f2e95e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6168.445215] Lustre: Unmounted lustre-client [ 6168.446753] Lustre: Skipped 1 previous similar message [ 6169.337910] Key type lgssc unregistered [ 6169.446462] LNet: 317901:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6169.450153] LNetError: 317901:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6169.459701] LNet: Removed LNI 192.168.204.53@tcp [ 6169.696158] Key type .llcrypt unregistered [ 6169.697155] Key type ._llcrypt unregistered [ 6169.952744] Key type ._llcrypt registered [ 6169.953768] Key type .llcrypt registered [ 6170.141456] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6170.146561] alg: No test for adler32 (adler32-zlib) [ 6170.999930] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6171.077495] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6172.664094] Key type lgssc registered [ 6173.034307] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6176.260012] Lustre: DEBUG MARKER: Iteration 17 [ 6176.350550] LustreError: 318722:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6176.350576] LustreError: 318723:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6176.355751] LustreError: 318722:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6177.156490] Lustre: Mounted lustre-client [ 6177.157492] Lustre: Skipped 1 previous similar message [ 6177.583827] LustreError: 318833:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f186e2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6177.619693] Lustre: Unmounted lustre-client [ 6178.447898] Key type lgssc unregistered [ 6178.555483] LNet: 319075:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6178.558047] LNetError: 319075:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6178.566730] LNet: Removed LNI 192.168.204.53@tcp [ 6178.772089] Key type .llcrypt unregistered [ 6178.773055] Key type ._llcrypt unregistered [ 6179.047657] Key type ._llcrypt registered [ 6179.048890] Key type .llcrypt registered [ 6179.191704] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6179.196252] alg: No test for adler32 (adler32-zlib) [ 6180.045820] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6180.121169] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6181.704106] Key type lgssc registered [ 6182.048630] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6185.164303] Lustre: DEBUG MARKER: Iteration 18 [ 6185.254127] LustreError: 319896:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6185.254151] LustreError: 319897:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6185.258202] LustreError: 319896:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6186.052667] Lustre: Mounted lustre-client [ 6186.494714] LustreError: 320003:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f11ca2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6186.497921] LustreError: 320003:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6186.512765] Lustre: Unmounted lustre-client [ 6187.340170] Key type lgssc unregistered [ 6187.451460] LNet: 320247:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6187.453834] LNetError: 320247:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6187.461382] LNet: Removed LNI 192.168.204.53@tcp [ 6187.687151] Key type .llcrypt unregistered [ 6187.688478] Key type ._llcrypt unregistered [ 6188.022482] Key type ._llcrypt registered [ 6188.028237] Key type .llcrypt registered [ 6188.139903] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6188.144166] alg: No test for adler32 (adler32-zlib) [ 6188.990550] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6189.066664] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6190.640114] Key type lgssc registered [ 6190.975792] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6193.982714] Lustre: DEBUG MARKER: Iteration 19 [ 6194.083171] LustreError: 321068:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6194.084304] LustreError: 321069:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6194.087199] LustreError: 321068:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6194.892155] Lustre: Mounted lustre-client [ 6194.893119] Lustre: Skipped 1 previous similar message [ 6195.331707] LustreError: 321174:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f112a0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6195.375454] Lustre: Unmounted lustre-client [ 6196.198217] Key type lgssc unregistered [ 6196.302493] LNet: 321415:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6196.304672] LNetError: 321415:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6196.313644] LNet: Removed LNI 192.168.204.53@tcp [ 6196.507102] Key type .llcrypt unregistered [ 6196.508121] Key type ._llcrypt unregistered [ 6196.752518] Key type ._llcrypt registered [ 6196.753704] Key type .llcrypt registered [ 6196.939781] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6196.944327] alg: No test for adler32 (adler32-zlib) [ 6197.788302] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6197.866046] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6199.440122] Key type lgssc registered [ 6199.785945] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6202.986526] Lustre: DEBUG MARKER: Iteration 20 [ 6203.088475] LustreError: 322235:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6203.088528] LustreError: 322236:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6203.093054] LustreError: 322235:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6203.888390] Lustre: Mounted lustre-client [ 6204.328484] LustreError: 322343:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f3311a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6204.332212] LustreError: 322343:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6204.409760] Lustre: Unmounted lustre-client [ 6205.264519] Key type lgssc unregistered [ 6205.369421] LNet: 322586:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6205.371693] LNetError: 322586:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6205.378645] LNet: Removed LNI 192.168.204.53@tcp [ 6205.604104] Key type .llcrypt unregistered [ 6205.605673] Key type ._llcrypt unregistered [ 6205.855729] Key type ._llcrypt registered [ 6205.856662] Key type .llcrypt registered [ 6206.070517] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6206.075255] alg: No test for adler32 (adler32-zlib) [ 6206.932681] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6207.011592] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6208.592156] Key type lgssc registered [ 6208.966337] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6212.328691] Lustre: DEBUG MARKER: Iteration 21 [ 6212.422070] LustreError: 323406:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6212.422121] LustreError: 323407:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6212.426684] LustreError: 323406:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6213.224993] Lustre: Mounted lustre-client [ 6213.226042] Lustre: Skipped 1 previous similar message [ 6213.674948] LustreError: 323514:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f108a0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6213.720077] Lustre: Unmounted lustre-client [ 6213.720981] Lustre: Skipped 1 previous similar message [ 6214.625385] Key type lgssc unregistered [ 6214.738481] LNet: 323756:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6214.740590] LNetError: 323756:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6214.748653] LNet: Removed LNI 192.168.204.53@tcp [ 6214.977093] Key type .llcrypt unregistered [ 6214.978044] Key type ._llcrypt unregistered [ 6215.203625] Key type ._llcrypt registered [ 6215.204644] Key type .llcrypt registered [ 6215.430369] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6215.435093] alg: No test for adler32 (adler32-zlib) [ 6216.290823] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6216.375146] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6217.960119] Key type lgssc registered [ 6218.306535] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6221.556123] Lustre: DEBUG MARKER: Iteration 22 [ 6221.662303] LustreError: 324576:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6221.662349] LustreError: 324577:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6221.666344] LustreError: 324576:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6222.464068] Lustre: Mounted lustre-client [ 6222.465863] Lustre: Skipped 1 previous similar message [ 6222.929923] LustreError: 324679:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f130ce800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6222.958617] Lustre: Unmounted lustre-client [ 6223.852948] Key type lgssc unregistered [ 6223.967372] LNet: 324922:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6223.969529] LNetError: 324922:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6223.977816] LNet: Removed LNI 192.168.204.53@tcp [ 6224.191074] Key type .llcrypt unregistered [ 6224.192157] Key type ._llcrypt unregistered [ 6224.419822] Key type ._llcrypt registered [ 6224.420804] Key type .llcrypt registered [ 6224.627124] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6224.633048] alg: No test for adler32 (adler32-zlib) [ 6225.482775] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6225.557378] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6227.136116] Key type lgssc registered [ 6227.525080] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6231.068183] Lustre: DEBUG MARKER: Iteration 23 [ 6231.170607] LustreError: 325750:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6231.170639] LustreError: 325751:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6231.176418] LustreError: 325750:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6231.977834] Lustre: Mounted lustre-client [ 6231.979663] Lustre: Skipped 1 previous similar message [ 6232.437838] LustreError: 325860:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f07dca000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6232.444926] LustreError: 325860:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6232.483496] Lustre: Unmounted lustre-client [ 6233.359244] Key type lgssc unregistered [ 6233.462563] LNet: 326103:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6233.464966] LNetError: 326103:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6233.476790] LNet: Removed LNI 192.168.204.53@tcp [ 6233.694122] Key type .llcrypt unregistered [ 6233.695145] Key type ._llcrypt unregistered [ 6233.914865] Key type ._llcrypt registered [ 6233.915857] Key type .llcrypt registered [ 6234.103382] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6234.117942] alg: No test for adler32 (adler32-zlib) [ 6234.983724] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6235.059884] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6236.648145] Key type lgssc registered [ 6236.996633] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6240.191540] Lustre: DEBUG MARKER: Iteration 24 [ 6240.293228] LustreError: 326925:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6240.293258] LustreError: 326924:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6240.298476] LustreError: 326925:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6241.095786] Lustre: Mounted lustre-client [ 6241.535151] LustreError: 327031:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f183fb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6241.539743] LustreError: 327031:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6241.559855] Lustre: Unmounted lustre-client [ 6242.405211] Key type lgssc unregistered [ 6242.516548] LNet: 327273:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6242.519818] LNetError: 327273:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6242.528744] LNet: Removed LNI 192.168.204.53@tcp [ 6242.740078] Key type .llcrypt unregistered [ 6242.741219] Key type ._llcrypt unregistered [ 6243.002564] Key type ._llcrypt registered [ 6243.003688] Key type .llcrypt registered [ 6243.181081] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6243.187580] alg: No test for adler32 (adler32-zlib) [ 6244.049829] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6244.125754] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6245.704121] Key type lgssc registered [ 6246.061357] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6249.241983] Lustre: DEBUG MARKER: Iteration 25 [ 6249.350470] LustreError: 328094:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6249.350515] LustreError: 328093:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6249.357735] LustreError: 328094:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6250.153634] Lustre: Mounted lustre-client [ 6250.155901] Lustre: Skipped 1 previous similar message [ 6250.615474] LustreError: 328198:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f02ad1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6250.630678] Lustre: Unmounted lustre-client [ 6251.521772] Key type lgssc unregistered [ 6251.636525] LNet: 328443:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6251.638823] LNetError: 328443:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6251.647656] LNet: Removed LNI 192.168.204.53@tcp [ 6251.866108] Key type .llcrypt unregistered [ 6251.867683] Key type ._llcrypt unregistered [ 6252.101428] Key type ._llcrypt registered [ 6252.102991] Key type .llcrypt registered [ 6252.292534] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6252.300587] alg: No test for adler32 (adler32-zlib) [ 6253.145244] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6253.223102] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6254.800132] Key type lgssc registered [ 6255.187493] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6258.387687] Lustre: DEBUG MARKER: Iteration 26 [ 6258.487294] LustreError: 329263:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6258.488824] LustreError: 329264:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6258.492360] LustreError: 329263:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6259.292195] Lustre: Mounted lustre-client [ 6259.293885] Lustre: Skipped 1 previous similar message [ 6259.718182] LustreError: 329369:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f03971800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6259.723339] LustreError: 329369:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6259.732784] Lustre: Unmounted lustre-client [ 6260.668909] Key type lgssc unregistered [ 6260.781535] LNet: 329613:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6260.783718] LNetError: 329613:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6260.792684] LNet: Removed LNI 192.168.204.53@tcp [ 6261.045086] Key type .llcrypt unregistered [ 6261.046657] Key type ._llcrypt unregistered [ 6261.282368] Key type ._llcrypt registered [ 6261.283359] Key type .llcrypt registered [ 6261.500594] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6261.507164] alg: No test for adler32 (adler32-zlib) [ 6262.365446] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6262.440454] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6264.024091] Key type lgssc registered [ 6264.380137] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6267.632553] Lustre: DEBUG MARKER: Iteration 27 [ 6267.736834] LustreError: 330433:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6267.737516] LustreError: 330434:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6267.741461] LustreError: 330433:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6268.546378] Lustre: Mounted lustre-client [ 6268.547510] Lustre: Skipped 1 previous similar message [ 6268.997808] LustreError: 330542:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f084a6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6269.027900] Lustre: Unmounted lustre-client [ 6269.948684] Key type lgssc unregistered [ 6270.067438] LNet: 330784:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6270.071267] LNetError: 330784:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6270.081777] LNet: Removed LNI 192.168.204.53@tcp [ 6270.294116] Key type .llcrypt unregistered [ 6270.295580] Key type ._llcrypt unregistered [ 6270.551560] Key type ._llcrypt registered [ 6270.552527] Key type .llcrypt registered [ 6270.745476] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6270.750409] alg: No test for adler32 (adler32-zlib) [ 6271.600182] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6271.681795] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6273.264091] Key type lgssc registered [ 6273.626711] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6276.877530] Lustre: DEBUG MARKER: Iteration 28 [ 6276.969825] LustreError: 331604:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6276.971196] LustreError: 331605:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6276.975037] LustreError: 331604:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6277.770172] Lustre: Mounted lustre-client [ 6277.771794] Lustre: Skipped 1 previous similar message [ 6278.205681] LustreError: 331709:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f112a1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6278.209280] LustreError: 331709:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6278.239283] Lustre: Unmounted lustre-client [ 6279.140774] Key type lgssc unregistered [ 6279.258364] LNet: 331952:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6279.260757] LNetError: 331952:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6279.268693] LNet: Removed LNI 192.168.204.53@tcp [ 6279.489081] Key type .llcrypt unregistered [ 6279.490260] Key type ._llcrypt unregistered [ 6279.747079] Key type ._llcrypt registered [ 6279.749844] Key type .llcrypt registered [ 6279.930199] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6279.938226] alg: No test for adler32 (adler32-zlib) [ 6280.799904] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6280.883277] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6282.464094] Key type lgssc registered [ 6282.851731] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6286.082658] Lustre: DEBUG MARKER: Iteration 29 [ 6286.188513] LustreError: 332772:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6286.189778] LustreError: 332773:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6286.194363] LustreError: 332772:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6286.984986] Lustre: Mounted lustre-client [ 6286.986524] Lustre: Skipped 1 previous similar message [ 6287.412595] LustreError: 332872:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f03855800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6287.451100] Lustre: Unmounted lustre-client [ 6288.292569] Key type lgssc unregistered [ 6288.389495] LNet: 333115:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6288.391709] LNetError: 333115:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6288.397586] LNet: Removed LNI 192.168.204.53@tcp [ 6288.608138] Key type .llcrypt unregistered [ 6288.609606] Key type ._llcrypt unregistered [ 6288.895337] Key type ._llcrypt registered [ 6288.898639] Key type .llcrypt registered [ 6289.092860] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6289.098656] alg: No test for adler32 (adler32-zlib) [ 6289.961538] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6290.049197] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6291.632109] Key type lgssc registered [ 6292.019987] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6295.372553] Lustre: DEBUG MARKER: Iteration 30 [ 6295.487040] LustreError: 333935:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6295.487109] LustreError: 333936:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6295.494511] LustreError: 333935:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6296.293438] Lustre: Mounted lustre-client [ 6296.295322] Lustre: Skipped 1 previous similar message [ 6296.750929] LustreError: 334043:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f32b16800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6296.754476] LustreError: 334043:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6296.811813] Lustre: Unmounted lustre-client [ 6297.744341] Key type lgssc unregistered [ 6297.858549] LNet: 334286:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6297.861901] LNetError: 334286:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6297.870793] LNet: Removed LNI 192.168.204.53@tcp [ 6298.112098] Key type .llcrypt unregistered [ 6298.113173] Key type ._llcrypt unregistered [ 6298.386977] Key type ._llcrypt registered [ 6298.389568] Key type .llcrypt registered [ 6298.569535] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6298.575435] alg: No test for adler32 (adler32-zlib) [ 6299.433108] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6299.528104] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6301.112117] Key type lgssc registered [ 6301.495133] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6304.761088] Lustre: DEBUG MARKER: Iteration 31 [ 6304.861104] LustreError: 335106:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6304.861392] LustreError: 335107:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6304.866049] LustreError: 335106:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6305.664791] Lustre: Mounted lustre-client [ 6306.107533] LustreError: 335214:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f130cb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6306.111419] LustreError: 335214:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6306.174088] Lustre: Unmounted lustre-client [ 6307.076542] Key type lgssc unregistered [ 6307.180491] LNet: 335457:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6307.182686] LNetError: 335457:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6307.193692] LNet: Removed LNI 192.168.204.53@tcp [ 6307.418093] Key type .llcrypt unregistered [ 6307.419329] Key type ._llcrypt unregistered [ 6307.661251] Key type ._llcrypt registered [ 6307.663305] Key type .llcrypt registered [ 6307.846929] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6307.860105] alg: No test for adler32 (adler32-zlib) [ 6308.713186] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6308.796654] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6310.376125] Key type lgssc registered [ 6310.743669] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6314.066370] Lustre: DEBUG MARKER: Iteration 32 [ 6314.170327] LustreError: 336278:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6314.170604] LustreError: 336279:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6314.176695] LustreError: 336278:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6314.982988] Lustre: Mounted lustre-client [ 6314.985260] Lustre: Skipped 1 previous similar message [ 6315.438953] LustreError: 336387:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f118f9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6315.491548] Lustre: Unmounted lustre-client [ 6316.473663] Key type lgssc unregistered [ 6316.580422] LNet: 336629:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6316.582734] LNetError: 336629:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6316.590680] LNet: Removed LNI 192.168.204.53@tcp [ 6316.811098] Key type .llcrypt unregistered [ 6316.812583] Key type ._llcrypt unregistered [ 6317.074105] Key type ._llcrypt registered [ 6317.075962] Key type .llcrypt registered [ 6317.281220] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6317.286082] alg: No test for adler32 (adler32-zlib) [ 6318.131514] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6318.210617] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6319.792092] Key type lgssc registered [ 6320.145348] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6323.392063] Lustre: DEBUG MARKER: Iteration 33 [ 6323.493049] LustreError: 337449:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6323.494320] LustreError: 337450:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6323.497661] LustreError: 337449:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6324.299041] Lustre: Mounted lustre-client [ 6324.746500] LustreError: 337551:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f03857800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6324.751173] LustreError: 337551:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6324.764093] Lustre: Unmounted lustre-client [ 6325.647526] Key type lgssc unregistered [ 6325.770508] LNet: 337794:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6325.773125] LNetError: 337794:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6325.783602] LNet: Removed LNI 192.168.204.53@tcp [ 6326.017105] Key type .llcrypt unregistered [ 6326.018110] Key type ._llcrypt unregistered [ 6326.282590] Key type ._llcrypt registered [ 6326.285659] Key type .llcrypt registered [ 6326.480712] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6326.485873] alg: No test for adler32 (adler32-zlib) [ 6327.350392] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6327.435331] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6329.024123] Key type lgssc registered [ 6329.402875] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6332.706875] Lustre: DEBUG MARKER: Iteration 34 [ 6332.809102] LustreError: 338615:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6332.811055] LustreError: 338614:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6332.813316] LustreError: 338615:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6333.616650] Lustre: Mounted lustre-client [ 6333.618825] Lustre: Skipped 1 previous similar message [ 6334.081327] LustreError: 338724:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f32b17000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6334.135911] Lustre: Unmounted lustre-client [ 6335.016573] Key type lgssc unregistered [ 6335.126360] LNet: 338969:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6335.128821] LNetError: 338969:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6335.142769] LNet: Removed LNI 192.168.204.53@tcp [ 6335.360148] Key type .llcrypt unregistered [ 6335.361724] Key type ._llcrypt unregistered [ 6335.595941] Key type ._llcrypt registered [ 6335.602458] Key type .llcrypt registered [ 6335.812501] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6335.816980] alg: No test for adler32 (adler32-zlib) [ 6336.672716] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6336.751827] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6338.336131] Key type lgssc registered [ 6338.747271] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6342.207811] Lustre: DEBUG MARKER: Iteration 35 [ 6342.306378] LustreError: 339789:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6342.306417] LustreError: 339790:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6342.313067] LustreError: 339789:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6343.110436] Lustre: Mounted lustre-client [ 6343.112371] Lustre: Skipped 1 previous similar message [ 6343.585201] LustreError: 339895:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f03974000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6343.589669] LustreError: 339895:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6343.609077] Lustre: Unmounted lustre-client [ 6344.540943] Key type lgssc unregistered [ 6344.654543] LNet: 340137:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6344.656695] LNetError: 340137:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6344.665788] LNet: Removed LNI 192.168.204.53@tcp [ 6344.885089] Key type .llcrypt unregistered [ 6344.886147] Key type ._llcrypt unregistered [ 6345.159349] Key type ._llcrypt registered [ 6345.160488] Key type .llcrypt registered [ 6345.354423] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6345.359579] alg: No test for adler32 (adler32-zlib) [ 6346.213154] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6346.298366] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6347.872144] Key type lgssc registered [ 6348.279479] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6351.733621] Lustre: DEBUG MARKER: Iteration 36 [ 6351.840978] LustreError: 340958:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6351.841052] LustreError: 340959:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6351.847189] LustreError: 340958:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6352.655749] Lustre: Mounted lustre-client [ 6352.657376] Lustre: Skipped 1 previous similar message [ 6353.150293] LustreError: 341064:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f112a2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6353.155651] LustreError: 341064:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6353.200569] Lustre: Unmounted lustre-client [ 6354.209963] Key type lgssc unregistered [ 6354.327546] LNet: 341308:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6354.329835] LNetError: 341308:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6354.339621] LNet: Removed LNI 192.168.204.53@tcp [ 6354.576154] Key type .llcrypt unregistered [ 6354.577768] Key type ._llcrypt unregistered [ 6354.844620] Key type ._llcrypt registered [ 6354.851193] Key type .llcrypt registered [ 6355.057788] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6355.062891] alg: No test for adler32 (adler32-zlib) [ 6355.927397] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6356.014657] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6357.600091] Key type lgssc registered [ 6358.034558] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6361.487428] Lustre: DEBUG MARKER: Iteration 37 [ 6361.588067] LustreError: 342128:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6361.588321] LustreError: 342129:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6361.593358] LustreError: 342128:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6362.388727] Lustre: Mounted lustre-client [ 6362.868451] LustreError: 342232:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f02ad4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6362.912101] Lustre: Unmounted lustre-client [ 6363.747402] Key type lgssc unregistered [ 6363.853526] LNet: 342474:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6363.855860] LNetError: 342474:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6363.863738] LNet: Removed LNI 192.168.204.53@tcp [ 6364.067073] Key type .llcrypt unregistered [ 6364.068125] Key type ._llcrypt unregistered [ 6364.319484] Key type ._llcrypt registered [ 6364.322561] Key type .llcrypt registered [ 6364.499843] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6364.505386] alg: No test for adler32 (adler32-zlib) [ 6365.353992] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6365.434542] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6367.016135] Key type lgssc registered [ 6367.381990] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6370.712551] Lustre: DEBUG MARKER: Iteration 38 [ 6370.815213] LustreError: 343294:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6370.817355] LustreError: 343295:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6370.820809] LustreError: 343294:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6371.612523] Lustre: Mounted lustre-client [ 6371.614600] Lustre: Skipped 1 previous similar message [ 6372.082943] LustreError: 343400:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f3e16b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6372.086882] LustreError: 343400:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6372.115083] Lustre: Unmounted lustre-client [ 6373.024409] Key type lgssc unregistered [ 6373.130443] LNet: 343643:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6373.133967] LNetError: 343643:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6373.142653] LNet: Removed LNI 192.168.204.53@tcp [ 6373.376095] Key type .llcrypt unregistered [ 6373.377179] Key type ._llcrypt unregistered [ 6373.616769] Key type ._llcrypt registered [ 6373.617883] Key type .llcrypt registered [ 6373.830317] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6373.837716] alg: No test for adler32 (adler32-zlib) [ 6374.693302] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6374.771954] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6376.344121] Key type lgssc registered [ 6376.736776] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6380.020517] Lustre: DEBUG MARKER: Iteration 39 [ 6380.136964] LustreError: 344463:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6380.137087] LustreError: 344464:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6380.142139] LustreError: 344463:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6380.943279] Lustre: Mounted lustre-client [ 6380.944764] Lustre: Skipped 1 previous similar message [ 6381.398078] LustreError: 344566:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f069e9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6381.402284] LustreError: 344566:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6381.420046] Lustre: Unmounted lustre-client [ 6382.276895] Key type lgssc unregistered [ 6382.382289] LNet: 344809:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6382.385418] LNetError: 344809:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6382.394654] LNet: Removed LNI 192.168.204.53@tcp [ 6382.604083] Key type .llcrypt unregistered [ 6382.605134] Key type ._llcrypt unregistered [ 6382.921286] Key type ._llcrypt registered [ 6382.922526] Key type .llcrypt registered [ 6383.055626] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6383.060487] alg: No test for adler32 (adler32-zlib) [ 6383.907612] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6383.985099] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6385.560131] Key type lgssc registered [ 6385.942174] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6389.295236] Lustre: DEBUG MARKER: Iteration 40 [ 6389.398428] LustreError: 345631:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6389.398483] LustreError: 345630:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6389.402401] LustreError: 345631:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6390.198816] Lustre: Mounted lustre-client [ 6390.734417] LustreError: 345731:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f112a2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6390.738878] LustreError: 345731:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6390.754650] Lustre: Unmounted lustre-client [ 6391.740299] Key type lgssc unregistered [ 6391.862781] LNet: 345975:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6391.865346] LNetError: 345975:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6391.872742] LNet: Removed LNI 192.168.204.53@tcp [ 6392.119121] Key type .llcrypt unregistered [ 6392.120947] Key type ._llcrypt unregistered [ 6392.428489] Key type ._llcrypt registered [ 6392.430225] Key type .llcrypt registered [ 6392.730471] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6392.735562] alg: No test for adler32 (adler32-zlib) [ 6393.615915] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6393.721426] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6395.312117] Key type lgssc registered [ 6395.754515] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6399.384609] Lustre: DEBUG MARKER: Iteration 41 [ 6399.504943] LustreError: 346795:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6399.505025] LustreError: 346796:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6399.511136] LustreError: 346795:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6400.314922] Lustre: Mounted lustre-client [ 6400.821540] LustreError: 346902:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f084c4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6400.828043] LustreError: 346902:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6400.844747] Lustre: Unmounted lustre-client [ 6401.886583] Key type lgssc unregistered [ 6402.005518] LNet: 347145:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6402.007731] LNetError: 347145:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6402.016658] LNet: Removed LNI 192.168.204.53@tcp [ 6402.245121] Key type .llcrypt unregistered [ 6402.246217] Key type ._llcrypt unregistered [ 6402.550825] Key type ._llcrypt registered [ 6402.552620] Key type .llcrypt registered [ 6402.746655] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6402.751583] alg: No test for adler32 (adler32-zlib) [ 6403.601928] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6403.682477] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6405.264167] Key type lgssc registered [ 6405.678144] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6409.157375] Lustre: DEBUG MARKER: Iteration 42 [ 6409.266044] LustreError: 347965:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6409.268844] LustreError: 347966:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6409.273587] LustreError: 347965:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 6410.075418] Lustre: Mounted lustre-client [ 6410.077099] Lustre: Skipped 1 previous similar message [ 6410.557952] LustreError: 348073:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f18011000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6410.579805] Lustre: Unmounted lustre-client [ 6410.581024] Lustre: Skipped 1 previous similar message [ 6411.485859] Key type lgssc unregistered [ 6411.596473] LNet: 348316:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6411.598629] LNetError: 348316:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6411.604803] LNet: Removed LNI 192.168.204.53@tcp [ 6411.839126] Key type .llcrypt unregistered [ 6411.842065] Key type ._llcrypt unregistered [ 6412.112631] Key type ._llcrypt registered [ 6412.113698] Key type .llcrypt registered [ 6412.307402] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6412.312286] alg: No test for adler32 (adler32-zlib) [ 6413.192130] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6413.272491] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6414.856166] Key type lgssc registered [ 6415.230734] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6418.571405] Lustre: DEBUG MARKER: Iteration 43 [ 6418.682114] LustreError: 349136:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6418.682169] LustreError: 349137:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6418.686655] LustreError: 349136:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6419.481766] Lustre: Mounted lustre-client [ 6419.483920] Lustre: Skipped 1 previous similar message [ 6419.952494] LustreError: 349236:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f11826000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6420.005197] Lustre: Unmounted lustre-client [ 6420.006319] Lustre: Skipped 1 previous similar message [ 6420.970944] Key type lgssc unregistered [ 6421.089509] LNet: 349480:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6421.091675] LNetError: 349480:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6421.100757] LNet: Removed LNI 192.168.204.53@tcp [ 6421.345118] Key type .llcrypt unregistered [ 6421.346531] Key type ._llcrypt unregistered [ 6421.626375] Key type ._llcrypt registered [ 6421.627967] Key type .llcrypt registered [ 6421.861487] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6421.866465] alg: No test for adler32 (adler32-zlib) [ 6422.722770] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6422.803399] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6424.384153] Key type lgssc registered [ 6424.777718] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6428.168115] Lustre: DEBUG MARKER: Iteration 44 [ 6428.279299] LustreError: 350300:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6428.280356] LustreError: 350301:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6428.284710] LustreError: 350300:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6429.082862] Lustre: Mounted lustre-client [ 6429.084656] Lustre: Skipped 1 previous similar message [ 6429.541495] LustreError: 350401:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f2b613000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6429.606089] Lustre: Unmounted lustre-client [ 6429.607453] Lustre: Skipped 1 previous similar message [ 6430.537333] Key type lgssc unregistered [ 6430.669540] LNet: 350645:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6430.672672] LNetError: 350645:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6430.681652] LNet: Removed LNI 192.168.204.53@tcp [ 6430.925147] Key type .llcrypt unregistered [ 6430.927136] Key type ._llcrypt unregistered [ 6431.203482] Key type ._llcrypt registered [ 6431.206329] Key type .llcrypt registered [ 6431.403425] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6431.410147] alg: No test for adler32 (adler32-zlib) [ 6432.266169] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6432.342896] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6433.928114] Key type lgssc registered [ 6434.317091] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6437.754135] Lustre: DEBUG MARKER: Iteration 45 [ 6437.855316] LustreError: 351466:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6437.855673] LustreError: 351467:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6437.860910] LustreError: 351466:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6438.658922] Lustre: Mounted lustre-client [ 6438.660901] Lustre: Skipped 1 previous similar message [ 6439.116879] LustreError: 351576:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f10890000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6439.122441] LustreError: 351576:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6439.135085] Lustre: Unmounted lustre-client [ 6440.011493] Key type lgssc unregistered [ 6440.123534] LNet: 351818:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6440.127146] LNetError: 351818:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6440.136640] LNet: Removed LNI 192.168.204.53@tcp [ 6440.386175] Key type .llcrypt unregistered [ 6440.387735] Key type ._llcrypt unregistered [ 6440.622379] Key type ._llcrypt registered [ 6440.629651] Key type .llcrypt registered [ 6440.849227] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6440.856651] alg: No test for adler32 (adler32-zlib) [ 6441.722306] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6441.818291] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6443.400175] Key type lgssc registered [ 6443.798079] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6447.131268] Lustre: DEBUG MARKER: Iteration 46 [ 6447.233465] LustreError: 352639:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6447.233546] LustreError: 352640:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6447.237856] LustreError: 352639:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6448.035269] Lustre: Mounted lustre-client [ 6448.037070] Lustre: Skipped 1 previous similar message [ 6448.509463] LustreError: 352746:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f06a52800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6448.514397] LustreError: 352746:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6448.553666] Lustre: Unmounted lustre-client [ 6448.555247] Lustre: Skipped 1 previous similar message [ 6449.494444] Key type lgssc unregistered [ 6449.607424] LNet: 352989:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6449.610816] LNetError: 352989:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6449.620758] LNet: Removed LNI 192.168.204.53@tcp [ 6449.856105] Key type .llcrypt unregistered [ 6449.857569] Key type ._llcrypt unregistered [ 6450.146230] Key type ._llcrypt registered [ 6450.147320] Key type .llcrypt registered [ 6450.318334] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6450.323656] alg: No test for adler32 (adler32-zlib) [ 6451.177386] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6451.259540] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6452.848154] Key type lgssc registered [ 6453.210832] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6456.599251] Lustre: DEBUG MARKER: Iteration 47 [ 6456.716176] LustreError: 353810:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6456.716475] LustreError: 353809:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6456.723099] LustreError: 353810:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6457.512971] Lustre: Mounted lustre-client [ 6457.964512] LustreError: 353911:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f186e6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6458.022131] Lustre: Unmounted lustre-client [ 6458.948552] Key type lgssc unregistered [ 6459.070474] LNet: 354154:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6459.072726] LNetError: 354154:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6459.083779] LNet: Removed LNI 192.168.204.53@tcp [ 6459.335101] Key type .llcrypt unregistered [ 6459.336784] Key type ._llcrypt unregistered [ 6459.679397] Key type ._llcrypt registered [ 6459.680869] Key type .llcrypt registered [ 6459.821562] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6459.826384] alg: No test for adler32 (adler32-zlib) [ 6460.693468] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6460.788566] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6462.376079] Key type lgssc registered [ 6462.767954] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6466.156323] Lustre: DEBUG MARKER: Iteration 48 [ 6466.262407] LustreError: 354975:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6466.263717] LustreError: 354976:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6466.267876] LustreError: 354975:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6467.067184] Lustre: Mounted lustre-client [ 6467.069213] Lustre: Skipped 1 previous similar message [ 6467.538943] LustreError: 355081:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f08797000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6467.544140] LustreError: 355081:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6467.605901] Lustre: Unmounted lustre-client [ 6468.558935] Key type lgssc unregistered [ 6468.668548] LNet: 355324:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6468.671888] LNetError: 355324:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6468.679638] LNet: Removed LNI 192.168.204.53@tcp [ 6468.910090] Key type .llcrypt unregistered [ 6468.911064] Key type ._llcrypt unregistered [ 6469.268852] Key type ._llcrypt registered [ 6469.278990] Key type .llcrypt registered [ 6469.384470] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6469.389946] alg: No test for adler32 (adler32-zlib) [ 6470.242934] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6470.323053] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6471.904173] Key type lgssc registered [ 6472.334179] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6475.687274] Lustre: DEBUG MARKER: Iteration 49 [ 6475.785195] LustreError: 356144:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6475.785315] LustreError: 356145:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6475.790423] LustreError: 356144:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6476.587441] Lustre: Mounted lustre-client [ 6476.588982] Lustre: Skipped 1 previous similar message [ 6477.050965] LustreError: 356253:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f11822000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6477.072069] Lustre: Unmounted lustre-client [ 6478.028212] Key type lgssc unregistered [ 6478.152531] LNet: 356496:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6478.154971] LNetError: 356496:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6478.165666] LNet: Removed LNI 192.168.204.53@tcp [ 6478.389102] Key type .llcrypt unregistered [ 6478.390470] Key type ._llcrypt unregistered [ 6478.635772] Key type ._llcrypt registered [ 6478.637049] Key type .llcrypt registered [ 6478.860360] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6478.865584] alg: No test for adler32 (adler32-zlib) [ 6479.722063] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6479.803726] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6481.384096] Key type lgssc registered [ 6481.754447] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6485.168473] Lustre: DEBUG MARKER: Iteration 50 [ 6485.281256] LustreError: 357316:0:(llite_lib.c:1368:ll_fill_super()) cfs_race id 1417 sleeping [ 6485.281558] LustreError: 357317:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 waking [ 6485.286869] LustreError: 357316:0:(llite_lib.c:1368:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6486.080989] Lustre: Mounted lustre-client [ 6486.082820] Lustre: Skipped 1 previous similar message [ 6486.515174] LustreError: 357419:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f084c4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6486.518491] LustreError: 357419:0:(lov_obd.c:786:lov_cleanup()) Skipped 2 previous similar messages [ 6486.536098] Lustre: Unmounted lustre-client [ 6487.444748] Key type lgssc unregistered [ 6487.569474] LNet: 357662:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6487.571975] LNetError: 357662:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6487.579686] LNet: Removed LNI 192.168.204.53@tcp [ 6487.795159] Key type .llcrypt unregistered [ 6487.796261] Key type ._llcrypt unregistered [ 6488.080609] Key type ._llcrypt registered [ 6488.082466] Key type .llcrypt registered [ 6488.262262] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6488.267486] alg: No test for adler32 (adler32-zlib) [ 6489.134503] Lustre: Lustre: Build Version: 2.17.51_74_g79190eb [ 6489.216686] LNet: Added LNI 192.168.204.53@tcp [8/256/0/180] [ 6490.800153] Key type lgssc registered [ 6491.189059] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6494.991832] Lustre: Mounted lustre-client [ 6497.218582] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 08:54:01 (1775048041) [ 6513.120087] Lustre: 358995:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775048042/real 1775048042] req@ffff8a7f05444000 x1861272767308800/t0(0) o36->lustre-MDT0000-mdc-ffff8a7f11824800@192.168.204.153@tcp:12/10 lens 496/440 e 0 to 1 dl 1775048058 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6513.131179] Lustre: lustre-MDT0000-mdc-ffff8a7f11824800: Connection to lustre-MDT0000 (at 192.168.204.153@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6513.141487] Lustre: lustre-MDT0000-mdc-ffff8a7f11824800: Connection restored to 192.168.204.153@tcp (at 192.168.204.153@tcp) [ 6529.504139] Lustre: 358995:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775048058/real 1775048058] req@ffff8a7f05444000 x1861272767308800/t0(0) o36->lustre-MDT0000-mdc-ffff8a7f11824800@192.168.204.153@tcp:12/10 lens 496/440 e 0 to 1 dl 1775048074 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6529.515994] Lustre: lustre-MDT0000-mdc-ffff8a7f11824800: Connection to lustre-MDT0000 (at 192.168.204.153@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6529.528487] Lustre: lustre-MDT0000-mdc-ffff8a7f11824800: Connection restored to 192.168.204.153@tcp (at 192.168.204.153@tcp) [ 6545.888120] Lustre: 358995:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775048074/real 1775048074] req@ffff8a7f05444000 x1861272767308800/t0(0) o36->lustre-MDT0000-mdc-ffff8a7f11824800@192.168.204.153@tcp:12/10 lens 496/440 e 0 to 1 dl 1775048090 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6545.896994] Lustre: lustre-MDT0000-mdc-ffff8a7f11824800: Connection to lustre-MDT0000 (at 192.168.204.153@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6545.905379] Lustre: lustre-MDT0000-mdc-ffff8a7f11824800: Connection restored to 192.168.204.153@tcp (at 192.168.204.153@tcp) [ 6562.272096] Lustre: 358995:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775048091/real 1775048091] req@ffff8a7f05444000 x1861272767308800/t0(0) o36->lustre-MDT0000-mdc-ffff8a7f11824800@192.168.204.153@tcp:12/10 lens 496/440 e 0 to 1 dl 1775048107 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6562.282769] Lustre: lustre-MDT0000-mdc-ffff8a7f11824800: Connection to lustre-MDT0000 (at 192.168.204.153@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6562.294204] Lustre: lustre-MDT0000-mdc-ffff8a7f11824800: Connection restored to 192.168.204.153@tcp (at 192.168.204.153@tcp) [ 6562.810229] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 08:55:07 (1775048107) [ 6563.394808] Lustre: DEBUG MARKER: SKIP: sanityn test_111 needs >= 2 MDTs [ 6563.980517] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 08:55:08 (1775048108) [ 6564.431317] Lustre: DEBUG MARKER: SKIP: sanityn test_112 We need at least 2 MDTs for this test [ 6564.971809] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 08:55:09 (1775048109) [ 6567.109556] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 08:55:11 (1775048111) [ 6567.552662] Lustre: DEBUG MARKER: SKIP: sanityn test_114 We need at least 2 MDTs for this test [ 6568.075771] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 08:55:12 (1775048112) [ 6568.538597] Lustre: DEBUG MARKER: SKIP: sanityn test_115 ldiskfs only test [ 6569.080160] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 08:55:13 (1775048113) [ 6569.551096] Lustre: DEBUG MARKER: SKIP: sanityn test_116 needs >= 2 MDTs [ 6570.106555] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 08:55:14 (1775048114) [ 6570.171954] LustreError: 361647:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [ 6572.256102] LustreError: 361647:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [ 6574.445506] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 08:55:19 (1775048119) [ 6592.992186] Lustre: 357857:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775048122/real 1775048122] req@ffff8a7f2f529f80 x1861272767341440/t0(0) o4->lustre-OST0000-osc-ffff8a7f11824800@192.168.204.153@tcp:6/4 lens 4584/448 e 0 to 1 dl 1775048138 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 6592.992346] Lustre: lustre-OST0000-osc-ffff8a7f11824800: Connection to lustre-OST0000 (at 192.168.204.153@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6593.005512] Lustre: 357857:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 1 previous similar message [ 6593.021130] Lustre: lustre-OST0000-osc-ffff8a7f11824800: Connection restored to 192.168.204.153@tcp (at 192.168.204.153@tcp) [ 6609.376148] Lustre: 357856:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775048138/real 1775048138] req@ffff8a7f3bcfca80 x1861272767341056/t0(0) o4->lustre-OST0000-osc-ffff8a7f11824800@192.168.204.153@tcp:6/4 lens 4584/448 e 0 to 1 dl 1775048154 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 6609.376179] Lustre: lustre-OST0000-osc-ffff8a7f11824800: Connection to lustre-OST0000 (at 192.168.204.153@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6609.382779] Lustre: 357856:0:(client.c:2479:ptlrpc_expire_one_request()) Skipped 5 previous similar messages [ 6609.395076] Lustre: lustre-OST0000-osc-ffff8a7f11824800: Connection restored to 192.168.204.153@tcp (at 192.168.204.153@tcp) [ 6625.632186] Lustre: 357856:0:(client.c:2479:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1775048154/real 1775048154] req@ffff8a7f3bcfca80 x1861272767341056/t0(0) o4->lustre-OST0000-osc-ffff8a7f11824800@192.168.204.153@tcp:6/4 lens 4584/448 e 0 to 1 dl 1775048170 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 6625.652079] Lustre: lustre-OST0000-osc-ffff8a7f11824800: Connection to lustre-OST0000 (at 192.168.204.153@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6625.668367] Lustre: lustre-OST0000-osc-ffff8a7f11824800: Connection restored to 192.168.204.153@tcp (at 192.168.204.153@tcp) [ 6640.192108] Lustre: DEBUG MARKER: oleg453-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8a7f11824800.ost_server_uuid 50 [ 6640.636748] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8a7f11824800.ost_server_uuid in FULL state after 0 sec [ 6641.137906] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 6641.644670] Lustre: DEBUG MARKER: == sanityn test complete, duration 6336 sec ============== 08:56:26 (1775048186) [ 6642.126640] Lustre: DEBUG MARKER: === sanityn: start cleanup 08:56:26 (1775048186) === [ 6686.546050] LustreError: 363641:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f18232000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6686.557140] Lustre: Unmounted lustre-client [ 6687.586070] Lustre: DEBUG MARKER: === sanityn: finish cleanup 08:57:12 (1775048232) === [ 6687.867109] LustreError: 363941:0:(lov_obd.c:786:lov_cleanup()) lustre-clilov-ffff8a7f11824800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6687.870018] LustreError: 363941:0:(lov_obd.c:786:lov_cleanup()) Skipped 1 previous similar message [ 6687.890485] Lustre: Unmounted lustre-client [ 6715.919530] Key type lgssc unregistered [ 6716.026443] LNet: 364422:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6716.028628] LNetError: 364422:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6716.036849] LNet: Removed LNI 192.168.204.53@tcp [ 6716.247091] Key type .llcrypt unregistered [ 6716.248161] Key type ._llcrypt unregistered