[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.2-1.fc38 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 454988756 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f5b50-0x000f5b5f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5970 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1BB7 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1A53 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001A13 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1AC7 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1B57 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1B8F 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1a53-0xbffe1ac6] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a52] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1ac7-0xbffe1b56] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1b57-0xbffe1b8e] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1b8f-0xbffe1bb6] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001013] APIC: Switch to symmetric I/O mode setup [ 0.003341] x2apic enabled [ 0.004010] Switched APIC routing to physical x2apic. [ 0.005015] kvm-guest: setup PV IPIs [ 0.007718] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008025] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009013] pid_max: default: 32768 minimum: 301 [ 0.010155] LSM: Security Framework initializing [ 0.011051] Yama: becoming mindful. [ 0.012043] SELinux: Initializing. [ 0.013098] *** VALIDATE selinux *** [ 0.021196] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026129] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027177] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028137] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029104] *** VALIDATE tmpfs *** [ 0.031029] *** VALIDATE proc *** [ 0.032234] *** VALIDATE cgroup *** [ 0.033009] *** VALIDATE cgroup2 *** [ 0.034281] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035157] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036010] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037033] Spectre V2 : User space: Vulnerable [ 0.038011] Speculative Store Bypass: Vulnerable [ 0.041074] debug: unmapping init [mem 0xffffffffbc859000-0xffffffffbc860fff] [ 0.043931] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.045045] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.046030] ... version: 2 [ 0.047015] ... bit width: 48 [ 0.048018] ... generic registers: 4 [ 0.049013] ... value mask: 0000ffffffffffff [ 0.050015] ... max period: 00007fffffffffff [ 0.051016] ... fixed-purpose events: 3 [ 0.052015] ... event mask: 000000070000000f [ 0.053315] rcu: Hierarchical SRCU implementation. [ 0.055508] smp: Bringing up secondary CPUs ... [ 0.056517] x86: Booting SMP configuration: [ 0.057026] .... node #0, CPUs: #1 #2 #3 [ 0.061453] smp: Brought up 1 node, 4 CPUs [ 0.063015] smpboot: Max logical packages: 1 [ 0.064019] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.140000] node 0 deferred pages initialised in 75ms [ 0.143210] devtmpfs: initialized [ 0.144261] x86/mm: Memory block size: 128MB [ 0.146779] gcov: version magic: 0x41383552 [ 0.150292] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.151103] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.152272] pinctrl core: initialized pinctrl subsystem [ 0.153176] [ 0.153803] ************************************************************* [ 0.155022] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.157015] ** ** [ 0.158012] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.161015] ** ** [ 0.163015] ** This means that this kernel is built to expose internal ** [ 0.165015] ** IOMMU data structures, which may compromise security on ** [ 0.168014] ** your system. ** [ 0.169010] ** ** [ 0.171013] ** If you see this message and you are not debugging the ** [ 0.174014] ** kernel, report this immediately to your vendor! ** [ 0.176012] ** ** [ 0.178016] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.181017] ************************************************************* [ 0.183686] NET: Registered protocol family 16 [ 0.185423] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.188082] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.191073] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.195104] cpuidle: using governor menu [ 0.196600] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.199448] PCI: Using configuration type 1 for base access [ 0.201126] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.210052] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.212052] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.215090] cryptd: max_cpu_qlen set to 1000 [ 0.219262] ACPI: Added _OSI(Module Device) [ 0.220023] ACPI: Added _OSI(Processor Device) [ 0.222026] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.224019] ACPI: Added _OSI(Processor Aggregator Device) [ 0.228487] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.234353] ACPI: Interpreter enabled [ 0.236069] ACPI: PM: (supports S0 S3 S4 S5) [ 0.238015] ACPI: Using IOAPIC for interrupt routing [ 0.239107] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.242418] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.252335] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.254043] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.256018] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.259082] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.264202] acpiphp: Slot [2] registered [ 0.266254] acpiphp: Slot [3] registered [ 0.267140] acpiphp: Slot [4] registered [ 0.269136] acpiphp: Slot [5] registered [ 0.271275] acpiphp: Slot [6] registered [ 0.272143] acpiphp: Slot [7] registered [ 0.273109] acpiphp: Slot [8] registered [ 0.274110] acpiphp: Slot [9] registered [ 0.275102] acpiphp: Slot [10] registered [ 0.277141] acpiphp: Slot [11] registered [ 0.278119] acpiphp: Slot [12] registered [ 0.280156] acpiphp: Slot [13] registered [ 0.281143] acpiphp: Slot [14] registered [ 0.283128] acpiphp: Slot [15] registered [ 0.284114] acpiphp: Slot [16] registered [ 0.285131] acpiphp: Slot [17] registered [ 0.287149] acpiphp: Slot [18] registered [ 0.289190] acpiphp: Slot [19] registered [ 0.291124] acpiphp: Slot [20] registered [ 0.292114] acpiphp: Slot [21] registered [ 0.293132] acpiphp: Slot [22] registered [ 0.295166] acpiphp: Slot [23] registered [ 0.297156] acpiphp: Slot [24] registered [ 0.298186] acpiphp: Slot [25] registered [ 0.300135] acpiphp: Slot [26] registered [ 0.302150] acpiphp: Slot [27] registered [ 0.303123] acpiphp: Slot [28] registered [ 0.305131] acpiphp: Slot [29] registered [ 0.306121] acpiphp: Slot [30] registered [ 0.308106] acpiphp: Slot [31] registered [ 0.310240] PCI host bridge to bus 0000:00 [ 0.311017] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.313020] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.315024] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.317029] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.320026] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.322023] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.323184] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.327316] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.332661] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.340016] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.345440] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.348021] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.350016] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.352016] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.354322] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.356822] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.360077] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.362765] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.367020] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.374939] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.383836] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.389033] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.394015] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.399019] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.416016] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.426543] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.432023] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.438024] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.449030] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.460744] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.463410] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.469459] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.472260] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.474278] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.479671] iommu: Default domain type: Passthrough [ 0.481531] SCSI subsystem initialized [ 0.483216] ACPI: bus type USB registered [ 0.485187] usbcore: registered new interface driver usbfs [ 0.487130] usbcore: registered new interface driver hub [ 0.489128] usbcore: registered new device driver usb [ 0.491228] pps_core: LinuxPPS API ver. 1 registered [ 0.493015] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.496089] PTP clock support registered [ 0.499045] EDAC MC: Ver: 3.0.0 [ 0.500295] PCI: Using ACPI for IRQ routing [ 0.501817] NetLabel: Initializing [ 0.502010] NetLabel: domain hash size = 128 [ 0.503010] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.504099] NetLabel: unlabeled traffic allowed by default [ 0.505143] vgaarb: loaded [ 0.506332] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.508020] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.514243] clocksource: Switched to clocksource kvm-clock [ 0.617315] VFS: Disk quotas dquot_6.6.0 [ 0.619216] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.622409] *** VALIDATE ramfs *** [ 0.623710] *** VALIDATE hugetlbfs *** [ 0.625784] pnp: PnP ACPI init [ 0.629035] pnp: PnP ACPI: found 6 devices [ 0.651707] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.654370] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.655992] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.657498] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.659187] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.660918] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.662976] NET: Registered protocol family 2 [ 0.664840] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.668913] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.671477] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.675522] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.678394] TCP: Hash tables configured (established 65536 bind 65536) [ 0.680573] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.683178] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.685660] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.688027] NET: Registered protocol family 1 [ 0.691379] RPC: Registered named UNIX socket transport module. [ 0.693923] RPC: Registered udp transport module. [ 0.695256] RPC: Registered tcp transport module. [ 0.696826] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.699150] NET: Registered protocol family 44 [ 0.700458] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.702176] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.703603] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.705446] PCI: CLS 0 bytes, default 64 [ 0.706855] Unpacking initramfs... [ 2.122262] debug: unmapping init [mem 0xffff90497cc64000-0xffff90497ffcffff] [ 2.126119] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.128024] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.130605] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.646765] Initialise system trusted keyrings [ 2.648456] Key type blacklist registered [ 2.650305] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.659652] zbud: loaded [ 2.663820] *** VALIDATE nfs *** [ 2.664931] *** VALIDATE nfs4 *** [ 2.666267] pstore: using deflate compression [ 2.669316] Platform Keyring initialized [ 2.776850] NET: Registered protocol family 38 [ 2.778084] Key type asymmetric registered [ 2.779078] Asymmetric key parser 'x509' registered [ 2.781042] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.783494] io scheduler mq-deadline registered [ 2.784804] io scheduler kyber registered [ 2.785913] io scheduler bfq registered [ 2.787192] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.789295] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.791606] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.793772] ACPI: Power Button [PWRF] [ 2.888925] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.978554] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.075744] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.103158] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.131398] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.137163] Non-volatile memory driver v1.3 [ 3.138444] Linux agpgart interface v0.103 [ 3.170483] virtio_blk virtio1: [vda] 133792 512-byte logical blocks (68.5 MB/65.3 MiB) [ 3.173964] vda: detected capacity change from 0 to 68501504 [ 3.189406] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.191557] vdb: detected capacity change from 0 to 1073741824 [ 3.200112] libphy: Fixed MDIO Bus: probed [ 3.209855] usbcore: registered new interface driver usbserial_generic [ 3.211956] usbserial: USB Serial support registered for generic [ 3.214146] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.217669] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.219303] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.221558] mousedev: PS/2 mouse device common for all mice [ 3.224295] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.226374] rtc_cmos 00:05: RTC can wake from S4 [ 3.231578] rtc_cmos 00:05: registered as rtc0 [ 3.233979] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.234237] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.236661] intel_pstate: CPU model not supported [ 3.242052] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.244626] hid: raw HID events driver (C) Jiri Kosina [ 3.248215] usbcore: registered new interface driver usbhid [ 3.250041] usbhid: USB HID core driver [ 3.251606] drop_monitor: Initializing network drop monitor service [ 3.253687] Initializing XFRM netlink socket [ 3.255496] NET: Registered protocol family 10 [ 3.258194] Segment Routing with IPv6 [ 3.259555] NET: Registered protocol family 17 [ 3.262995] mpls_gso: MPLS GSO support [ 3.268927] RAS: Correctable Errors collector initialized. [ 3.271060] AVX version of gcm_enc/dec engaged. [ 3.272913] AES CTR mode by8 optimization enabled [ 3.355788] sched_clock: Marking stable (3355761011, 0)->(4255626185, -899865174) [ 3.359566] registered taskstats version 1 [ 3.361924] Loading compiled-in X.509 certificates [ 3.363701] zswap: loaded using pool lzo/zbud [ 3.406758] Key type big_key registered [ 3.422791] Key type encrypted registered [ 3.424119] ima: No TPM chip found, activating TPM-bypass! [ 3.425914] ima: Allocated hash algorithm: sha1 [ 3.428054] ima: No architecture policies found [ 3.429596] evm: Initialising EVM extended attributes: [ 3.430952] evm: security.selinux [ 3.431846] evm: security.ima [ 3.432665] evm: security.capability [ 3.433620] evm: HMAC attrs: 0x1 [ 3.435774] rtc_cmos 00:05: setting system clock to 2025-10-23 07:57:54 UTC (1761206274) [ 3.442825] debug: unmapping init [mem 0xffffffffbd803000-0xffffffffbd9fffff] [ 3.445691] debug: unmapping init [mem 0xffffffffbc582000-0xffffffffbc858fff] [ 3.455312] Write protecting the kernel read-only data: 28672k [ 3.458767] debug: unmapping init [mem 0xffffffffbac03000-0xffffffffbadfffff] [ 3.461817] debug: unmapping init [mem 0xffffffffbb514000-0xffffffffbb5fffff] [ 3.494772] 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.501547] systemd[1]: Detected virtualization kvm. [ 3.503233] systemd[1]: Detected architecture x86-64. [ 3.504742] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.527185] systemd[1]: No hostname configured. [ 3.528565] systemd[1]: Set hostname to . [ 3.530378] random: systemd: uninitialized urandom read (16 bytes read) [ 3.532260] systemd[1]: Initializing machine ID from random generator. [ 3.657914] random: systemd: uninitialized urandom read (16 bytes read) [ 3.660535] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 3.665657] random: systemd: uninitialized urandom read (16 bytes read) [ 3.668302] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 3.674137] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Swap. [ OK ] Listening on udev Kernel Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Listening on Journal Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Memstrack Anylazing Service. Starting Create Volatile Files and Directories... [ OK ] Reached target Sockets. Starting Journal Service... Starting Apply Kernel Variables... Starting Setup Virtual Console... [ OK ] Reached target Timers. [ OK ] Reached target Initrd Root Device. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.330278] device-mapper: uevent: version 1.0.3 [ 4.332513] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 5.076297] random: fast init done [ 5.078789] virtio_net virtio0 ens2: renamed from eth0 [ 5.155477] scsi host0: ata_piix [ 5.177735] scsi host1: ata_piix [ 5.198678] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.201144] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.552454] dracut-initqueue[589]: RTNETLINK answers: File exists [ 9.939253] random: crng init done [ 9.940904] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.361427] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Timers. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.582812] printk: systemd: 25 output lines suppressed due to ratelimiting [ 11.836380] SELinux: Disabled at runtime. [ 11.896620] 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) [ 11.906593] systemd[1]: Detected virtualization kvm. [ 11.908322] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.420574] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.423853] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.428768] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.432648] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.436857] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.448384] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.453149] systemd[1]: proc-sys-fs-binfmt_misc.automount: Refusing to start, unit to trigger not loaded. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Control Socket. Mounting Huge Pages File System... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice system-getty.slice. Starting Create list of required st…ce nodes for the current kernel... Activating swap /dev/disk/by-label/SWAP... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on initctl Compatibility Named Pipe. Mounting Kernel Debug File System... [ OK ] Stopped target Initrd Root File System. [ OK ] Created slice system-serial\x2dgetty.slice. Starting Remount Root and Kernel File Systems... [ 12.542388] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Mounting POSIX Message Queue File System... [ OK ] Listening on Process Core Dump Socket. Starting Apply Kernel Variables... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ 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 POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 12.852038] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Coldplug all Devices. [ OK ] Started udev Kernel Device Manager. [ 13.205284] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.213889] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.273697] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.294319] EDAC sbridge: Ver: 1.1.2 [ 14.517981] Key type dns_resolver registered [ 14.846828] NFS: Registering the id_resolver key type [ 14.848534] Key type id_resolver registered [ 14.849642] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ 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 ] Reached target Timers. [ OK ] Reached target Basic System. Starting Login Service... [ OK ] Started D-Bus System Message Bus. [ OK ] Reached target sshd-keygen.target. Starting Network Manager... [ OK ] Started irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting Network Manager Wait Online... [ 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... Starting Hostname Service... [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting System Logging Service... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg421-client login: [ 55.258483] libcfs: loading out-of-tree module taints kernel. [ 55.329690] Key type ._llcrypt registered [ 55.335774] Key type .llcrypt registered [ 56.116121] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 56.154787] alg: No test for adler32 (adler32-zlib) [ 57.851856] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [ 58.357921] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [ 60.034073] Key type lgssc registered [ 61.642743] Lustre: Echo OBD driver; http://www.lustre.org/ [ 61.883116] hrtimer: interrupt took 7106314 ns [ 220.394893] Lustre: Mounted lustre-client [ 225.374664] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 243.503496] Lustre: DEBUG MARKER: oleg421-client.virtnet: executing check_logdir /tmp/testlogs/ [ 246.239340] Lustre: lustre-OST0000-osc-ffff9049e059c800: disconnect after 24s idle [ 248.267690] Lustre: DEBUG MARKER: oleg421-client.virtnet: executing yml_node [ 253.774176] Lustre: DEBUG MARKER: Client: 2.16.59.37 [ 257.649955] Lustre: DEBUG MARKER: MDS: 2.16.59.37 [ 261.100357] Lustre: DEBUG MARKER: OSS: 2.16.59.37 [ 263.642968] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Thu Oct 23 04:02:12 EDT 2025 [ 287.730412] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 289.492861] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 291.465675] Lustre: DEBUG MARKER: === sanityn: start setup 04:02:40 (1761206560) === [ 292.311725] Lustre: Mounted lustre-client [ 296.650581] Lustre: DEBUG MARKER: oleg421-client.virtnet: executing check_config_client /mnt/lustre [ 315.911645] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 327.455710] Lustre: DEBUG MARKER: === sanityn: finish setup 04:03:16 (1761206596) === [ 329.685757] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 04:03:19 (1761206599) [ 337.031759] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 04:03:27 (1761206607) [ 342.589134] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 04:03:32 (1761206612) [ 348.108377] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 04:03:38 (1761206618) [ 353.735954] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 04:03:43 (1761206623) [ 359.476735] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 04:03:49 (1761206629) [ 364.826945] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 04:03:54 (1761206634) [ 370.390077] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 04:04:00 (1761206640) [ 376.590759] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 04:04:06 (1761206646) [ 382.779215] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 04:04:12 (1761206652) [ 388.009449] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 04:04:17 (1761206657) [ 394.479282] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 04:04:24 (1761206664) [ 399.840161] Lustre: lustre-OST0001-osc-ffff9049e059c800: disconnect after 23s idle [ 399.843135] Lustre: Skipped 1 previous similar message [ 400.299648] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 04:04:30 (1761206670) [ 406.239186] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 04:04:36 (1761206676) [ 410.084912] Lustre: lustre-OST0000-osc-ffff9049d0fc8800: disconnect after 21s idle [ 411.964263] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 04:04:41 (1761206681) [ 418.600113] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 04:04:48 (1761206688) [ 425.411858] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 04:04:55 (1761206695) [ 431.170766] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 04:05:00 (1761206700) [ 439.168725] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 04:05:08 (1761206708) [ 447.471144] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 04:05:16 (1761206716) [ 455.243338] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 04:05:24 (1761206724) [ 456.150980] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279518 file: /mnt/lustre/lockdir/lockfile=144115205289279517 [ 597.202736] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 04:07:47 (1761206867) [ 605.374565] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 04:07:55 (1761206875) [ 612.244406] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 04:08:01 (1761206881) [ 619.271846] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 04:08:09 (1761206889) [ 625.814594] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 04:08:15 (1761206895) [ 631.566820] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 04:08:21 (1761206901) [ 633.917723] Lustre: DEBUG MARKER: chmod [ 640.571300] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 04:08:30 (1761206910) [ 1413.609676] Lustre: lustre-OST0000-osc-ffff9049e059c800: disconnect after 21s idle [ 1413.977922] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 04:21:23 (1761207683) [ 1572.324040] Lustre: lustre-OST0000-osc-ffff9049d0fc8800: disconnect after 23s idle [ 1572.338725] Lustre: Skipped 3 previous similar messages [ 1643.592685] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 04:25:13 (1761207913) [ 1762.968267] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 04:27:12 (1761208032) [ 1905.408623] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 04:29:35 (1761208175) [ 1941.663488] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 04:30:11 (1761208211) [ 1946.091329] Lustre: lustre-OST0001-osc-ffff9049d0fc8800: disconnect after 24s idle [ 1948.750853] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 04:30:18 (1761208218) [ 1950.058212] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1950.112252] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1950.213730] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1950.335177] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1950.464239] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1950.552303] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1950.619760] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1950.706465] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1950.770111] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1950.818780] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1950.901235] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1950.998605] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1951.098228] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1951.182467] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1951.269798] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1951.370865] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1951.435726] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1951.541325] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1951.634300] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1951.739410] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1951.858762] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1951.924569] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1952.033262] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1952.127172] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1952.242370] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1952.350203] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1952.412117] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1952.455996] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1952.552422] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1952.634229] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1952.759334] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1952.896663] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1952.959955] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1953.062943] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1953.190390] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1953.325474] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1953.485842] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1953.676107] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1953.803951] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1953.905744] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1953.999325] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1954.099683] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1954.206467] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1954.298980] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1954.360302] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1954.430320] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1954.479414] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1954.525769] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1954.624015] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1954.677108] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1954.749287] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1954.910809] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1954.996943] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1955.067761] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1955.128231] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1955.248542] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1955.371967] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1955.440874] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1955.505606] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1955.580894] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1955.640636] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1955.741090] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1955.794464] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1955.865732] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1955.940615] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1956.068329] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1956.161453] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1956.281766] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1956.406576] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1956.491828] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1956.584448] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1956.678384] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1956.768456] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1956.885099] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1957.031133] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1957.117744] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1957.188605] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1957.286393] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1957.360493] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1957.423571] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1957.476895] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1957.543614] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1957.588646] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1957.685056] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1957.782155] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1957.864285] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1957.970508] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1958.077495] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1958.203437] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1958.281807] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1958.394485] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1958.505408] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1958.605541] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1958.731257] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1958.799478] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1958.884247] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1958.949113] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1959.050921] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1959.193859] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1959.344415] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1959.451928] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1959.512402] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1959.572348] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1959.628870] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1959.708537] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1959.787489] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1959.851888] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1959.939767] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1960.050031] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1960.122813] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1960.226038] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1960.322885] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1960.408655] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1960.484668] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1960.593690] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1960.700133] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1960.764894] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1960.838879] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1960.996991] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1961.173374] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1961.307070] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1961.430856] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1961.526204] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1961.673908] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1961.771141] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1961.850403] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1961.922124] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1962.012849] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1962.117866] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1962.234530] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1962.317256] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1962.390406] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1962.461165] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1962.525953] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1962.587311] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1962.688434] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1962.769785] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1962.840842] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1962.892433] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1962.979273] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1963.057122] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1963.132421] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1963.223153] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1963.347736] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1963.483387] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1963.613262] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1963.724387] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1963.847236] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1963.936127] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1964.012576] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1964.088886] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1964.159388] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1964.255486] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1964.316689] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1964.364120] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1964.424970] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1964.532483] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1964.645095] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1964.726759] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1964.782367] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1964.841124] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1964.909752] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1964.963684] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1965.037646] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1965.104607] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1965.185675] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1965.279520] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1965.342026] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1965.420312] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1965.503303] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1965.596996] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1965.666834] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1965.753827] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1965.810955] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1965.925744] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1965.987135] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1966.031788] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1966.092452] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1966.193139] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1966.253223] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1966.319618] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1966.392995] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1966.539158] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1966.559131] Lustre: lustre-OST0000-osc-ffff9049e059c800: disconnect after 21s idle [ 1966.637705] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1966.746974] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1966.822417] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1966.901713] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1967.005912] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1967.081579] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1967.220032] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1967.282610] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1967.375172] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1967.483048] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1967.580023] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1967.703185] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1967.846645] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1967.944393] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1968.050575] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1968.142638] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1968.235904] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1968.392245] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1968.527680] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1968.660476] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1968.813764] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1968.899990] rw_seq_cst_vs_d (32391): drop_caches: 3 [ 1975.561697] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 04:30:45 (1761208245) [ 1976.100110] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1976.254337] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1976.366228] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1976.462063] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1976.486056] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1976.629314] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1976.737312] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1976.767321] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1976.832771] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1976.981562] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1977.102923] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1977.140092] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1977.174306] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1977.290135] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1977.364465] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1977.485376] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1977.636384] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1977.727912] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1977.766753] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1977.910227] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1977.942720] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1978.077813] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1978.121460] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1978.251439] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1978.383293] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1978.451430] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1978.627894] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1978.687213] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1978.797290] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1978.927226] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1978.992859] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1979.062449] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1979.113621] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1979.142409] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1979.253654] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1979.287245] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1979.325536] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1979.469642] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1979.504942] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1979.559989] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1979.658980] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1979.861133] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1979.919468] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1980.061112] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1980.084858] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1980.183863] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1980.235684] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1980.263713] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1980.491665] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1980.552782] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1980.591353] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1980.692991] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1980.900555] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1980.938839] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1980.966545] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1981.059384] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1981.102593] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1981.158047] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1981.212770] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1981.260412] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1981.308291] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1981.389450] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1981.451478] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1981.517514] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1981.539951] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1981.589796] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1981.672452] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1981.767616] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1981.857023] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1982.031483] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1982.142518] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1982.254186] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1982.311269] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1982.392176] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1982.505478] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1982.535587] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1982.614482] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1982.764889] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1982.879895] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1983.002731] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1983.056159] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1983.110946] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1983.178591] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1983.240382] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1983.335488] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1983.371940] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1983.516696] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1983.594601] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1983.739802] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1983.783240] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1983.983701] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1984.106937] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1984.162307] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1984.276274] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1984.460289] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1984.491674] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1984.535969] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1984.574488] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1984.624223] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1984.688902] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1984.931925] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1985.022520] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1985.107619] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1985.153580] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1985.272075] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1985.322083] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1985.374122] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1985.467116] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1985.518193] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1985.569982] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1985.607256] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1985.672787] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1985.817962] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1985.878761] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1985.995083] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1986.082301] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1986.124273] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1986.170824] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1986.272907] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1986.409509] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1986.454722] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1986.547542] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1986.737535] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1986.842721] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1986.974570] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1987.117745] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1987.180618] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1987.240858] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1987.276431] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1987.340592] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1987.430289] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1987.582493] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1987.628731] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1987.675631] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1987.701304] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1987.878506] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1988.009530] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1988.132467] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1988.172988] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1988.261410] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1988.320905] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1988.447501] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1988.492393] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1988.533848] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1988.696634] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1988.739624] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1988.771797] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1988.909343] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1989.070595] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1989.106379] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1989.291774] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1989.334997] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1989.376918] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1989.483541] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1989.763859] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1989.809092] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1989.832950] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1989.920643] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1989.952605] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1989.987393] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1990.032994] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1990.057418] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1990.214182] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1990.385301] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1990.439686] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1990.482534] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1990.568853] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1990.725083] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1990.832422] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1990.922620] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1991.027441] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1991.073398] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1991.095489] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1991.211287] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1991.256490] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1991.296452] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1991.468541] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1991.622271] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1991.666620] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1991.813207] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1991.952330] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1992.105683] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1992.155108] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1992.161542] Lustre: lustre-OST0001-osc-ffff9049d0fc8800: disconnect after 24s idle [ 1992.169155] Lustre: Skipped 1 previous similar message [ 1992.320509] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1992.350755] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1992.454815] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1992.491244] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1992.532366] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1992.697620] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1992.814823] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1992.908703] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1993.193404] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1993.277554] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1993.325536] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1993.365356] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1993.458023] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1993.644651] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1993.914798] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1994.018253] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1994.043449] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1994.107955] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1994.215471] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1994.293307] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1994.438918] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1994.584829] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1994.630487] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1994.662556] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1994.685812] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1994.725440] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1994.809903] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1994.863448] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1994.931933] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1995.174632] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1995.208686] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1995.340547] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1995.447199] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1995.590260] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 1995.712869] rw_seq_cst_vs_d (32984): drop_caches: 3 [ 2000.902781] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 04:31:10 (1761208270) [ 2006.203113] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 04:31:16 (1761208276) [ 2012.109165] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 04:31:22 (1761208282) [ 2032.032063] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 04:31:42 (1761208302) [ 2032.442745] bash (35460): drop_caches: 3 [ 2035.644923] bash (35460): drop_caches: 3 [ 2038.771880] bash (35460): drop_caches: 3 [ 2041.864913] bash (35460): drop_caches: 3 [ 2044.965621] bash (35460): drop_caches: 3 [ 2048.154559] bash (35460): drop_caches: 3 [ 2051.496109] bash (35460): drop_caches: 3 [ 2054.709843] bash (35460): drop_caches: 3 [ 2057.850640] bash (35460): drop_caches: 3 [ 2061.024810] bash (35460): drop_caches: 3 [ 2062.912675] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 04:32:12 (1761208332) [ 2071.484950] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 04:32:21 (1761208341) [ 2084.320198] Lustre: lustre-OST0001-osc-ffff9049d0fc8800: disconnect after 21s idle [ 2084.322716] Lustre: Skipped 1 previous similar message [ 2116.848875] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 04:33:06 (1761208386) [ 2124.153617] Lustre: DEBUG MARKER: loop 5 [ 2127.538922] Lustre: DEBUG MARKER: loop 10 [ 2130.405302] Lustre: lustre-OST0000-osc-ffff9049e059c800: disconnect after 22s idle [ 2131.026483] Lustre: DEBUG MARKER: loop 15 [ 2134.873652] Lustre: DEBUG MARKER: loop 20 [ 2141.213653] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 04:33:31 (1761208411) [ 2145.740336] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 04:33:35 (1761208415) [ 2152.215883] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 04:33:42 (1761208422) [ 2176.479979] Lustre: lustre-OST0000-osc-ffff9049e059c800: disconnect after 23s idle [ 2176.485548] Lustre: Skipped 4 previous similar messages [ 2218.635382] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 04:34:48 (1761208488) [ 2223.487995] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 04:34:53 (1761208493) [ 2228.046719] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 04:34:58 (1761208498) [ 2233.197924] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 04:35:03 (1761208503) [ 2239.282350] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 04:35:09 (1761208509) [ 2244.609252] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 04:35:14 (1761208514) [ 2251.352911] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 04:35:21 (1761208521) [ 2257.667272] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 2258.936962] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 04:35:28 (1761208528) [ 2266.949436] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 04:35:36 (1761208536) [ 2267.464907] Lustre: *** cfs_fail_loc=314, val=0*** [ 2268.511219] Lustre: *** cfs_fail_loc=314, val=0*** [ 2268.512578] Lustre: Skipped 2 previous similar messages [ 2273.759146] Lustre: lustre-OST0000-osc-ffff9049e059c800: disconnect after 21s idle [ 2274.795511] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 04:35:44 (1761208544) [ 2283.582364] Lustre: *** cfs_fail_loc=314, val=0*** [ 2283.656072] LustreError: lustre-OST0000-osc-ffff9049d0fc8800: operation ldlm_enqueue to node 192.168.204.121@tcp failed: rc = -107 [ 2283.669591] Lustre: lustre-OST0000-osc-ffff9049d0fc8800: Connection to lustre-OST0000 (at 192.168.204.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2283.701210] LustreError: lustre-OST0000-osc-ffff9049d0fc8800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2283.723287] LustreError: 46379:0:(ldlm_resource.c:981:ldlm_resource_complain()) lustre-OST0000-osc-ffff9049d0fc8800: namespace resource [0x280000401:0x38:0x0].0x0 (ffff9049c8c84400) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 2283.749494] Lustre: lustre-OST0000-osc-ffff9049d0fc8800: Connection restored to 192.168.204.121@tcp (at 192.168.204.121@tcp) [ 2290.807884] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 04:36:00 (1761208560) [ 2291.261606] LustreError: 46969:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 2294.295755] LustreError: 46969:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 2302.892620] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 04:36:12 (1761208572) [ 2312.195613] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 04:36:22 (1761208582) [ 2318.983339] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 2320.384811] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 04:36:30 (1761208590) [ 2321.782118] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 2323.317652] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 04:36:33 (1761208593) [ 2330.103331] Lustre: lustre-MDT0000-mdc-ffff9049e059c800: Connection to lustre-MDT0000 (at 192.168.204.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2335.222284] LustreError: MGC192.168.204.121@tcp: Connection to MGS (at 192.168.204.121@tcp) was lost; in progress operations using this service will fail [ 2335.242750] Lustre: Evicted from MGS (at 192.168.204.121@tcp) after server handle changed from 0x2714ef60c15f95f to 0x2714ef60c210b8d [ 2335.254604] Lustre: MGC192.168.204.121@tcp: Connection restored to 192.168.204.121@tcp (at 192.168.204.121@tcp) [ 2338.560246] Lustre: lustre-MDT0000-mdc-ffff9049e059c800: Connection restored to 192.168.204.121@tcp (at 192.168.204.121@tcp) [ 2361.621622] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 04:37:11 (1761208631) [ 2404.276647] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 04:37:53 (1761208673) [ 2425.179429] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 04:38:15 (1761208695) [ 2478.574237] Lustre: lustre-OST0000-osc-ffff9049e059c800: Connection to lustre-OST0000 (at 192.168.204.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2478.591383] Lustre: Skipped 1 previous similar message [ 2478.612283] LustreError: lustre-OST0000-osc-ffff9049e059c800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2478.634025] LustreError: lustre-OST0000-osc-ffff9049d0fc8800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2478.635063] Lustre: lustre-OST0000-osc-ffff9049e059c800: Connection restored to 192.168.204.121@tcp (at 192.168.204.121@tcp) [ 2478.659427] Lustre: Skipped 2 previous similar messages [ 2492.830618] Lustre: lustre-OST0001-osc-ffff9049e059c800: Connection to lustre-OST0001 (at 192.168.204.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2492.854454] Lustre: Skipped 1 previous similar message [ 2492.866127] LustreError: lustre-OST0001-osc-ffff9049e059c800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 2492.899878] Lustre: lustre-OST0001-osc-ffff9049e059c800: Connection restored to 192.168.204.121@tcp (at 192.168.204.121@tcp) [ 2499.041202] Lustre: lustre-OST0000-osc-ffff9049e059c800: disconnect after 21s idle [ 2510.126585] Lustre: DEBUG MARKER: oleg421-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9049d0fc8800.ost_server_uuid 50 [ 2511.289386] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9049d0fc8800.ost_server_uuid in IDLE state after 0 sec [ 2514.253408] Lustre: DEBUG MARKER: oleg421-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9049d0fc8800.ost_server_uuid 50 [ 2515.425466] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9049d0fc8800.ost_server_uuid in FULL state after 0 sec [ 2519.421873] Lustre: DEBUG MARKER: oleg421-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9049d0fc8800.ost_server_uuid 50 [ 2520.666032] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9049d0fc8800.ost_server_uuid in IDLE state after 0 sec [ 2524.271227] Lustre: DEBUG MARKER: oleg421-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9049d0fc8800.ost_server_uuid 50 [ 2525.989975] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9049d0fc8800.ost_server_uuid in FULL state after 0 sec [ 2538.513234] Lustre: DEBUG MARKER: oleg421-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9049d0fc8800.ost_server_uuid 50 [ 2540.437242] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9049d0fc8800.ost_server_uuid in IDLE state after 0 sec [ 2544.452793] Lustre: DEBUG MARKER: oleg421-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9049d0fc8800.ost_server_uuid 50 [ 2546.180490] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9049d0fc8800.ost_server_uuid in FULL state after 0 sec [ 2547.515870] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 04:40:17 (1761208817) [ 2550.081866] Lustre: DEBUG MARKER: Race attempt 0 [ 2552.474875] Lustre: DEBUG MARKER: Wait for 57546 57576 for 60 sec... [ 2619.200192] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 04:41:28 (1761208888) [ 2628.346591] Lustre: DEBUG MARKER: start test - cycle (0) [ 2654.160790] Lustre: DEBUG MARKER: start test - cycle (1) [ 2674.426758] Lustre: DEBUG MARKER: start test - cycle (2) [ 2695.857241] Lustre: DEBUG MARKER: start test - cycle (3) [ 2720.847956] Lustre: DEBUG MARKER: start test - cycle (4) [ 2741.345615] Lustre: DEBUG MARKER: start test - cycle (5) [ 2763.313744] Lustre: DEBUG MARKER: start test - cycle (6) [ 2786.662829] Lustre: DEBUG MARKER: start test - cycle (7) [ 2807.035464] Lustre: DEBUG MARKER: start test - cycle (8) [ 2828.252697] Lustre: DEBUG MARKER: start test - cycle (9) [ 2848.489112] Lustre: DEBUG MARKER: start test - cycle (10) [ 2874.166874] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 04:45:44 (1761209144) [ 2877.919220] Lustre: lustre-OST0001-osc-ffff9049d0fc8800: disconnect after 22s idle [ 2877.931462] Lustre: Skipped 2 previous similar messages [ 2953.657854] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 04:47:03 (1761209223) [ 2960.065291] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 04:47:09 (1761209229) [ 2966.876240] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 04:47:16 (1761209236) [ 2973.416487] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 04:47:23 (1761209243) [ 2973.693757] Lustre: *** cfs_fail_loc=411, val=0*** [ 2978.802619] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 2980.333217] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 04:47:30 (1761209250) [ 2996.303119] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 04:47:46 (1761209266) [ 3013.111652] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 04:48:02 (1761209282) [ 3031.358935] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 04:48:20 (1761209300) [ 3049.762906] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 04:48:39 (1761209319) [ 3064.117760] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 04:48:53 (1761209333) [ 3076.624564] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 04:49:06 (1761209346) [ 3092.597611] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 04:49:22 (1761209362) [ 3106.241550] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 04:49:36 (1761209376) [ 3118.964406] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 04:49:48 (1761209388) [ 3130.896472] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 04:50:01 (1761209401) [ 3144.770545] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 04:50:14 (1761209414) [ 3159.461543] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 04:50:29 (1761209429) [ 3776.998890] Lustre: lustre-OST0000-osc-ffff9049d0fc8800: disconnect after 23s idle [ 3777.011784] Lustre: Skipped 11 previous similar messages [ 4219.288486] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 05:08:08 (1761210488) [ 4233.402552] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 05:08:22 (1761210502) [ 4248.349759] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 05:08:37 (1761210517) [ 4262.785482] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 05:08:52 (1761210532) [ 4277.121142] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 05:09:06 (1761210546) [ 4290.969608] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 05:09:20 (1761210560) [ 4305.918964] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 05:09:35 (1761210575) [ 4322.680042] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 05:09:52 (1761210592) [ 4336.483213] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 05:10:06 (1761210606) [ 4440.049580] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 05:11:50 (1761210710) [ 4450.487108] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 05:12:00 (1761210720) [ 4461.737634] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 05:12:11 (1761210731) [ 4474.226132] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 05:12:23 (1761210743) [ 4487.241976] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 05:12:36 (1761210756) [ 4503.970704] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 05:12:53 (1761210773) [ 4504.034328] Lustre: lustre-OST0001-osc-ffff9049e059c800: disconnect after 22s idle [ 4504.037972] Lustre: Skipped 4 previous similar messages [ 4517.117954] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 05:13:06 (1761210786) [ 4531.006239] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 05:13:20 (1761210800) [ 4547.203068] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 05:13:36 (1761210816) [ 4687.235193] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 05:15:56 (1761210956) [ 5133.801334] Lustre: lustre-OST0000-osc-ffff9049e059c800: disconnect after 22s idle [ 5133.804049] Lustre: Skipped 12 previous similar messages [ 5748.191274] Lustre: lustre-OST0001-osc-ffff9049d0fc8800: disconnect after 20s idle [ 5748.206780] Lustre: Skipped 3 previous similar messages [ 5898.909702] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 05:36:08 (1761212168) [ 5912.499708] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 05:36:22 (1761212182) [ 5925.676337] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 05:36:35 (1761212195) [ 5939.148259] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 05:36:48 (1761212208) [ 5952.556929] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 05:37:02 (1761212222) [ 5966.237792] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 05:37:15 (1761212235) [ 5979.628965] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 05:37:29 (1761212249) [ 5993.471454] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 05:37:43 (1761212263) [ 6008.561385] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 05:37:58 (1761212278) [ 6022.231558] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 05:38:12 (1761212292) [ 6206.860979] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 05:41:16 (1761212476) [ 6219.690687] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 05:41:29 (1761212489) [ 6232.259861] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 05:41:42 (1761212502) [ 6243.453433] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 05:41:53 (1761212513) [ 6257.127706] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 05:42:06 (1761212526) [ 6269.598292] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 05:42:19 (1761212539) [ 6282.759186] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 05:42:32 (1761212552) [ 6297.402843] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 05:42:47 (1761212567) [ 6310.008181] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 05:42:59 (1761212579) [ 6444.513205] Lustre: lustre-OST0000-osc-ffff9049e059c800: disconnect after 22s idle [ 6444.523807] Lustre: Skipped 10 previous similar messages [ 7064.031234] Lustre: lustre-OST0001-osc-ffff9049e059c800: disconnect after 20s idle [ 7064.038626] Lustre: Skipped 6 previous similar messages [ 7567.322524] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 06:03:57 (1761213837) [ 7581.828271] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 06:04:11 (1761213851) [ 7595.409519] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 06:04:25 (1761213865) [ 7607.832877] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 06:04:37 (1761213877) [ 7620.186932] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 06:04:50 (1761213890) [ 7632.760108] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 06:05:02 (1761213902) [ 7645.910212] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 06:05:15 (1761213915) [ 7658.676690] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 06:05:28 (1761213928) [ 7671.524702] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 06:05:41 (1761213941) [ 7673.312140] Lustre: lustre-OST0001-osc-ffff9049e059c800: disconnect after 20s idle [ 7673.314195] Lustre: Skipped 14 previous similar messages [ 7683.341255] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 06:05:53 (1761213953) [ 7693.979900] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 06:06:04 (1761213964) [ 7706.155789] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 06:06:16 (1761213976) [ 7718.792281] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 06:06:28 (1761213988) [ 7730.858996] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 06:06:41 (1761214001) [ 7742.871570] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 06:06:52 (1761214012) [ 7760.530202] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 06:07:09 (1761214029) [ 7775.936116] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 06:07:25 (1761214045) [ 7776.419079] LustreError: 6181:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 7778.503496] LustreError: 6181:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 7788.709070] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 06:07:38 (1761214058) [ 7798.322054] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 06:07:47 (1761214067) [ 7798.626778] LustreError: 285266:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7802.703141] LustreError: 285266:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 7802.747384] LustreError: 285266:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7806.815613] LustreError: 285266:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 7806.855962] LustreError: 285273:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7810.919134] LustreError: 285273:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 7816.535730] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 06:08:06 (1761214086) [ 7830.369387] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 06:08:19 (1761214099) [ 7839.481909] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 06:08:28 (1761214108) [ 7848.888825] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 06:08:38 (1761214118) [ 7881.544823] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 06:09:11 (1761214151) [ 7894.045197] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 06:09:24 (1761214164) [ 7905.838987] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 06:09:35 (1761214175) [ 7922.980262] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 06:09:52 (1761214192) [ 7937.578940] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 06:10:07 (1761214207) [ 7956.369452] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 06:10:26 (1761214226) [ 7974.359533] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 06:10:44 (1761214244) [ 7994.638693] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 06:11:04 (1761214264) [ 8012.255153] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 06:11:22 (1761214282) [ 8104.551934] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 06:12:54 (1761214374) [ 8113.940367] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 8120.836663] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 06:13:10 (1761214390) [ 8128.549317] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 06:13:18 (1761214398) [ 8134.992548] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 06:13:24 (1761214404) [ 8141.685620] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 06:13:31 (1761214411) [ 8187.155038] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 06:14:17 (1761214457) [ 8237.213319] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 06:15:06 (1761214506) [ 8245.592934] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 06:15:15 (1761214515) [ 8254.729760] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 06:15:23 (1761214523) [ 8258.149774] LustreError: lustre-MDT0000-mdc-ffff9049d0fc8800: operation ldlm_enqueue to node 192.168.204.121@tcp failed: rc = -35 [ 8265.159773] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 06:15:34 (1761214534) [ 8265.788949] LustreError: 2414:0:(osc_request.c:3127:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 8267.807162] LustreError: 2414:0:(osc_request.c:3127:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 8277.629390] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 06:15:47 (1761214547) [ 8286.691592] Lustre: lustre-OST0000-osc-ffff9049d0fc8800: disconnect after 21s idle [ 8286.698758] Lustre: Skipped 6 previous similar messages [ 8432.219916] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 06:18:22 (1761214702) [ 8441.169803] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 06:18:30 (1761214710) [ 8452.729267] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 06:18:42 (1761214722) [ 8465.155085] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 06:18:55 (1761214735) [ 8483.527256] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 06:19:12 (1761214752) [ 8509.269989] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 06:19:39 (1761214779) [ 8534.779398] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 06:20:04 (1761214804) [ 8545.387404] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 06:20:15 (1761214815) [ 8557.547709] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 06:20:27 (1761214827) [ 8577.937630] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 06:20:48 (1761214848) [ 8631.973810] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 06:21:41 (1761214901) [ 8760.817681] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 06:23:50 (1761215030) [ 8890.804782] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 06:26:00 (1761215160) [ 8890.849570] Lustre: lustre-OST0000-osc-ffff9049e059c800: disconnect after 23s idle [ 8890.851371] Lustre: Skipped 11 previous similar messages [ 9222.356828] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 06:31:31 (1761215491) [ 9258.343482] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 06:32:08 (1761215528) [ 9294.143905] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 06:32:44 (1761215564) [ 9325.300873] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 06:33:15 (1761215595) [ 9398.947100] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 06:34:28 (1761215668) [ 9463.783776] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 06:35:33 (1761215733) [ 9495.007566] Lustre: lustre-OST0001-osc-ffff9049e059c800: disconnect after 22s idle [ 9495.011069] Lustre: Skipped 15 previous similar messages [ 9577.814587] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 06:37:27 (1761215847) [ 9588.266239] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 06:37:37 (1761215857) [ 9643.800932] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 06:38:33 (1761215913) [ 9702.359058] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 06:39:32 (1761215972) [ 9712.487591] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 06:39:42 (1761215982) [ 9813.819866] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 06:41:23 (1761216083) [ 9842.671102] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 06:41:52 (1761216112) [ 9896.001591] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 06:42:45 (1761216165) [ 9903.643140] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 06:42:53 (1761216173) [ 9920.727109] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 06:43:10 (1761216190) [ 9932.359947] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 06:43:22 (1761216202) [ 9934.216649] LustreError: 343402:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000402:0x9a3:0x0]: rc = -5 [ 9934.231713] LustreError: 343402:0:(llite_lib.c:3793:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9934.929387] LustreError: 343422:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000402:0x9a3:0x0]: rc = -5 [ 9934.937758] LustreError: 343422:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 5 previous similar messages [ 9934.959593] LustreError: 343422:0:(llite_lib.c:3793:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9934.963541] LustreError: 343422:0:(llite_lib.c:3793:ll_prep_inode()) Skipped 5 previous similar messages [10038.518138] LustreError: 343229:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000402:0x9a7:0x0]: rc = -5 [10038.527799] LustreError: 343229:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 1 previous similar message [10038.536823] LustreError: 343229:0:(llite_lib.c:3793:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10038.545755] LustreError: lustre-MDT0001-mdc-ffff9049e059c800: operation mds_getattr_lock to node 192.168.204.121@tcp failed: rc = -107 [10038.552230] LustreError: 343229:0:(llite_lib.c:3793:ll_prep_inode()) Skipped 1 previous similar message [10038.566882] Lustre: lustre-MDT0001-mdc-ffff9049e059c800: Connection to lustre-MDT0001 (at 192.168.204.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [10038.585653] LustreError: lustre-MDT0001-mdc-ffff9049e059c800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [10038.595185] LustreError: 343421:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff9049e059c800: inode [0x240000402:0x9a2:0x0] mdc close failed: rc = -108 [10038.606387] Lustre: lustre-MDT0001-mdc-ffff9049e059c800: Connection restored to 192.168.204.121@tcp (at 192.168.204.121@tcp) [10038.613525] LustreError: 343421:0:(file.c:249:ll_close_inode_openhandle()) Skipped 2 previous similar messages [10043.617475] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 06:45:13 (1761216313) [10050.687180] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 06:45:20 (1761216320) [10254.825891] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 06:48:44 (1761216524) [10256.217248] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [10258.237438] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 06:48:47 (1761216527) [10456.383934] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 06:52:06 (1761216726) [10462.517377] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 06:52:12 (1761216732) [10483.178899] Lustre: lustre-OST0001-osc-ffff9049d0fc8800: disconnect after 24s idle [10483.185030] Lustre: Skipped 9 previous similar messages [10589.984613] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 06:54:19 (1761216859) [10604.344401] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 06:54:33 (1761216873) [10616.080643] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 06:54:45 (1761216885) [10803.690504] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 06:57:53 (1761217073) [10989.869771] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 07:00:59 (1761217259) [10996.388757] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 07:01:06 (1761217266) [11012.193188] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 07:01:21 (1761217281) [11012.491465] Lustre: DEBUG MARKER: write [11012.554174] LustreError: 6188:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [11014.575739] Lustre: DEBUG MARKER: kill 373822 [11014.596525] LustreError: 373822:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [11017.567197] LustreError: 6188:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [11020.616452] LustreError: 373822:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [11028.691210] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 07:01:38 (1761217298) [11031.891381] LustreError: 374437:0:(rw.c:1967:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [11041.919132] LustreError: 374437:0:(rw.c:1967:ll_readpage()) cfs_fail_timeout id 1422 awake [11049.284804] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 07:01:58 (1761217318) [11049.963474] LustreError: 375025:0:(rw.c:2201:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [11052.047109] LustreError: 375025:0:(rw.c:2201:ll_readpage()) cfs_fail_timeout interrupted [11062.929296] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 07:02:12 (1761217332) [11065.300673] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [11067.593208] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 07:02:16 (1761217336) [11078.428408] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 07:02:27 (1761217347) [11088.592894] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 07:02:37 (1761217357) [11097.782778] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 07:02:47 (1761217367) [11104.954989] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 07:02:54 (1761217374) [11112.752778] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 07:03:02 (1761217382) [11120.515776] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 07:03:09 (1761217389) [11131.051136] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 07:03:19 (1761217399) [11141.154834] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 07:03:30 (1761217410) [11143.047104] Lustre: *** cfs_fail_loc=415, val=0*** [11155.203292] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 07:03:44 (1761217424) [11186.783223] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 07:04:16 (1761217456) [11187.259331] LustreError: 15673:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [11187.265368] LustreError: 15673:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [11192.271122] LustreError: 15673:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [11192.271122] LustreError: 15672:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [11192.271139] LustreError: 15672:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 1 previous similar message [11192.279780] LustreError: 15673:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 1 previous similar message [11213.344954] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 07:04:43 (1761217483) [11219.758579] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 07:04:49 (1761217489) [11226.784251] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 07:04:56 (1761217496) [11232.676757] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 07:05:02 (1761217502) [11241.263946] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 07:05:10 (1761217510) [11254.254130] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 07:05:23 (1761217523) [11254.856242] LustreError: 385771:0:(osc_request.c:2978:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [11254.861103] LustreError: 385771:0:(osc_request.c:2978:osc_build_rpc()) Skipped 6 previous similar messages [11258.937391] LustreError: 385771:0:(osc_request.c:2978:osc_build_rpc()) cfs_fail_timeout id 414 awake [11258.952204] LustreError: 385771:0:(osc_request.c:2978:osc_build_rpc()) Skipped 6 previous similar messages [11266.784670] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 07:05:36 (1761217536) [11270.289889] LustreError: 386481:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049e059c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11270.320585] LustreError: 386481:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [11270.373148] Lustre: Unmounted lustre-client [11272.735484] LustreError: 386501:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049d0fc8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11272.739064] LustreError: 386501:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [11272.749419] LustreError: 386501:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11272.758939] LustreError: 386501:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [11272.799031] Lustre: Unmounted lustre-client [11274.049453] Lustre: DEBUG MARKER: Iteration 1 [11274.675451] LustreError: 386665:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11274.677243] LustreError: 386666:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11274.694407] LustreError: 386665:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4983 [11274.955070] Lustre: Mounted lustre-client [11276.187844] LustreError: 386764:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049f276f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11276.200403] LustreError: 386764:0:(lov_obd.c:783:lov_cleanup()) Skipped 3 previous similar messages [11276.217827] LustreError: 386764:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11276.224605] LustreError: 386764:0:(obd_class.h:479:obd_check_dev()) Skipped 11 previous similar messages [11276.275119] Lustre: Unmounted lustre-client [11279.052303] Key type lgssc unregistered [11279.348966] LNet: 387009:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11279.363560] LNetError: 387009:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11279.392174] LNet: Removed LNI 192.168.204.21@tcp [11280.238176] Key type .llcrypt unregistered [11280.247529] Key type ._llcrypt unregistered [11280.874247] Key type ._llcrypt registered [11280.875612] Key type .llcrypt registered [11281.788547] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11281.804265] alg: No test for adler32 (adler32-zlib) [11283.144458] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11284.154515] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11286.039177] Key type lgssc registered [11287.722313] Lustre: Echo OBD driver; http://www.lustre.org/ [11300.028730] Lustre: DEBUG MARKER: Iteration 2 [11300.469485] LustreError: 387839:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11300.476210] LustreError: 387840:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11300.491208] LustreError: 387839:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [11301.721485] Lustre: Mounted lustre-client [11303.392995] LustreError: 387943:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049f2672000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11303.406912] LustreError: 387943:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11303.481805] Lustre: Unmounted lustre-client [11306.447846] Key type lgssc unregistered [11306.736549] LNet: 388187:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11306.756791] LNetError: 388187:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11306.781417] LNet: Removed LNI 192.168.204.21@tcp [11307.619638] Key type .llcrypt unregistered [11307.621244] Key type ._llcrypt unregistered [11308.708592] Key type ._llcrypt registered [11308.709985] Key type .llcrypt registered [11309.180369] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11309.208504] alg: No test for adler32 (adler32-zlib) [11310.519512] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11310.934974] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11312.663552] Key type lgssc registered [11314.300860] Lustre: Echo OBD driver; http://www.lustre.org/ [11328.094615] Lustre: DEBUG MARKER: Iteration 3 [11328.482491] LustreError: 389020:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11328.482492] LustreError: 389019:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11328.499753] LustreError: 389020:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4984 [11329.746047] Lustre: Mounted lustre-client [11330.985382] LustreError: 389121:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049d12d5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11331.006428] LustreError: 389121:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11331.072076] Lustre: Unmounted lustre-client [11333.585411] Key type lgssc unregistered [11333.842992] LNet: 389367:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11333.853165] LNetError: 389367:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11333.876639] LNet: Removed LNI 192.168.204.21@tcp [11334.492749] Key type .llcrypt unregistered [11334.494246] Key type ._llcrypt unregistered [11334.994312] Key type ._llcrypt registered [11334.995827] Key type .llcrypt registered [11335.387481] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11335.415751] alg: No test for adler32 (adler32-zlib) [11336.419510] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11336.644162] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11338.327190] Key type lgssc registered [11339.488668] Lustre: Echo OBD driver; http://www.lustre.org/ [11350.898100] Lustre: DEBUG MARKER: Iteration 4 [11351.375745] LustreError: 390196:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11351.378993] LustreError: 390197:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11351.385393] LustreError: 390196:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [11352.684280] Lustre: Mounted lustre-client [11354.014551] LustreError: 390294:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049fff9b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11354.039725] LustreError: 390294:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11354.114452] Lustre: Unmounted lustre-client [11356.659905] Key type lgssc unregistered [11356.920607] LNet: 390543:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11356.934993] LNetError: 390543:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11357.993200] LNet: Removed LNI 192.168.204.21@tcp [11358.628215] Key type .llcrypt unregistered [11358.629833] Key type ._llcrypt unregistered [11359.800366] Key type ._llcrypt registered [11359.801820] Key type .llcrypt registered [11360.056154] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11360.067562] alg: No test for adler32 (adler32-zlib) [11361.105973] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11361.308957] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11362.991183] Key type lgssc registered [11364.174493] Lustre: Echo OBD driver; http://www.lustre.org/ [11377.066597] Lustre: DEBUG MARKER: Iteration 5 [11377.387516] LustreError: 391371:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11377.390327] LustreError: 391372:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11377.409667] LustreError: 391371:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [11378.713844] Lustre: Mounted lustre-client [11378.725219] Lustre: Skipped 1 previous similar message [11380.288833] LustreError: 391481:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049d16a4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11380.336309] LustreError: 391483:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11380.400111] Lustre: Unmounted lustre-client [11383.769777] Key type lgssc unregistered [11384.061253] LNet: 391723:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11384.082274] LNetError: 391723:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11385.129604] LNet: Removed LNI 192.168.204.21@tcp [11385.929117] Key type .llcrypt unregistered [11385.940778] Key type ._llcrypt unregistered [11387.259238] Key type ._llcrypt registered [11387.262073] Key type .llcrypt registered [11387.546717] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11387.574099] alg: No test for adler32 (adler32-zlib) [11388.778369] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11389.049673] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11390.727178] Key type lgssc registered [11391.909703] Lustre: Echo OBD driver; http://www.lustre.org/ [11402.825674] Lustre: DEBUG MARKER: Iteration 6 [11403.120577] LustreError: 392553:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11403.121134] LustreError: 392554:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11403.133767] LustreError: 392553:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [11404.340889] Lustre: Mounted lustre-client [11405.329733] LustreError: 392651:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049f2670800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11405.347850] LustreError: 392651:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [11405.410468] Lustre: Unmounted lustre-client [11407.762797] Key type lgssc unregistered [11408.021591] LNet: 392900:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11408.031758] LNetError: 392900:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11408.048718] LNet: Removed LNI 192.168.204.21@tcp [11408.668274] Key type .llcrypt unregistered [11408.670045] Key type ._llcrypt unregistered [11409.513115] Key type ._llcrypt registered [11409.524677] Key type .llcrypt registered [11409.848073] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11409.857595] alg: No test for adler32 (adler32-zlib) [11410.923800] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11411.114336] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11412.775198] Key type lgssc registered [11413.867411] Lustre: Echo OBD driver; http://www.lustre.org/ [11423.841798] Lustre: DEBUG MARKER: Iteration 7 [11424.159428] LustreError: 393727:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11424.161948] LustreError: 393731:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11424.171834] LustreError: 393727:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [11425.473940] Lustre: Mounted lustre-client [11426.695259] LustreError: 393832:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049d12d2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11426.726663] LustreError: 393832:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11426.778416] Lustre: Unmounted lustre-client [11429.183110] Key type lgssc unregistered [11429.417644] LNet: 394073:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11429.425297] LNetError: 394073:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11429.444285] LNet: Removed LNI 192.168.204.21@tcp [11429.918208] Key type .llcrypt unregistered [11429.919856] Key type ._llcrypt unregistered [11430.824550] Key type ._llcrypt registered [11430.826143] Key type .llcrypt registered [11431.111945] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11431.124328] alg: No test for adler32 (adler32-zlib) [11432.211363] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11432.459666] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11434.119173] Key type lgssc registered [11435.184467] Lustre: Echo OBD driver; http://www.lustre.org/ [11444.496935] Lustre: DEBUG MARKER: Iteration 8 [11444.817975] LustreError: 394898:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11444.819888] LustreError: 394903:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11444.841618] LustreError: 394898:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [11446.123079] Lustre: Mounted lustre-client [11447.225212] LustreError: 395002:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049c2ad2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11447.248223] LustreError: 395002:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11447.306029] Lustre: Unmounted lustre-client [11450.298254] Key type lgssc unregistered [11450.528121] LNet: 395252:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11450.537128] LNetError: 395252:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11450.552874] LNet: Removed LNI 192.168.204.21@tcp [11451.254159] Key type .llcrypt unregistered [11451.255314] Key type ._llcrypt unregistered [11452.066263] Key type ._llcrypt registered [11452.072056] Key type .llcrypt registered [11452.438914] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11452.449248] alg: No test for adler32 (adler32-zlib) [11453.479900] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11453.687332] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11455.391921] Key type lgssc registered [11456.466187] Lustre: Echo OBD driver; http://www.lustre.org/ [11466.766926] Lustre: DEBUG MARKER: Iteration 9 [11467.033407] LustreError: 396079:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11467.038592] LustreError: 396089:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11467.041973] LustreError: 396079:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [11468.349217] Lustre: Mounted lustre-client [11469.580069] LustreError: 396177:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049f276f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11469.621612] LustreError: 396177:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11469.718284] Lustre: Unmounted lustre-client [11472.618077] Key type lgssc unregistered [11472.845893] LNet: 396426:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11472.851294] LNetError: 396426:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11472.876419] LNet: Removed LNI 192.168.204.21@tcp [11473.387186] Key type .llcrypt unregistered [11473.389125] Key type ._llcrypt unregistered [11474.064221] Key type ._llcrypt registered [11474.066047] Key type .llcrypt registered [11474.290644] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11474.307115] alg: No test for adler32 (adler32-zlib) [11475.287494] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11475.503421] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11477.167208] Key type lgssc registered [11478.108648] Lustre: Echo OBD driver; http://www.lustre.org/ [11487.477207] Lustre: DEBUG MARKER: Iteration 10 [11487.683522] LustreError: 397253:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11487.684238] LustreError: 397256:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11487.698638] LustreError: 397253:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11488.923803] Lustre: Mounted lustre-client [11488.925368] Lustre: Skipped 1 previous similar message [11489.963657] LustreError: 397363:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049c682c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11489.982968] LustreError: 397363:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11490.072463] Lustre: Unmounted lustre-client [11492.358291] Key type lgssc unregistered [11492.623773] LNet: 397604:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11492.627516] LNetError: 397604:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11492.641800] LNet: Removed LNI 192.168.204.21@tcp [11493.225152] Key type .llcrypt unregistered [11493.227847] Key type ._llcrypt unregistered [11493.920371] Key type ._llcrypt registered [11493.922018] Key type .llcrypt registered [11494.347102] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11494.366161] alg: No test for adler32 (adler32-zlib) [11495.500175] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11495.728932] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11497.415442] Key type lgssc registered [11498.525717] Lustre: Echo OBD driver; http://www.lustre.org/ [11507.746154] Lustre: DEBUG MARKER: Iteration 11 [11508.111140] LustreError: 398434:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11508.111495] LustreError: 398435:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11508.119656] LustreError: 398434:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [11509.355639] Lustre: Mounted lustre-client [11510.598738] LustreError: 398532:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049c5350800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11510.628411] LustreError: 398532:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11510.706858] Lustre: Unmounted lustre-client [11513.305860] Key type lgssc unregistered [11513.542903] LNet: 398781:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11513.564430] LNetError: 398781:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11514.601481] LNet: Removed LNI 192.168.204.21@tcp [11515.155147] Key type .llcrypt unregistered [11515.160935] Key type ._llcrypt unregistered [11515.822895] Key type ._llcrypt registered [11515.852405] Key type .llcrypt registered [11516.074243] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11516.084292] alg: No test for adler32 (adler32-zlib) [11517.069482] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11517.233887] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11518.855196] Key type lgssc registered [11519.762755] Lustre: Echo OBD driver; http://www.lustre.org/ [11528.243620] Lustre: DEBUG MARKER: Iteration 12 [11528.502870] LustreError: 399609:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11528.503533] LustreError: 399611:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11528.518416] LustreError: 399609:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [11529.715481] Lustre: Mounted lustre-client [11530.883053] LustreError: 399709:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff904a0072d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11530.909436] LustreError: 399709:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11530.971097] Lustre: Unmounted lustre-client [11533.425481] Key type lgssc unregistered [11533.700865] LNet: 399957:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11533.718145] LNetError: 399957:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11533.727813] LNet: Removed LNI 192.168.204.21@tcp [11534.479154] Key type .llcrypt unregistered [11534.480617] Key type ._llcrypt unregistered [11535.634323] Key type ._llcrypt registered [11535.647696] Key type .llcrypt registered [11535.871811] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11535.903151] alg: No test for adler32 (adler32-zlib) [11536.947813] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11537.142835] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11538.802196] Key type lgssc registered [11540.175552] Lustre: Echo OBD driver; http://www.lustre.org/ [11551.615514] Lustre: DEBUG MARKER: Iteration 13 [11551.925948] LustreError: 400788:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11551.926635] LustreError: 400787:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11551.943288] LustreError: 400788:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [11553.132126] Lustre: Mounted lustre-client [11554.396627] LustreError: 400885:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049c2fe0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11554.417631] LustreError: 400892:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [11554.426096] LustreError: 400892:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11554.498852] Lustre: Unmounted lustre-client [11557.114181] Key type lgssc unregistered [11557.400780] LNet: 401135:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11557.420100] LNetError: 401135:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11557.445454] LNet: Removed LNI 192.168.204.21@tcp [11558.031586] Key type .llcrypt unregistered [11558.035577] Key type ._llcrypt unregistered [11559.106586] Key type ._llcrypt registered [11559.109658] Key type .llcrypt registered [11559.398298] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11559.414732] alg: No test for adler32 (adler32-zlib) [11560.498577] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11560.700355] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11562.383400] Key type lgssc registered [11563.693949] Lustre: Echo OBD driver; http://www.lustre.org/ [11574.350969] Lustre: DEBUG MARKER: Iteration 14 [11574.748304] LustreError: 401965:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11574.752275] LustreError: 401966:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11574.756486] LustreError: 401965:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [11576.022412] Lustre: Mounted lustre-client [11577.254145] LustreError: 402059:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049d0e95800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11577.274288] LustreError: 402059:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11577.348879] Lustre: Unmounted lustre-client [11580.134426] Key type lgssc unregistered [11580.473160] LNet: 402308:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11580.489295] LNetError: 402308:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11580.520715] LNet: Removed LNI 192.168.204.21@tcp [11581.190180] Key type .llcrypt unregistered [11581.196652] Key type ._llcrypt unregistered [11581.976635] Key type ._llcrypt registered [11581.981883] Key type .llcrypt registered [11582.405278] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11582.418776] alg: No test for adler32 (adler32-zlib) [11583.496131] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11583.842822] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11585.479194] Key type lgssc registered [11586.804444] Lustre: Echo OBD driver; http://www.lustre.org/ [11597.666696] Lustre: DEBUG MARKER: Iteration 15 [11598.006376] LustreError: 403134:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11598.009210] LustreError: 403139:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11598.028583] LustreError: 403134:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [11600.396318] Lustre: Mounted lustre-client [11601.654894] LustreError: 403238:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049c2fe1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11601.690762] LustreError: 403238:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11601.787450] Lustre: Unmounted lustre-client [11604.308977] Key type lgssc unregistered [11604.567127] LNet: 403487:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11604.570704] LNetError: 403487:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11605.606730] LNet: Removed LNI 192.168.204.21@tcp [11606.175169] Key type .llcrypt unregistered [11606.180305] Key type ._llcrypt unregistered [11607.061284] Key type ._llcrypt registered [11607.064488] Key type .llcrypt registered [11607.227017] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11607.239658] alg: No test for adler32 (adler32-zlib) [11608.264108] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11608.452343] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11610.111636] Key type lgssc registered [11611.305447] Lustre: Echo OBD driver; http://www.lustre.org/ [11622.653251] Lustre: DEBUG MARKER: Iteration 16 [11623.103369] LustreError: 404316:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11623.110106] LustreError: 404317:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11623.123917] LustreError: 404316:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4984 [11624.361352] Lustre: Mounted lustre-client [11626.024314] LustreError: 404425:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049f276b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11626.050244] LustreError: 404425:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11626.113260] Lustre: Unmounted lustre-client [11629.044552] Key type lgssc unregistered [11629.350169] LNet: 404667:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11629.365946] LNetError: 404667:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11629.397244] LNet: Removed LNI 192.168.204.21@tcp [11630.123260] Key type .llcrypt unregistered [11630.125990] Key type ._llcrypt unregistered [11631.045486] Key type ._llcrypt registered [11631.046614] Key type .llcrypt registered [11631.393959] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11631.402023] alg: No test for adler32 (adler32-zlib) [11632.516823] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11632.913866] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11634.751130] Key type lgssc registered [11636.308548] Lustre: Echo OBD driver; http://www.lustre.org/ [11648.092376] Lustre: DEBUG MARKER: Iteration 17 [11648.462958] LustreError: 405496:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11648.466335] LustreError: 405498:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11648.473585] LustreError: 405496:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [11649.714549] Lustre: Mounted lustre-client [11649.720169] Lustre: Skipped 1 previous similar message [11651.047269] LustreError: 405601:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049c394b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11651.075905] LustreError: 405601:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11651.138511] Lustre: Unmounted lustre-client [11653.999713] Key type lgssc unregistered [11654.293976] LNet: 405850:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11654.302785] LNetError: 405850:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11655.334254] LNet: Removed LNI 192.168.204.21@tcp [11655.985132] Key type .llcrypt unregistered [11655.986803] Key type ._llcrypt unregistered [11656.888929] Key type ._llcrypt registered [11656.896704] Key type .llcrypt registered [11657.120102] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11657.146321] alg: No test for adler32 (adler32-zlib) [11658.264269] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11658.475431] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11660.175131] Key type lgssc registered [11661.444620] Lustre: Echo OBD driver; http://www.lustre.org/ [11673.207955] Lustre: DEBUG MARKER: Iteration 18 [11673.528718] LustreError: 406680:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11673.528771] LustreError: 406681:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11673.538866] LustreError: 406680:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [11674.755266] Lustre: Mounted lustre-client [11676.012355] LustreError: 406780:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049e0599800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11676.025994] LustreError: 406780:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11676.115392] Lustre: Unmounted lustre-client [11678.894273] Key type lgssc unregistered [11679.240630] LNet: 407029:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11679.249679] LNetError: 407029:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11679.271038] LNet: Removed LNI 192.168.204.21@tcp [11680.000291] Key type .llcrypt unregistered [11680.003452] Key type ._llcrypt unregistered [11680.876316] Key type ._llcrypt registered [11680.884035] Key type .llcrypt registered [11681.373549] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11681.398608] alg: No test for adler32 (adler32-zlib) [11682.570065] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11682.802810] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11684.479157] Key type lgssc registered [11686.003188] Lustre: Echo OBD driver; http://www.lustre.org/ [11697.531149] Lustre: DEBUG MARKER: Iteration 19 [11697.906087] LustreError: 407859:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11697.914232] LustreError: 407860:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11697.925036] LustreError: 407859:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4984 [11699.192066] Lustre: Mounted lustre-client [11700.510849] LustreError: 407952:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049eb100800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11700.533250] LustreError: 407952:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11700.620700] Lustre: Unmounted lustre-client [11703.383807] Key type lgssc unregistered [11703.674192] LNet: 408202:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11703.677886] LNetError: 408202:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11703.710237] LNet: Removed LNI 192.168.204.21@tcp [11704.417146] Key type .llcrypt unregistered [11704.418623] Key type ._llcrypt unregistered [11705.038351] Key type ._llcrypt registered [11705.040853] Key type .llcrypt registered [11705.700228] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11705.713952] alg: No test for adler32 (adler32-zlib) [11706.859025] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11707.125242] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11708.839566] Key type lgssc registered [11710.402452] Lustre: Echo OBD driver; http://www.lustre.org/ [11722.835256] Lustre: DEBUG MARKER: Iteration 20 [11723.062245] LustreError: 409031:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11723.089780] LustreError: 409048:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11723.096619] LustreError: 409031:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4971 [11724.406145] Lustre: Mounted lustre-client [11725.700214] LustreError: 409133:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049d16a0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11725.711996] LustreError: 409133:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11725.801280] Lustre: Unmounted lustre-client [11728.145383] Key type lgssc unregistered [11728.430103] LNet: 409381:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11728.441818] LNetError: 409381:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11728.461918] LNet: Removed LNI 192.168.204.21@tcp [11729.082337] Key type .llcrypt unregistered [11729.088624] Key type ._llcrypt unregistered [11730.301926] Key type ._llcrypt registered [11730.309751] Key type .llcrypt registered [11730.489281] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11730.504514] alg: No test for adler32 (adler32-zlib) [11731.702467] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11731.942492] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11733.631194] Key type lgssc registered [11734.864595] Lustre: Echo OBD driver; http://www.lustre.org/ [11746.321886] Lustre: DEBUG MARKER: Iteration 21 [11746.746183] LustreError: 410213:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11746.746896] LustreError: 410214:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11746.761630] LustreError: 410213:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [11748.032083] Lustre: Mounted lustre-client [11748.033573] Lustre: Skipped 1 previous similar message [11749.646946] LustreError: 410311:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049e0680000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11749.675211] LustreError: 410311:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11749.757260] Lustre: Unmounted lustre-client [11752.334182] Key type lgssc unregistered [11752.570577] LNet: 410561:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11752.581187] LNetError: 410561:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11752.605059] LNet: Removed LNI 192.168.204.21@tcp [11753.339272] Key type .llcrypt unregistered [11753.348384] Key type ._llcrypt unregistered [11754.425712] Key type ._llcrypt registered [11754.427386] Key type .llcrypt registered [11754.704823] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11754.730592] alg: No test for adler32 (adler32-zlib) [11755.944154] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11756.166286] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11757.865561] Key type lgssc registered [11758.997065] Lustre: Echo OBD driver; http://www.lustre.org/ [11771.171760] Lustre: DEBUG MARKER: Iteration 22 [11771.533332] LustreError: 411394:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11771.533725] LustreError: 411395:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11771.545844] LustreError: 411394:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [11772.764891] Lustre: Mounted lustre-client [11773.941790] LustreError: 411491:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049c5618000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11773.963151] LustreError: 411491:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11774.018048] Lustre: Unmounted lustre-client [11776.465680] Key type lgssc unregistered [11776.741887] LNet: 411740:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11776.749721] LNetError: 411740:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11776.775259] LNet: Removed LNI 192.168.204.21@tcp [11777.252779] Key type .llcrypt unregistered [11777.257554] Key type ._llcrypt unregistered [11778.147541] Key type ._llcrypt registered [11778.149901] Key type .llcrypt registered [11778.466826] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11778.479320] alg: No test for adler32 (adler32-zlib) [11779.595370] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11779.802877] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11781.464117] Key type lgssc registered [11782.704712] Lustre: Echo OBD driver; http://www.lustre.org/ [11793.109312] Lustre: DEBUG MARKER: Iteration 23 [11793.466160] LustreError: 412571:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11793.468023] LustreError: 412572:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11793.513123] LustreError: 412571:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11794.786430] Lustre: Mounted lustre-client [11795.945478] LustreError: 412673:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049eb105000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11795.975898] LustreError: 412673:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11796.077166] Lustre: Unmounted lustre-client [11799.004714] Key type lgssc unregistered [11799.259407] LNet: 412923:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11799.266765] LNetError: 412923:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11799.283445] LNet: Removed LNI 192.168.204.21@tcp [11799.906307] Key type .llcrypt unregistered [11799.912327] Key type ._llcrypt unregistered [11800.724802] Key type ._llcrypt registered [11800.726874] Key type .llcrypt registered [11801.153487] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11801.169286] alg: No test for adler32 (adler32-zlib) [11802.215688] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11802.518264] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11804.207174] Key type lgssc registered [11805.160234] Lustre: Echo OBD driver; http://www.lustre.org/ [11815.653747] Lustre: DEBUG MARKER: Iteration 24 [11816.016272] LustreError: 413749:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11816.023142] LustreError: 413761:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11816.028507] LustreError: 413749:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [11817.241496] Lustre: Mounted lustre-client [11818.416400] LustreError: 413859:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049d11a4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11818.423459] LustreError: 413859:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11818.433635] LustreError: 413851:0:(obd_class.h:479:obd_check_dev()) Device 7 not setup [11818.497105] Lustre: Unmounted lustre-client [11820.710960] Key type lgssc unregistered [11820.987695] LNet: 414100:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11820.998198] LNetError: 414100:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11821.019556] LNet: Removed LNI 192.168.204.21@tcp [11821.724175] Key type .llcrypt unregistered [11821.726152] Key type ._llcrypt unregistered [11822.324083] Key type ._llcrypt registered [11822.326352] Key type .llcrypt registered [11822.449587] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11822.462658] alg: No test for adler32 (adler32-zlib) [11823.648486] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11823.837366] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11825.551180] Key type lgssc registered [11826.824927] Lustre: Echo OBD driver; http://www.lustre.org/ [11838.401373] Lustre: DEBUG MARKER: Iteration 25 [11838.772715] LustreError: 414927:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11838.777115] LustreError: 414928:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11838.803067] LustreError: 414927:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4974 [11840.098375] Lustre: Mounted lustre-client [11841.624806] LustreError: 415027:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049e0fc3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11841.649996] LustreError: 415027:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11841.698743] Lustre: Unmounted lustre-client [11844.357924] Key type lgssc unregistered [11844.617792] LNet: 415273:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11844.621338] LNetError: 415273:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11844.634812] LNet: Removed LNI 192.168.204.21@tcp [11845.335721] Key type .llcrypt unregistered [11845.346536] Key type ._llcrypt unregistered [11845.885862] Key type ._llcrypt registered [11845.887154] Key type .llcrypt registered [11846.436836] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11846.456898] alg: No test for adler32 (adler32-zlib) [11847.561822] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11847.770447] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11849.423476] Key type lgssc registered [11850.607058] Lustre: Echo OBD driver; http://www.lustre.org/ [11861.927856] Lustre: DEBUG MARKER: Iteration 26 [11862.266897] LustreError: 416098:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11862.267420] LustreError: 416100:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11862.281182] LustreError: 416098:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [11863.654565] Lustre: Mounted lustre-client [11865.197486] LustreError: 416202:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049d12d1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11865.229315] LustreError: 416202:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11865.299983] Lustre: Unmounted lustre-client [11868.279027] Key type lgssc unregistered [11868.619694] LNet: 416449:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11868.624922] LNetError: 416449:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11868.649150] LNet: Removed LNI 192.168.204.21@tcp [11869.469172] Key type .llcrypt unregistered [11869.470880] Key type ._llcrypt unregistered [11870.138967] Key type ._llcrypt registered [11870.140349] Key type .llcrypt registered [11870.876679] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11870.895559] alg: No test for adler32 (adler32-zlib) [11872.049479] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11872.394269] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11874.119403] Key type lgssc registered [11875.408784] Lustre: Echo OBD driver; http://www.lustre.org/ [11887.089696] Lustre: DEBUG MARKER: Iteration 27 [11887.484633] LustreError: 417279:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11887.488496] LustreError: 417281:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11887.493391] LustreError: 417279:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [11888.750412] Lustre: Mounted lustre-client [11890.483792] LustreError: 417387:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049d16a2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11890.513180] LustreError: 417387:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11890.596588] Lustre: Unmounted lustre-client [11893.513647] Key type lgssc unregistered [11893.823512] LNet: 417632:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11893.831335] LNetError: 417632:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11893.854384] LNet: Removed LNI 192.168.204.21@tcp [11894.554142] Key type .llcrypt unregistered [11894.558304] Key type ._llcrypt unregistered [11895.374760] Key type ._llcrypt registered [11895.384423] Key type .llcrypt registered [11895.739418] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11895.755357] alg: No test for adler32 (adler32-zlib) [11896.973748] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11897.207888] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11898.919549] Key type lgssc registered [11900.336687] Lustre: Echo OBD driver; http://www.lustre.org/ [11912.856924] Lustre: DEBUG MARKER: Iteration 28 [11913.200948] LustreError: 418463:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11913.205143] LustreError: 418462:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11913.217079] LustreError: 418463:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4985 [11914.475927] Lustre: Mounted lustre-client [11915.833903] LustreError: 418559:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049e028d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11915.864117] LustreError: 418559:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [11915.923120] Lustre: Unmounted lustre-client [11918.911579] Key type lgssc unregistered [11919.211704] LNet: 418807:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11919.234320] LNetError: 418807:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11919.253287] LNet: Removed LNI 192.168.204.21@tcp [11920.109151] Key type .llcrypt unregistered [11920.114352] Key type ._llcrypt unregistered [11921.037414] Key type ._llcrypt registered [11921.058732] Key type .llcrypt registered [11921.587684] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11921.612507] alg: No test for adler32 (adler32-zlib) [11922.798674] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11923.152748] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11924.839138] Key type lgssc registered [11926.158440] Lustre: Echo OBD driver; http://www.lustre.org/ [11938.696128] Lustre: DEBUG MARKER: Iteration 29 [11939.062219] LustreError: 419637:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11939.064933] LustreError: 419639:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11939.075371] LustreError: 419637:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [11940.340214] Lustre: Mounted lustre-client [11940.346101] Lustre: Skipped 1 previous similar message [11941.809756] LustreError: 419738:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049c394d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11941.831350] LustreError: 419738:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11941.882587] Lustre: Unmounted lustre-client [11944.631501] Key type lgssc unregistered [11944.908173] LNet: 419988:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11944.919034] LNetError: 419988:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11944.942164] LNet: Removed LNI 192.168.204.21@tcp [11945.515133] Key type .llcrypt unregistered [11945.520382] Key type ._llcrypt unregistered [11946.298629] Key type ._llcrypt registered [11946.300462] Key type .llcrypt registered [11946.897426] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11946.918168] alg: No test for adler32 (adler32-zlib) [11947.983712] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11948.236289] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11950.024633] Key type lgssc registered [11951.486597] Lustre: Echo OBD driver; http://www.lustre.org/ [11963.579121] Lustre: DEBUG MARKER: Iteration 30 [11963.921216] LustreError: 420816:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11963.929153] LustreError: 420820:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11963.939838] LustreError: 420816:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [11965.295022] Lustre: Mounted lustre-client [11966.900517] LustreError: 420920:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049f2670000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11966.933171] LustreError: 420920:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11967.011384] Lustre: Unmounted lustre-client [11969.618723] Key type lgssc unregistered [11969.920641] LNet: 421169:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11969.933479] LNetError: 421169:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11969.950306] LNet: Removed LNI 192.168.204.21@tcp [11970.684251] Key type .llcrypt unregistered [11970.690138] Key type ._llcrypt unregistered [11971.925072] Key type ._llcrypt registered [11971.955489] Key type .llcrypt registered [11972.286244] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11972.309365] alg: No test for adler32 (adler32-zlib) [11973.569158] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11973.844951] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11975.567194] Key type lgssc registered [11976.697438] Lustre: Echo OBD driver; http://www.lustre.org/ [11987.944760] Lustre: DEBUG MARKER: Iteration 31 [11988.350872] LustreError: 421998:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11988.351192] LustreError: 422000:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11988.362170] LustreError: 421998:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [11989.691824] Lustre: Mounted lustre-client [11990.909961] LustreError: 422095:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049fa188000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11990.937050] LustreError: 422095:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11991.003669] Lustre: Unmounted lustre-client [11994.277897] Key type lgssc unregistered [11994.586573] LNet: 422345:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11994.594907] LNetError: 422345:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11994.612918] LNet: Removed LNI 192.168.204.21@tcp [11995.394247] Key type .llcrypt unregistered [11995.396289] Key type ._llcrypt unregistered [11996.180655] Key type ._llcrypt registered [11996.185590] Key type .llcrypt registered [11996.831171] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11996.847916] alg: No test for adler32 (adler32-zlib) [11997.958482] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [11998.234201] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [11999.928556] Key type lgssc registered [12001.267251] Lustre: Echo OBD driver; http://www.lustre.org/ [12012.199396] Lustre: DEBUG MARKER: Iteration 32 [12012.484385] LustreError: 423174:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12012.484892] LustreError: 423175:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12012.497110] LustreError: 423174:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [12013.665395] Lustre: Mounted lustre-client [12014.808697] LustreError: 423273:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049d0e95800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12014.824917] LustreError: 423273:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12014.833777] LustreError: 423278:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12014.875507] Lustre: Unmounted lustre-client [12017.064586] Key type lgssc unregistered [12017.320367] LNet: 423521:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12017.326333] LNetError: 423521:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12017.339761] LNet: Removed LNI 192.168.204.21@tcp [12017.957348] Key type .llcrypt unregistered [12017.959092] Key type ._llcrypt unregistered [12018.539935] Key type ._llcrypt registered [12018.541134] Key type .llcrypt registered [12018.921231] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12018.936073] alg: No test for adler32 (adler32-zlib) [12020.214479] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [12020.449171] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [12022.175244] Key type lgssc registered [12023.628205] Lustre: Echo OBD driver; http://www.lustre.org/ [12037.698447] Lustre: DEBUG MARKER: Iteration 33 [12038.106628] LustreError: 424345:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12038.110332] LustreError: 424350:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12038.124699] LustreError: 424345:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [12039.480068] Lustre: Mounted lustre-client [12041.281445] LustreError: 424452:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049d16a7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12041.300876] LustreError: 424452:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12041.382206] Lustre: Unmounted lustre-client [12044.413183] Key type lgssc unregistered [12044.725972] LNet: 424696:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12044.738108] LNetError: 424696:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12044.767638] LNet: Removed LNI 192.168.204.21@tcp [12045.549133] Key type .llcrypt unregistered [12045.556745] Key type ._llcrypt unregistered [12046.636960] Key type ._llcrypt registered [12046.638685] Key type .llcrypt registered [12047.110340] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12047.129526] alg: No test for adler32 (adler32-zlib) [12048.170877] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [12048.450889] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [12050.209472] Key type lgssc registered [12051.198477] Lustre: Echo OBD driver; http://www.lustre.org/ [12061.551029] Lustre: DEBUG MARKER: Iteration 34 [12062.142128] LustreError: 425526:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12062.143801] LustreError: 425527:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12062.162665] LustreError: 425526:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [12063.493577] Lustre: Mounted lustre-client [12065.102347] LustreError: 425626:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049d11a7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12065.132673] LustreError: 425626:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12065.222301] Lustre: Unmounted lustre-client [12067.546385] Key type lgssc unregistered [12067.775326] LNet: 425875:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12067.785712] LNetError: 425875:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12067.804312] LNet: Removed LNI 192.168.204.21@tcp [12068.395310] Key type .llcrypt unregistered [12068.397950] Key type ._llcrypt unregistered [12069.176695] Key type ._llcrypt registered [12069.178229] Key type .llcrypt registered [12069.559204] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12069.571989] alg: No test for adler32 (adler32-zlib) [12070.730771] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [12070.967549] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [12072.647207] Key type lgssc registered [12073.907509] Lustre: Echo OBD driver; http://www.lustre.org/ [12083.675683] Lustre: DEBUG MARKER: Iteration 35 [12084.091268] LustreError: 426705:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12084.098242] LustreError: 426712:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12084.109412] LustreError: 426705:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [12086.404088] Lustre: Mounted lustre-client [12087.579719] LustreError: 426804:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049c3ee2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12087.596913] LustreError: 426804:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12087.663684] Lustre: Unmounted lustre-client [12089.799074] Key type lgssc unregistered [12090.044702] LNet: 427055:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12090.052698] LNetError: 427055:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12090.075590] LNet: Removed LNI 192.168.204.21@tcp [12090.687342] Key type .llcrypt unregistered [12090.689103] Key type ._llcrypt unregistered [12091.306482] Key type ._llcrypt registered [12091.308125] Key type .llcrypt registered [12091.765288] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12091.785258] alg: No test for adler32 (adler32-zlib) [12092.896368] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [12093.131650] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [12094.807579] Key type lgssc registered [12095.709549] Lustre: Echo OBD driver; http://www.lustre.org/ [12106.797416] Lustre: DEBUG MARKER: Iteration 36 [12107.069247] LustreError: 427884:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12107.093805] LustreError: 427891:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12107.100303] LustreError: 427884:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4969 [12108.335415] Lustre: Mounted lustre-client [12108.343511] Lustre: Skipped 1 previous similar message [12109.720796] LustreError: 427980:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049d16a4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12109.739294] LustreError: 427980:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [12109.827394] Lustre: Unmounted lustre-client [12112.532084] Key type lgssc unregistered [12112.833803] LNet: 428229:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12112.846590] LNetError: 428229:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12112.869194] LNet: Removed LNI 192.168.204.21@tcp [12113.561191] Key type .llcrypt unregistered [12113.570227] Key type ._llcrypt unregistered [12114.497245] Key type ._llcrypt registered [12114.499656] Key type .llcrypt registered [12114.937509] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12114.959033] alg: No test for adler32 (adler32-zlib) [12116.111577] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [12116.342902] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [12118.047552] Key type lgssc registered [12119.337115] Lustre: Echo OBD driver; http://www.lustre.org/ [12131.592456] Lustre: DEBUG MARKER: Iteration 37 [12132.065269] LustreError: 429058:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12132.073636] LustreError: 429060:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12132.081832] LustreError: 429058:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [12133.428705] Lustre: Mounted lustre-client [12134.933277] LustreError: 429162:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049e028b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12134.976937] LustreError: 429162:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [12135.029233] Lustre: Unmounted lustre-client [12137.652056] Key type lgssc unregistered [12137.951920] LNet: 429413:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12137.962564] LNetError: 429413:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12137.989512] LNet: Removed LNI 192.168.204.21@tcp [12138.649171] Key type .llcrypt unregistered [12138.650948] Key type ._llcrypt unregistered [12139.327052] Key type ._llcrypt registered [12139.332067] Key type .llcrypt registered [12139.492273] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12139.508843] alg: No test for adler32 (adler32-zlib) [12140.525540] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [12140.761837] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [12142.407174] Key type lgssc registered [12143.578585] Lustre: Echo OBD driver; http://www.lustre.org/ [12154.774598] Lustre: DEBUG MARKER: Iteration 38 [12155.070558] LustreError: 430239:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12155.093675] LustreError: 430249:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12155.101450] LustreError: 430239:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4969 [12156.337915] Lustre: Mounted lustre-client [12157.659873] LustreError: 430349:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049e028e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12157.670639] LustreError: 430349:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12157.723860] Lustre: Unmounted lustre-client [12160.241593] Key type lgssc unregistered [12160.501952] LNet: 430592:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12160.514531] LNetError: 430592:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12160.530834] LNet: Removed LNI 192.168.204.21@tcp [12161.034156] Key type .llcrypt unregistered [12161.035641] Key type ._llcrypt unregistered [12161.747260] Key type ._llcrypt registered [12161.748676] Key type .llcrypt registered [12162.164148] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12162.178973] alg: No test for adler32 (adler32-zlib) [12163.276216] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [12163.517957] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [12165.215177] Key type lgssc registered [12166.501578] Lustre: Echo OBD driver; http://www.lustre.org/ [12177.974896] Lustre: DEBUG MARKER: Iteration 39 [12178.279681] LustreError: 431421:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12178.287626] LustreError: 431422:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12178.292137] LustreError: 431421:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [12179.496133] Lustre: Mounted lustre-client [12180.755631] LustreError: 431526:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049c3ee2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12180.772205] LustreError: 431526:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12180.780097] LustreError: 431527:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12180.839187] Lustre: Unmounted lustre-client [12183.243606] Key type lgssc unregistered [12183.530818] LNet: 431769:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12183.537228] LNetError: 431769:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12183.552985] LNet: Removed LNI 192.168.204.21@tcp [12184.124157] Key type .llcrypt unregistered [12184.126522] Key type ._llcrypt unregistered [12184.666839] Key type ._llcrypt registered [12184.674410] Key type .llcrypt registered [12185.041757] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12185.051267] alg: No test for adler32 (adler32-zlib) [12186.036978] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [12186.181256] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [12187.855178] Key type lgssc registered [12188.968467] Lustre: Echo OBD driver; http://www.lustre.org/ [12199.502404] Lustre: DEBUG MARKER: Iteration 40 [12199.784287] LustreError: 432597:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12199.807274] LustreError: 432611:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12199.814234] LustreError: 432597:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4974 [12201.065027] Lustre: Mounted lustre-client [12202.440065] LustreError: 432705:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049e059a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12202.461828] LustreError: 432705:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12202.529260] Lustre: Unmounted lustre-client [12205.072686] Key type lgssc unregistered [12205.329518] LNet: 432948:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12205.349573] LNetError: 432948:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12205.370936] LNet: Removed LNI 192.168.204.21@tcp [12206.091608] Key type .llcrypt unregistered [12206.093067] Key type ._llcrypt unregistered [12207.266921] Key type ._llcrypt registered [12207.268367] Key type .llcrypt registered [12207.547205] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12207.569806] alg: No test for adler32 (adler32-zlib) [12208.732144] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [12209.000906] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [12210.727187] Key type lgssc registered [12212.129231] Lustre: Echo OBD driver; http://www.lustre.org/ [12225.443747] Lustre: DEBUG MARKER: Iteration 41 [12226.022172] LustreError: 433780:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12226.025500] LustreError: 433781:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12226.039609] LustreError: 433780:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4985 [12227.411238] Lustre: Mounted lustre-client [12228.933801] LustreError: 433881:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049d15e5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12228.960607] LustreError: 433881:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [12229.021552] Lustre: Unmounted lustre-client [12232.150853] Key type lgssc unregistered [12232.478543] LNet: 434128:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12232.488180] LNetError: 434128:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12233.522928] LNet: Removed LNI 192.168.204.21@tcp [12234.195309] Key type .llcrypt unregistered [12234.199387] Key type ._llcrypt unregistered [12235.165752] Key type ._llcrypt registered [12235.167407] Key type .llcrypt registered [12235.565203] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12235.578150] alg: No test for adler32 (adler32-zlib) [12236.733983] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [12236.984361] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [12238.640245] Key type lgssc registered [12240.051710] Lustre: Echo OBD driver; http://www.lustre.org/ [12251.731991] Lustre: DEBUG MARKER: Iteration 42 [12252.127556] LustreError: 434959:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12252.135518] LustreError: 434960:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12252.149625] LustreError: 434959:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [12253.399249] Lustre: Mounted lustre-client [12254.746141] LustreError: 435056:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049c2ad4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12254.788740] LustreError: 435056:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [12254.851980] Lustre: Unmounted lustre-client [12257.860351] Key type lgssc unregistered [12258.144857] LNet: 435303:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12258.147566] LNetError: 435303:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12259.179776] LNet: Removed LNI 192.168.204.21@tcp [12259.710414] Key type .llcrypt unregistered [12259.713468] Key type ._llcrypt unregistered [12260.446664] Key type ._llcrypt registered [12260.448067] Key type .llcrypt registered [12260.990874] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12261.003444] alg: No test for adler32 (adler32-zlib) [12262.209185] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [12262.530322] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [12264.296818] Key type lgssc registered [12265.989698] Lustre: Echo OBD driver; http://www.lustre.org/ [12277.327927] Lustre: DEBUG MARKER: Iteration 43 [12277.671396] LustreError: 436135:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12277.684936] LustreError: 436144:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12277.693960] LustreError: 436135:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4979 [12278.936689] Lustre: Mounted lustre-client [12280.356407] LustreError: 436235:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049d16a0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12280.374121] LustreError: 436235:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [12280.454802] Lustre: Unmounted lustre-client [12283.154179] Key type lgssc unregistered [12283.436767] LNet: 436483:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12283.449752] LNetError: 436483:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12283.469303] LNet: Removed LNI 192.168.204.21@tcp [12284.198161] Key type .llcrypt unregistered [12284.204517] Key type ._llcrypt unregistered [12285.021115] Key type ._llcrypt registered [12285.022717] Key type .llcrypt registered [12285.436072] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12285.453669] alg: No test for adler32 (adler32-zlib) [12286.538723] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [12286.758974] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [12288.447215] Key type lgssc registered [12289.743885] Lustre: Echo OBD driver; http://www.lustre.org/ [12300.735624] Lustre: DEBUG MARKER: Iteration 44 [12301.083278] LustreError: 437314:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12301.085226] LustreError: 437315:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12301.145939] LustreError: 437314:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4940 [12302.378391] Lustre: Mounted lustre-client [12303.841565] LustreError: 437419:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049ca809000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12303.849129] LustreError: 437419:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12303.872898] LustreError: 437421:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12303.958258] Lustre: Unmounted lustre-client [12306.798463] Key type lgssc unregistered [12307.059057] LNet: 437662:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12307.077712] LNetError: 437662:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12307.112922] LNet: Removed LNI 192.168.204.21@tcp [12307.880586] Key type .llcrypt unregistered [12307.886077] Key type ._llcrypt unregistered [12308.761478] Key type ._llcrypt registered [12308.763067] Key type .llcrypt registered [12309.264278] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12309.295848] alg: No test for adler32 (adler32-zlib) [12310.423884] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [12310.730043] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [12312.455464] Key type lgssc registered [12313.662549] Lustre: Echo OBD driver; http://www.lustre.org/ [12323.950314] Lustre: DEBUG MARKER: Iteration 45 [12324.247351] LustreError: 438493:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12324.248700] LustreError: 438492:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12324.278051] LustreError: 438492:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [12325.513864] Lustre: Mounted lustre-client [12326.734913] LustreError: 438596:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049ff423800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12326.748298] LustreError: 438596:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12326.748773] LustreError: 438597:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [12326.832960] Lustre: Unmounted lustre-client [12328.995587] Key type lgssc unregistered [12329.254631] LNet: 438837:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12329.271349] LNetError: 438837:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12329.289447] LNet: Removed LNI 192.168.204.21@tcp [12329.881146] Key type .llcrypt unregistered [12329.882607] Key type ._llcrypt unregistered [12330.638186] Key type ._llcrypt registered [12330.685175] Key type .llcrypt registered [12331.056660] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12331.078408] alg: No test for adler32 (adler32-zlib) [12332.188944] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [12332.366396] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [12334.047240] Key type lgssc registered [12335.401227] Lustre: Echo OBD driver; http://www.lustre.org/ [12347.093238] Lustre: DEBUG MARKER: Iteration 46 [12347.599067] LustreError: 439669:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12347.599488] LustreError: 439672:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12347.615951] LustreError: 439669:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [12348.925750] Lustre: Mounted lustre-client [12348.933639] Lustre: Skipped 1 previous similar message [12350.679683] LustreError: 439771:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049c394d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12350.708512] LustreError: 439771:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [12350.716673] LustreError: 439771:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [12350.821367] Lustre: Unmounted lustre-client [12353.924231] Key type lgssc unregistered [12354.193403] LNet: 440015:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12354.208123] LNetError: 440015:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12355.238583] LNet: Removed LNI 192.168.204.21@tcp [12355.982249] Key type .llcrypt unregistered [12355.983676] Key type ._llcrypt unregistered [12356.809657] Key type ._llcrypt registered [12356.811391] Key type .llcrypt registered [12357.457843] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12357.474172] alg: No test for adler32 (adler32-zlib) [12358.631242] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [12358.872857] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [12360.567183] Key type lgssc registered [12362.087483] Lustre: Echo OBD driver; http://www.lustre.org/ [12374.175588] Lustre: DEBUG MARKER: Iteration 47 [12374.564986] LustreError: 440846:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12374.567808] LustreError: 440845:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12374.589491] LustreError: 440846:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4974 [12375.820378] Lustre: Mounted lustre-client [12377.044876] LustreError: 440946:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049c3949000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12377.079337] LustreError: 440951:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [12377.082703] LustreError: 440951:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [12377.158088] Lustre: Unmounted lustre-client [12379.791958] Key type lgssc unregistered [12380.046230] LNet: 441191:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12380.057135] LNetError: 441191:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12381.094420] LNet: Removed LNI 192.168.204.21@tcp [12381.920135] Key type .llcrypt unregistered [12381.921812] Key type ._llcrypt unregistered [12383.064286] Key type ._llcrypt registered [12383.065837] Key type .llcrypt registered [12383.410303] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12383.429841] alg: No test for adler32 (adler32-zlib) [12384.566737] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [12384.825245] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [12386.567160] Key type lgssc registered [12387.813413] Lustre: Echo OBD driver; http://www.lustre.org/ [12397.349745] Lustre: DEBUG MARKER: Iteration 48 [12397.571939] LustreError: 442020:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12397.573425] LustreError: 442021:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12397.593613] LustreError: 442020:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [12398.801195] Lustre: Mounted lustre-client [12400.312760] LustreError: 442119:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049f2677000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12400.327338] LustreError: 442119:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12400.441553] Lustre: Unmounted lustre-client [12403.017257] Key type lgssc unregistered [12403.306734] LNet: 442367:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12403.320586] LNetError: 442367:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12403.338920] LNet: Removed LNI 192.168.204.21@tcp [12404.080135] Key type .llcrypt unregistered [12404.085612] Key type ._llcrypt unregistered [12404.864937] Key type ._llcrypt registered [12404.887349] Key type .llcrypt registered [12405.500665] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12405.517379] alg: No test for adler32 (adler32-zlib) [12406.603478] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [12406.777548] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [12408.471371] Key type lgssc registered [12409.933670] Lustre: Echo OBD driver; http://www.lustre.org/ [12421.067584] Lustre: DEBUG MARKER: Iteration 49 [12421.379657] LustreError: 443197:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12421.380356] LustreError: 443198:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12421.392662] LustreError: 443197:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [12422.677288] Lustre: Mounted lustre-client [12423.970833] LustreError: 443300:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049f25d6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12424.008035] LustreError: 443300:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12424.096502] Lustre: Unmounted lustre-client [12426.601187] Key type lgssc unregistered [12426.814595] LNet: 443550:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12426.819044] LNetError: 443550:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12426.827498] LNet: Removed LNI 192.168.204.21@tcp [12427.392168] Key type .llcrypt unregistered [12427.393866] Key type ._llcrypt unregistered [12428.027541] Key type ._llcrypt registered [12428.100824] Key type .llcrypt registered [12428.417193] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12428.434821] alg: No test for adler32 (adler32-zlib) [12429.452394] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [12429.768873] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [12431.455445] Key type lgssc registered [12432.523617] Lustre: Echo OBD driver; http://www.lustre.org/ [12443.337107] Lustre: DEBUG MARKER: Iteration 50 [12443.789834] LustreError: 444381:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12443.792920] LustreError: 444380:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12443.800448] LustreError: 444381:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [12445.067930] Lustre: Mounted lustre-client [12446.444266] LustreError: 444484:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049c394f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12446.462607] LustreError: 444484:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12446.544143] Lustre: Unmounted lustre-client [12448.908297] Key type lgssc unregistered [12449.151867] LNet: 444727:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12449.160242] LNetError: 444727:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12449.175621] LNet: Removed LNI 192.168.204.21@tcp [12449.855546] Key type .llcrypt unregistered [12449.857302] Key type ._llcrypt unregistered [12450.587745] Key type ._llcrypt registered [12450.589390] Key type .llcrypt registered [12451.009959] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12451.027436] alg: No test for adler32 (adler32-zlib) [12452.128476] Lustre: Lustre: Build Version: 2.16.59_37_ga5ee792 [12452.429649] LNet: Added LNI 192.168.204.21@tcp [8/256/0/180] [12454.175223] Key type lgssc registered [12455.286508] Lustre: Echo OBD driver; http://www.lustre.org/ [12465.752761] Lustre: Mounted lustre-client [12472.863797] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 07:25:42 (1761218742) [12490.721961] Lustre: 446077:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761218745/real 1761218745] req@ffff9049ed227b80 x1846771683700224/t0(0) o36->lustre-MDT0000-mdc-ffff9049f276b800@192.168.204.121@tcp:12/10 lens 496/440 e 0 to 1 dl 1761218761 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [12490.755766] Lustre: lustre-MDT0000-mdc-ffff9049f276b800: Connection to lustre-MDT0000 (at 192.168.204.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [12490.810649] Lustre: lustre-MDT0000-mdc-ffff9049f276b800: Connection restored to 192.168.204.121@tcp (at 192.168.204.121@tcp) [12506.081184] Lustre: 446077:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761218761/real 1761218761] req@ffff9049ed227b80 x1846771683700224/t0(0) o36->lustre-MDT0000-mdc-ffff9049f276b800@192.168.204.121@tcp:12/10 lens 496/440 e 0 to 1 dl 1761218777 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [12506.124088] Lustre: lustre-MDT0000-mdc-ffff9049f276b800: Connection to lustre-MDT0000 (at 192.168.204.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [12506.179548] Lustre: lustre-MDT0000-mdc-ffff9049f276b800: Connection restored to 192.168.204.121@tcp (at 192.168.204.121@tcp) [12522.463166] Lustre: 446077:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761218777/real 1761218777] req@ffff9049ed227b80 x1846771683700224/t0(0) o36->lustre-MDT0000-mdc-ffff9049f276b800@192.168.204.121@tcp:12/10 lens 496/440 e 0 to 1 dl 1761218793 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [12522.499555] Lustre: lustre-MDT0000-mdc-ffff9049f276b800: Connection to lustre-MDT0000 (at 192.168.204.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [12522.533220] Lustre: lustre-MDT0000-mdc-ffff9049f276b800: Connection restored to 192.168.204.121@tcp (at 192.168.204.121@tcp) [12538.847123] Lustre: 446077:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761218793/real 1761218793] req@ffff9049ed227b80 x1846771683700224/t0(0) o36->lustre-MDT0000-mdc-ffff9049f276b800@192.168.204.121@tcp:12/10 lens 496/440 e 0 to 1 dl 1761218809 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [12538.882069] Lustre: lustre-MDT0000-mdc-ffff9049f276b800: Connection to lustre-MDT0000 (at 192.168.204.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [12538.929018] Lustre: lustre-MDT0000-mdc-ffff9049f276b800: Connection restored to 192.168.204.121@tcp (at 192.168.204.121@tcp) [12542.773796] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 07:26:52 (1761218812) [12554.180556] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 07:27:04 (1761218824) [12565.015721] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 07:27:14 (1761218834) [12570.928458] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 07:27:20 (1761218840) [12592.238664] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 07:27:42 (1761218862) [12627.866885] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 07:28:17 (1761218897) [12636.008471] Lustre: DEBUG MARKER: == sanityn test 117a: TCU: Init and enable Trash Can on MDTs ========================================================== 07:28:25 (1761218905) [12646.163668] Lustre: DEBUG MARKER: == sanityn test 117b: Move regular file and empty dir into trash can dir ========================================================== 07:28:35 (1761218915) [12652.307239] LustreError: 451721:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff9049f276b800: inode [0x200000400:0x2:0x0] mdc close failed: rc = -9 [12660.397653] Lustre: DEBUG MARKER: == sanityn test 117c: Move deleted tree with multiple levels into trash ========================================================== 07:28:50 (1761218930) [12665.371107] LustreError: 452564:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff9049f276b800: inode [0x200000400:0x2:0x0] mdc close failed: rc = -9 [12665.378581] LustreError: 452564:0:(file.c:249:ll_close_inode_openhandle()) Skipped 6 previous similar messages [12672.569800] Lustre: DEBUG MARKER: == sanityn test 117d: Per-User Trash can Type testing ==== 07:29:02 (1761218942) [12677.380532] LustreError: 453450:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff9049f276b800: inode [0x200000400:0x2:0x0] mdc close failed: rc = -9 [12677.386091] LustreError: 453450:0:(file.c:249:ll_close_inode_openhandle()) Skipped 14 previous similar messages [12685.629956] Lustre: DEBUG MARKER: == sanityn test 117e: Undeleted dir in trash should keep its original xattrs ========================================================== 07:29:15 (1761218955) [12690.674872] LustreError: 454292:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff9049f276b800: inode [0x200000400:0x2:0x0] mdc close failed: rc = -9 [12690.681896] LustreError: 454292:0:(file.c:249:ll_close_inode_openhandle()) Skipped 10 previous similar messages [12699.233916] Lustre: DEBUG MARKER: == sanityn test 117f: Uncache the dentry under the trash dir ========================================================== 07:29:29 (1761218969) [12704.608852] LustreError: 455131:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff9049f276b800: inode [0x200000bd0:0x1e4:0x0] mdc close failed: rc = -9 [12704.613681] LustreError: 455131:0:(file.c:249:ll_close_inode_openhandle()) Skipped 13 previous similar messages [12714.984095] Lustre: DEBUG MARKER: == sanityn test 117g: Access .Trash for a non-striped directory ========================================================== 07:29:44 (1761218984) [12720.114869] LustreError: 456021:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff9049f276b800: inode [0x200000bd0:0x1eb:0x0] mdc close failed: rc = -9 [12720.121617] LustreError: 456021:0:(file.c:249:ll_close_inode_openhandle()) Skipped 41 previous similar messages [12728.362076] Lustre: DEBUG MARKER: == sanityn test 117h: Permission testing of various operations for files in Trash Can ========================================================== 07:29:58 (1761218998) [12733.851392] LustreError: 456938:0:(llite_lib.c:2047:ll_md_setattr()) md_setattr fails: rc = -9 [12742.443444] Lustre: DEBUG MARKER: == sanityn test 117i: Forbid to create a file with name .Trash ========================================================== 07:30:12 (1761219012) [12748.066098] Lustre: DEBUG MARKER: == sanityn test 117j: Move back file from Trash Can ====== 07:30:17 (1761219017) [12753.082240] LustreError: 458403:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff9049f276b800: inode [0x200000bd0:0x1ee:0x0] mdc close failed: rc = -9 [12753.087655] LustreError: 458403:0:(file.c:249:ll_close_inode_openhandle()) Skipped 15 previous similar messages [12761.589808] Lustre: DEBUG MARKER: == sanityn test 117k: Unrm file from Trash Can =========== 07:30:31 (1761219031) [12774.890918] Lustre: DEBUG MARKER: == sanityn test 117l: Clean up files in Trash Can via command 'lfs trash clean' ========================================================== 07:30:44 (1761219044) [12786.872608] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 07:30:56 (1761219056) [12807.135859] Lustre: 444920:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761219062/real 1761219062] req@ffff9049ff120700 x1846771685048064/t0(0) o4->lustre-OST0000-osc-ffff9049f276b800@192.168.204.121@tcp:6/4 lens 4584/448 e 0 to 1 dl 1761219078 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [12807.136037] Lustre: lustre-OST0001-osc-ffff9049f276b800: Connection to lustre-OST0001 (at 192.168.204.121@tcp) was lost; in progress operations using this service will wait for recovery to complete [12807.152739] Lustre: 444920:0:(client.c:2468:ptlrpc_expire_one_request()) Skipped 5 previous similar messages [12807.167840] Lustre: Skipped 1 previous similar message [12807.184586] Lustre: lustre-OST0001-osc-ffff9049f276b800: Connection restored to 192.168.204.121@tcp (at 192.168.204.121@tcp) [12900.796333] Lustre: DEBUG MARKER: oleg421-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9049c394e000.ost_server_uuid 50 [12902.132563] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9049c394e000.ost_server_uuid in IDLE state after 0 sec [12903.867121] Lustre: DEBUG MARKER: cleanup: ====================================================== [12905.513932] Lustre: DEBUG MARKER: == sanityn test complete, duration 12641 sec ============= 07:32:55 (1761219175) [12907.099877] Lustre: DEBUG MARKER: === sanityn: start cleanup 07:32:56 (1761219176) === [13198.759262] LustreError: 462438:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049c394e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13198.778326] LustreError: 462438:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [13198.843322] Lustre: Unmounted lustre-client [13202.411430] Lustre: DEBUG MARKER: === sanityn: finish cleanup 07:37:52 (1761219472) === [13204.246379] LustreError: 462747:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff9049f276b800: inode [0x200000400:0x2:0x0] mdc close failed: rc = -9 [13204.251388] LustreError: 462747:0:(file.c:249:ll_close_inode_openhandle()) Skipped 30 previous similar messages [13204.364820] LustreError: 462747:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9049f276b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13204.369178] LustreError: 462747:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [13204.381603] LustreError: 462747:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [13204.389394] LustreError: 462747:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [13204.443858] Lustre: Unmounted lustre-client [13248.204830] Key type lgssc unregistered [13248.474861] LNet: 463432:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13248.485786] LNetError: 463432:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [13248.499367] LNet: Removed LNI 192.168.204.21@tcp [13249.127436] Key type .llcrypt unregistered [13249.131418] Key type ._llcrypt unregistered