[ 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 391105101 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: 2870712K/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.001010] APIC: Switch to symmetric I/O mode setup [ 0.002315] x2apic enabled [ 0.003008] Switched APIC routing to physical x2apic. [ 0.004041] kvm-guest: setup PV IPIs [ 0.006898] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008026] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009020] pid_max: default: 32768 minimum: 301 [ 0.011129] LSM: Security Framework initializing [ 0.012073] Yama: becoming mindful. [ 0.013043] SELinux: Initializing. [ 0.014095] *** VALIDATE selinux *** [ 0.023532] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028764] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029156] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030078] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031081] *** VALIDATE tmpfs *** [ 0.033258] *** VALIDATE proc *** [ 0.034197] *** VALIDATE cgroup *** [ 0.035004] *** VALIDATE cgroup2 *** [ 0.036209] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.037124] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.038004] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.039023] Spectre V2 : User space: Vulnerable [ 0.040005] Speculative Store Bypass: Vulnerable [ 0.043168] debug: unmapping init [mem 0xffffffffa1059000-0xffffffffa1060fff] [ 0.045143] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.046589] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.047018] ... version: 2 [ 0.048009] ... bit width: 48 [ 0.049007] ... generic registers: 4 [ 0.050007] ... value mask: 0000ffffffffffff [ 0.051008] ... max period: 00007fffffffffff [ 0.052007] ... fixed-purpose events: 3 [ 0.053007] ... event mask: 000000070000000f [ 0.054247] rcu: Hierarchical SRCU implementation. [ 0.056318] smp: Bringing up secondary CPUs ... [ 0.057447] x86: Booting SMP configuration: [ 0.058015] .... node #0, CPUs: #1 #2 #3 [ 0.062051] smp: Brought up 1 node, 4 CPUs [ 0.064010] smpboot: Max logical packages: 1 [ 0.065016] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.147154] node 0 deferred pages initialised in 78ms [ 0.151009] devtmpfs: initialized [ 0.152527] x86/mm: Memory block size: 128MB [ 0.154869] gcov: version magic: 0x41383552 [ 0.155593] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.158247] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.161266] pinctrl core: initialized pinctrl subsystem [ 0.163137] [ 0.163680] ************************************************************* [ 0.166018] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.167010] ** ** [ 0.169007] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.171012] ** ** [ 0.172007] ** This means that this kernel is built to expose internal ** [ 0.174008] ** IOMMU data structures, which may compromise security on ** [ 0.175006] ** your system. ** [ 0.177008] ** ** [ 0.178007] ** If you see this message and you are not debugging the ** [ 0.179009] ** kernel, report this immediately to your vendor! ** [ 0.180010] ** ** [ 0.182008] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.184007] ************************************************************* [ 0.186953] NET: Registered protocol family 16 [ 0.188485] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.190045] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.192062] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.195049] cpuidle: using governor menu [ 0.196751] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.198456] PCI: Using configuration type 1 for base access [ 0.200124] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.208169] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.209011] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.211076] cryptd: max_cpu_qlen set to 1000 [ 0.213219] ACPI: Added _OSI(Module Device) [ 0.215008] ACPI: Added _OSI(Processor Device) [ 0.216008] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.217008] ACPI: Added _OSI(Processor Aggregator Device) [ 0.221888] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.227650] ACPI: Interpreter enabled [ 0.229063] ACPI: PM: (supports S0 S3 S4 S5) [ 0.231009] ACPI: Using IOAPIC for interrupt routing [ 0.232112] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.234390] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.244573] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.246026] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.247010] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.250061] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.254335] acpiphp: Slot [2] registered [ 0.255128] acpiphp: Slot [3] registered [ 0.256060] acpiphp: Slot [4] registered [ 0.257056] acpiphp: Slot [5] registered [ 0.258084] acpiphp: Slot [6] registered [ 0.259092] acpiphp: Slot [7] registered [ 0.260058] acpiphp: Slot [8] registered [ 0.261058] acpiphp: Slot [9] registered [ 0.263057] acpiphp: Slot [10] registered [ 0.264060] acpiphp: Slot [11] registered [ 0.265065] acpiphp: Slot [12] registered [ 0.266106] acpiphp: Slot [13] registered [ 0.267063] acpiphp: Slot [14] registered [ 0.268061] acpiphp: Slot [15] registered [ 0.269063] acpiphp: Slot [16] registered [ 0.270057] acpiphp: Slot [17] registered [ 0.272090] acpiphp: Slot [18] registered [ 0.273093] acpiphp: Slot [19] registered [ 0.274058] acpiphp: Slot [20] registered [ 0.275059] acpiphp: Slot [21] registered [ 0.276061] acpiphp: Slot [22] registered [ 0.277072] acpiphp: Slot [23] registered [ 0.278088] acpiphp: Slot [24] registered [ 0.279071] acpiphp: Slot [25] registered [ 0.281070] acpiphp: Slot [26] registered [ 0.282062] acpiphp: Slot [27] registered [ 0.283058] acpiphp: Slot [28] registered [ 0.284066] acpiphp: Slot [29] registered [ 0.285010] acpiphp: Slot [30] registered [ 0.286106] acpiphp: Slot [31] registered [ 0.287056] PCI host bridge to bus 0000:00 [ 0.288014] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.290011] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.291009] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.293048] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.295011] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.297020] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.298230] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.302654] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.306396] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.309000] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.309000] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.312017] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.313011] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.316033] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.318689] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.321800] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.323033] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.325612] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.329900] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.336000] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.339017] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.344152] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.346000] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.347012] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.355016] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.363531] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.376073] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.380000] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.395018] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.407645] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.409277] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.411302] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.413276] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.415670] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.420050] iommu: Default domain type: Passthrough [ 0.421463] SCSI subsystem initialized [ 0.423109] ACPI: bus type USB registered [ 0.428185] usbcore: registered new interface driver usbfs [ 0.430091] usbcore: registered new interface driver hub [ 0.431072] usbcore: registered new device driver usb [ 0.437197] pps_core: LinuxPPS API ver. 1 registered [ 0.438009] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.441071] PTP clock support registered [ 0.447048] EDAC MC: Ver: 3.0.0 [ 0.451382] PCI: Using ACPI for IRQ routing [ 0.452508] NetLabel: Initializing [ 0.453000] NetLabel: domain hash size = 128 [ 0.454015] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.456083] NetLabel: unlabeled traffic allowed by default [ 0.457381] vgaarb: loaded [ 0.460172] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.461008] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.540214] clocksource: Switched to clocksource kvm-clock [ 0.751551] VFS: Disk quotas dquot_6.6.0 [ 0.753267] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.757632] *** VALIDATE ramfs *** [ 0.763277] *** VALIDATE hugetlbfs *** [ 0.767810] pnp: PnP ACPI init [ 0.773100] pnp: PnP ACPI: found 6 devices [ 0.813892] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.820584] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.825296] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.828679] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.836638] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.841313] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.843618] NET: Registered protocol family 2 [ 0.853541] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.866410] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.873754] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.884117] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.889536] TCP: Hash tables configured (established 65536 bind 65536) [ 0.892399] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.896108] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.898542] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.901089] NET: Registered protocol family 1 [ 0.904397] RPC: Registered named UNIX socket transport module. [ 0.907961] RPC: Registered udp transport module. [ 0.909405] RPC: Registered tcp transport module. [ 0.911097] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.920570] NET: Registered protocol family 44 [ 0.921866] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.923454] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.929835] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.931591] PCI: CLS 0 bytes, default 64 [ 0.937724] Unpacking initramfs... [ 2.635545] debug: unmapping init [mem 0xffff930dfcc64000-0xffff930dfffcffff] [ 2.642369] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.644038] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.650924] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 3.248795] Initialise system trusted keyrings [ 3.250407] Key type blacklist registered [ 3.252948] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.261796] zbud: loaded [ 3.265504] *** VALIDATE nfs *** [ 3.266717] *** VALIDATE nfs4 *** [ 3.268036] pstore: using deflate compression [ 3.271366] Platform Keyring initialized [ 3.484472] NET: Registered protocol family 38 [ 3.489625] Key type asymmetric registered [ 3.491748] Asymmetric key parser 'x509' registered [ 3.495675] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.503897] io scheduler mq-deadline registered [ 3.505020] io scheduler kyber registered [ 3.506337] io scheduler bfq registered [ 3.508106] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.511346] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.514153] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.516406] ACPI: Power Button [PWRF] [ 3.679875] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.829325] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 4.068201] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 4.125658] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 4.201937] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 4.227879] Non-volatile memory driver v1.3 [ 4.229935] Linux agpgart interface v0.103 [ 4.258540] virtio_blk virtio1: [vda] 133800 512-byte logical blocks (68.5 MB/65.3 MiB) [ 4.261030] vda: detected capacity change from 0 to 68505600 [ 4.454883] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 4.459368] vdb: detected capacity change from 0 to 1073741824 [ 4.479811] libphy: Fixed MDIO Bus: probed [ 4.507105] usbcore: registered new interface driver usbserial_generic [ 4.511119] usbserial: USB Serial support registered for generic [ 4.513409] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 4.518378] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 4.520305] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 4.523159] mousedev: PS/2 mouse device common for all mice [ 4.527676] rtc_cmos 00:05: RTC can wake from S4 [ 4.533078] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 4.533217] rtc_cmos 00:05: registered as rtc0 [ 4.543566] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 4.547671] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 4.551616] intel_pstate: CPU model not supported [ 4.556986] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 4.566838] hid: raw HID events driver (C) Jiri Kosina [ 4.568802] usbcore: registered new interface driver usbhid [ 4.570802] usbhid: USB HID core driver [ 4.572696] drop_monitor: Initializing network drop monitor service [ 4.575651] Initializing XFRM netlink socket [ 4.577711] NET: Registered protocol family 10 [ 4.582199] Segment Routing with IPv6 [ 4.583675] NET: Registered protocol family 17 [ 4.585986] mpls_gso: MPLS GSO support [ 4.592081] RAS: Correctable Errors collector initialized. [ 4.597019] AVX version of gcm_enc/dec engaged. [ 4.598609] AES CTR mode by8 optimization enabled [ 4.819027] sched_clock: Marking stable (4819004293, 0)->(5684711267, -865706974) [ 4.830829] registered taskstats version 1 [ 4.834081] Loading compiled-in X.509 certificates [ 4.836201] zswap: loaded using pool lzo/zbud [ 4.891184] Key type big_key registered [ 4.928414] Key type encrypted registered [ 4.930393] ima: No TPM chip found, activating TPM-bypass! [ 4.933890] ima: Allocated hash algorithm: sha1 [ 4.937211] ima: No architecture policies found [ 4.939468] evm: Initialising EVM extended attributes: [ 4.942583] evm: security.selinux [ 4.944295] evm: security.ima [ 4.945106] evm: security.capability [ 4.946407] evm: HMAC attrs: 0x1 [ 4.955972] rtc_cmos 00:05: setting system clock to 2025-10-23 12:44:38 UTC (1761223478) [ 4.966111] debug: unmapping init [mem 0xffffffffa2003000-0xffffffffa21fffff] [ 4.971314] debug: unmapping init [mem 0xffffffffa0d82000-0xffffffffa1058fff] [ 4.985176] Write protecting the kernel read-only data: 28672k [ 4.988459] debug: unmapping init [mem 0xffffffff9f403000-0xffffffff9f5fffff] [ 4.992217] debug: unmapping init [mem 0xffffffff9fd14000-0xffffffff9fdfffff] [ 5.056282] 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) [ 5.064742] systemd[1]: Detected virtualization kvm. [ 5.065971] systemd[1]: Detected architecture x86-64. [ 5.067650] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 5.097439] systemd[1]: No hostname configured. [ 5.098896] systemd[1]: Set hostname to . [ 5.101361] random: systemd: uninitialized urandom read (16 bytes read) [ 5.104745] systemd[1]: Initializing machine ID from random generator. [ 5.535399] random: systemd: uninitialized urandom read (16 bytes read) [ 5.537234] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 5.544962] random: systemd: uninitialized urandom read (16 bytes read) [ 5.549901] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 5.560783] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Timers. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Local File Systems. [ OK ] Reached target Swap. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket. [ OK ] Reached target Sockets. Starting Create Volatile Files and Directories... Starting Create list of required st…ce nodes for the current kernel... Starting Apply Kernel Variables... Starting Setup Virtual Console... [ OK ] Started Memstrack Anylazing Service. Starting Journal Service... [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Setup Virtual Console. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 7.162427] device-mapper: uevent: version 1.0.3 [ 7.164408] 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... [ 9.107793] virtio_net virtio0 ens2: renamed from eth0 [ 9.594604] scsi host0: ata_piix [ 9.618323] scsi host1: ata_piix [ 9.619376] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 9.621191] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 13.118008] hrtimer: interrupt took 3992844 ns [ 14.543360] random: crng init done [ 14.544979] random: 7 urandom warning(s) missed due to ratelimiting [ 17.833695] dracut-initqueue[583]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 19.816335] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Swap. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 22.477269] printk: systemd: 26 output lines suppressed due to ratelimiting [ 22.965867] SELinux: Disabled at runtime. [ 23.062031] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 23.077277] systemd[1]: Detected virtualization kvm. [ 23.079109] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 25.369162] systemd[1]: initrd-switch-root.service: Succeeded. [ 25.371855] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 25.381391] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 25.391044] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 25.393699] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 25.413432] systemd[1]: Starting Journal Service... Starting Journal Service... [ 25.437715] systemd[1]: Created slice system-sshd\x2dkeygen.slice. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice system-getty.slice. Mounting Huge Pages File System... [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Process Core Dump Socket. Starting Remount Root and Kernel File Systems... [ OK ] Reached target RPC Port Mapper. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Listening on initctl Compatibility Named Pipe. Mounting POSIX Message Queue File System... [ OK ] Stopped target Initrd File Systems. [ OK ] Reached target Local Encrypted Volumes. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... Mounting Kernel Debug File System... Starting Apply Kernel Variables... [ 26.450462] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Reached target rpc_pipefs.target. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Huge Pages 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 ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 28.193862] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 29.261046] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 29.473826] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 29.896123] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 30.439489] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (8s / no limit) [** ] A start job is running for Configur…-only root support (9s / no limit) [*** ] A start job is running for Configur…-only root support (9s / no limit)[ 35.487300] Key type dns_resolver registered [ *** ] A start job is running for Configur…only root support (10s / no limit)[ 36.206863] NFS: Registering the id_resolver key type [ 36.213087] Key type id_resolver registered [ 36.218265] Key type id_legacy registered [ *** ] A start job is running for Configur…only root support (11s / no limit) [ ***] A start job is running for Configur…only root support (11s / no limit) [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting 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 daily update of the root trust anchor for DNSSEC. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting Hostname Service... [ OK ] Started Login Service. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg229-client login: [ 135.906738] libcfs: loading out-of-tree module taints kernel. [ 135.980048] Key type ._llcrypt registered [ 135.982061] Key type .llcrypt registered [ 136.718463] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 136.735835] alg: No test for adler32 (adler32-zlib) [ 138.445631] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [ 138.972355] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [ 140.744200] Key type lgssc registered [ 142.120182] Lustre: Echo OBD driver; http://www.lustre.org/ [ 284.418684] Lustre: Mounted lustre-client [ 289.086274] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 309.090494] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing check_logdir /tmp/testlogs/ [ 310.242891] Lustre: lustre-OST0000-osc-ffff930e44d54800: disconnect after 23s idle [ 313.392755] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing yml_node [ 317.625153] Lustre: DEBUG MARKER: Client: 2.16.59.38 [ 320.212762] Lustre: DEBUG MARKER: MDS: 2.16.59.38 [ 322.614449] Lustre: DEBUG MARKER: OSS: 2.16.59.38 [ 324.411527] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Thu Oct 23 08:49:56 EDT 2025 [ 341.470688] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 342.945141] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 344.630857] Lustre: DEBUG MARKER: === sanityn: start setup 08:50:16 (1761223816) === [ 345.209094] Lustre: Mounted lustre-client [ 348.132173] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing check_config_client /mnt/lustre [ 362.564508] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 366.052882] Lustre: lustre-OST0000-osc-ffff930e43245800: disconnect after 21s idle [ 366.054836] Lustre: Skipped 1 previous similar message [ 372.535326] Lustre: DEBUG MARKER: === sanityn: finish setup 08:50:44 (1761223844) === [ 374.688637] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 08:50:47 (1761223847) [ 382.433607] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 08:50:54 (1761223854) [ 388.947238] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 08:51:01 (1761223861) [ 395.479493] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 08:51:07 (1761223867) [ 401.908560] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 08:51:14 (1761223874) [ 408.101778] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 08:51:20 (1761223880) [ 414.131689] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 08:51:26 (1761223886) [ 420.855191] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 08:51:33 (1761223893) [ 428.215640] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 08:51:40 (1761223900) [ 434.661261] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 08:51:46 (1761223906) [ 440.871874] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 08:51:53 (1761223913) [ 448.203352] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 08:52:00 (1761223920) [ 453.093902] Lustre: lustre-OST0000-osc-ffff930e44d54800: disconnect after 24s idle [ 453.096845] Lustre: Skipped 1 previous similar message [ 454.437982] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 08:52:06 (1761223926) [ 461.259259] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 08:52:13 (1761223933) [ 468.410701] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 08:52:20 (1761223940) [ 474.717985] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 08:52:27 (1761223947) [ 481.510582] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 08:52:33 (1761223953) [ 487.319325] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 08:52:39 (1761223959) [ 493.763730] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 08:52:46 (1761223966) [ 499.524621] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 08:52:51 (1761223971) [ 505.255588] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 08:52:57 (1761223977) [ 505.937435] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279518 file: /mnt/lustre/lockdir/lockfile=144115205289279517 [ 648.565923] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 08:55:20 (1761224120) [ 655.541276] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 08:55:27 (1761224127) [ 661.368661] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 08:55:33 (1761224133) [ 667.509115] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 08:55:39 (1761224139) [ 675.330510] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 08:55:47 (1761224147) [ 683.031356] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 08:55:55 (1761224155) [ 685.170056] Lustre: DEBUG MARKER: chmod [ 690.617081] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 08:56:03 (1761224163) [ 1477.776274] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 09:09:10 (1761224950) [ 1595.360992] Lustre: lustre-OST0001-osc-ffff930e43245800: disconnect after 22s idle [ 1654.159525] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 09:12:06 (1761225126) [ 1741.663214] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 09:13:34 (1761225214) [ 1858.019024] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 09:15:30 (1761225330) [ 1884.348802] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 09:15:56 (1761225356) [ 1889.559617] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 09:16:02 (1761225362) [ 1890.123035] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1890.211588] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1890.262811] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1890.330041] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1890.378860] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1890.437852] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1890.485568] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1890.533772] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1890.570454] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1890.619192] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1890.684554] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1890.741920] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1890.797359] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1890.847095] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1890.916793] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1890.954775] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1891.006018] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1891.052112] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1891.109353] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1891.161312] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1891.214935] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1891.273128] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1891.341829] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1891.395313] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1891.488863] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1891.537786] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1891.616352] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1891.677938] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1891.743155] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1891.799700] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1891.860692] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1891.931333] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1891.988651] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1892.039092] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1892.094063] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1892.141972] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1892.189345] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1892.241305] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1892.298682] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1892.347374] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1892.433400] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1892.506945] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1892.567091] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1892.642854] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1892.698767] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1892.745723] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1892.793602] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1892.846846] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1892.917762] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1892.995359] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1893.076755] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1893.123902] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1893.173290] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1893.222303] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1893.273444] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1893.336476] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1893.397973] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1893.463254] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1893.523276] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1893.586670] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1893.667790] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1893.737674] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1893.807894] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1893.872089] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1893.915588] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1893.975680] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1894.041326] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1894.117896] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1894.170252] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1894.260841] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1894.322907] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1894.377704] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1894.458089] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1894.521789] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1894.638818] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1894.694671] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1894.747680] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1894.799128] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1894.847598] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1894.903520] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1894.953955] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1895.017112] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1895.076061] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1895.123377] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1895.175585] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1895.213031] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1895.260287] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1895.345253] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1895.424197] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1895.485505] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1895.570959] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1895.628219] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1895.679955] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1895.716018] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1895.759963] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1895.838490] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1895.917457] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1895.967110] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1896.017387] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1896.104426] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1896.162804] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1896.211576] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1896.300886] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1896.392332] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1896.468976] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1896.574984] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1896.693320] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1896.756228] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1896.812879] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1896.888808] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1896.933341] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1897.014823] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1897.079356] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1897.156250] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1897.220367] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1897.293272] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1897.371857] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1897.455864] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1897.511281] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1897.573389] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1897.642573] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1897.711753] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1897.752212] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1897.820134] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1897.875915] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1897.949585] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1898.021808] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1898.077840] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1898.140611] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1898.205810] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1898.251632] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1898.304651] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1898.347811] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1898.404852] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1898.447170] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1898.524338] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1898.564082] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1898.601942] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1898.623745] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1898.657232] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1898.702211] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1898.758886] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1898.832917] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1898.899862] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1898.961648] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1899.010929] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1899.072550] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1899.126780] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1899.192376] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1899.256230] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1899.322694] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1899.394444] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1899.450964] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1899.510412] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1899.548790] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1899.609211] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1899.664874] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1899.702868] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1899.767753] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1899.819057] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1899.878219] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1899.926923] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1899.994661] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1900.058834] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1900.100726] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1900.152942] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1900.219427] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1900.308475] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1900.387950] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1900.459266] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1900.498909] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1900.562575] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1900.634862] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1900.712453] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1900.775850] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1900.826115] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1900.891577] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1900.971443] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1901.017028] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1901.081561] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1901.136728] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1901.205369] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1901.286927] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1901.342318] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1901.390038] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1901.447185] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1901.518762] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1901.595891] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1901.655414] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1901.715214] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1901.779754] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1901.851152] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1901.900845] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1901.948399] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1901.999883] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1902.062644] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1902.104811] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1902.144812] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1902.193325] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1902.241910] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1902.277164] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1902.322896] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1902.377554] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1902.429192] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1902.482233] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1902.516129] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1902.576473] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1902.629198] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1902.675910] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1902.731191] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1902.778177] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1902.821944] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1902.873355] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1902.924590] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1902.988153] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1903.039383] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1903.074626] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1903.120083] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1903.171129] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1903.223137] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1903.281521] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1903.338641] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1903.373348] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1903.440742] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1903.508405] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1903.571952] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1903.633339] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1903.692734] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1903.733236] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1903.768182] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1903.811784] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1903.854901] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1903.906103] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1903.951915] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1904.038379] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1904.074053] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1904.133064] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1904.188687] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1904.224442] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1904.277864] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1904.320303] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1904.365187] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1904.403982] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1904.437940] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1904.481796] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1904.522853] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1904.568299] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1904.607620] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1904.663911] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1904.710886] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1904.762789] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1904.815438] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1904.851668] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1904.895976] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1904.934900] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1904.982595] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1905.031107] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1905.079319] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1905.130704] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1905.174351] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1905.241715] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1905.306084] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1905.378370] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1905.435318] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1905.519571] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1905.573145] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1905.630419] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1905.683661] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1905.727388] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1905.784162] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1905.830599] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1905.881755] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1905.939686] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1905.993198] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1906.030678] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1906.079412] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1906.111038] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1906.173516] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1906.241463] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1906.293396] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1906.344395] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1906.399848] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1906.453464] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1906.515233] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1906.564828] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1906.620707] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1906.680500] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1906.728087] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1906.787857] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1906.836816] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1906.893339] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1906.950985] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1906.993149] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1907.071683] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1907.127996] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1907.197161] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1907.246668] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1907.298912] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1907.400557] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1907.456880] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1907.516150] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1907.584227] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1907.650503] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1907.681312] Lustre: lustre-OST0000-osc-ffff930e44d54800: disconnect after 20s idle [ 1907.702728] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1907.753406] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1907.787215] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1907.821088] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1907.866435] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1907.932178] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1908.008580] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1908.112444] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1908.180505] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1908.236156] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1908.269183] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1908.306067] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1908.368771] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1908.430376] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1908.512836] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1908.565527] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1908.624195] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1908.705751] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1908.793619] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1908.851774] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1908.919692] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1908.979086] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1909.030053] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1909.103808] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1909.156795] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1909.220903] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1909.272874] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1909.323716] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1909.371210] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1909.405381] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1909.452374] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1909.509220] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1909.557714] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1909.629887] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1909.677451] rw_seq_cst_vs_d (32582): drop_caches: 3 [ 1915.247852] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 09:16:27 (1761225387) [ 1915.562990] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1915.670350] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1915.809583] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1915.873903] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1915.962811] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1916.008452] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1916.156184] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1916.201082] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1916.391769] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1916.444615] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1916.620822] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1916.753355] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1916.801802] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1916.848465] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1916.893214] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1917.003203] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1917.127211] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1917.162795] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1917.396089] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1917.428414] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1917.514038] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1917.568074] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1917.700449] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1917.890812] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1917.976377] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1918.085734] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1918.155406] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1918.204407] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1918.318640] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1918.427783] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1918.462469] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1918.620861] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1918.673680] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1918.843389] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1918.907730] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1918.951220] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1918.989347] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1919.084046] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1919.203604] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1919.364152] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1919.406299] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1919.569708] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1919.663128] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1919.836512] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1919.931177] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1919.976944] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1920.062585] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1920.092053] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1920.257914] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1920.284656] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1920.568218] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1920.670213] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1920.736582] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1920.774023] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1920.857197] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1921.012280] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1921.075948] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1921.111014] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1921.186964] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1921.246876] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1921.323350] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1921.392307] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1921.541386] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1921.571823] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1921.602502] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1921.639509] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1921.707498] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1921.812271] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1921.952363] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1922.128902] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1922.200917] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1922.353379] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1922.529676] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1922.604538] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1922.793991] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1922.841153] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1922.925843] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1923.028624] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1923.300675] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1923.564090] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1923.585404] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1923.610598] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1923.682439] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1923.711954] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1923.828868] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1923.858243] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1923.905837] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1923.983964] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1924.020076] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1924.119579] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1924.164967] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1924.293411] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1924.436590] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1924.470203] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1924.635284] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1924.694267] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1924.738795] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1924.807586] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1924.834042] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1924.946505] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1925.061769] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1925.221609] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1925.266930] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1925.343038] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1925.454935] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1925.583166] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1925.643723] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1925.831860] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1926.024269] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1926.137294] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1926.287244] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1926.330538] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1926.477133] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1926.508886] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1926.617935] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1926.744400] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1926.851709] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1926.949559] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1927.018814] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1927.078657] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1927.186229] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1927.212713] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1927.278063] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1927.351553] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1927.437346] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1927.473299] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1927.635447] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1927.715236] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1927.851133] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1927.875197] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1928.101705] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1928.240028] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1928.336028] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1928.492560] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1928.533391] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1928.781904] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1928.875214] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1928.936733] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1928.987810] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1929.047457] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1929.121698] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1929.162833] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1929.218345] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1929.354976] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1929.387077] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1929.486968] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1929.515321] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1929.590608] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1929.634743] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1929.810263] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1929.868252] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1930.021282] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1930.059630] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1930.118920] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1930.237414] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1930.294438] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1930.353927] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1930.449870] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1930.489978] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1930.651932] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1930.685448] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1930.775992] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1930.813350] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1930.866491] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1930.991790] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1931.049524] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1931.084404] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1931.256270] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1931.296643] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1931.329451] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1931.518912] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1931.619614] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1931.659811] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1931.757379] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1931.843213] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1931.983645] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1932.065185] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1932.277664] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1932.324510] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1932.443413] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1932.517130] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1932.632152] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1932.691291] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1932.766465] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1932.839133] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1933.168771] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1933.251172] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1933.281146] Lustre: lustre-OST0001-osc-ffff930e43245800: disconnect after 23s idle [ 1933.283872] Lustre: Skipped 1 previous similar message [ 1933.413166] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1933.481434] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1933.855374] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1933.985417] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1934.072283] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1934.196242] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1934.578641] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1934.744268] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1934.779109] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1934.930616] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1934.993535] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1935.042290] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1935.084667] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1935.188695] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1935.287823] rw_seq_cst_vs_d (33165): drop_caches: 3 [ 1938.408231] Lustre: lustre-OST0001-osc-ffff930e44d54800: disconnect after 23s idle [ 1942.142092] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 09:16:54 (1761225414) [ 1948.851483] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 09:17:00 (1761225420) [ 1955.559326] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 09:17:07 (1761225427) [ 1980.455345] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 09:17:32 (1761225452) [ 1980.801130] bash (35662): drop_caches: 3 [ 1984.131595] bash (35662): drop_caches: 3 [ 1987.336465] bash (35662): drop_caches: 3 [ 1990.525352] bash (35662): drop_caches: 3 [ 1993.637945] bash (35662): drop_caches: 3 [ 1996.759636] bash (35662): drop_caches: 3 [ 1999.983944] bash (35662): drop_caches: 3 [ 2003.176047] bash (35662): drop_caches: 3 [ 2006.296369] bash (35662): drop_caches: 3 [ 2009.376949] bash (35662): drop_caches: 3 [ 2013.451593] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 09:18:06 (1761225486) [ 2021.827883] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 09:18:14 (1761225494) [ 2055.094809] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 09:18:47 (1761225527) [ 2059.910499] Lustre: DEBUG MARKER: loop 5 [ 2062.713101] Lustre: DEBUG MARKER: loop 10 [ 2065.693421] Lustre: DEBUG MARKER: loop 15 [ 2068.821684] Lustre: DEBUG MARKER: loop 20 [ 2071.522916] Lustre: lustre-OST0001-osc-ffff930e44d54800: disconnect after 22s idle [ 2074.762260] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 09:19:07 (1761225547) [ 2079.730335] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 09:19:12 (1761225552) [ 2084.491533] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 09:19:17 (1761225557) [ 2095.008265] Lustre: lustre-OST0000-osc-ffff930e44d54800: disconnect after 20s idle [ 2095.022150] Lustre: Skipped 1 previous similar message [ 2150.354731] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 09:20:23 (1761225623) [ 2154.539964] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 09:20:27 (1761225627) [ 2159.330408] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 09:20:31 (1761225631) [ 2165.362876] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 09:20:37 (1761225637) [ 2171.105427] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 09:20:43 (1761225643) [ 2177.313515] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 09:20:49 (1761225649) [ 2184.760377] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 09:20:57 (1761225657) [ 2191.506756] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 2193.074412] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 09:21:05 (1761225665) [ 2200.685914] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 09:21:13 (1761225673) [ 2200.992086] Lustre: *** cfs_fail_loc=314, val=0*** [ 2202.016251] Lustre: *** cfs_fail_loc=314, val=0*** [ 2202.017986] Lustre: Skipped 2 previous similar messages [ 2206.851797] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 09:21:19 (1761225679) [ 2207.712747] Lustre: lustre-OST0001-osc-ffff930e44d54800: disconnect after 22s idle [ 2207.721793] Lustre: Skipped 3 previous similar messages [ 2214.991545] Lustre: *** cfs_fail_loc=314, val=0*** [ 2217.959690] Lustre: lustre-OST0000-osc-ffff930e43245800: Connection to lustre-OST0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2218.008521] LustreError: lustre-OST0000-osc-ffff930e43245800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2218.020849] Lustre: lustre-OST0000-osc-ffff930e43245800: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [ 2220.810513] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 09:21:33 (1761225693) [ 2221.101752] LustreError: 47160:0:(file.c:765:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 2224.144131] LustreError: 47160:0:(file.c:765:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 2229.353667] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 09:21:41 (1761225701) [ 2235.446205] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 09:21:47 (1761225707) [ 2241.658684] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 2242.911878] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 09:21:55 (1761225715) [ 2244.151274] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 2245.373895] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 09:21:57 (1761225717) [ 2248.687991] Lustre: lustre-MDT0000-mdc-ffff930e44d54800: Connection to lustre-MDT0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2253.816494] LustreError: MGC192.168.202.129@tcp: Connection to MGS (at 192.168.202.129@tcp) was lost; in progress operations using this service will fail [ 2253.840244] Lustre: Evicted from MGS (at 192.168.202.129@tcp) after server handle changed from 0x4cff7c89a2485d13 to 0x4cff7c89a25376bf [ 2253.850559] Lustre: MGC192.168.202.129@tcp: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [ 2258.914587] Lustre: lustre-OST0000-osc-ffff930e43245800: disconnect after 22s idle [ 2259.228618] Lustre: lustre-MDT0000-mdc-ffff930e44d54800: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [ 2285.715485] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 09:22:37 (1761225757) [ 2326.051040] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 09:23:18 (1761225798) [ 2343.232777] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 09:23:35 (1761225815) [ 2356.195109] Lustre: lustre-OST0000-osc-ffff930e43245800: disconnect after 20s idle [ 2356.200719] Lustre: Skipped 1 previous similar message [ 2396.096048] Lustre: lustre-OST0000-osc-ffff930e43245800: Connection to lustre-OST0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2396.104894] Lustre: Skipped 1 previous similar message [ 2396.119362] LustreError: lustre-OST0000-osc-ffff930e43245800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2396.129671] LustreError: lustre-OST0000-osc-ffff930e44d54800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2396.135619] Lustre: lustre-OST0000-osc-ffff930e44d54800: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [ 2396.141490] Lustre: Skipped 2 previous similar messages [ 2411.481071] Lustre: lustre-OST0001-osc-ffff930e44d54800: Connection to lustre-OST0001 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2411.487643] Lustre: Skipped 1 previous similar message [ 2411.495807] LustreError: lustre-OST0001-osc-ffff930e44d54800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 2411.508042] Lustre: lustre-OST0001-osc-ffff930e44d54800: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [ 2425.303554] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff930e43245800.ost_server_uuid 50 [ 2426.606071] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff930e43245800.ost_server_uuid in IDLE state after 0 sec [ 2429.354849] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff930e43245800.ost_server_uuid 50 [ 2430.698570] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff930e43245800.ost_server_uuid in FULL state after 0 sec [ 2435.477624] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff930e43245800.ost_server_uuid 50 [ 2436.711794] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff930e43245800.ost_server_uuid in IDLE state after 0 sec [ 2440.095556] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff930e43245800.ost_server_uuid 50 [ 2441.258851] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff930e43245800.ost_server_uuid in FULL state after 0 sec [ 2447.054798] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff930e43245800.ost_server_uuid 50 [ 2448.009838] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff930e43245800.ost_server_uuid in IDLE state after 0 sec [ 2450.186560] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff930e43245800.ost_server_uuid 50 [ 2451.155761] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff930e43245800.ost_server_uuid in FULL state after 0 sec [ 2452.337947] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 09:25:24 (1761225924) [ 2454.121087] Lustre: DEBUG MARKER: Race attempt 0 [ 2456.201416] Lustre: DEBUG MARKER: Wait for 57731 57822 for 60 sec... [ 2521.119327] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 09:26:33 (1761225993) [ 2528.230594] Lustre: DEBUG MARKER: start test - cycle (0) [ 2547.973332] Lustre: DEBUG MARKER: start test - cycle (1) [ 2570.385307] Lustre: DEBUG MARKER: start test - cycle (2) [ 2589.587897] Lustre: DEBUG MARKER: start test - cycle (3) [ 2611.004769] Lustre: DEBUG MARKER: start test - cycle (4) [ 2629.743821] Lustre: DEBUG MARKER: start test - cycle (5) [ 2651.097479] Lustre: DEBUG MARKER: start test - cycle (6) [ 2670.831386] Lustre: DEBUG MARKER: start test - cycle (7) [ 2692.257624] Lustre: DEBUG MARKER: start test - cycle (8) [ 2712.569994] Lustre: DEBUG MARKER: start test - cycle (9) [ 2733.065717] Lustre: DEBUG MARKER: start test - cycle (10) [ 2759.533304] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 09:30:31 (1761226231) [ 2765.792967] Lustre: lustre-OST0000-osc-ffff930e43245800: disconnect after 22s idle [ 2765.795179] Lustre: Skipped 3 previous similar messages [ 2822.960500] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 09:31:35 (1761226295) [ 2828.216909] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 09:31:40 (1761226300) [ 2835.027532] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 09:31:47 (1761226307) [ 2841.148709] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 09:31:53 (1761226313) [ 2841.425731] Lustre: *** cfs_fail_loc=411, val=0*** [ 2846.534931] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 2847.809292] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 09:32:00 (1761226320) [ 2862.475953] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 09:32:15 (1761226335) [ 2877.092199] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 09:32:29 (1761226349) [ 2893.181516] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 09:32:45 (1761226365) [ 2908.048290] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 09:33:00 (1761226380) [ 2920.843743] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 09:33:13 (1761226393) [ 2933.297822] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 09:33:25 (1761226405) [ 2945.803298] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 09:33:38 (1761226418) [ 2958.544595] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 09:33:50 (1761226430) [ 2970.297897] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 09:34:02 (1761226442) [ 2981.874074] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 09:34:14 (1761226454) [ 2991.997154] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 09:34:24 (1761226464) [ 3004.290103] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 09:34:36 (1761226476) [ 3620.834761] Lustre: lustre-OST0000-osc-ffff930e43245800: disconnect after 21s idle [ 3620.837410] Lustre: Skipped 11 previous similar messages [ 3989.170374] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 09:51:01 (1761227461) [ 3999.151170] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 09:51:11 (1761227471) [ 4008.270670] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 09:51:20 (1761227480) [ 4018.043515] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 09:51:30 (1761227490) [ 4028.127155] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 09:51:40 (1761227500) [ 4039.773402] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 09:51:52 (1761227512) [ 4050.202472] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 09:52:02 (1761227522) [ 4061.577986] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 09:52:13 (1761227533) [ 4071.595345] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 09:52:24 (1761227544) [ 4154.510394] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 09:53:46 (1761227626) [ 4164.961267] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 09:53:57 (1761227637) [ 4175.320717] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 09:54:07 (1761227647) [ 4185.551390] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 09:54:17 (1761227657) [ 4197.294407] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 09:54:29 (1761227669) [ 4208.794912] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 09:54:41 (1761227681) [ 4220.564251] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 09:54:53 (1761227693) [ 4231.434721] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 09:55:03 (1761227703) [ 4242.816735] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 09:55:15 (1761227715) [ 4245.476326] Lustre: lustre-OST0000-osc-ffff930e44d54800: disconnect after 20s idle [ 4245.481593] Lustre: Skipped 4 previous similar messages [ 4354.189898] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 09:57:06 (1761227826) [ 4849.632323] Lustre: lustre-OST0000-osc-ffff930e43245800: disconnect after 20s idle [ 4849.638601] Lustre: Skipped 8 previous similar messages [ 5379.155906] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 10:14:11 (1761228851) [ 5391.269564] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 10:14:23 (1761228863) [ 5402.786501] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 10:14:35 (1761228875) [ 5415.958729] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 10:14:48 (1761228888) [ 5428.990237] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 10:15:01 (1761228901) [ 5441.115557] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 10:15:13 (1761228913) [ 5454.924323] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 10:15:26 (1761228926) [ 5464.032728] Lustre: lustre-OST0000-osc-ffff930e44d54800: disconnect after 20s idle [ 5464.035353] Lustre: Skipped 3 previous similar messages [ 5465.464243] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 10:15:38 (1761228938) [ 5476.234672] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 10:15:48 (1761228948) [ 5487.291528] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 10:15:59 (1761228959) [ 5607.513451] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 10:17:59 (1761229079) [ 5618.605595] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 10:18:11 (1761229091) [ 5628.985210] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 10:18:21 (1761229101) [ 5639.554657] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 10:18:31 (1761229111) [ 5648.837637] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 10:18:41 (1761229121) [ 5659.645749] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 10:18:52 (1761229132) [ 5669.253680] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 10:19:01 (1761229141) [ 5678.346530] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 10:19:10 (1761229150) [ 5690.556671] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 10:19:22 (1761229162) [ 6913.822790] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 10:39:46 (1761230386) [ 6925.332996] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 10:39:57 (1761230397) [ 6928.353182] Lustre: lustre-OST0000-osc-ffff930e43245800: disconnect after 24s idle [ 6928.356066] Lustre: Skipped 3 previous similar messages [ 6938.065841] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 10:40:10 (1761230410) [ 6950.124423] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 10:40:22 (1761230422) [ 6961.860741] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 10:40:34 (1761230434) [ 6973.102711] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 10:40:45 (1761230445) [ 6983.779833] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 10:40:56 (1761230456) [ 6995.580668] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 10:41:07 (1761230467) [ 7007.649037] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 10:41:19 (1761230479) [ 7020.534179] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 10:41:32 (1761230492) [ 7025.634301] Lustre: lustre-OST0001-osc-ffff930e44d54800: disconnect after 23s idle [ 7025.636958] Lustre: Skipped 3 previous similar messages [ 7034.963761] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 10:41:47 (1761230507) [ 7050.985720] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 10:42:02 (1761230522) [ 7064.544480] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 10:42:16 (1761230536) [ 7077.289320] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 10:42:29 (1761230549) [ 7089.472233] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 10:42:41 (1761230561) [ 7101.086182] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 10:42:53 (1761230573) [ 7116.066834] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 10:43:08 (1761230588) [ 7116.367028] LustreError: 6160:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 7118.460818] LustreError: 6160:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 7127.312165] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 10:43:19 (1761230599) [ 7136.408528] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 10:43:28 (1761230608) [ 7136.748066] LustreError: 285703:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7140.808166] LustreError: 285703:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 7140.847943] LustreError: 285703:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7144.920178] LustreError: 285703:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 7145.007805] LustreError: 285710:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 7149.008093] LustreError: 285710:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 7155.610858] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 10:43:47 (1761230627) [ 7165.692496] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 10:43:58 (1761230638) [ 7171.972189] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 10:44:04 (1761230644) [ 7180.650684] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 10:44:12 (1761230652) [ 7212.091100] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 10:44:44 (1761230684) [ 7222.551935] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 10:44:55 (1761230695) [ 7234.972047] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 10:45:06 (1761230706) [ 7252.910471] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 10:45:25 (1761230725) [ 7266.384873] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 10:45:38 (1761230738) [ 7284.161494] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 10:45:56 (1761230756) [ 7302.240308] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 10:46:14 (1761230774) [ 7322.638348] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 10:46:35 (1761230795) [ 7340.381835] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 10:46:52 (1761230812) [ 7432.531760] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 10:48:24 (1761230904) [ 7441.678747] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 7448.825243] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 10:48:40 (1761230920) [ 7456.725231] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 10:48:48 (1761230928) [ 7463.309780] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 10:48:55 (1761230935) [ 7469.390216] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 10:49:01 (1761230941) [ 7507.064296] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 10:49:39 (1761230979) [ 7555.489176] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 10:50:27 (1761231027) [ 7561.366132] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 10:50:33 (1761231033) [ 7567.732231] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 10:50:39 (1761231039) [ 7571.103491] LustreError: lustre-MDT0000-mdc-ffff930e43245800: operation ldlm_enqueue to node 192.168.202.129@tcp failed: rc = -35 [ 7577.855690] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 10:50:49 (1761231049) [ 7578.391890] LustreError: 2409:0:(osc_request.c:3127:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 7580.480101] LustreError: 2409:0:(osc_request.c:3127:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 7589.907862] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 10:51:01 (1761231061) [ 7602.144924] Lustre: lustre-OST0000-osc-ffff930e43245800: disconnect after 24s idle [ 7602.147795] Lustre: Skipped 4 previous similar messages [ 7741.739529] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 10:53:34 (1761231214) [ 7749.719425] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 10:53:42 (1761231222) [ 7761.439414] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 10:53:53 (1761231233) [ 7777.560438] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 10:54:09 (1761231249) [ 7794.060987] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 10:54:26 (1761231266) [ 7817.841819] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 10:54:50 (1761231290) [ 7839.679896] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 10:55:11 (1761231311) [ 7849.519489] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 10:55:21 (1761231321) [ 7861.680807] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 10:55:33 (1761231333) [ 7884.076191] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 10:55:56 (1761231356) [ 7909.345260] Lustre: lustre-OST0001-osc-ffff930e44d54800: disconnect after 22s idle [ 7909.347919] Lustre: Skipped 5 previous similar messages [ 7936.441762] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 10:56:48 (1761231408) [ 8067.912136] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 10:59:00 (1761231540) [ 8197.058638] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 11:01:09 (1761231669) [ 8523.745070] Lustre: lustre-OST0000-osc-ffff930e44d54800: disconnect after 22s idle [ 8523.747787] Lustre: Skipped 13 previous similar messages [ 8528.026586] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 11:06:40 (1761232000) [ 8563.093682] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 11:07:15 (1761232035) [ 8600.728947] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 11:07:52 (1761232072) [ 8635.388779] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 11:08:27 (1761232107) [ 8708.923897] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 11:09:41 (1761232181) [ 8772.786656] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 11:10:45 (1761232245) [ 8882.584066] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 11:12:35 (1761232355) [ 8891.583963] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 11:12:44 (1761232364) [ 8947.643390] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 11:13:39 (1761232419) [ 9000.349201] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 11:14:32 (1761232472) [ 9010.926943] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 11:14:43 (1761232483) [ 9114.370479] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 11:16:26 (1761232586) [ 9150.740790] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 11:17:03 (1761232623) [ 9200.233403] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 11:17:52 (1761232672) [ 9210.512838] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 11:18:02 (1761232682) [ 9220.064413] Lustre: lustre-OST0001-osc-ffff930e43245800: disconnect after 23s idle [ 9220.066609] Lustre: Skipped 11 previous similar messages [ 9228.913877] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 11:18:20 (1761232700) [ 9237.319220] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 11:18:29 (1761232709) [ 9239.673090] LustreError: 342831:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000402:0x99a:0x0]: rc = -5 [ 9239.688228] LustreError: 342831:0:(llite_lib.c:3793:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9240.703890] LustreError: 342851:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x200000402:0xa26:0x0]: rc = -5 [ 9240.711895] LustreError: 342851:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 3 previous similar messages [ 9240.719725] LustreError: 342851:0:(llite_lib.c:3793:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9240.722829] LustreError: 342851:0:(llite_lib.c:3793:ll_prep_inode()) Skipped 3 previous similar messages [ 9241.351406] LustreError: 342864:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff930e44d54800: inode [0x200000403:0x1ed4:0x0] mdc close failed: rc = -116 [ 9241.978869] LustreError: 342882:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x200000402:0xa26:0x0]: rc = -5 [ 9241.992924] LustreError: 342882:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 5 previous similar messages [ 9242.011819] LustreError: 342882:0:(llite_lib.c:3793:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9242.017310] LustreError: 342882:0:(llite_lib.c:3793:ll_prep_inode()) Skipped 5 previous similar messages [ 9244.568525] LustreError: 342934:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x200000402:0xa40:0x0]: rc = -5 [ 9244.589117] LustreError: 342934:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 9 previous similar messages [ 9244.608625] LustreError: 342934:0:(llite_lib.c:3793:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9244.625854] LustreError: 342934:0:(llite_lib.c:3793:ll_prep_inode()) Skipped 9 previous similar messages [ 9248.944266] LustreError: 343004:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000402:0x9c7:0x0]: rc = -5 [ 9248.952546] LustreError: 343004:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 13 previous similar messages [ 9248.960711] LustreError: 343004:0:(llite_lib.c:3793:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9248.966054] LustreError: 343004:0:(llite_lib.c:3793:ll_prep_inode()) Skipped 13 previous similar messages [ 9257.249039] LustreError: 343155:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x200000402:0xa85:0x0]: rc = -5 [ 9257.265947] LustreError: 343155:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 29 previous similar messages [ 9257.272368] LustreError: 343155:0:(llite_lib.c:3793:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9257.279333] LustreError: 343155:0:(llite_lib.c:3793:ll_prep_inode()) Skipped 29 previous similar messages [ 9273.508894] LustreError: 343489:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x200000402:0xae1:0x0]: rc = -5 [ 9273.518790] LustreError: 343489:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 80 previous similar messages [ 9273.525284] LustreError: 343489:0:(llite_lib.c:3793:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 9273.533839] LustreError: 343489:0:(llite_lib.c:3793:ll_prep_inode()) Skipped 80 previous similar messages [ 9290.709699] LustreError: 343830:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff930e43245800: inode [0x240000402:0xac8:0x0] mdc close failed: rc = -2 [ 9305.148543] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 11:19:37 (1761232777) [ 9315.606264] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 11:19:47 (1761232787) [ 9530.903777] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 11:23:23 (1761233003) [ 9532.587948] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 9534.219484] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 11:23:26 (1761233006) [ 9759.532949] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 11:27:11 (1761233231) [ 9767.147683] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 11:27:18 (1761233238) [ 9875.425104] Lustre: lustre-OST0000-osc-ffff930e44d54800: disconnect after 24s idle [ 9875.427993] Lustre: Skipped 5 previous similar messages [ 9894.033705] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 11:29:26 (1761233366) [ 9908.450984] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 11:29:40 (1761233380) [ 9919.452581] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 11:29:51 (1761233391) [10106.488820] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 11:32:58 (1761233578) [10292.032498] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 11:36:04 (1761233764) [10298.730953] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 11:36:10 (1761233770) [10316.352172] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 11:36:28 (1761233788) [10316.653276] Lustre: DEBUG MARKER: write [10316.753378] LustreError: 15671:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [10318.762119] Lustre: DEBUG MARKER: kill 374546 [10318.768819] LustreError: 374546:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [10321.771494] LustreError: 15671:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [10324.840288] LustreError: 374546:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [10331.439136] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 11:36:43 (1761233803) [10334.273143] LustreError: 375159:0:(rw.c:1967:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [10344.304172] LustreError: 375159:0:(rw.c:1967:ll_readpage()) cfs_fail_timeout id 1422 awake [10351.078561] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 11:37:03 (1761233823) [10351.868217] LustreError: 375747:0:(rw.c:2201:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [10353.968152] LustreError: 375747:0:(rw.c:2201:ll_readpage()) cfs_fail_timeout interrupted [10363.758891] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 11:37:15 (1761233835) [10365.304115] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [10366.857431] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 11:37:19 (1761233839) [10373.743540] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 11:37:26 (1761233846) [10379.651123] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 11:37:32 (1761233852) [10386.177768] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 11:37:38 (1761233858) [10392.698093] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 11:37:44 (1761233864) [10398.938954] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 11:37:51 (1761233871) [10405.584297] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 11:37:57 (1761233877) [10413.078611] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 11:38:05 (1761233885) [10421.586818] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 11:38:13 (1761233893) [10423.213159] Lustre: *** cfs_fail_loc=415, val=0*** [10434.563150] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 11:38:26 (1761233906) [10465.009171] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 11:38:57 (1761233937) [10465.410398] LustreError: 15670:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [10465.414608] LustreError: 15670:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [10470.424120] LustreError: 35061:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [10480.457170] LustreError: 15669:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [10480.463197] LustreError: 15669:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 7 previous similar messages [10488.006232] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 11:39:20 (1761233960) [10495.528921] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 11:39:27 (1761233967) [10503.567569] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 11:39:35 (1761233975) [10510.729359] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 11:39:42 (1761233982) [10519.448894] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 11:39:51 (1761233991) [10532.032740] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 11:40:04 (1761234004) [10532.649610] LustreError: 386489:0:(osc_request.c:2978:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [10532.653773] LustreError: 386489:0:(osc_request.c:2978:osc_build_rpc()) Skipped 6 previous similar messages [10536.732109] LustreError: 386489:0:(osc_request.c:2978:osc_build_rpc()) cfs_fail_timeout id 414 awake [10536.735082] LustreError: 386489:0:(osc_request.c:2978:osc_build_rpc()) Skipped 1 previous similar message [10543.044667] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 11:40:15 (1761234015) [10545.676865] LustreError: 387199:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e44d54800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10545.692115] LustreError: 387199:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [10545.735696] Lustre: Unmounted lustre-client [10547.924866] LustreError: 387219:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e43245800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10547.934806] LustreError: 387219:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [10547.945300] LustreError: 387219:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10547.947308] LustreError: 387219:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [10548.027654] Lustre: Unmounted lustre-client [10549.473315] Lustre: DEBUG MARKER: Iteration 1 [10550.001978] LustreError: 387384:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10550.004969] LustreError: 387383:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10550.013189] LustreError: 387384:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [10550.252598] Lustre: Mounted lustre-client [10551.329185] LustreError: 387480:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e79224000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10551.344090] LustreError: 387480:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [10551.360841] LustreError: 387480:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10551.364731] LustreError: 387480:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [10551.477508] Lustre: Unmounted lustre-client [10554.089747] Key type lgssc unregistered [10554.340949] LNet: 387726:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10554.355652] LNetError: 387726:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10554.371058] LNet: Removed LNI 192.168.202.29@tcp [10555.024208] Key type .llcrypt unregistered [10555.025677] Key type ._llcrypt unregistered [10555.595065] Key type ._llcrypt registered [10555.596552] Key type .llcrypt registered [10556.382940] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10556.393469] alg: No test for adler32 (adler32-zlib) [10557.956182] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [10558.766199] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [10560.480233] Key type lgssc registered [10561.780288] Lustre: Echo OBD driver; http://www.lustre.org/ [10573.665278] Lustre: DEBUG MARKER: Iteration 2 [10574.069234] LustreError: 388556:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10574.078141] LustreError: 388557:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10574.085736] LustreError: 388556:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4986 [10576.409128] Lustre: Mounted lustre-client [10577.531208] LustreError: 388661:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e4e951800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10577.567775] LustreError: 388661:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [10577.624150] Lustre: Unmounted lustre-client [10580.056726] Key type lgssc unregistered [10580.315994] LNet: 388911:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10580.322557] LNetError: 388911:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10580.344696] LNet: Removed LNI 192.168.202.29@tcp [10581.013590] Key type .llcrypt unregistered [10581.016419] Key type ._llcrypt unregistered [10581.874544] Key type ._llcrypt registered [10581.876193] Key type .llcrypt registered [10582.100129] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10582.116378] alg: No test for adler32 (adler32-zlib) [10583.191470] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [10583.475994] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [10585.168194] Key type lgssc registered [10586.422508] Lustre: Echo OBD driver; http://www.lustre.org/ [10594.961963] Lustre: DEBUG MARKER: Iteration 3 [10595.190014] LustreError: 389740:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10595.192938] LustreError: 389741:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10595.202164] LustreError: 389740:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [10596.439804] Lustre: Mounted lustre-client [10596.441455] Lustre: Skipped 1 previous similar message [10597.432743] LustreError: 389839:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e7f4b5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10597.453385] LustreError: 389839:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [10597.518621] Lustre: Unmounted lustre-client [10599.481846] Key type lgssc unregistered [10599.659683] LNet: 390090:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10599.669551] LNetError: 390090:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10599.683572] LNet: Removed LNI 192.168.202.29@tcp [10600.210848] Key type .llcrypt unregistered [10600.212603] Key type ._llcrypt unregistered [10600.681110] Key type ._llcrypt registered [10600.700414] Key type .llcrypt registered [10600.955302] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10600.968153] alg: No test for adler32 (adler32-zlib) [10601.996173] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [10602.156702] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [10603.792488] Key type lgssc registered [10604.759665] Lustre: Echo OBD driver; http://www.lustre.org/ [10613.994777] Lustre: DEBUG MARKER: Iteration 4 [10614.332869] LustreError: 390921:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10614.335992] LustreError: 390920:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10614.347742] LustreError: 390921:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [10615.558900] Lustre: Mounted lustre-client [10616.684985] LustreError: 391023:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e52000000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10616.710137] LustreError: 391023:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10616.781211] Lustre: Unmounted lustre-client [10619.248684] Key type lgssc unregistered [10619.495900] LNet: 391266:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10619.500564] LNetError: 391266:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10619.524696] LNet: Removed LNI 192.168.202.29@tcp [10620.178187] Key type .llcrypt unregistered [10620.180101] Key type ._llcrypt unregistered [10621.139784] Key type ._llcrypt registered [10621.141422] Key type .llcrypt registered [10621.492311] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10621.511693] alg: No test for adler32 (adler32-zlib) [10622.512140] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [10622.736715] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [10624.432536] Key type lgssc registered [10625.623590] Lustre: Echo OBD driver; http://www.lustre.org/ [10634.542561] Lustre: DEBUG MARKER: Iteration 5 [10634.720967] LustreError: 392095:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10634.721126] LustreError: 392096:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10634.730147] LustreError: 392095:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [10635.885978] Lustre: Mounted lustre-client [10637.045095] LustreError: 392202:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e4c5c6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10637.066993] LustreError: 392202:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [10637.070983] LustreError: 392202:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [10637.144458] Lustre: Unmounted lustre-client [10639.414913] Key type lgssc unregistered [10639.665162] LNet: 392446:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10639.674461] LNetError: 392446:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10639.693747] LNet: Removed LNI 192.168.202.29@tcp [10640.442154] Key type .llcrypt unregistered [10640.443908] Key type ._llcrypt unregistered [10641.062977] Key type ._llcrypt registered [10641.073986] Key type .llcrypt registered [10641.595302] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10641.608923] alg: No test for adler32 (adler32-zlib) [10642.677472] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [10642.929343] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [10644.584191] Key type lgssc registered [10645.599716] Lustre: Echo OBD driver; http://www.lustre.org/ [10655.790881] Lustre: DEBUG MARKER: Iteration 6 [10656.149664] LustreError: 393275:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10656.149930] LustreError: 393281:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10656.161932] LustreError: 393275:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [10657.442952] Lustre: Mounted lustre-client [10658.578191] LustreError: 393373:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e7910c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10658.599568] LustreError: 393373:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10658.701717] Lustre: Unmounted lustre-client [10661.306595] Key type lgssc unregistered [10661.538940] LNet: 393622:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10661.556322] LNetError: 393622:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10661.607221] LNet: Removed LNI 192.168.202.29@tcp [10662.318108] Key type .llcrypt unregistered [10662.320974] Key type ._llcrypt unregistered [10662.983524] Key type ._llcrypt registered [10663.015768] Key type .llcrypt registered [10663.571834] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10663.584565] alg: No test for adler32 (adler32-zlib) [10664.737787] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [10664.991532] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [10666.697049] Key type lgssc registered [10668.137544] Lustre: Echo OBD driver; http://www.lustre.org/ [10679.706870] Lustre: DEBUG MARKER: Iteration 7 [10680.058888] LustreError: 394449:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10680.061468] LustreError: 394451:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10680.067560] LustreError: 394449:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [10681.354118] Lustre: Mounted lustre-client [10682.331224] LustreError: 394550:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e4c5c7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10682.359344] LustreError: 394550:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [10682.412459] Lustre: Unmounted lustre-client [10684.518335] Key type lgssc unregistered [10684.703724] LNet: 394794:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10684.710389] LNetError: 394794:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10684.732235] LNet: Removed LNI 192.168.202.29@tcp [10685.255168] Key type .llcrypt unregistered [10685.257046] Key type ._llcrypt unregistered [10686.063319] Key type ._llcrypt registered [10686.064934] Key type .llcrypt registered [10686.283899] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10686.294080] alg: No test for adler32 (adler32-zlib) [10687.303444] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [10687.479272] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [10689.112772] Key type lgssc registered [10690.014891] Lustre: Echo OBD driver; http://www.lustre.org/ [10698.489233] Lustre: DEBUG MARKER: Iteration 8 [10698.793036] LustreError: 395622:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10698.798194] LustreError: 395629:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10698.806781] LustreError: 395622:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [10700.002554] Lustre: Mounted lustre-client [10700.875497] LustreError: 395727:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e4bf99000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10700.884531] LustreError: 395727:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10700.938451] Lustre: Unmounted lustre-client [10702.755852] Key type lgssc unregistered [10702.903216] LNet: 395972:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10702.908347] LNetError: 395972:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10702.922271] LNet: Removed LNI 192.168.202.29@tcp [10703.314157] Key type .llcrypt unregistered [10703.315801] Key type ._llcrypt unregistered [10703.679754] Key type ._llcrypt registered [10703.687544] Key type .llcrypt registered [10704.122378] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10704.141902] alg: No test for adler32 (adler32-zlib) [10705.117868] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [10705.265521] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [10706.912209] Key type lgssc registered [10708.068589] Lustre: Echo OBD driver; http://www.lustre.org/ [10719.161622] Lustre: DEBUG MARKER: Iteration 9 [10719.418841] LustreError: 396801:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10719.418871] LustreError: 396802:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10719.424913] LustreError: 396801:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [10720.614994] Lustre: Mounted lustre-client [10721.789424] LustreError: 396905:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e43241800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10721.815601] LustreError: 396905:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [10721.871055] Lustre: Unmounted lustre-client [10723.993251] Key type lgssc unregistered [10724.218178] LNet: 397150:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10724.224732] LNetError: 397150:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10724.238277] LNet: Removed LNI 192.168.202.29@tcp [10724.852184] Key type .llcrypt unregistered [10724.853822] Key type ._llcrypt unregistered [10725.349735] Key type ._llcrypt registered [10725.357056] Key type .llcrypt registered [10725.804536] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10725.825832] alg: No test for adler32 (adler32-zlib) [10726.812935] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [10727.095728] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [10728.760237] Key type lgssc registered [10729.739511] Lustre: Echo OBD driver; http://www.lustre.org/ [10738.166241] Lustre: DEBUG MARKER: Iteration 10 [10738.431048] LustreError: 397979:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10738.435083] LustreError: 397982:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10738.441377] LustreError: 397979:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [10739.589878] Lustre: Mounted lustre-client [10739.591991] Lustre: Skipped 1 previous similar message [10740.596725] LustreError: 398076:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e42e0e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10740.614510] LustreError: 398076:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10740.677091] Lustre: Unmounted lustre-client [10742.860784] Key type lgssc unregistered [10743.054758] LNet: 398325:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10743.061491] LNetError: 398325:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10743.074434] LNet: Removed LNI 192.168.202.29@tcp [10743.674566] Key type .llcrypt unregistered [10743.680370] Key type ._llcrypt unregistered [10744.237388] Key type ._llcrypt registered [10744.250508] Key type .llcrypt registered [10744.594971] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10744.605888] alg: No test for adler32 (adler32-zlib) [10745.676785] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [10745.870803] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [10747.529356] Key type lgssc registered [10748.550467] Lustre: Echo OBD driver; http://www.lustre.org/ [10757.430986] Lustre: DEBUG MARKER: Iteration 11 [10757.839603] LustreError: 399154:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10757.852701] LustreError: 399155:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10757.858465] LustreError: 399154:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [10760.097544] Lustre: Mounted lustre-client [10761.266279] LustreError: 399252:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e4e523000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10761.274734] LustreError: 399252:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10761.375445] Lustre: Unmounted lustre-client [10763.669746] Key type lgssc unregistered [10763.887283] LNet: 399501:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10763.900971] LNetError: 399501:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10763.922883] LNet: Removed LNI 192.168.202.29@tcp [10764.571153] Key type .llcrypt unregistered [10764.579477] Key type ._llcrypt unregistered [10765.298949] Key type ._llcrypt registered [10765.302621] Key type .llcrypt registered [10765.702587] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10765.718441] alg: No test for adler32 (adler32-zlib) [10766.773577] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [10766.956153] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [10768.634279] Key type lgssc registered [10769.521601] Lustre: Echo OBD driver; http://www.lustre.org/ [10778.087657] Lustre: DEBUG MARKER: Iteration 12 [10778.461555] LustreError: 400328:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10778.464288] LustreError: 400331:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10778.480172] LustreError: 400328:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4984 [10779.741546] Lustre: Mounted lustre-client [10780.885665] LustreError: 400435:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e7f4b2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10780.892234] LustreError: 400436:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10780.894692] LustreError: 400435:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10780.986496] Lustre: Unmounted lustre-client [10783.196736] Key type lgssc unregistered [10783.450530] LNet: 400679:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10783.463148] LNetError: 400679:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10784.493303] LNet: Removed LNI 192.168.202.29@tcp [10784.995641] Key type .llcrypt unregistered [10784.997227] Key type ._llcrypt unregistered [10785.487642] Key type ._llcrypt registered [10785.489897] Key type .llcrypt registered [10785.964191] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10785.981756] alg: No test for adler32 (adler32-zlib) [10786.988268] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [10787.182409] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [10788.824128] Key type lgssc registered [10790.092526] Lustre: Echo OBD driver; http://www.lustre.org/ [10801.786026] Lustre: DEBUG MARKER: Iteration 13 [10802.130422] LustreError: 401508:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10802.131171] LustreError: 401511:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10802.138793] LustreError: 401508:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [10803.383069] Lustre: Mounted lustre-client [10804.505228] LustreError: 401615:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e430ce800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10804.512841] LustreError: 401615:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10804.532516] LustreError: 401613:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10804.596800] Lustre: Unmounted lustre-client [10807.148858] Key type lgssc unregistered [10807.399597] LNet: 401857:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10807.407929] LNetError: 401857:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10807.428697] LNet: Removed LNI 192.168.202.29@tcp [10808.105162] Key type .llcrypt unregistered [10808.111224] Key type ._llcrypt unregistered [10808.996489] Key type ._llcrypt registered [10808.997949] Key type .llcrypt registered [10809.642652] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10809.657348] alg: No test for adler32 (adler32-zlib) [10810.745090] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [10811.015768] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [10812.744533] Key type lgssc registered [10813.934476] Lustre: Echo OBD driver; http://www.lustre.org/ [10825.709181] Lustre: DEBUG MARKER: Iteration 14 [10826.145450] LustreError: 402688:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10826.145488] LustreError: 402687:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10826.165129] LustreError: 402688:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [10827.456862] Lustre: Mounted lustre-client [10828.820162] LustreError: 402786:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e65ab0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10828.848975] LustreError: 402786:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10828.907420] Lustre: Unmounted lustre-client [10831.463798] Key type lgssc unregistered [10831.714810] LNet: 403034:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10831.723461] LNetError: 403034:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10831.740529] LNet: Removed LNI 192.168.202.29@tcp [10832.405319] Key type .llcrypt unregistered [10832.406607] Key type ._llcrypt unregistered [10832.978676] Key type ._llcrypt registered [10832.980174] Key type .llcrypt registered [10833.502898] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10833.534139] alg: No test for adler32 (adler32-zlib) [10834.564499] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [10834.831578] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [10836.536175] Key type lgssc registered [10837.437055] Lustre: Echo OBD driver; http://www.lustre.org/ [10847.055804] Lustre: DEBUG MARKER: Iteration 15 [10847.357474] LustreError: 403863:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10847.359086] LustreError: 403864:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10847.374453] LustreError: 403863:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [10848.665145] Lustre: Mounted lustre-client [10849.869963] LustreError: 403970:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e516e9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10849.892810] LustreError: 403970:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [10849.984021] Lustre: Unmounted lustre-client [10852.282899] Key type lgssc unregistered [10852.501694] LNet: 404211:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10852.514398] LNetError: 404211:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10852.542249] LNet: Removed LNI 192.168.202.29@tcp [10853.209295] Key type .llcrypt unregistered [10853.213459] Key type ._llcrypt unregistered [10854.054967] Key type ._llcrypt registered [10854.058592] Key type .llcrypt registered [10854.463827] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10854.476318] alg: No test for adler32 (adler32-zlib) [10855.550594] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [10855.855783] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [10857.568167] Key type lgssc registered [10858.517535] Lustre: Echo OBD driver; http://www.lustre.org/ [10868.807623] Lustre: DEBUG MARKER: Iteration 16 [10869.050101] LustreError: 405040:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10869.058880] LustreError: 405043:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10869.070781] LustreError: 405040:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [10870.312746] Lustre: Mounted lustre-client [10870.316481] Lustre: Skipped 1 previous similar message [10871.647502] LustreError: 405145:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e4c5c0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10871.652713] LustreError: 405146:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [10871.654970] LustreError: 405145:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [10871.703655] Lustre: Unmounted lustre-client [10873.734613] Key type lgssc unregistered [10873.947841] LNet: 405386:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10873.950665] LNetError: 405386:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10873.960134] LNet: Removed LNI 192.168.202.29@tcp [10874.451485] Key type .llcrypt unregistered [10874.453354] Key type ._llcrypt unregistered [10875.322442] Key type ._llcrypt registered [10875.326117] Key type .llcrypt registered [10875.528838] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10875.541341] alg: No test for adler32 (adler32-zlib) [10876.569872] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [10876.785744] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [10878.464188] Key type lgssc registered [10879.521257] Lustre: Echo OBD driver; http://www.lustre.org/ [10889.092205] Lustre: DEBUG MARKER: Iteration 17 [10889.398775] LustreError: 406217:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10889.398822] LustreError: 406218:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10889.411506] LustreError: 406217:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [10890.632717] Lustre: Mounted lustre-client [10892.103803] LustreError: 406321:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e7a5b8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10892.124725] LustreError: 406321:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [10892.190127] Lustre: Unmounted lustre-client [10894.263388] Key type lgssc unregistered [10894.581757] LNet: 406563:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10894.597627] LNetError: 406563:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10894.621665] LNet: Removed LNI 192.168.202.29@tcp [10895.348306] Key type .llcrypt unregistered [10895.350449] Key type ._llcrypt unregistered [10896.155474] Key type ._llcrypt registered [10896.157102] Key type .llcrypt registered [10896.633531] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10896.662852] alg: No test for adler32 (adler32-zlib) [10897.779615] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [10897.996916] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [10899.648189] Key type lgssc registered [10900.943791] Lustre: Echo OBD driver; http://www.lustre.org/ [10912.994959] Lustre: DEBUG MARKER: Iteration 18 [10913.539539] LustreError: 407393:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10913.542750] LustreError: 407394:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10913.560186] LustreError: 407393:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4982 [10915.970762] Lustre: Mounted lustre-client [10916.962316] LustreError: 407493:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e79134000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10916.982112] LustreError: 407493:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10917.055402] Lustre: Unmounted lustre-client [10920.109832] Key type lgssc unregistered [10920.386863] LNet: 407745:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10920.402352] LNetError: 407745:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10920.431310] LNet: Removed LNI 192.168.202.29@tcp [10921.153134] Key type .llcrypt unregistered [10921.159022] Key type ._llcrypt unregistered [10921.882356] Key type ._llcrypt registered [10921.929099] Key type .llcrypt registered [10922.295368] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10922.304426] alg: No test for adler32 (adler32-zlib) [10923.352771] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [10923.526862] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [10925.248177] Key type lgssc registered [10926.461367] Lustre: Echo OBD driver; http://www.lustre.org/ [10937.003423] Lustre: DEBUG MARKER: Iteration 19 [10937.289071] LustreError: 408570:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10937.295502] LustreError: 408579:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10937.303949] LustreError: 408570:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [10938.631299] Lustre: Mounted lustre-client [10939.557102] LustreError: 408675:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e4b683000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10939.569389] LustreError: 408675:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10939.625369] Lustre: Unmounted lustre-client [10941.907569] Key type lgssc unregistered [10942.135639] LNet: 408924:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10942.148544] LNetError: 408924:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10942.185525] LNet: Removed LNI 192.168.202.29@tcp [10942.745169] Key type .llcrypt unregistered [10942.751508] Key type ._llcrypt unregistered [10943.432231] Key type ._llcrypt registered [10943.433404] Key type .llcrypt registered [10943.766675] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10943.779168] alg: No test for adler32 (adler32-zlib) [10944.828389] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [10945.045723] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [10946.704182] Key type lgssc registered [10947.972271] Lustre: Echo OBD driver; http://www.lustre.org/ [10957.550914] Lustre: DEBUG MARKER: Iteration 20 [10957.855349] LustreError: 409755:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10957.862224] LustreError: 409756:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10957.870184] LustreError: 409755:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [10959.098145] Lustre: Mounted lustre-client [10960.175672] LustreError: 409855:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e73262000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10960.197166] LustreError: 409855:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [10960.247627] Lustre: Unmounted lustre-client [10962.200258] Key type lgssc unregistered [10962.421544] LNet: 410103:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10962.428955] LNetError: 410103:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10962.449923] LNet: Removed LNI 192.168.202.29@tcp [10962.971270] Key type .llcrypt unregistered [10962.974153] Key type ._llcrypt unregistered [10963.472179] Key type ._llcrypt registered [10963.473260] Key type .llcrypt registered [10963.834988] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10963.850712] alg: No test for adler32 (adler32-zlib) [10964.831178] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [10965.043465] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [10966.696205] Key type lgssc registered [10967.657593] Lustre: Echo OBD driver; http://www.lustre.org/ [10976.164362] Lustre: DEBUG MARKER: Iteration 21 [10976.468794] LustreError: 410931:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10976.469348] LustreError: 410934:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10976.478908] LustreError: 410931:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [10977.660640] Lustre: Mounted lustre-client [10977.666930] Lustre: Skipped 1 previous similar message [10978.762148] LustreError: 411036:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e65ab7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10978.778080] LustreError: 411036:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [10978.844567] Lustre: Unmounted lustre-client [10980.884827] Key type lgssc unregistered [10981.155896] LNet: 411279:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10981.177843] LNetError: 411279:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10982.247931] LNet: Removed LNI 192.168.202.29@tcp [10982.853940] Key type .llcrypt unregistered [10982.858965] Key type ._llcrypt unregistered [10983.606631] Key type ._llcrypt registered [10983.714561] Key type .llcrypt registered [10983.941660] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [10983.957840] alg: No test for adler32 (adler32-zlib) [10984.949957] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [10985.158740] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [10986.832202] Key type lgssc registered [10987.945193] Lustre: Echo OBD driver; http://www.lustre.org/ [10996.875347] Lustre: DEBUG MARKER: Iteration 22 [10997.248766] LustreError: 412110:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [10997.249130] LustreError: 412113:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [10997.260973] LustreError: 412110:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [10998.500560] Lustre: Mounted lustre-client [10999.625554] LustreError: 412206:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e51f66800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10999.647727] LustreError: 412206:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [10999.722097] Lustre: Unmounted lustre-client [11001.666807] Key type lgssc unregistered [11001.829905] LNet: 412455:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11001.836575] LNetError: 412455:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11001.852748] LNet: Removed LNI 192.168.202.29@tcp [11002.485161] Key type .llcrypt unregistered [11002.486989] Key type ._llcrypt unregistered [11003.111925] Key type ._llcrypt registered [11003.115348] Key type .llcrypt registered [11003.424697] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11003.436817] alg: No test for adler32 (adler32-zlib) [11004.448442] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11004.630851] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11006.272530] Key type lgssc registered [11007.332897] Lustre: Echo OBD driver; http://www.lustre.org/ [11017.185387] Lustre: DEBUG MARKER: Iteration 23 [11017.498815] LustreError: 413286:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11017.500059] LustreError: 413287:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11017.514995] LustreError: 413286:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [11018.760067] Lustre: Mounted lustre-client [11019.815704] LustreError: 413392:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e58773000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11019.836822] LustreError: 413392:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11019.888609] Lustre: Unmounted lustre-client [11022.081480] Key type lgssc unregistered [11022.386507] LNet: 413632:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11022.393244] LNetError: 413632:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11023.463551] LNet: Removed LNI 192.168.202.29@tcp [11024.261274] Key type .llcrypt unregistered [11024.264498] Key type ._llcrypt unregistered [11024.825469] Key type ._llcrypt registered [11024.827259] Key type .llcrypt registered [11025.553456] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11025.567279] alg: No test for adler32 (adler32-zlib) [11026.661417] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11026.966330] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11028.632258] Key type lgssc registered [11029.839889] Lustre: Echo OBD driver; http://www.lustre.org/ [11039.073939] Lustre: DEBUG MARKER: Iteration 24 [11039.352153] LustreError: 414462:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11039.352478] LustreError: 414463:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11039.362738] LustreError: 414462:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [11041.672064] Lustre: Mounted lustre-client [11042.784541] LustreError: 414558:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e7af64800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11042.803336] LustreError: 414558:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11042.851413] Lustre: Unmounted lustre-client [11044.901252] Key type lgssc unregistered [11045.142434] LNet: 414808:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11045.151335] LNetError: 414808:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11045.163185] LNet: Removed LNI 192.168.202.29@tcp [11045.695174] Key type .llcrypt unregistered [11045.696817] Key type ._llcrypt unregistered [11046.172355] Key type ._llcrypt registered [11046.173920] Key type .llcrypt registered [11046.542281] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11046.552511] alg: No test for adler32 (adler32-zlib) [11047.577088] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11047.737574] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11049.384194] Key type lgssc registered [11050.359825] Lustre: Echo OBD driver; http://www.lustre.org/ [11059.735133] Lustre: DEBUG MARKER: Iteration 25 [11060.082385] LustreError: 415632:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11060.105356] LustreError: 415640:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11060.115598] LustreError: 415632:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4976 [11061.377595] Lustre: Mounted lustre-client [11062.521183] LustreError: 415742:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e4e523000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11062.543784] LustreError: 415742:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11062.543784] LustreError: 415743:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11062.561196] LustreError: 415743:0:(obd_class.h:479:obd_check_dev()) Skipped 3 previous similar messages [11062.599629] Lustre: Unmounted lustre-client [11064.629486] Key type lgssc unregistered [11064.825309] LNet: 415984:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11064.830938] LNetError: 415984:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11064.844159] LNet: Removed LNI 192.168.202.29@tcp [11065.383407] Key type .llcrypt unregistered [11065.385191] Key type ._llcrypt unregistered [11065.857885] Key type ._llcrypt registered [11065.859486] Key type .llcrypt registered [11066.180811] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11066.195686] alg: No test for adler32 (adler32-zlib) [11067.180408] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11067.342966] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11068.984127] Key type lgssc registered [11069.923488] Lustre: Echo OBD driver; http://www.lustre.org/ [11077.973221] Lustre: DEBUG MARKER: Iteration 26 [11078.305431] LustreError: 416813:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11078.306639] LustreError: 416814:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11078.316413] LustreError: 416813:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [11079.518667] Lustre: Mounted lustre-client [11079.520225] Lustre: Skipped 1 previous similar message [11080.664241] LustreError: 416910:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e43dcb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11080.691557] LustreError: 416910:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11080.700719] LustreError: 416910:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11080.750762] Lustre: Unmounted lustre-client [11082.922026] Key type lgssc unregistered [11083.160546] LNet: 417159:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11083.170272] LNetError: 417159:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11083.194430] LNet: Removed LNI 192.168.202.29@tcp [11083.846264] Key type .llcrypt unregistered [11083.854563] Key type ._llcrypt unregistered [11084.660277] Key type ._llcrypt registered [11084.661718] Key type .llcrypt registered [11084.935415] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11084.947104] alg: No test for adler32 (adler32-zlib) [11085.991736] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11086.232137] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11087.904175] Key type lgssc registered [11088.911458] Lustre: Echo OBD driver; http://www.lustre.org/ [11098.097544] Lustre: DEBUG MARKER: Iteration 27 [11098.414884] LustreError: 417985:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11098.418796] LustreError: 417994:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11098.424961] LustreError: 417985:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [11099.620024] Lustre: Mounted lustre-client [11100.926140] LustreError: 418091:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e58772000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11100.943265] LustreError: 418091:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11100.997731] Lustre: Unmounted lustre-client [11103.256589] Key type lgssc unregistered [11103.452316] LNet: 418334:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11103.465175] LNetError: 418334:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11104.488309] LNet: Removed LNI 192.168.202.29@tcp [11105.051171] Key type .llcrypt unregistered [11105.053471] Key type ._llcrypt unregistered [11105.529963] Key type ._llcrypt registered [11105.532839] Key type .llcrypt registered [11105.970552] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11105.991277] alg: No test for adler32 (adler32-zlib) [11107.013618] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11107.220083] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11108.872184] Key type lgssc registered [11110.064423] Lustre: Echo OBD driver; http://www.lustre.org/ [11119.823656] Lustre: DEBUG MARKER: Iteration 28 [11120.077828] LustreError: 419165:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11120.079042] LustreError: 419164:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11120.087509] LustreError: 419165:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [11121.273975] Lustre: Mounted lustre-client [11121.275447] Lustre: Skipped 1 previous similar message [11122.082603] LustreError: 419267:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e42e0b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11122.100751] LustreError: 419267:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11122.165126] Lustre: Unmounted lustre-client [11123.796903] Key type lgssc unregistered [11124.048108] LNet: 419510:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11124.052309] LNetError: 419510:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11124.069866] LNet: Removed LNI 192.168.202.29@tcp [11124.494437] Key type .llcrypt unregistered [11124.496094] Key type ._llcrypt unregistered [11124.855719] Key type ._llcrypt registered [11124.857392] Key type .llcrypt registered [11125.155602] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11125.169640] alg: No test for adler32 (adler32-zlib) [11126.150186] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11126.295579] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11127.936882] Key type lgssc registered [11128.580875] Lustre: Echo OBD driver; http://www.lustre.org/ [11137.178194] Lustre: DEBUG MARKER: Iteration 29 [11137.406694] LustreError: 420342:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11137.407432] LustreError: 420343:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11137.421187] LustreError: 420342:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [11138.621070] Lustre: Mounted lustre-client [11139.568305] LustreError: 420441:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e4c55f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11139.585553] LustreError: 420441:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11139.647136] Lustre: Unmounted lustre-client [11141.418790] Key type lgssc unregistered [11141.636796] LNet: 420689:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11141.640957] LNetError: 420689:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11141.653950] LNet: Removed LNI 192.168.202.29@tcp [11142.103444] Key type .llcrypt unregistered [11142.106264] Key type ._llcrypt unregistered [11142.575074] Key type ._llcrypt registered [11142.580617] Key type .llcrypt registered [11142.908853] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11142.919415] alg: No test for adler32 (adler32-zlib) [11143.956436] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11144.159353] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11145.808193] Key type lgssc registered [11146.934504] Lustre: Echo OBD driver; http://www.lustre.org/ [11156.863634] Lustre: DEBUG MARKER: Iteration 30 [11157.097108] LustreError: 421517:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11157.097197] LustreError: 421520:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11157.105766] LustreError: 421517:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [11158.265877] Lustre: Mounted lustre-client [11159.491222] LustreError: 421617:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e58772000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11159.502820] LustreError: 421617:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [11159.610596] Lustre: Unmounted lustre-client [11161.818233] Key type lgssc unregistered [11162.008872] LNet: 421862:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11162.013138] LNetError: 421862:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11162.025858] LNet: Removed LNI 192.168.202.29@tcp [11162.599165] Key type .llcrypt unregistered [11162.600740] Key type ._llcrypt unregistered [11163.166555] Key type ._llcrypt registered [11163.168105] Key type .llcrypt registered [11163.515296] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11163.532881] alg: No test for adler32 (adler32-zlib) [11164.534490] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11164.728160] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11166.378516] Key type lgssc registered [11167.334984] Lustre: Echo OBD driver; http://www.lustre.org/ [11176.017832] Lustre: DEBUG MARKER: Iteration 31 [11176.264045] LustreError: 422691:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11176.266032] LustreError: 422693:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11176.274457] LustreError: 422691:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11177.457558] Lustre: Mounted lustre-client [11178.398634] LustreError: 422791:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e43dcb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11178.412822] LustreError: 422791:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11178.479944] Lustre: Unmounted lustre-client [11180.507533] Key type lgssc unregistered [11180.726675] LNet: 423040:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11180.731637] LNetError: 423040:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11180.749778] LNet: Removed LNI 192.168.202.29@tcp [11181.271181] Key type .llcrypt unregistered [11181.274398] Key type ._llcrypt unregistered [11181.738903] Key type ._llcrypt registered [11181.744588] Key type .llcrypt registered [11182.130796] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11182.143513] alg: No test for adler32 (adler32-zlib) [11183.136398] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11183.348407] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11185.048432] Key type lgssc registered [11186.128789] Lustre: Echo OBD driver; http://www.lustre.org/ [11196.906953] Lustre: DEBUG MARKER: Iteration 32 [11197.244892] LustreError: 423871:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11197.250461] LustreError: 423872:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11197.258339] LustreError: 423871:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [11198.447752] Lustre: Mounted lustre-client [11199.678061] LustreError: 423964:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e7f4b7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11199.701943] LustreError: 423964:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11199.761865] Lustre: Unmounted lustre-client [11202.645521] Key type lgssc unregistered [11202.858762] LNet: 424215:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11202.863869] LNetError: 424215:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11202.886921] LNet: Removed LNI 192.168.202.29@tcp [11203.570177] Key type .llcrypt unregistered [11203.574845] Key type ._llcrypt unregistered [11204.476771] Key type ._llcrypt registered [11204.479364] Key type .llcrypt registered [11204.951802] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11204.974671] alg: No test for adler32 (adler32-zlib) [11206.125656] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11206.328738] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11208.009139] Key type lgssc registered [11209.119285] Lustre: Echo OBD driver; http://www.lustre.org/ [11219.264123] Lustre: DEBUG MARKER: Iteration 33 [11219.695545] LustreError: 425045:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11219.697248] LustreError: 425050:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11219.702830] LustreError: 425045:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [11220.980287] Lustre: Mounted lustre-client [11220.981727] Lustre: Skipped 1 previous similar message [11222.002726] LustreError: 425142:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e42e0e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11222.058339] LustreError: 425142:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11222.142461] Lustre: Unmounted lustre-client [11224.919718] Key type lgssc unregistered [11225.250182] LNet: 425391:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11225.259463] LNetError: 425391:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11226.282819] LNet: Removed LNI 192.168.202.29@tcp [11226.896483] Key type .llcrypt unregistered [11226.898144] Key type ._llcrypt unregistered [11227.329324] Key type ._llcrypt registered [11227.334482] Key type .llcrypt registered [11227.722310] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11227.740694] alg: No test for adler32 (adler32-zlib) [11228.854296] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11229.107694] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11230.824238] Key type lgssc registered [11231.950442] Lustre: Echo OBD driver; http://www.lustre.org/ [11240.627590] Lustre: DEBUG MARKER: Iteration 34 [11240.923777] LustreError: 426221:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11240.926079] LustreError: 426222:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11240.943176] LustreError: 426221:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [11242.079701] Lustre: Mounted lustre-client [11242.954862] LustreError: 426319:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e58775000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11242.972180] LustreError: 426319:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11243.062092] Lustre: Unmounted lustre-client [11245.007313] Key type lgssc unregistered [11245.226054] LNet: 426568:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11245.230868] LNetError: 426568:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11245.250935] LNet: Removed LNI 192.168.202.29@tcp [11245.821126] Key type .llcrypt unregistered [11245.822467] Key type ._llcrypt unregistered [11246.277611] Key type ._llcrypt registered [11246.279632] Key type .llcrypt registered [11246.531631] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11246.541357] alg: No test for adler32 (adler32-zlib) [11247.521855] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11247.692413] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11249.320229] Key type lgssc registered [11250.418661] Lustre: Echo OBD driver; http://www.lustre.org/ [11258.677964] Lustre: DEBUG MARKER: Iteration 35 [11259.072654] LustreError: 427399:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11259.075331] LustreError: 427400:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11259.093202] LustreError: 427399:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4986 [11261.376879] Lustre: Mounted lustre-client [11262.653302] LustreError: 427499:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e4e951800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11262.683928] LustreError: 427506:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11262.688508] LustreError: 427506:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11262.762533] Lustre: Unmounted lustre-client [11265.219285] Key type lgssc unregistered [11265.555484] LNet: 427747:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11265.567851] LNetError: 427747:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11266.598466] LNet: Removed LNI 192.168.202.29@tcp [11267.411126] Key type .llcrypt unregistered [11267.413567] Key type ._llcrypt unregistered [11268.140885] Key type ._llcrypt registered [11268.142454] Key type .llcrypt registered [11268.629390] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11268.645990] alg: No test for adler32 (adler32-zlib) [11269.737877] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11269.926909] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11271.592616] Key type lgssc registered [11272.910785] Lustre: Echo OBD driver; http://www.lustre.org/ [11283.400099] Lustre: DEBUG MARKER: Iteration 36 [11283.746218] LustreError: 428574:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11283.746626] LustreError: 428575:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11283.753365] LustreError: 428574:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11284.989606] Lustre: Mounted lustre-client [11286.153682] LustreError: 428683:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e4c5c1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11286.170215] LustreError: 428683:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [11286.186160] LustreError: 428683:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11286.249703] Lustre: Unmounted lustre-client [11289.017753] Key type lgssc unregistered [11289.325882] LNet: 428927:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11289.341334] LNetError: 428927:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11290.410155] LNet: Removed LNI 192.168.202.29@tcp [11291.043167] Key type .llcrypt unregistered [11291.045630] Key type ._llcrypt unregistered [11292.048371] Key type ._llcrypt registered [11292.069918] Key type .llcrypt registered [11292.550953] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11292.564819] alg: No test for adler32 (adler32-zlib) [11293.717402] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11293.991188] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11295.732278] Key type lgssc registered [11297.314414] Lustre: Echo OBD driver; http://www.lustre.org/ [11310.333340] Lustre: DEBUG MARKER: Iteration 37 [11310.872868] LustreError: 429757:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11310.876068] LustreError: 429759:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11310.883563] LustreError: 429757:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11312.128122] Lustre: Mounted lustre-client [11312.138085] Lustre: Skipped 1 previous similar message [11313.566552] LustreError: 429858:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e44d54800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11313.597486] LustreError: 429858:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11313.655865] Lustre: Unmounted lustre-client [11315.835716] Key type lgssc unregistered [11316.029985] LNet: 430108:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11316.043468] LNetError: 430108:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11316.060968] LNet: Removed LNI 192.168.202.29@tcp [11316.572339] Key type .llcrypt unregistered [11316.573792] Key type ._llcrypt unregistered [11317.034547] Key type ._llcrypt registered [11317.037739] Key type .llcrypt registered [11317.568683] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11317.602718] alg: No test for adler32 (adler32-zlib) [11318.768760] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11318.970122] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11320.624202] Key type lgssc registered [11321.929357] Lustre: Echo OBD driver; http://www.lustre.org/ [11332.317443] Lustre: DEBUG MARKER: Iteration 38 [11332.546536] LustreError: 430936:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11332.547110] LustreError: 430938:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11332.560473] LustreError: 430936:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [11334.861657] Lustre: Mounted lustre-client [11336.123969] LustreError: 431040:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e43ff4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11336.154709] LustreError: 431040:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [11336.217706] Lustre: Unmounted lustre-client [11338.373956] Key type lgssc unregistered [11338.618567] LNet: 431283:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11338.627688] LNetError: 431283:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11339.687229] LNet: Removed LNI 192.168.202.29@tcp [11340.245281] Key type .llcrypt unregistered [11340.249524] Key type ._llcrypt unregistered [11340.935790] Key type ._llcrypt registered [11340.937040] Key type .llcrypt registered [11341.238206] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11341.258361] alg: No test for adler32 (adler32-zlib) [11342.345222] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11342.568730] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11344.218042] Key type lgssc registered [11345.293478] Lustre: Echo OBD driver; http://www.lustre.org/ [11354.764359] Lustre: DEBUG MARKER: Iteration 39 [11355.029927] LustreError: 432113:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11355.030311] LustreError: 432112:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11355.037678] LustreError: 432113:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [11356.277657] Lustre: Mounted lustre-client [11357.629097] LustreError: 432215:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e65ab0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11357.644712] LustreError: 432215:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11357.690955] Lustre: Unmounted lustre-client [11359.362662] Key type lgssc unregistered [11359.566328] LNet: 432457:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11359.576842] LNetError: 432457:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11359.590418] LNet: Removed LNI 192.168.202.29@tcp [11360.094163] Key type .llcrypt unregistered [11360.100926] Key type ._llcrypt unregistered [11360.706992] Key type ._llcrypt registered [11360.708937] Key type .llcrypt registered [11361.218084] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11361.242346] alg: No test for adler32 (adler32-zlib) [11362.272089] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11362.446203] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11364.168227] Key type lgssc registered [11365.419024] Lustre: Echo OBD driver; http://www.lustre.org/ [11376.490481] Lustre: DEBUG MARKER: Iteration 40 [11376.852072] LustreError: 433285:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11376.852588] LustreError: 433286:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11376.867821] LustreError: 433285:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [11379.133170] Lustre: Mounted lustre-client [11380.462726] LustreError: 433387:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e44d68000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11380.474195] LustreError: 433387:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11380.543099] Lustre: Unmounted lustre-client [11383.132266] Key type lgssc unregistered [11383.323115] LNet: 433637:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11383.329188] LNetError: 433637:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11383.344652] LNet: Removed LNI 192.168.202.29@tcp [11383.856384] Key type .llcrypt unregistered [11383.858054] Key type ._llcrypt unregistered [11384.306033] Key type ._llcrypt registered [11384.307458] Key type .llcrypt registered [11384.638880] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11384.647769] alg: No test for adler32 (adler32-zlib) [11385.660376] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11385.897846] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11387.568212] Key type lgssc registered [11388.659351] Lustre: Echo OBD driver; http://www.lustre.org/ [11398.285734] Lustre: DEBUG MARKER: Iteration 41 [11398.608634] LustreError: 434465:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11398.612399] LustreError: 434467:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11398.617057] LustreError: 434465:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [11399.907942] Lustre: Mounted lustre-client [11401.036413] LustreError: 434565:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e58776800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11401.068389] LustreError: 434565:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [11401.146136] Lustre: Unmounted lustre-client [11403.566081] Key type lgssc unregistered [11403.814525] LNet: 434815:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11403.828970] LNetError: 434815:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11403.849443] LNet: Removed LNI 192.168.202.29@tcp [11404.448487] Key type .llcrypt unregistered [11404.451589] Key type ._llcrypt unregistered [11405.159239] Key type ._llcrypt registered [11405.160418] Key type .llcrypt registered [11405.553827] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11405.567371] alg: No test for adler32 (adler32-zlib) [11406.561577] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11406.754029] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11408.472205] Key type lgssc registered [11409.517125] Lustre: Echo OBD driver; http://www.lustre.org/ [11417.993381] Lustre: DEBUG MARKER: Iteration 42 [11418.317685] LustreError: 435645:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11418.318823] LustreError: 435646:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11418.325639] LustreError: 435645:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [11419.510342] Lustre: Mounted lustre-client [11420.670278] LustreError: 435741:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e4b680000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11420.690557] LustreError: 435741:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11420.762910] Lustre: Unmounted lustre-client [11423.303411] Key type lgssc unregistered [11423.512737] LNet: 435990:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11423.528700] LNetError: 435990:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11423.543488] LNet: Removed LNI 192.168.202.29@tcp [11424.110167] Key type .llcrypt unregistered [11424.113652] Key type ._llcrypt unregistered [11424.699127] Key type ._llcrypt registered [11424.706527] Key type .llcrypt registered [11425.200548] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11425.216641] alg: No test for adler32 (adler32-zlib) [11426.283811] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11426.507053] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11428.216169] Key type lgssc registered [11429.352636] Lustre: Echo OBD driver; http://www.lustre.org/ [11438.770190] Lustre: DEBUG MARKER: Iteration 43 [11439.065920] LustreError: 436819:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11439.066585] LustreError: 436820:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11439.076160] LustreError: 436819:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [11441.341918] Lustre: Mounted lustre-client [11442.356716] LustreError: 436917:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e430c8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11442.374182] LustreError: 436917:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11442.427974] Lustre: Unmounted lustre-client [11445.204450] Key type lgssc unregistered [11445.447775] LNet: 437166:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11445.460640] LNetError: 437166:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11446.504346] LNet: Removed LNI 192.168.202.29@tcp [11447.002670] Key type .llcrypt unregistered [11447.004073] Key type ._llcrypt unregistered [11447.656594] Key type ._llcrypt registered [11447.658022] Key type .llcrypt registered [11448.001791] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11448.015499] alg: No test for adler32 (adler32-zlib) [11448.944358] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11449.107664] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11450.768468] Key type lgssc registered [11452.040758] Lustre: Echo OBD driver; http://www.lustre.org/ [11464.347959] Lustre: DEBUG MARKER: Iteration 44 [11464.627273] LustreError: 437993:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11464.627341] LustreError: 437998:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11464.637193] LustreError: 437993:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [11465.826374] Lustre: Mounted lustre-client [11467.154848] LustreError: 438096:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e42e0b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11467.181631] LustreError: 438096:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11467.256138] Lustre: Unmounted lustre-client [11469.785624] Key type lgssc unregistered [11470.044113] LNet: 438348:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11470.055360] LNetError: 438348:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11471.078794] LNet: Removed LNI 192.168.202.29@tcp [11471.819167] Key type .llcrypt unregistered [11471.825915] Key type ._llcrypt unregistered [11472.775713] Key type ._llcrypt registered [11472.781759] Key type .llcrypt registered [11473.099515] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11473.112058] alg: No test for adler32 (adler32-zlib) [11474.108951] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11474.277525] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11475.896673] Key type lgssc registered [11476.975148] Lustre: Echo OBD driver; http://www.lustre.org/ [11488.083309] Lustre: DEBUG MARKER: Iteration 45 [11488.484709] LustreError: 439179:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11488.484830] LustreError: 439180:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11488.495638] LustreError: 439179:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [11489.721158] Lustre: Mounted lustre-client [11491.221495] LustreError: 439285:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e4be70800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11491.237325] LustreError: 439287:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [11491.313744] Lustre: Unmounted lustre-client [11494.440258] Key type lgssc unregistered [11494.738533] LNet: 439530:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11494.755140] LNetError: 439530:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11494.779770] LNet: Removed LNI 192.168.202.29@tcp [11495.365144] Key type .llcrypt unregistered [11495.370499] Key type ._llcrypt unregistered [11495.916751] Key type ._llcrypt registered [11495.938514] Key type .llcrypt registered [11496.384151] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11496.394644] alg: No test for adler32 (adler32-zlib) [11497.485385] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11497.748268] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11499.458607] Key type lgssc registered [11500.525528] Lustre: Echo OBD driver; http://www.lustre.org/ [11511.545683] Lustre: DEBUG MARKER: Iteration 46 [11512.164718] LustreError: 440361:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11512.167284] LustreError: 440362:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11512.223583] LustreError: 440361:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4979 [11514.500969] Lustre: Mounted lustre-client [11515.872074] LustreError: 440465:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e43243000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11515.876482] LustreError: 440465:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11515.882172] LustreError: 440463:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11515.884080] LustreError: 440463:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11515.920111] Lustre: Unmounted lustre-client [11517.532806] Key type lgssc unregistered [11517.767465] LNet: 440706:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11517.771681] LNetError: 440706:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11517.784438] LNet: Removed LNI 192.168.202.29@tcp [11518.256805] Key type .llcrypt unregistered [11518.258521] Key type ._llcrypt unregistered [11518.782275] Key type ._llcrypt registered [11518.783405] Key type .llcrypt registered [11519.132316] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11519.144107] alg: No test for adler32 (adler32-zlib) [11520.216529] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11520.395140] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11522.057573] Key type lgssc registered [11523.300848] Lustre: Echo OBD driver; http://www.lustre.org/ [11533.597748] Lustre: DEBUG MARKER: Iteration 47 [11533.818033] LustreError: 441535:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11533.819591] LustreError: 441537:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11533.826722] LustreError: 441535:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [11534.997851] Lustre: Mounted lustre-client [11535.902347] LustreError: 441640:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e58cb8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11535.922966] LustreError: 441640:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11535.975701] Lustre: Unmounted lustre-client [11538.474517] Key type lgssc unregistered [11538.740514] LNet: 441883:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11538.773756] LNetError: 441883:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11538.787695] LNet: Removed LNI 192.168.202.29@tcp [11539.354297] Key type .llcrypt unregistered [11539.358298] Key type ._llcrypt unregistered [11539.959396] Key type ._llcrypt registered [11539.961595] Key type .llcrypt registered [11540.402937] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11540.431169] alg: No test for adler32 (adler32-zlib) [11541.402740] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11541.586797] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11543.232155] Key type lgssc registered [11544.386458] Lustre: Echo OBD driver; http://www.lustre.org/ [11554.729665] Lustre: DEBUG MARKER: Iteration 48 [11555.062147] LustreError: 442713:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11555.065865] LustreError: 442716:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11555.075618] LustreError: 442713:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [11556.360889] Lustre: Mounted lustre-client [11557.524710] LustreError: 442824:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e43dcb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11557.538941] LustreError: 442822:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11557.543792] LustreError: 442824:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11557.566544] LustreError: 442822:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [11557.607340] Lustre: Unmounted lustre-client [11560.024753] Key type lgssc unregistered [11560.233112] LNet: 443065:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11560.238735] LNetError: 443065:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11560.274620] LNet: Removed LNI 192.168.202.29@tcp [11560.917570] Key type .llcrypt unregistered [11560.927232] Key type ._llcrypt unregistered [11561.505593] Key type ._llcrypt registered [11561.507278] Key type .llcrypt registered [11561.852583] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11561.861997] alg: No test for adler32 (adler32-zlib) [11562.957250] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11563.169474] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11564.816260] Key type lgssc registered [11566.165714] Lustre: Echo OBD driver; http://www.lustre.org/ [11575.980346] Lustre: DEBUG MARKER: Iteration 49 [11576.377142] LustreError: 443897:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11576.377649] LustreError: 443898:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11576.389738] LustreError: 443897:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [11577.611357] Lustre: Mounted lustre-client [11578.804652] LustreError: 444002:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e4c5c0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11578.824269] LustreError: 444002:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11578.826587] LustreError: 444004:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11578.885625] Lustre: Unmounted lustre-client [11580.964210] Key type lgssc unregistered [11581.193345] LNet: 444244:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11581.198931] LNetError: 444244:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11581.214820] LNet: Removed LNI 192.168.202.29@tcp [11581.859158] Key type .llcrypt unregistered [11581.860844] Key type ._llcrypt unregistered [11582.376402] Key type ._llcrypt registered [11582.378988] Key type .llcrypt registered [11582.760414] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11582.777916] alg: No test for adler32 (adler32-zlib) [11583.895434] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11584.125849] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11585.864303] Key type lgssc registered [11586.875515] Lustre: Echo OBD driver; http://www.lustre.org/ [11596.166625] Lustre: DEBUG MARKER: Iteration 50 [11596.411314] LustreError: 445074:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11596.411425] LustreError: 445075:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11596.418562] LustreError: 445074:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [11597.589455] Lustre: Mounted lustre-client [11597.591326] Lustre: Skipped 1 previous similar message [11598.703450] LustreError: 445179:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e42e0a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11598.713335] LustreError: 445179:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [11598.732138] LustreError: 445179:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [11598.734566] LustreError: 445179:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11598.810238] Lustre: Unmounted lustre-client [11600.962649] Key type lgssc unregistered [11601.198679] LNet: 445421:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11601.204544] LNetError: 445421:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11601.220141] LNet: Removed LNI 192.168.202.29@tcp [11601.743151] Key type .llcrypt unregistered [11601.744464] Key type ._llcrypt unregistered [11602.309233] Key type ._llcrypt registered [11602.315015] Key type .llcrypt registered [11602.638968] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11602.650142] alg: No test for adler32 (adler32-zlib) [11603.695621] Lustre: Lustre: Build Version: 2.16.59_38_g3dab24e [11603.898722] LNet: Added LNI 192.168.202.29@tcp [8/256/0/180] [11605.592186] Key type lgssc registered [11606.787552] Lustre: Echo OBD driver; http://www.lustre.org/ [11615.684709] Lustre: Mounted lustre-client [11621.160549] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 11:58:13 (1761235093) [11638.240878] Lustre: 446765:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761235095/real 1761235095] req@ffff930e44711c00 x1846788832111872/t0(0) o36->lustre-MDT0000-mdc-ffff930e58777800@192.168.202.129@tcp:12/10 lens 496/440 e 0 to 1 dl 1761235111 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [11638.269714] Lustre: lustre-MDT0000-mdc-ffff930e58777800: Connection to lustre-MDT0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [11638.310342] Lustre: lustre-MDT0000-mdc-ffff930e58777800: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [11653.600102] Lustre: 446765:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761235111/real 1761235111] req@ffff930e44711c00 x1846788832111872/t0(0) o36->lustre-MDT0000-mdc-ffff930e58777800@192.168.202.129@tcp:12/10 lens 496/440 e 0 to 1 dl 1761235127 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [11653.612619] Lustre: lustre-MDT0000-mdc-ffff930e58777800: Connection to lustre-MDT0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [11653.631378] Lustre: lustre-MDT0000-mdc-ffff930e58777800: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [11669.986273] Lustre: 446765:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761235127/real 1761235127] req@ffff930e44711c00 x1846788832111872/t0(0) o36->lustre-MDT0000-mdc-ffff930e58777800@192.168.202.129@tcp:12/10 lens 496/440 e 0 to 1 dl 1761235143 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [11670.000391] Lustre: lustre-MDT0000-mdc-ffff930e58777800: Connection to lustre-MDT0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [11670.033423] Lustre: lustre-MDT0000-mdc-ffff930e58777800: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [11686.369211] Lustre: 446765:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761235143/real 1761235143] req@ffff930e44711c00 x1846788832111872/t0(0) o36->lustre-MDT0000-mdc-ffff930e58777800@192.168.202.129@tcp:12/10 lens 496/440 e 0 to 1 dl 1761235159 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [11686.402929] Lustre: lustre-MDT0000-mdc-ffff930e58777800: Connection to lustre-MDT0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [11686.447614] Lustre: lustre-MDT0000-mdc-ffff930e58777800: Connection restored to 192.168.202.129@tcp (at 192.168.202.129@tcp) [11691.628279] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 11:59:23 (1761235163) [11703.542783] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 11:59:35 (1761235175) [11715.888653] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 11:59:48 (1761235188) [11722.731866] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 11:59:54 (1761235194) [11746.405723] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 12:00:18 (1761235218) [11785.297512] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 12:00:57 (1761235257) [11793.153413] Lustre: DEBUG MARKER: == sanityn test 117a: TCU: Init and enable Trash Can on MDTs ========================================================== 12:01:05 (1761235265) [11803.975984] Lustre: DEBUG MARKER: == sanityn test 117b: Move regular file and empty dir into trash can dir ========================================================== 12:01:16 (1761235276) [11809.642733] LustreError: 452419:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff930e58777800: inode [0x200000400:0x2:0x0] mdc close failed: rc = -9 [11816.337041] LustreError: 452959:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff930e58777800: inode [0x240000400:0x2:0x0] mdc close failed: rc = -9 [11816.345761] LustreError: 452959:0:(file.c:249:ll_close_inode_openhandle()) Skipped 6 previous similar messages [11817.965079] Lustre: DEBUG MARKER: == sanityn test 117c: Move deleted tree with multiple levels into trash ========================================================== 12:01:30 (1761235290) [11822.410575] LustreError: 453268:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff930e58777800: inode [0x200000400:0x2:0x0] mdc close failed: rc = -9 [11822.415636] LustreError: 453268:0:(file.c:249:ll_close_inode_openhandle()) Skipped 4 previous similar messages [11830.070664] LustreError: 453805:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff930e58777800: inode [0x240000400:0x2:0x0] mdc close failed: rc = -9 [11830.082084] LustreError: 453805:0:(file.c:249:ll_close_inode_openhandle()) Skipped 12 previous similar messages [11831.854594] Lustre: DEBUG MARKER: == sanityn test 117d: Per-User Trash can Type testing ==== 12:01:43 (1761235303) [11837.368909] LustreError: 454158:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff930e58777800: inode [0x200000bd0:0x219:0x0] mdc close failed: rc = -9 [11837.374245] LustreError: 454158:0:(file.c:249:ll_close_inode_openhandle()) Skipped 3 previous similar messages [11846.382377] Lustre: DEBUG MARKER: == sanityn test 117e: Undeleted dir in trash should keep its original xattrs ========================================================== 12:01:58 (1761235318) [11852.339586] LustreError: 455010:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff930e58777800: inode [0x200000bd0:0x219:0x0] mdc close failed: rc = -9 [11852.359088] LustreError: 455010:0:(file.c:249:ll_close_inode_openhandle()) Skipped 20 previous similar messages [11862.943492] Lustre: DEBUG MARKER: == sanityn test 117f: Uncache the dentry under the trash dir ========================================================== 12:02:14 (1761235334) [11868.749902] LustreError: 455858:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff930e58777800: inode [0x200000bd0:0x219:0x0] mdc close failed: rc = -9 [11868.755790] LustreError: 455858:0:(file.c:249:ll_close_inode_openhandle()) Skipped 13 previous similar messages [11879.504557] Lustre: DEBUG MARKER: == sanityn test 117g: Access .Trash for a non-striped directory ========================================================== 12:02:31 (1761235351) [11893.121890] Lustre: DEBUG MARKER: == sanityn test 117h: Permission testing of various operations for files in Trash Can ========================================================== 12:02:45 (1761235365) [11898.973831] LustreError: 457670:0:(llite_lib.c:2047:ll_md_setattr()) md_setattr fails: rc = -9 [11906.232500] LustreError: 458249:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff930e58777800: inode [0x200000bd0:0x21f:0x0] mdc close failed: rc = -9 [11906.250412] LustreError: 458249:0:(file.c:249:ll_close_inode_openhandle()) Skipped 40 previous similar messages [11908.123919] Lustre: DEBUG MARKER: == sanityn test 117i: Forbid to create a file with name .Trash ========================================================== 12:03:00 (1761235380) [11914.581867] Lustre: DEBUG MARKER: == sanityn test 117j: Move back file from Trash Can ====== 12:03:06 (1761235386) [11929.187169] Lustre: DEBUG MARKER: == sanityn test 117k: Unrm file from Trash Can =========== 12:03:21 (1761235401) [11945.502130] Lustre: DEBUG MARKER: == sanityn test 117l: Clean up files in Trash Can via command 'lfs trash clean' ========================================================== 12:03:37 (1761235417) [11961.931706] Lustre: DEBUG MARKER: == sanityn test 117m: Empty Trash Can via the command 'lfs trash clean' ========================================================== 12:03:54 (1761235434) [11975.156845] LustreError: 462325:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff930e58777800: inode [0x200000bd0:0x21f:0x0] mdc close failed: rc = -9 [11975.162792] LustreError: 462325:0:(file.c:249:ll_close_inode_openhandle()) Skipped 70 previous similar messages [11976.809651] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 12:04:09 (1761235449) [11997.664391] Lustre: 445609:0:(client.c:2468:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1761235455/real 1761235455] req@ffff930e7aca9f80 x1846788833527168/t0(0) o4->lustre-OST0000-osc-ffff930e58777800@192.168.202.129@tcp:6/4 lens 4584/448 e 0 to 1 dl 1761235471 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [11997.666355] Lustre: lustre-OST0000-osc-ffff930e58777800: Connection to lustre-OST0000 (at 192.168.202.129@tcp) was lost; in progress operations using this service will wait for recovery to complete [11997.701305] Lustre: 445609:0:(client.c:2468:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [12072.533159] Lustre: DEBUG MARKER: oleg229-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff930e43dca800.ost_server_uuid 50 [12074.141945] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff930e43dca800.ost_server_uuid in IDLE state after 0 sec [12075.744778] Lustre: DEBUG MARKER: cleanup: ====================================================== [12077.154662] Lustre: DEBUG MARKER: == sanityn test complete, duration 11751 sec ============= 12:05:49 (1761235549) [12078.545965] Lustre: DEBUG MARKER: === sanityn: start cleanup 12:05:50 (1761235550) === [12380.030699] LustreError: 464021:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e43dca800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12380.043793] LustreError: 464021:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12380.113793] Lustre: Unmounted lustre-client [12383.646818] Lustre: DEBUG MARKER: === sanityn: finish cleanup 12:10:55 (1761235855) === [12384.754493] LustreError: 464330:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff930e58777800: inode [0x200000400:0x2:0x0] mdc close failed: rc = -9 [12384.763044] LustreError: 464330:0:(file.c:249:ll_close_inode_openhandle()) Skipped 7 previous similar messages [12384.819456] LustreError: 464330:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff930e58777800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12384.833916] LustreError: 464330:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [12384.855977] LustreError: 464330:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [12384.859319] LustreError: 464330:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [12384.911464] Lustre: Unmounted lustre-client [12433.266561] Key type lgssc unregistered [12433.555548] LNet: 465014:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12433.574851] LNetError: 465014:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12434.602943] LNet: Removed LNI 192.168.202.29@tcp [12435.280309] Key type .llcrypt unregistered [12435.284288] Key type ._llcrypt unregistered