[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-8.fc42 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 448562177 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001013] APIC: Switch to symmetric I/O mode setup [ 0.003445] x2apic enabled [ 0.004009] Switched APIC routing to physical x2apic. [ 0.005013] kvm-guest: setup PV IPIs [ 0.007459] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008020] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009009] pid_max: default: 32768 minimum: 301 [ 0.010101] LSM: Security Framework initializing [ 0.011037] Yama: becoming mindful. [ 0.012025] SELinux: Initializing. [ 0.013045] *** VALIDATE selinux *** [ 0.019667] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.023668] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.024126] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.025087] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.026092] *** VALIDATE tmpfs *** [ 0.028230] *** VALIDATE proc *** [ 0.029240] *** VALIDATE cgroup *** [ 0.030010] *** VALIDATE cgroup2 *** [ 0.031277] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.032175] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.033010] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.034031] Spectre V2 : User space: Vulnerable [ 0.035010] Speculative Store Bypass: Vulnerable [ 0.038119] debug: unmapping init [mem 0xffffffffaf659000-0xffffffffaf660fff] [ 0.041000] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.041757] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.042028] ... version: 2 [ 0.043015] ... bit width: 48 [ 0.044013] ... generic registers: 4 [ 0.045009] ... value mask: 0000ffffffffffff [ 0.046010] ... max period: 00007fffffffffff [ 0.047015] ... fixed-purpose events: 3 [ 0.048012] ... event mask: 000000070000000f [ 0.049326] rcu: Hierarchical SRCU implementation. [ 0.051640] smp: Bringing up secondary CPUs ... [ 0.052731] x86: Booting SMP configuration: [ 0.053029] .... node #0, CPUs: #1 #2 #3 [ 0.057010] smp: Brought up 1 node, 4 CPUs [ 0.059017] smpboot: Max logical packages: 1 [ 0.060010] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.150985] node 0 deferred pages initialised in 88ms [ 0.154098] devtmpfs: initialized [ 0.156051] x86/mm: Memory block size: 128MB [ 0.158982] gcov: version magic: 0x41383552 [ 0.160125] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.164114] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.167435] pinctrl core: initialized pinctrl subsystem [ 0.169202] [ 0.169764] ************************************************************* [ 0.172012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.175014] ** ** [ 0.177010] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.180015] ** ** [ 0.182011] ** This means that this kernel is built to expose internal ** [ 0.184013] ** IOMMU data structures, which may compromise security on ** [ 0.187014] ** your system. ** [ 0.189011] ** ** [ 0.191012] ** If you see this message and you are not debugging the ** [ 0.193011] ** kernel, report this immediately to your vendor! ** [ 0.195030] ** ** [ 0.198013] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.201013] ************************************************************* [ 0.203658] NET: Registered protocol family 16 [ 0.206441] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.209062] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.211058] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.215033] cpuidle: using governor menu [ 0.217117] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.219501] PCI: Using configuration type 1 for base access [ 0.220097] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.230096] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.231026] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.234069] cryptd: max_cpu_qlen set to 1000 [ 0.238211] ACPI: Added _OSI(Module Device) [ 0.240016] ACPI: Added _OSI(Processor Device) [ 0.241012] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.243014] ACPI: Added _OSI(Processor Aggregator Device) [ 0.247999] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.254574] ACPI: Interpreter enabled [ 0.256064] ACPI: PM: (supports S0 S3 S4 S5) [ 0.257016] ACPI: Using IOAPIC for interrupt routing [ 0.259112] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.263394] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.273590] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.276041] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.279020] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.282085] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.287430] acpiphp: Slot [2] registered [ 0.289163] acpiphp: Slot [5] registered [ 0.290158] acpiphp: Slot [6] registered [ 0.292123] acpiphp: Slot [3] registered [ 0.293099] acpiphp: Slot [4] registered [ 0.295120] acpiphp: Slot [7] registered [ 0.296088] acpiphp: Slot [8] registered [ 0.297076] acpiphp: Slot [9] registered [ 0.298084] acpiphp: Slot [10] registered [ 0.299078] acpiphp: Slot [11] registered [ 0.301140] acpiphp: Slot [12] registered [ 0.304153] acpiphp: Slot [13] registered [ 0.306151] acpiphp: Slot [14] registered [ 0.308126] acpiphp: Slot [15] registered [ 0.310104] acpiphp: Slot [16] registered [ 0.312079] acpiphp: Slot [17] registered [ 0.313124] acpiphp: Slot [18] registered [ 0.315135] acpiphp: Slot [19] registered [ 0.317129] acpiphp: Slot [20] registered [ 0.318117] acpiphp: Slot [21] registered [ 0.320078] acpiphp: Slot [22] registered [ 0.322106] acpiphp: Slot [23] registered [ 0.323111] acpiphp: Slot [24] registered [ 0.325134] acpiphp: Slot [25] registered [ 0.327251] acpiphp: Slot [26] registered [ 0.328124] acpiphp: Slot [27] registered [ 0.330104] acpiphp: Slot [28] registered [ 0.332111] acpiphp: Slot [29] registered [ 0.334117] acpiphp: Slot [30] registered [ 0.335092] acpiphp: Slot [31] registered [ 0.337095] PCI host bridge to bus 0000:00 [ 0.339020] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.341022] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.343032] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.346031] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.349027] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.352024] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.353156] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.356101] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.360246] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.367542] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.371056] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.374016] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.376021] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.379020] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.381593] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.383634] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.386033] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.388825] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.393015] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.404890] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.409013] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.414574] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.424016] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.430014] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.449016] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.459947] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.476018] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.484018] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.498018] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.511546] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.514370] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.517335] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.519291] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.520211] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.526129] iommu: Default domain type: Passthrough [ 0.527468] SCSI subsystem initialized [ 0.528150] ACPI: bus type USB registered [ 0.529158] usbcore: registered new interface driver usbfs [ 0.531145] usbcore: registered new interface driver hub [ 0.533079] usbcore: registered new device driver usb [ 0.535263] pps_core: LinuxPPS API ver. 1 registered [ 0.537011] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.540064] PTP clock support registered [ 0.543046] EDAC MC: Ver: 3.0.0 [ 0.545125] PCI: Using ACPI for IRQ routing [ 0.547823] NetLabel: Initializing [ 0.549010] NetLabel: domain hash size = 128 [ 0.550009] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.552103] NetLabel: unlabeled traffic allowed by default [ 0.554211] vgaarb: loaded [ 0.556280] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.558014] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.563005] clocksource: Switched to clocksource kvm-clock [ 0.683548] VFS: Disk quotas dquot_6.6.0 [ 0.685216] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.687834] *** VALIDATE ramfs *** [ 0.689164] *** VALIDATE hugetlbfs *** [ 0.691428] pnp: PnP ACPI init [ 0.693965] pnp: PnP ACPI: found 6 devices [ 0.711611] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.715218] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.717170] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.719114] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.721262] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.723383] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.726399] NET: Registered protocol family 2 [ 0.728918] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.733814] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.737532] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.742720] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.746392] TCP: Hash tables configured (established 65536 bind 65536) [ 0.748722] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.751245] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.754374] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.757180] NET: Registered protocol family 1 [ 0.759498] RPC: Registered named UNIX socket transport module. [ 0.761053] RPC: Registered udp transport module. [ 0.762842] RPC: Registered tcp transport module. [ 0.764727] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.767268] NET: Registered protocol family 44 [ 0.769107] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.771540] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.773742] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.776214] PCI: CLS 0 bytes, default 64 [ 0.777892] Unpacking initramfs... [ 2.177262] debug: unmapping init [mem 0xffff9cd07cc64000-0xffff9cd07ffcffff] [ 2.181342] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.183874] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.187053] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.695352] Initialise system trusted keyrings [ 2.696679] Key type blacklist registered [ 2.697995] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.709984] zbud: loaded [ 2.713903] *** VALIDATE nfs *** [ 2.715352] *** VALIDATE nfs4 *** [ 2.717304] pstore: using deflate compression [ 2.720636] Platform Keyring initialized [ 2.817288] NET: Registered protocol family 38 [ 2.819080] Key type asymmetric registered [ 2.820057] Asymmetric key parser 'x509' registered [ 2.821376] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.823583] io scheduler mq-deadline registered [ 2.825211] io scheduler kyber registered [ 2.826798] io scheduler bfq registered [ 2.828497] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.831356] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.834161] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.837339] ACPI: Power Button [PWRF] [ 2.842605] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.849615] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.859709] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.887409] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.915818] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.920696] Non-volatile memory driver v1.3 [ 2.922354] Linux agpgart interface v0.103 [ 2.956689] virtio_blk virtio1: [vda] 133936 512-byte logical blocks (68.6 MB/65.4 MiB) [ 2.960081] vda: detected capacity change from 0 to 68575232 [ 2.986781] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.990180] vdb: detected capacity change from 0 to 1073741824 [ 3.004800] libphy: Fixed MDIO Bus: probed [ 3.012767] usbcore: registered new interface driver usbserial_generic [ 3.015882] usbserial: USB Serial support registered for generic [ 3.018845] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.023617] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.025752] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.028503] mousedev: PS/2 mouse device common for all mice [ 3.032121] rtc_cmos 00:05: RTC can wake from S4 [ 3.034752] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.039469] rtc_cmos 00:05: registered as rtc0 [ 3.041681] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.042274] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.046515] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.048520] intel_pstate: CPU model not supported [ 3.055990] hid: raw HID events driver (C) Jiri Kosina [ 3.057845] usbcore: registered new interface driver usbhid [ 3.059805] usbhid: USB HID core driver [ 3.061711] drop_monitor: Initializing network drop monitor service [ 3.064475] Initializing XFRM netlink socket [ 3.066651] NET: Registered protocol family 10 [ 3.069567] Segment Routing with IPv6 [ 3.070670] NET: Registered protocol family 17 [ 3.072262] mpls_gso: MPLS GSO support [ 3.078920] RAS: Correctable Errors collector initialized. [ 3.081283] AVX version of gcm_enc/dec engaged. [ 3.082881] AES CTR mode by8 optimization enabled [ 3.157879] sched_clock: Marking stable (3157847217, 0)->(4049318484, -891471267) [ 3.161207] registered taskstats version 1 [ 3.163979] Loading compiled-in X.509 certificates [ 3.166224] zswap: loaded using pool lzo/zbud [ 3.191641] Key type big_key registered [ 3.202293] Key type encrypted registered [ 3.203722] ima: No TPM chip found, activating TPM-bypass! [ 3.205046] ima: Allocated hash algorithm: sha1 [ 3.206195] ima: No architecture policies found [ 3.207691] evm: Initialising EVM extended attributes: [ 3.209444] evm: security.selinux [ 3.210834] evm: security.ima [ 3.211869] evm: security.capability [ 3.212885] evm: HMAC attrs: 0x1 [ 3.215747] rtc_cmos 00:05: setting system clock to 2026-01-12 20:46:07 UTC (1768250767) [ 3.222912] debug: unmapping init [mem 0xffffffffb0603000-0xffffffffb07fffff] [ 3.226105] debug: unmapping init [mem 0xffffffffaf382000-0xffffffffaf658fff] [ 3.234138] Write protecting the kernel read-only data: 28672k [ 3.237800] debug: unmapping init [mem 0xffffffffada03000-0xffffffffadbfffff] [ 3.240483] debug: unmapping init [mem 0xffffffffae314000-0xffffffffae3fffff] [ 3.273803] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 3.283106] systemd[1]: Detected virtualization kvm. [ 3.286075] systemd[1]: Detected architecture x86-64. [ 3.288022] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.313302] systemd[1]: No hostname configured. [ 3.315020] systemd[1]: Set hostname to . [ 3.317210] random: systemd: uninitialized urandom read (16 bytes read) [ 3.320962] systemd[1]: Initializing machine ID from random generator. [ 3.362290] random: ln: uninitialized urandom read (6 bytes read) [ 3.465767] random: systemd: uninitialized urandom read (16 bytes read) [ 3.468841] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ 3.473908] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 3.477451] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Reached target Timers. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. [ OK ] Listening on Journal Socket. Starting Journal Service... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Sockets. Starting Setup Virtual Console... Starting Apply Kernel Variables... [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.116289] device-mapper: uevent: version 1.0.3 [ 4.118585] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 4.859798] random: fast init done [ 4.876562] virtio_net virtio0 ens2: renamed from eth0 [ 4.935728] scsi host0: ata_piix [ 4.943485] scsi host1: ata_piix [ 4.945067] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.947711] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.453320] dracut-initqueue[589]: RTNETLINK answers: File exists [ 9.719445] random: crng init done [ 9.721022] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 10.217597] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Timers. [ 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 udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped target Swap. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.412945] printk: systemd: 24 output lines suppressed due to ratelimiting [ 11.675453] SELinux: Disabled at runtime. [ 11.736207] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.745249] systemd[1]: Detected virtualization kvm. [ 11.747182] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.222417] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.225979] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.230570] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.237669] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.241737] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.249327] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.258623] systemd[1]: Mounting Huge Pages File System... Mounting Huge Pages File System... Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice User and Session Slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target rpc_pipefs.target. [ 12.308169] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting Remount Root and Kernel File Systems... [ OK ] Reached target RPC Port Mapper. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Stopped target Switch Root. [ OK ] Created slice system-getty.slice. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Listening on Process Core Dump Socket. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. Mounting Kernel Debug File System... [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... Mounting POSIX Message Queue File System... [ OK ] Reached target Slices. [ OK ] Created slice system-serial\x2dgetty.slice. Starting Apply Kernel Variables... [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 12.800339] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.175838] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.178929] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.306928] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.318571] EDAC sbridge: Ver: 1.1.2 [ 14.536581] Key type dns_resolver registered [ 14.836378] NFS: Registering the id_resolver key type [ 14.838751] Key type id_resolver registered [ 14.840582] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... 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 ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Basic System. Starting Login Service... [ OK ] Started D-Bus System Message Bus. [ OK ] Reached target sshd-keygen.target. Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Network Manager... [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Hostname 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 ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg402-client login: [ 61.514559] libcfs: loading out-of-tree module taints kernel. [ 61.859504] Key type ._llcrypt registered [ 61.860565] Key type .llcrypt registered [ 63.157989] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 63.203954] alg: No test for adler32 (adler32-zlib) [ 64.736541] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [ 66.170249] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [ 68.013238] Key type lgssc registered [ 70.919289] Lustre: Echo OBD driver; http://www.lustre.org/ [ 81.646244] hrtimer: interrupt took 23279597 ns [ 261.995311] Lustre: Mounted lustre-client [ 267.056806] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 287.711608] Lustre: lustre-OST0000-osc-ffff9cd0c9a8f000: disconnect after 23s idle [ 291.294378] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing check_logdir /tmp/testlogs/ [ 296.588321] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing yml_node [ 301.764719] Lustre: DEBUG MARKER: Client: 2.17.0.RC1 [ 304.768389] Lustre: DEBUG MARKER: MDS: 2.17.0.RC1 [ 308.319863] Lustre: DEBUG MARKER: OSS: 2.17.0.RC1 [ 310.295280] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Mon Jan 12 15:51:12 EST 2026 [ 331.028832] Lustre: DEBUG MARKER: excepting tests: 27 40a 102 [ 333.159581] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 335.020980] Lustre: DEBUG MARKER: === sanityn: start setup 15:51:37 (1768251097) === [ 336.165957] Lustre: Mounted lustre-client [ 340.580828] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing check_config_client /mnt/lustre [ 365.288582] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 377.808974] Lustre: DEBUG MARKER: === sanityn: finish setup 15:52:20 (1768251140) === [ 380.943907] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 15:52:23 (1768251143) [ 392.496955] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 15:52:34 (1768251154) [ 397.791768] Lustre: lustre-OST0001-osc-ffff9cd0c9a8f000: disconnect after 20s idle [ 397.808243] Lustre: Skipped 1 previous similar message [ 400.367318] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 15:52:42 (1768251162) [ 409.661479] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 15:52:51 (1768251171) [ 416.823820] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 15:52:59 (1768251179) [ 423.459072] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 15:53:06 (1768251186) [ 432.554606] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 15:53:14 (1768251194) [ 441.094245] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 15:53:23 (1768251203) [ 450.817163] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 15:53:33 (1768251213) [ 460.533184] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 15:53:42 (1768251222) [ 470.183905] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 15:53:52 (1768251232) [ 474.594904] Lustre: lustre-OST0000-osc-ffff9cd0c9a8f000: disconnect after 23s idle [ 479.364861] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 15:54:02 (1768251242) [ 488.007045] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 15:54:10 (1768251250) [ 495.073481] Lustre: lustre-OST0001-osc-ffff9cd0c7c5e800: disconnect after 24s idle [ 497.508710] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 15:54:19 (1768251259) [ 508.486528] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 15:54:30 (1768251270) [ 515.571452] Lustre: lustre-OST0000-osc-ffff9cd0c9a8f000: disconnect after 23s idle [ 519.469493] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 15:54:41 (1768251281) [ 528.853348] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 15:54:51 (1768251291) [ 537.555422] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 15:54:59 (1768251299) [ 548.813191] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 15:55:10 (1768251310) [ 558.075706] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 15:55:20 (1768251320) [ 568.065327] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 15:55:30 (1768251330) [ 569.639409] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279518 file: /mnt/lustre/lockdir/lockfile=144115205289279517 [ 716.090819] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 15:57:58 (1768251478) [ 726.722893] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 15:58:09 (1768251489) [ 735.347250] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 15:58:17 (1768251497) [ 743.702861] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 15:58:26 (1768251506) [ 754.431191] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 15:58:36 (1768251516) [ 765.684977] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 15:58:47 (1768251527) [ 768.656925] Lustre: DEBUG MARKER: chmod [ 778.107876] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 15:59:00 (1768251540) [ 818.618884] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7527424KB free > 800000KB, increase MAXFREE (or reduce fs size) [ 838.631879] Lustre: DEBUG MARKER: == sanityn test 16a: 500 iterations of dual-mount fsx ==== 16:00:00 (1768251600) [ 910.769567] Lustre: DEBUG MARKER: == sanityn test 16b: 500 iterations of dual-mount fsx at small size ========================================================== 16:01:13 (1768251673) [ 947.535751] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 16:01:49 (1768251709) [ 950.593989] Lustre: DEBUG MARKER: SKIP: sanityn test_16c dio on ldiskfs only [ 952.985325] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 16:01:55 (1768251715) [ 1007.076458] Lustre: lustre-OST0001-osc-ffff9cd0c7c5e800: disconnect after 24s idle [ 1023.143538] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 16:03:05 (1768251785) [ 1033.730757] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 16:03:15 (1768251795) [ 1035.431975] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1035.554815] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1035.665316] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1035.733883] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1035.843975] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1035.969574] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1036.105508] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1036.253906] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1036.427371] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1036.584847] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1036.730673] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1036.879870] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1037.020059] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1037.153451] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1037.271902] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1037.374799] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1037.510810] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1037.615360] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1037.727811] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1037.822310] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1037.940446] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1038.060728] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1038.147796] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1038.252244] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1038.352230] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1038.458552] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1038.605323] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1038.748967] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1038.944633] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1039.090597] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1039.245110] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1039.457459] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1039.583911] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1039.770333] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1039.974926] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1040.151147] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1040.255884] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1040.429816] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1040.590776] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1040.713095] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1040.886500] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1041.057779] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1041.216741] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1041.380820] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1041.495116] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1041.605169] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1041.708614] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1041.811443] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1041.966791] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1042.109938] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1042.315264] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1042.432180] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1042.530616] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1042.665760] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1042.814139] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1042.944936] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1043.043493] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1043.150346] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1043.245323] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1043.328546] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1043.419800] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1043.519065] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1043.602424] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1043.734096] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1043.818706] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1043.907336] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1043.994648] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1044.097966] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1044.173716] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1044.224242] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1044.300071] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1044.383131] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1044.547869] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1044.752665] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1044.874184] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1044.961586] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1045.062173] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1045.185270] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1045.342966] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1045.491070] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1045.604583] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1045.748682] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1045.915402] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1046.021520] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1046.106786] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1046.259712] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1046.353629] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1046.558688] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1046.685593] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1046.805443] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1046.914698] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1047.027388] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1047.113844] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1047.208225] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1047.293522] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1047.418232] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1047.535527] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1047.632427] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1047.722807] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1047.861854] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1048.006420] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1048.039806] Lustre: lustre-OST0000-osc-ffff9cd0c7c5e800: disconnect after 24s idle [ 1048.199001] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1048.287782] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1048.367237] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1048.443824] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1048.549744] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1048.623453] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1048.709093] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1048.762602] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1048.807405] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1048.861245] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1048.930483] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1048.981264] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1049.038188] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1049.153065] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1049.313449] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1049.470877] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1049.601963] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1049.713730] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1049.784894] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1049.857096] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1049.914244] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1049.981129] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1050.130083] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1050.261864] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1050.454555] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1050.691493] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1050.862615] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1050.948380] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1051.079143] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1051.203362] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1051.339649] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1051.477359] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1051.589298] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1051.714298] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1051.833166] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1051.971395] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1052.054451] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1052.161609] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1052.258361] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1052.398995] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1052.528544] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1052.647802] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1052.844482] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1052.954673] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1053.126379] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1053.251325] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1053.360569] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1053.503838] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1053.621489] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1053.706544] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1053.830276] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1053.922740] rw_seq_cst_vs_d (31067): drop_caches: 3 [ 1065.157734] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 16:03:47 (1768251827) [ 1066.093065] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1066.315892] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1066.430751] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1066.634600] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1066.710603] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1066.802510] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1067.004747] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1067.109852] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1067.267580] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1067.381791] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1067.446621] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1067.613322] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1067.717229] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1067.909719] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1068.067765] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1068.237430] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1068.425906] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1068.481754] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1068.622899] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1068.827777] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1069.068032] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1069.162964] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1069.321898] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1069.471503] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1069.700341] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1069.829348] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1069.898951] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1070.160523] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1070.221624] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1070.264557] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1070.416524] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1070.505398] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1070.646075] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1070.764967] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1070.846780] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1070.948868] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1071.123018] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1071.261543] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1071.297261] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1071.414761] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1071.542250] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1071.731775] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1071.798151] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1071.885175] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1072.020869] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1072.253207] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1072.387461] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1072.518044] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1072.673121] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1072.814378] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1072.887527] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1073.112749] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1073.325790] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1073.463805] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1073.584715] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1073.711554] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1073.882646] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1074.023649] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1074.138455] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1074.215797] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1074.353838] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1074.574730] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1074.740357] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1074.795292] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1074.886551] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1075.026470] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1075.172174] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1075.263561] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1075.418453] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1075.543178] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1075.725905] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1075.869741] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1076.065668] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1076.257836] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1076.327750] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1076.499902] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1076.709355] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1076.764460] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1076.908436] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1077.067520] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1077.201770] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1077.389923] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1077.646685] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1077.742827] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1077.910728] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1077.981599] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1078.037765] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1078.174095] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1078.261990] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1078.364503] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1078.717440] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1078.902826] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1079.008570] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1079.141707] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1079.344751] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1079.415483] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1079.558964] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1079.692853] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1079.847288] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1079.968634] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1080.100350] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1080.243296] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1080.350943] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1080.425384] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1080.553073] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1080.632784] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1080.763395] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1080.843925] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1081.023606] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1081.109956] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1081.345382] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1081.447836] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1081.511763] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1081.575956] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1081.727878] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1081.834990] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1081.933405] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1082.099644] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1082.229195] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1082.426889] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1082.560491] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1082.695934] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1082.805834] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1082.845732] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1082.978620] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1083.099672] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1083.350242] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1083.530170] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1083.718349] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1083.808762] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1083.905483] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1084.020810] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1084.134830] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1084.197446] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1084.258780] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1084.421127] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1084.558944] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1084.782128] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1084.979731] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1085.078970] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1085.303767] rw_seq_cst_vs_d (31659): drop_caches: 3 [ 1095.936657] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 16:04:18 (1768251858) [ 1106.967129] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 16:04:29 (1768251869) [ 1109.471604] Lustre: lustre-OST0000-osc-ffff9cd0c7c5e800: disconnect after 24s idle [ 1109.485685] Lustre: Skipped 3 previous similar messages [ 1117.380610] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 16:04:39 (1768251879) [ 1178.694865] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 16:05:40 (1768251940) [ 1179.253721] bash (34134): drop_caches: 3 [ 1182.625260] bash (34134): drop_caches: 3 [ 1185.918688] bash (34134): drop_caches: 3 [ 1190.047312] bash (34134): drop_caches: 3 [ 1194.803111] bash (34134): drop_caches: 3 [ 1198.168979] bash (34134): drop_caches: 3 [ 1201.411388] bash (34134): drop_caches: 3 [ 1201.631564] Lustre: lustre-OST0000-osc-ffff9cd0c7c5e800: disconnect after 24s idle [ 1201.636993] Lustre: Skipped 1 previous similar message [ 1204.644957] bash (34134): drop_caches: 3 [ 1207.880960] bash (34134): drop_caches: 3 [ 1211.101473] bash (34134): drop_caches: 3 [ 1214.269973] bash (34134): drop_caches: 3 [ 1217.468028] bash (34134): drop_caches: 3 [ 1220.709832] bash (34134): drop_caches: 3 [ 1223.842948] bash (34134): drop_caches: 3 [ 1227.000343] bash (34134): drop_caches: 3 [ 1230.248353] bash (34134): drop_caches: 3 [ 1233.447080] bash (34134): drop_caches: 3 [ 1236.562842] bash (34134): drop_caches: 3 [ 1239.715948] bash (34134): drop_caches: 3 [ 1242.864991] bash (34134): drop_caches: 3 [ 1246.165478] bash (34134): drop_caches: 3 [ 1249.426942] bash (34134): drop_caches: 3 [ 1252.753656] bash (34134): drop_caches: 3 [ 1259.123116] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 16:07:00 (1768252020) [ 1272.180165] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 16:07:14 (1768252034) [ 1315.570822] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 16:07:57 (1768252077) [ 1319.619977] Lustre: DEBUG MARKER: SKIP: sanityn test_19 not cache-capable obdfilter [ 1322.397343] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 16:08:04 (1768252084) [ 1334.001601] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 16:08:16 (1768252096) [ 1343.885079] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 16:08:26 (1768252106) [ 1344.991594] Lustre: lustre-OST0000-osc-ffff9cd0c9a8f000: disconnect after 21s idle [ 1345.020399] Lustre: Skipped 3 previous similar messages [ 1415.049519] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 16:09:37 (1768252177) [ 1423.431318] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 16:09:46 (1768252186) [ 1431.213480] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 16:09:54 (1768252194) [ 1440.528864] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 16:10:03 (1768252203) [ 1449.015839] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 16:10:11 (1768252211) [ 1457.581818] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 16:10:19 (1768252219) [ 1466.673159] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 16:10:29 (1768252229) [ 1475.300168] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1477.339877] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 16:10:39 (1768252239) [ 1487.019337] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 16:10:49 (1768252249) [ 1488.065081] Lustre: *** cfs_fail_loc=314, val=0*** [ 1489.119288] Lustre: *** cfs_fail_loc=314, val=0*** [ 1489.121211] Lustre: Skipped 2 previous similar messages [ 1496.841937] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 16:10:59 (1768252259) [ 1513.714545] Lustre: *** cfs_fail_loc=314, val=0*** [ 1513.793351] LustreError: lustre-OST0000-osc-ffff9cd0c7c5e800: operation ldlm_enqueue to node 192.168.204.102@tcp failed: rc = -107 [ 1513.799149] Lustre: lustre-OST0000-osc-ffff9cd0c7c5e800: Connection to lustre-OST0000 (at 192.168.204.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1513.829341] LustreError: lustre-OST0000-osc-ffff9cd0c7c5e800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1513.844749] LustreError: 43856:0:(ldlm_resource.c:981:ldlm_resource_complain()) lustre-OST0000-osc-ffff9cd0c7c5e800: namespace resource [0x280000401:0x30:0x0].0x0 (ffff9cd0c4d76600) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 1513.876730] Lustre: lustre-OST0000-osc-ffff9cd0c7c5e800: Connection restored to 192.168.204.102@tcp (at 192.168.204.102@tcp) [ 1523.142634] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 16:11:25 (1768252285) [ 1523.809378] LustreError: 44447:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 1526.847214] LustreError: 44447:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 1534.619487] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 16:11:37 (1768252297) [ 1544.230670] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 16:11:46 (1768252306) [ 1554.112536] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 1556.925796] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 16:11:59 (1768252319) [ 1559.474916] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 1561.825128] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 16:12:04 (1768252324) [ 1565.186841] Lustre: lustre-MDT0000-mdc-ffff9cd0c9a8f000: Connection to lustre-MDT0000 (at 192.168.204.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1580.547197] LustreError: MGC192.168.204.102@tcp: Connection to MGS (at 192.168.204.102@tcp) was lost; in progress operations using this service will fail [ 1580.581377] Lustre: Evicted from MGS (at 192.168.204.102@tcp) after server handle changed from 0x8d3383b4fc8431b2 to 0x8d3383b4fc8ac40c [ 1580.593896] Lustre: MGC192.168.204.102@tcp: Connection restored to 192.168.204.102@tcp (at 192.168.204.102@tcp) [ 1581.749299] Lustre: lustre-MDT0000-mdc-ffff9cd0c9a8f000: Connection restored to 192.168.204.102@tcp (at 192.168.204.102@tcp) [ 1624.007822] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 16:13:06 (1768252386) [ 1626.591981] Lustre: lustre-OST0000-osc-ffff9cd0c7c5e800: disconnect after 23s idle [ 1626.599677] Lustre: Skipped 5 previous similar messages [ 1689.716493] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 16:14:11 (1768252451) [ 1720.486541] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 16:14:43 (1768252483) [ 1774.057326] Lustre: lustre-OST0000-osc-ffff9cd0c9a8f000: Connection to lustre-OST0000 (at 192.168.204.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1774.087960] Lustre: Skipped 1 previous similar message [ 1774.110642] LustreError: lustre-OST0000-osc-ffff9cd0c9a8f000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1774.125480] LustreError: lustre-OST0000-osc-ffff9cd0c7c5e800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1774.126601] Lustre: lustre-OST0000-osc-ffff9cd0c9a8f000: Connection restored to 192.168.204.102@tcp (at 192.168.204.102@tcp) [ 1774.151526] Lustre: Skipped 2 previous similar messages [ 1794.542902] Lustre: lustre-OST0001-osc-ffff9cd0c9a8f000: Connection to lustre-OST0001 (at 192.168.204.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1794.567802] Lustre: Skipped 1 previous similar message [ 1794.584695] LustreError: lustre-OST0001-osc-ffff9cd0c9a8f000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1794.603210] Lustre: lustre-OST0001-osc-ffff9cd0c9a8f000: Connection restored to 192.168.204.102@tcp (at 192.168.204.102@tcp) [ 1812.846567] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9cd0c7c5e800.ost_server_uuid 50 [ 1814.636843] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9cd0c7c5e800.ost_server_uuid in IDLE state after 0 sec [ 1818.866526] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9cd0c7c5e800.ost_server_uuid 50 [ 1820.655878] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9cd0c7c5e800.ost_server_uuid in FULL state after 0 sec [ 1825.765916] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9cd0c7c5e800.ost_server_uuid 50 [ 1827.189944] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9cd0c7c5e800.ost_server_uuid in IDLE state after 0 sec [ 1831.138085] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9cd0c7c5e800.ost_server_uuid 50 [ 1832.849290] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9cd0c7c5e800.ost_server_uuid in FULL state after 0 sec [ 1844.127442] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9cd0c7c5e800.ost_server_uuid 50 [ 1845.455840] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9cd0c7c5e800.ost_server_uuid in IDLE state after 0 sec [ 1849.669456] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9cd0c7c5e800.ost_server_uuid 50 [ 1851.108100] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9cd0c7c5e800.ost_server_uuid in FULL state after 0 sec [ 1852.873428] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 16:16:55 (1768252615) [ 1855.809330] Lustre: DEBUG MARKER: Race attempt 0 [ 1859.520182] Lustre: DEBUG MARKER: Wait for 54929 54941 for 60 sec... [ 1927.355303] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 16:18:09 (1768252689) [ 1936.009694] Lustre: DEBUG MARKER: start test - cycle (0) [ 1964.933881] Lustre: DEBUG MARKER: start test - cycle (1) [ 2000.443704] Lustre: DEBUG MARKER: start test - cycle (2) [ 2018.930923] Lustre: DEBUG MARKER: start test - cycle (3) [ 2046.667399] Lustre: DEBUG MARKER: start test - cycle (4) [ 2077.966347] Lustre: DEBUG MARKER: start test - cycle (5) [ 2096.504845] Lustre: DEBUG MARKER: start test - cycle (6) [ 2127.816706] Lustre: DEBUG MARKER: start test - cycle (7) [ 2159.071347] Lustre: lustre-OST0000-osc-ffff9cd0c9a8f000: disconnect after 23s idle [ 2159.087578] Lustre: Skipped 17 previous similar messages [ 2159.435542] Lustre: DEBUG MARKER: start test - cycle (8) [ 2192.236661] Lustre: DEBUG MARKER: start test - cycle (9) [ 2225.606826] Lustre: DEBUG MARKER: start test - cycle (10) [ 2267.340217] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 16:23:49 (1768253029) [ 2374.726559] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 16:25:37 (1768253137) [ 2383.993962] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 16:25:47 (1768253147) [ 2395.334808] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 16:25:57 (1768253157) [ 2406.663911] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 16:26:09 (1768253169) [ 2406.964406] Lustre: *** cfs_fail_loc=411, val=0*** [ 2413.888485] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 2416.402385] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 16:26:18 (1768253178) [ 2439.491905] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 16:26:42 (1768253202) [ 2461.361676] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 16:27:04 (1768253224) [ 2481.180635] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 16:27:23 (1768253243) [ 2499.480873] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 16:27:42 (1768253262) [ 2516.844319] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 16:27:59 (1768253279) [ 2533.373366] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 16:28:15 (1768253295) [ 2549.091227] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 16:28:32 (1768253312) [ 2564.569043] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 16:28:47 (1768253327) [ 2579.736367] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 16:29:02 (1768253342) [ 2594.450690] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 16:29:17 (1768253357) [ 2610.968904] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 16:29:33 (1768253373) [ 2626.020955] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 16:29:48 (1768253388) [ 3242.463305] Lustre: lustre-OST0000-osc-ffff9cd0c7c5e800: disconnect after 23s idle [ 3242.471144] Lustre: Skipped 30 previous similar messages [ 3664.160941] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 16:47:07 (1768254427) [ 3674.262976] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 16:47:17 (1768254437) [ 3684.629535] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 16:47:27 (1768254447) [ 3695.362988] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 16:47:38 (1768254458) [ 3705.886231] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 16:47:48 (1768254468) [ 3716.970840] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 16:48:00 (1768254480) [ 3726.396875] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 16:48:09 (1768254489) [ 3736.382634] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 16:48:19 (1768254499) [ 3748.948919] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 16:48:31 (1768254511) [ 3891.573073] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 16:50:54 (1768254654) [ 3901.158640] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 16:51:04 (1768254664) [ 3911.202108] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 16:51:14 (1768254674) [ 3920.986895] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 16:51:24 (1768254684) [ 3931.125805] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 16:51:34 (1768254694) [ 3940.725970] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 16:51:43 (1768254703) [ 3951.046083] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 16:51:54 (1768254714) [ 3962.407267] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 16:52:05 (1768254725) [ 3972.310258] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 16:52:15 (1768254735) [ 3979.745672] Lustre: lustre-OST0001-osc-ffff9cd0c9a8f000: disconnect after 22s idle [ 3979.753045] Lustre: Skipped 4 previous similar messages [ 4067.428378] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 16:53:50 (1768254830) [ 4583.905602] Lustre: lustre-OST0000-osc-ffff9cd0c7c5e800: disconnect after 24s idle [ 4583.921159] Lustre: Skipped 6 previous similar messages [ 5246.864772] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 17:13:30 (1768256010) [ 5255.828762] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 17:13:39 (1768256019) [ 5259.743407] Lustre: lustre-OST0000-osc-ffff9cd0c7c5e800: disconnect after 22s idle [ 5264.936664] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 17:13:48 (1768256028) [ 5274.104330] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 17:13:57 (1768256037) [ 5283.920951] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 17:14:07 (1768256047) [ 5293.422313] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 17:14:16 (1768256056) [ 5303.408386] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 17:14:26 (1768256066) [ 5313.423351] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 17:14:36 (1768256076) [ 5323.920264] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 17:14:47 (1768256087) [ 5333.469223] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 17:14:56 (1768256096) [ 5482.171166] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 17:17:25 (1768256245) [ 5489.720875] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 17:17:33 (1768256253) [ 5497.624405] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 17:17:41 (1768256261) [ 5505.771933] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 17:17:49 (1768256269) [ 5514.309498] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 17:17:57 (1768256277) [ 5523.522726] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 17:18:06 (1768256286) [ 5533.442557] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 17:18:16 (1768256296) [ 5549.757596] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 17:18:32 (1768256312) [ 5567.677210] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 17:18:49 (1768256329) [ 6002.143818] Lustre: lustre-OST0001-osc-ffff9cd0c9a8f000: disconnect after 24s idle [ 6002.146372] Lustre: Skipped 11 previous similar messages [ 6672.864313] Lustre: lustre-OST0001-osc-ffff9cd0c9a8f000: disconnect after 24s idle [ 6672.870684] Lustre: Skipped 8 previous similar messages [ 6785.999993] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 17:39:09 (1768257549) [ 6794.151572] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 17:39:17 (1768257557) [ 6801.636126] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 17:39:25 (1768257565) [ 6808.954139] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 17:39:32 (1768257572) [ 6816.004468] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 17:39:39 (1768257579) [ 6823.092477] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 17:39:46 (1768257586) [ 6830.104757] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 17:39:53 (1768257593) [ 6837.818156] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 17:40:01 (1768257601) [ 6845.574557] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 17:40:09 (1768257609) [ 6852.868775] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 17:40:16 (1768257616) [ 6860.655957] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 17:40:24 (1768257624) [ 6869.284822] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 17:40:32 (1768257632) [ 6877.288705] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 17:40:40 (1768257640) [ 6886.334546] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 17:40:49 (1768257649) [ 6901.982688] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 17:41:05 (1768257665) [ 6922.395783] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 17:41:24 (1768257684) [ 6946.345319] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 17:41:48 (1768257708) [ 6947.082710] LustreError: 6319:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 6949.087179] LustreError: 6319:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 6960.542065] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 17:42:03 (1768257723) [ 6969.541729] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 17:42:12 (1768257732) [ 6970.106450] LustreError: 234080:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 6974.176877] LustreError: 234080:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 6974.243623] LustreError: 234080:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 6978.311206] LustreError: 234080:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 6978.357200] LustreError: 234086:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 6982.431473] LustreError: 234086:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 6989.376358] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 17:42:32 (1768257752) [ 7001.815532] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 17:42:44 (1768257764) [ 7009.552903] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 17:42:52 (1768257772) [ 7017.932412] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 17:43:00 (1768257780) [ 7053.113579] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 17:43:36 (1768257816) [ 7066.491896] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 17:43:49 (1768257829) [ 7079.250306] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 17:44:02 (1768257842) [ 7098.578180] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 17:44:21 (1768257861) [ 7119.738573] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 17:44:41 (1768257881) [ 7143.005559] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 17:45:05 (1768257905) [ 7164.523320] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 17:45:27 (1768257927) [ 7192.208666] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 17:45:54 (1768257954) [ 7348.822235] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 17:48:31 (1768258111) [ 7466.977131] Lustre: lustre-OST0000-osc-ffff9cd0c7c5e800: disconnect after 20s idle [ 7466.991279] Lustre: Skipped 7 previous similar messages [ 7490.122573] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 17:50:52 (1768258252) [ 7502.099820] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 7513.023887] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 17:51:15 (1768258275) [ 7523.918515] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 17:51:26 (1768258286) [ 7526.056471] Lustre: DEBUG MARKER: SKIP: sanityn test_71a ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 7528.306758] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 17:51:30 (1768258290) [ 7530.605249] Lustre: DEBUG MARKER: SKIP: sanityn test_71b ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 7533.346629] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 17:51:35 (1768258295) [ 7535.367402] Lustre: DEBUG MARKER: SKIP: sanityn test_71c support only ldiskfs ost [ 7537.248265] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 17:51:40 (1768258300) [ 7539.004681] Lustre: DEBUG MARKER: SKIP: sanityn test_71d ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 7540.899527] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 17:51:43 (1768258303) [ 7550.324768] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 17:51:52 (1768258312) [ 7558.905489] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 17:52:01 (1768258321) [ 7562.298463] LustreError: lustre-MDT0000-mdc-ffff9cd0c7c5e800: operation ldlm_enqueue to node 192.168.204.102@tcp failed: rc = -35 [ 7571.679881] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 17:52:14 (1768258334) [ 7572.504137] LustreError: 2408:0:(osc_request.c:3093:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 7574.509723] LustreError: 2408:0:(osc_request.c:3093:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 7587.355966] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 17:52:29 (1768258349) [ 7810.143241] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 17:56:11 (1768258571) [ 7821.621791] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 17:56:24 (1768258584) [ 7840.541950] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 17:56:42 (1768258602) [ 7861.713404] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 17:57:04 (1768258624) [ 7882.280633] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 17:57:24 (1768258644) [ 7912.553317] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 17:57:55 (1768258675) [ 7938.125574] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 17:58:20 (1768258700) [ 7951.114269] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 17:58:33 (1768258713) [ 7966.213743] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 17:58:48 (1768258728) [ 7993.262064] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 17:59:15 (1768258755) [ 8066.299411] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 18:00:29 (1768258829) [ 8096.735358] Lustre: lustre-OST0001-osc-ffff9cd0c9a8f000: disconnect after 25s idle [ 8096.748206] Lustre: Skipped 8 previous similar messages [ 8222.396440] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 18:03:05 (1768258985) [ 8377.960166] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 18:05:40 (1768259140) [ 8706.015887] Lustre: lustre-OST0001-osc-ffff9cd0c9a8f000: disconnect after 22s idle [ 8706.027102] Lustre: Skipped 16 previous similar messages [ 8832.747223] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 18:13:15 (1768259595) [ 8884.821582] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 18:14:08 (1768259648) [ 8935.562825] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 18:14:58 (1768259698) [ 8983.754624] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 18:15:46 (1768259746) [ 9076.915618] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 18:17:19 (1768259839) [ 9154.969634] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 18:18:37 (1768259917) [ 9301.018216] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 18:21:03 (1768260063) [ 9306.400767] LustreError: 275059:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0c9a8f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9306.420267] LustreError: 275059:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 9306.473588] Lustre: Unmounted lustre-client [ 9310.561137] LustreError: 275073:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0c7c5e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9310.572724] LustreError: 275073:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 9310.593493] LustreError: 275073:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 9310.598515] LustreError: 275073:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 9310.668033] Lustre: Unmounted lustre-client [ 9439.180530] Lustre: Mounted lustre-client [ 9442.303897] Lustre: Mounted lustre-client [ 9445.965613] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 9462.751590] Lustre: lustre-OST0000-osc-ffff9cd0c9217800: disconnect after 21s idle [ 9462.759226] Lustre: Skipped 17 previous similar messages [ 9576.555557] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 18:25:39 (1768260339) [ 9600.075835] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 18:26:02 (1768260362) [ 9612.458800] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 18:26:15 (1768260375) [ 9670.787310] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 18:27:13 (1768260433) [ 9759.916257] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 18:28:42 (1768260522) [ 9772.263381] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 18:28:55 (1768260535) [ 9893.436933] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 18:30:56 (1768260656) [ 9933.446893] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 18:31:36 (1768260696) [ 9994.811210] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 18:32:37 (1768260757) [10007.879789] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 18:32:50 (1768260770) [10028.424383] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 18:33:11 (1768260791) [10042.108115] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 18:33:24 (1768260804) [10044.866236] LustreError: 291491:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x16e:0x0]: rc = -5 [10044.885586] LustreError: 291491:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10045.559550] LustreError: 291500:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x16e:0x0]: rc = -5 [10045.572625] LustreError: 291500:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 1 previous similar message [10045.578889] LustreError: 291500:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10045.584477] LustreError: 291500:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 1 previous similar message [10047.533679] LustreError: 291519:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x25:0x0]: rc = -5 [10047.544469] LustreError: 291519:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 4 previous similar messages [10047.553379] LustreError: 291519:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10047.562563] LustreError: 291519:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 4 previous similar messages [10049.549375] LustreError: 291328:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x21:0x0]: rc = -5 [10049.554605] LustreError: 291328:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 10 previous similar messages [10049.558876] LustreError: 291328:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10049.564933] LustreError: 291328:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 10 previous similar messages [10053.633481] LustreError: 291592:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x42:0x0]: rc = -5 [10053.662416] LustreError: 291592:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 10 previous similar messages [10053.676829] LustreError: 291592:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10053.694732] LustreError: 291592:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 10 previous similar messages [10062.100713] LustreError: 291675:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x51:0x0]: rc = -5 [10062.108142] LustreError: 291675:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 22 previous similar messages [10062.114269] LustreError: 291675:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10062.128390] LustreError: 291675:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 22 previous similar messages [10078.665486] LustreError: 291858:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x6b:0x0]: rc = -5 [10078.680173] LustreError: 291858:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 53 previous similar messages [10078.695529] LustreError: 291858:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [10078.698354] LustreError: 291858:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 53 previous similar messages [10111.798461] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 18:34:34 (1768260874) [10119.946556] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 18:34:42 (1768260882) [10128.352553] Lustre: lustre-OST0001-osc-ffff9cd0c9217800: disconnect after 21s idle [10128.358327] Lustre: Skipped 13 previous similar messages [10659.848756] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 18:43:42 (1768261422) [10661.528070] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [10664.085157] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 18:43:46 (1768261426) [10937.037299] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 18:48:19 (1768261699) [10944.378589] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 18:48:27 (1768261707) [10952.676367] Lustre: lustre-OST0000-osc-ffff9cd0c9217800: disconnect after 23s idle [10952.680350] Lustre: Skipped 3 previous similar messages [11074.862851] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 18:50:37 (1768261837) [11089.332747] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 18:50:52 (1768261852) [11104.053526] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 18:51:06 (1768261866) [11294.207828] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 18:54:16 (1768262056) [11482.231268] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 18:57:25 (1768262245) [11489.959713] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 18:57:32 (1768262252) [11508.896324] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 18:57:51 (1768262271) [11509.330185] Lustre: DEBUG MARKER: write [11509.366166] LustreError: 276765:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [11511.404664] Lustre: DEBUG MARKER: kill 318715 [11511.420186] LustreError: 318715:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [11514.463239] LustreError: 276765:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [11517.519253] LustreError: 318715:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [11526.688170] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 18:58:09 (1768262289) [11529.506651] LustreError: 319331:0:(rw.c:1957:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [11539.519909] LustreError: 319331:0:(rw.c:1957:ll_readpage()) cfs_fail_timeout id 1422 awake [11547.970694] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 18:58:30 (1768262310) [11548.745249] LustreError: 319919:0:(rw.c:2202:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [11550.839136] LustreError: 319919:0:(rw.c:2202:ll_readpage()) cfs_fail_timeout interrupted [11562.228546] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 18:58:45 (1768262325) [11564.297802] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [11566.558899] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 18:58:49 (1768262329) [11574.834516] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 18:58:57 (1768262337) [11582.613070] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 18:59:05 (1768262345) [11590.843549] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 18:59:13 (1768262353) [11599.698432] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 18:59:21 (1768262361) [11608.093606] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 18:59:30 (1768262370) [11617.423716] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 18:59:40 (1768262380) [11626.256214] Lustre: DEBUG MARKER: SKIP: sanityn test_102 skipping ALWAYS excluded test 102 [11628.103781] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 18:59:50 (1768262390) [11630.766419] Lustre: *** cfs_fail_loc=415, val=0*** [11643.664596] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 19:00:06 (1768262406) [11645.674056] Lustre: DEBUG MARKER: SKIP: sanityn test_104 ldiskfs only test [11647.630083] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 19:00:10 (1768262410) [11648.273404] LustreError: 306433:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [11648.280892] LustreError: 306433:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [11653.280207] LustreError: 277459:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [11653.290285] LustreError: 277459:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 2 previous similar messages [11663.391221] LustreError: 276765:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [11663.397655] LustreError: 276765:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 4 previous similar messages [11671.113696] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 19:00:34 (1768262434) [11672.818645] Lustre: DEBUG MARKER: SKIP: sanityn test_106a Test only for ldiskfs and statx() supported [11674.557979] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 19:00:37 (1768262437) [11682.930452] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 19:00:45 (1768262445) [11690.002335] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 19:00:52 (1768262452) [11699.182434] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 19:01:02 (1768262462) [11712.109582] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 19:01:15 (1768262475) [11712.685412] LustreError: 309117:0:(osc_request.c:2944:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [11712.691084] LustreError: 309117:0:(osc_request.c:2944:osc_build_rpc()) Skipped 5 previous similar messages [11716.759169] LustreError: 309117:0:(osc_request.c:2944:osc_build_rpc()) cfs_fail_timeout id 414 awake [11723.269709] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 19:01:26 (1768262486) [11726.887904] LustreError: 330038:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0c341a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11726.895918] LustreError: 330038:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [11726.926723] LustreError: 330038:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [11726.937741] LustreError: 330038:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [11727.009053] Lustre: Unmounted lustre-client [11730.641518] LustreError: 330059:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0c9217800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11730.653115] LustreError: 330059:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [11730.672904] LustreError: 330059:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11730.680678] LustreError: 330059:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [11730.796269] Lustre: Unmounted lustre-client [11732.539799] Lustre: DEBUG MARKER: Iteration 1 [11733.063781] LustreError: 330222:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11733.065603] LustreError: 330223:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11733.086492] LustreError: 330222:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4985 [11733.469867] Lustre: Mounted lustre-client [11733.475935] Lustre: Skipped 1 previous similar message [11735.269851] LustreError: 330326:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0e75a5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11735.281648] LustreError: 330326:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [11735.306462] LustreError: 330326:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11735.316613] LustreError: 330326:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [11735.439173] Lustre: Unmounted lustre-client [11739.003329] Key type lgssc unregistered [11739.336725] LNet: 330576:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11739.342124] LNetError: 330576:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11740.394477] LNet: Removed LNI 192.168.204.2@tcp [11741.380287] Key type .llcrypt unregistered [11741.386573] Key type ._llcrypt unregistered [11742.435089] Key type ._llcrypt registered [11742.445472] Key type .llcrypt registered [11743.107333] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11743.140112] alg: No test for adler32 (adler32-zlib) [11744.381839] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [11745.010845] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [11746.776233] Key type lgssc registered [11749.309492] Lustre: Echo OBD driver; http://www.lustre.org/ [11767.568391] Lustre: DEBUG MARKER: Iteration 2 [11768.211774] LustreError: 331409:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11768.212363] LustreError: 331410:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11768.237106] LustreError: 331409:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [11769.389666] Lustre: Mounted lustre-client [11771.042049] LustreError: 331517:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0d09fb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11771.082887] LustreError: 331517:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11771.165621] Lustre: Unmounted lustre-client [11774.457625] Key type lgssc unregistered [11774.957185] LNet: 331766:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11774.965912] LNetError: 331766:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11774.992209] LNet: Removed LNI 192.168.204.2@tcp [11775.708339] Key type .llcrypt unregistered [11775.716640] Key type ._llcrypt unregistered [11776.793247] Key type ._llcrypt registered [11776.794386] Key type .llcrypt registered [11777.460954] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11777.479712] alg: No test for adler32 (adler32-zlib) [11778.644786] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [11778.945776] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [11780.671451] Key type lgssc registered [11781.866717] Lustre: Echo OBD driver; http://www.lustre.org/ [11795.527719] Lustre: DEBUG MARKER: Iteration 3 [11795.811765] LustreError: 332594:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11795.813030] LustreError: 332598:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11795.832383] LustreError: 332594:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [11796.971792] Lustre: Mounted lustre-client [11798.245716] LustreError: 332703:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0d0945800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11798.293159] LustreError: 332703:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [11798.371784] Lustre: Unmounted lustre-client [11801.286379] Key type lgssc unregistered [11801.649373] LNet: 332953:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11801.657586] LNetError: 332953:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11802.728257] LNet: Removed LNI 192.168.204.2@tcp [11803.452175] Key type .llcrypt unregistered [11803.458763] Key type ._llcrypt unregistered [11804.422619] Key type ._llcrypt registered [11804.446562] Key type .llcrypt registered [11805.104416] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11805.113813] alg: No test for adler32 (adler32-zlib) [11806.157166] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [11806.416740] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [11808.079219] Key type lgssc registered [11809.470976] Lustre: Echo OBD driver; http://www.lustre.org/ [11823.834995] Lustre: DEBUG MARKER: Iteration 4 [11824.300122] LustreError: 333787:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11824.301896] LustreError: 333786:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11824.326919] LustreError: 333787:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4985 [11826.488963] Lustre: Mounted lustre-client [11827.798317] LustreError: 333895:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0c9afd000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11827.832937] LustreError: 333895:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11827.878482] Lustre: Unmounted lustre-client [11830.579640] Key type lgssc unregistered [11830.876887] LNet: 334143:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11830.884776] LNetError: 334143:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11830.898649] LNet: Removed LNI 192.168.204.2@tcp [11831.675211] Key type .llcrypt unregistered [11831.676568] Key type ._llcrypt unregistered [11832.953686] Key type ._llcrypt registered [11833.024228] Key type .llcrypt registered [11833.449760] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11833.467675] alg: No test for adler32 (adler32-zlib) [11834.674922] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [11834.953410] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [11836.740801] Key type lgssc registered [11838.170211] Lustre: Echo OBD driver; http://www.lustre.org/ [11850.940391] Lustre: DEBUG MARKER: Iteration 5 [11851.373615] LustreError: 334969:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11851.379453] LustreError: 334974:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11851.403592] LustreError: 334969:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4985 [11852.555974] Lustre: Mounted lustre-client [11853.723743] LustreError: 335086:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0d008c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11853.751185] LustreError: 335086:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11853.789619] Lustre: Unmounted lustre-client [11856.153665] Key type lgssc unregistered [11856.452675] LNet: 335330:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11856.460211] LNetError: 335330:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11856.474717] LNet: Removed LNI 192.168.204.2@tcp [11857.158262] Key type .llcrypt unregistered [11857.161556] Key type ._llcrypt unregistered [11858.091073] Key type ._llcrypt registered [11858.098844] Key type .llcrypt registered [11858.467479] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11858.478808] alg: No test for adler32 (adler32-zlib) [11859.615811] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [11859.911446] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [11861.647589] Key type lgssc registered [11863.008295] Lustre: Echo OBD driver; http://www.lustre.org/ [11874.304768] Lustre: DEBUG MARKER: Iteration 6 [11874.851381] LustreError: 336162:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11874.855380] LustreError: 336163:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11874.862401] LustreError: 336162:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [11875.997662] Lustre: Mounted lustre-client [11877.975208] LustreError: 336279:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0c6872800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11878.033488] LustreError: 336279:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [11878.037515] LustreError: 336279:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [11878.109207] Lustre: Unmounted lustre-client [11880.981522] Key type lgssc unregistered [11881.213859] LNet: 336520:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11881.217285] LNetError: 336520:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11881.231577] LNet: Removed LNI 192.168.204.2@tcp [11882.004886] Key type .llcrypt unregistered [11882.009706] Key type ._llcrypt unregistered [11883.132439] Key type ._llcrypt registered [11883.141444] Key type .llcrypt registered [11883.472764] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11883.485672] alg: No test for adler32 (adler32-zlib) [11884.575406] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [11884.861804] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [11886.602518] Key type lgssc registered [11888.165091] Lustre: Echo OBD driver; http://www.lustre.org/ [11901.329284] Lustre: DEBUG MARKER: Iteration 7 [11901.858717] LustreError: 337351:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11901.863148] LustreError: 337352:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11901.884043] LustreError: 337351:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [11903.037410] Lustre: Mounted lustre-client [11904.537723] LustreError: 337457:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0c9afe000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11904.569041] LustreError: 337457:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11904.656045] Lustre: Unmounted lustre-client [11907.864293] Key type lgssc unregistered [11908.144064] LNet: 337706:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11908.159426] LNetError: 337706:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11909.231290] LNet: Removed LNI 192.168.204.2@tcp [11910.480496] Key type .llcrypt unregistered [11910.483913] Key type ._llcrypt unregistered [11911.797730] Key type ._llcrypt registered [11911.810768] Key type .llcrypt registered [11912.461570] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11912.473891] alg: No test for adler32 (adler32-zlib) [11913.720100] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [11914.085869] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [11915.914045] Key type lgssc registered [11918.188745] Lustre: Echo OBD driver; http://www.lustre.org/ [11932.866518] Lustre: DEBUG MARKER: Iteration 8 [11933.371442] LustreError: 338538:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11933.375326] LustreError: 338539:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11933.383964] LustreError: 338538:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [11935.510393] Lustre: Mounted lustre-client [11936.699504] LustreError: 338648:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0d097e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11936.727307] LustreError: 338648:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [11936.808067] Lustre: Unmounted lustre-client [11939.546430] Key type lgssc unregistered [11939.810347] LNet: 338895:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11939.816450] LNetError: 338895:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11939.842933] LNet: Removed LNI 192.168.204.2@tcp [11940.645069] Key type .llcrypt unregistered [11940.658162] Key type ._llcrypt unregistered [11941.238418] Key type ._llcrypt registered [11941.241441] Key type .llcrypt registered [11941.921519] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11941.950169] alg: No test for adler32 (adler32-zlib) [11943.169688] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [11943.506789] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [11945.395617] Key type lgssc registered [11947.358737] Lustre: Echo OBD driver; http://www.lustre.org/ [11963.495506] Lustre: DEBUG MARKER: Iteration 9 [11964.165127] LustreError: 339725:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11964.165165] LustreError: 339727:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11964.183526] LustreError: 339725:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4986 [11965.230658] Lustre: Mounted lustre-client [11966.507129] LustreError: 339838:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0d135e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11966.528463] LustreError: 339838:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [11966.579598] Lustre: Unmounted lustre-client [11969.873607] Key type lgssc unregistered [11970.162825] LNet: 340087:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11970.167478] LNetError: 340087:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11970.182494] LNet: Removed LNI 192.168.204.2@tcp [11970.997181] Key type .llcrypt unregistered [11970.999688] Key type ._llcrypt unregistered [11972.029810] Key type ._llcrypt registered [11972.042400] Key type .llcrypt registered [11972.641445] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [11972.653856] alg: No test for adler32 (adler32-zlib) [11973.865064] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [11974.287312] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [11976.063372] Key type lgssc registered [11977.457780] Lustre: Echo OBD driver; http://www.lustre.org/ [11991.865943] Lustre: DEBUG MARKER: Iteration 10 [11992.363527] LustreError: 340919:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [11992.368059] LustreError: 340917:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [11992.375028] LustreError: 340919:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [11993.672586] Lustre: Mounted lustre-client [11995.428152] LustreError: 341028:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0d89d6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11995.480769] LustreError: 341028:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [11995.549399] Lustre: Unmounted lustre-client [11999.593912] Key type lgssc unregistered [11999.876775] LNet: 341277:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [11999.882945] LNetError: 341277:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [11999.900293] LNet: Removed LNI 192.168.204.2@tcp [12000.612471] Key type .llcrypt unregistered [12000.615792] Key type ._llcrypt unregistered [12002.270377] Key type ._llcrypt registered [12002.283789] Key type .llcrypt registered [12002.731716] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12002.748521] alg: No test for adler32 (adler32-zlib) [12003.961516] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12004.345792] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12006.144097] Key type lgssc registered [12008.488030] Lustre: Echo OBD driver; http://www.lustre.org/ [12023.193279] Lustre: DEBUG MARKER: Iteration 11 [12024.033971] LustreError: 342113:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12024.034289] LustreError: 342114:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12024.056848] LustreError: 342113:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [12025.151911] Lustre: Mounted lustre-client [12026.657156] LustreError: 342227:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0c341d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12026.689181] LustreError: 342227:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12026.734253] Lustre: Unmounted lustre-client [12029.291601] Key type lgssc unregistered [12029.769929] LNet: 342471:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12029.773985] LNetError: 342471:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12029.791968] LNet: Removed LNI 192.168.204.2@tcp [12030.762204] Key type .llcrypt unregistered [12030.766047] Key type ._llcrypt unregistered [12031.863739] Key type ._llcrypt registered [12031.873508] Key type .llcrypt registered [12032.298656] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12032.310752] alg: No test for adler32 (adler32-zlib) [12033.440509] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12033.665272] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12035.419081] Key type lgssc registered [12036.689752] Lustre: Echo OBD driver; http://www.lustre.org/ [12050.764192] Lustre: DEBUG MARKER: Iteration 12 [12051.247437] LustreError: 343301:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12051.250479] LustreError: 343303:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12051.277123] LustreError: 343301:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4984 [12052.428432] Lustre: Mounted lustre-client [12053.790421] LustreError: 343410:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0fe8be000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12053.812922] LustreError: 343410:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12053.885510] Lustre: Unmounted lustre-client [12056.837398] Key type lgssc unregistered [12057.095286] LNet: 343660:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12057.109333] LNetError: 343660:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12057.127332] LNet: Removed LNI 192.168.204.2@tcp [12057.858181] Key type .llcrypt unregistered [12057.860639] Key type ._llcrypt unregistered [12058.564223] Key type ._llcrypt registered [12058.566792] Key type .llcrypt registered [12059.286259] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12059.299424] alg: No test for adler32 (adler32-zlib) [12060.663164] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12060.943957] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12062.591327] Key type lgssc registered [12063.984524] Lustre: Echo OBD driver; http://www.lustre.org/ [12076.516817] Lustre: DEBUG MARKER: Iteration 13 [12076.896675] LustreError: 344492:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12076.900951] LustreError: 344490:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12076.920381] LustreError: 344492:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4985 [12078.056678] Lustre: Mounted lustre-client [12078.067112] Lustre: Skipped 1 previous similar message [12079.871938] LustreError: 344603:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0f8e02800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12079.910802] LustreError: 344603:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [12079.970094] Lustre: Unmounted lustre-client [12083.292700] Key type lgssc unregistered [12083.503635] LNet: 344853:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12083.514965] LNetError: 344853:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12084.586879] LNet: Removed LNI 192.168.204.2@tcp [12085.501404] Key type .llcrypt unregistered [12085.516450] Key type ._llcrypt unregistered [12086.246226] Key type ._llcrypt registered [12086.254025] Key type .llcrypt registered [12086.760705] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12086.774860] alg: No test for adler32 (adler32-zlib) [12087.891967] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12088.127416] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12089.775180] Key type lgssc registered [12091.378320] Lustre: Echo OBD driver; http://www.lustre.org/ [12104.448736] Lustre: DEBUG MARKER: Iteration 14 [12104.938820] LustreError: 345683:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12104.941484] LustreError: 345687:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12104.967784] LustreError: 345683:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4980 [12106.163946] Lustre: Mounted lustre-client [12107.562427] LustreError: 345801:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0d06f2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12107.596562] LustreError: 345801:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12107.675867] Lustre: Unmounted lustre-client [12110.034236] Key type lgssc unregistered [12110.258759] LNet: 346043:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12110.265265] LNetError: 346043:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12110.277459] LNet: Removed LNI 192.168.204.2@tcp [12111.085596] Key type .llcrypt unregistered [12111.090135] Key type ._llcrypt unregistered [12111.722986] Key type ._llcrypt registered [12111.736518] Key type .llcrypt registered [12112.216822] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12112.227555] alg: No test for adler32 (adler32-zlib) [12113.308407] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12113.506138] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12115.184281] Key type lgssc registered [12116.721618] Lustre: Echo OBD driver; http://www.lustre.org/ [12131.034713] Lustre: DEBUG MARKER: Iteration 15 [12131.586042] LustreError: 346876:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12131.589537] LustreError: 346877:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12131.613864] LustreError: 346876:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4979 [12132.833286] Lustre: Mounted lustre-client [12134.426805] LustreError: 346984:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0c9e61800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12134.441824] LustreError: 346984:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [12134.518136] Lustre: Unmounted lustre-client [12137.592631] Key type lgssc unregistered [12138.012723] LNet: 347234:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12138.019765] LNetError: 347234:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12138.043648] LNet: Removed LNI 192.168.204.2@tcp [12138.602180] Key type .llcrypt unregistered [12138.603751] Key type ._llcrypt unregistered [12139.764211] Key type ._llcrypt registered [12139.765951] Key type .llcrypt registered [12140.115790] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12140.127961] alg: No test for adler32 (adler32-zlib) [12141.462868] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12141.750427] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12143.439223] Key type lgssc registered [12144.663495] Lustre: Echo OBD driver; http://www.lustre.org/ [12159.922789] Lustre: DEBUG MARKER: Iteration 16 [12160.522563] LustreError: 348064:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12160.523557] LustreError: 348065:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12160.540576] LustreError: 348064:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4984 [12161.631719] Lustre: Mounted lustre-client [12163.332775] LustreError: 348171:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0c3419800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12163.362098] LustreError: 348171:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [12163.420610] Lustre: Unmounted lustre-client [12166.376298] Key type lgssc unregistered [12166.663532] LNet: 348420:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12166.678741] LNetError: 348420:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12166.715546] LNet: Removed LNI 192.168.204.2@tcp [12167.681121] Key type .llcrypt unregistered [12167.685981] Key type ._llcrypt unregistered [12168.553133] Key type ._llcrypt registered [12168.565517] Key type .llcrypt registered [12168.964477] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12168.995860] alg: No test for adler32 (adler32-zlib) [12170.150544] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12170.375576] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12172.131457] Key type lgssc registered [12173.581434] Lustre: Echo OBD driver; http://www.lustre.org/ [12188.013085] Lustre: DEBUG MARKER: Iteration 17 [12188.554440] LustreError: 349261:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12188.555139] LustreError: 349262:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12188.576358] LustreError: 349261:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4988 [12190.783840] Lustre: Mounted lustre-client [12192.710472] LustreError: 349376:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0f6a9b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12192.748897] LustreError: 349376:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12192.824509] Lustre: Unmounted lustre-client [12196.404515] Key type lgssc unregistered [12196.746384] LNet: 349619:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12196.751749] LNetError: 349619:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12196.799125] LNet: Removed LNI 192.168.204.2@tcp [12197.751170] Key type .llcrypt unregistered [12197.755722] Key type ._llcrypt unregistered [12198.971820] Key type ._llcrypt registered [12198.973291] Key type .llcrypt registered [12199.247850] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12199.277747] alg: No test for adler32 (adler32-zlib) [12200.428625] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12200.715870] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12202.479203] Key type lgssc registered [12204.002814] Lustre: Echo OBD driver; http://www.lustre.org/ [12218.980530] Lustre: DEBUG MARKER: Iteration 18 [12219.487755] LustreError: 350453:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12219.489854] LustreError: 350456:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12219.504545] LustreError: 350453:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4985 [12220.575744] Lustre: Mounted lustre-client [12222.014202] LustreError: 350561:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0d09fa800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12222.040291] LustreError: 350561:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12222.146761] Lustre: Unmounted lustre-client [12224.926799] Key type lgssc unregistered [12225.210775] LNet: 350810:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12225.216901] LNetError: 350810:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12225.228418] LNet: Removed LNI 192.168.204.2@tcp [12225.927247] Key type .llcrypt unregistered [12225.929070] Key type ._llcrypt unregistered [12227.267651] Key type ._llcrypt registered [12227.293224] Key type .llcrypt registered [12227.773881] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12227.800794] alg: No test for adler32 (adler32-zlib) [12229.071627] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12229.468297] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12231.231209] Key type lgssc registered [12232.682438] Lustre: Echo OBD driver; http://www.lustre.org/ [12246.823116] Lustre: DEBUG MARKER: Iteration 19 [12247.502154] LustreError: 351643:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12247.512288] LustreError: 351644:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12247.530475] LustreError: 351643:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [12249.675685] Lustre: Mounted lustre-client [12251.112641] LustreError: 351753:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0f3994800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12251.126421] LustreError: 351753:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12251.175478] Lustre: Unmounted lustre-client [12254.545989] Key type lgssc unregistered [12254.832088] LNet: 352003:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12254.848643] LNetError: 352003:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12255.914649] LNet: Removed LNI 192.168.204.2@tcp [12256.892394] Key type .llcrypt unregistered [12256.897494] Key type ._llcrypt unregistered [12257.960927] Key type ._llcrypt registered [12257.983480] Key type .llcrypt registered [12258.526541] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12258.551275] alg: No test for adler32 (adler32-zlib) [12259.822514] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12260.155099] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12261.808083] Key type lgssc registered [12263.682589] Lustre: Echo OBD driver; http://www.lustre.org/ [12277.416899] Lustre: DEBUG MARKER: Iteration 20 [12277.720935] LustreError: 352832:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12277.721910] LustreError: 352834:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12277.738287] LustreError: 352832:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [12278.770772] Lustre: Mounted lustre-client [12280.063162] LustreError: 352948:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0d0941000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12280.109256] LustreError: 352948:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [12280.171320] Lustre: Unmounted lustre-client [12282.937698] Key type lgssc unregistered [12283.234989] LNet: 353195:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12283.248807] LNetError: 353195:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12283.272270] LNet: Removed LNI 192.168.204.2@tcp [12284.025452] Key type .llcrypt unregistered [12284.028344] Key type ._llcrypt unregistered [12284.781810] Key type ._llcrypt registered [12284.789173] Key type .llcrypt registered [12285.468890] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12285.500818] alg: No test for adler32 (adler32-zlib) [12286.681953] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12287.039663] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12288.649176] Key type lgssc registered [12289.959814] Lustre: Echo OBD driver; http://www.lustre.org/ [12303.394459] Lustre: DEBUG MARKER: Iteration 21 [12303.776682] LustreError: 354027:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12303.780201] LustreError: 354028:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12303.796780] LustreError: 354027:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [12305.893075] Lustre: Mounted lustre-client [12307.319362] LustreError: 354137:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0e7530000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12307.346255] LustreError: 354137:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [12307.429073] Lustre: Unmounted lustre-client [12310.541233] Key type lgssc unregistered [12310.920882] LNet: 354385:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12310.929841] LNetError: 354385:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12310.954845] LNet: Removed LNI 192.168.204.2@tcp [12311.861266] Key type .llcrypt unregistered [12311.865697] Key type ._llcrypt unregistered [12312.799079] Key type ._llcrypt registered [12312.806757] Key type .llcrypt registered [12313.361053] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12313.372959] alg: No test for adler32 (adler32-zlib) [12314.519773] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12314.746279] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12316.410147] Key type lgssc registered [12318.407825] Lustre: Echo OBD driver; http://www.lustre.org/ [12332.351138] Lustre: DEBUG MARKER: Iteration 22 [12332.654739] LustreError: 355215:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12332.655276] LustreError: 355216:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12332.666479] LustreError: 355215:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [12334.887310] Lustre: Mounted lustre-client [12336.743751] LustreError: 355325:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0c6871800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12336.792395] LustreError: 355325:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12336.862028] Lustre: Unmounted lustre-client [12340.184639] Key type lgssc unregistered [12340.544053] LNet: 355575:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12340.552150] LNetError: 355575:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12340.572248] LNet: Removed LNI 192.168.204.2@tcp [12341.394725] Key type .llcrypt unregistered [12341.399767] Key type ._llcrypt unregistered [12342.751337] Key type ._llcrypt registered [12342.760881] Key type .llcrypt registered [12343.416942] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12343.442852] alg: No test for adler32 (adler32-zlib) [12344.620542] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12344.947857] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12346.608693] Key type lgssc registered [12348.202456] Lustre: Echo OBD driver; http://www.lustre.org/ [12362.353582] Lustre: DEBUG MARKER: Iteration 23 [12362.770676] LustreError: 356405:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12362.777322] LustreError: 356407:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12362.783424] LustreError: 356405:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [12363.971608] Lustre: Mounted lustre-client [12365.545264] LustreError: 356525:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0d09f8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12365.579511] LustreError: 356526:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12365.628652] Lustre: Unmounted lustre-client [12368.158758] Key type lgssc unregistered [12368.399350] LNet: 356767:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12368.403379] LNetError: 356767:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12368.422318] LNet: Removed LNI 192.168.204.2@tcp [12369.200245] Key type .llcrypt unregistered [12369.203762] Key type ._llcrypt unregistered [12370.058238] Key type ._llcrypt registered [12370.064935] Key type .llcrypt registered [12370.653614] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12370.670477] alg: No test for adler32 (adler32-zlib) [12371.929570] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12372.197801] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12373.943438] Key type lgssc registered [12375.037231] Lustre: Echo OBD driver; http://www.lustre.org/ [12389.676775] Lustre: DEBUG MARKER: Iteration 24 [12390.006303] LustreError: 357599:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12390.012744] LustreError: 357600:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12390.020139] LustreError: 357599:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [12392.213059] Lustre: Mounted lustre-client [12393.742426] LustreError: 357707:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0c341e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12393.756773] LustreError: 357707:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12393.818828] Lustre: Unmounted lustre-client [12396.843648] Key type lgssc unregistered [12397.074571] LNet: 357957:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12397.082349] LNetError: 357957:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12397.107973] LNet: Removed LNI 192.168.204.2@tcp [12397.911309] Key type .llcrypt unregistered [12397.914912] Key type ._llcrypt unregistered [12399.242372] Key type ._llcrypt registered [12399.251040] Key type .llcrypt registered [12399.408431] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12399.424432] alg: No test for adler32 (adler32-zlib) [12400.591802] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12400.757704] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12402.407210] Key type lgssc registered [12403.616855] Lustre: Echo OBD driver; http://www.lustre.org/ [12418.015707] Lustre: DEBUG MARKER: Iteration 25 [12418.444168] LustreError: 358788:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12418.465052] LustreError: 358795:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12418.480794] LustreError: 358788:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4973 [12420.640826] Lustre: Mounted lustre-client [12422.493664] LustreError: 358896:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0e77ae000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12422.526523] LustreError: 358896:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [12422.625142] Lustre: Unmounted lustre-client [12425.303704] Key type lgssc unregistered [12425.621115] LNet: 359145:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12425.629454] LNetError: 359145:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12425.646616] LNet: Removed LNI 192.168.204.2@tcp [12426.258339] Key type .llcrypt unregistered [12426.261187] Key type ._llcrypt unregistered [12427.179347] Key type ._llcrypt registered [12427.185239] Key type .llcrypt registered [12427.963772] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12427.987794] alg: No test for adler32 (adler32-zlib) [12429.201434] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12429.474036] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12431.256873] Key type lgssc registered [12433.416554] Lustre: Echo OBD driver; http://www.lustre.org/ [12447.264842] Lustre: DEBUG MARKER: Iteration 26 [12447.651093] LustreError: 359976:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12447.677957] LustreError: 359991:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12447.691212] LustreError: 359976:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4974 [12448.754181] Lustre: Mounted lustre-client [12450.255471] LustreError: 360085:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0fdc1c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12450.285068] LustreError: 360085:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12450.356668] Lustre: Unmounted lustre-client [12453.290095] Key type lgssc unregistered [12453.588558] LNet: 360335:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12453.592688] LNetError: 360335:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12454.636829] LNet: Removed LNI 192.168.204.2@tcp [12455.387299] Key type .llcrypt unregistered [12455.396340] Key type ._llcrypt unregistered [12457.271281] Key type ._llcrypt registered [12457.281688] Key type .llcrypt registered [12457.606313] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12457.636148] alg: No test for adler32 (adler32-zlib) [12458.806226] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12459.079659] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12460.799406] Key type lgssc registered [12462.083664] Lustre: Echo OBD driver; http://www.lustre.org/ [12473.547687] Lustre: DEBUG MARKER: Iteration 27 [12473.846222] LustreError: 361171:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12473.847825] LustreError: 361175:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12473.857133] LustreError: 361171:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [12474.910718] Lustre: Mounted lustre-client [12475.991187] LustreError: 361283:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0f4c12800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12476.020467] LustreError: 361283:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12476.060144] Lustre: Unmounted lustre-client [12479.211563] Key type lgssc unregistered [12479.503926] LNet: 361533:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12479.512816] LNetError: 361533:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12479.535576] LNet: Removed LNI 192.168.204.2@tcp [12480.293252] Key type .llcrypt unregistered [12480.303087] Key type ._llcrypt unregistered [12481.261237] Key type ._llcrypt registered [12481.325157] Key type .llcrypt registered [12481.810963] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12481.823468] alg: No test for adler32 (adler32-zlib) [12482.998562] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12483.238303] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12484.947105] Key type lgssc registered [12486.058340] Lustre: Echo OBD driver; http://www.lustre.org/ [12498.134454] Lustre: DEBUG MARKER: Iteration 28 [12498.434623] LustreError: 362363:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12498.434696] LustreError: 362361:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12498.452655] LustreError: 362363:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4986 [12499.539664] Lustre: Mounted lustre-client [12500.844587] LustreError: 362476:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0e5d6e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12500.867289] LustreError: 362476:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12500.878278] LustreError: 362476:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [12500.906109] Lustre: Unmounted lustre-client [12503.573579] Key type lgssc unregistered [12503.917791] LNet: 362720:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12503.929796] LNetError: 362720:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12503.962044] LNet: Removed LNI 192.168.204.2@tcp [12504.477530] Key type .llcrypt unregistered [12504.481837] Key type ._llcrypt unregistered [12505.256348] Key type ._llcrypt registered [12505.265238] Key type .llcrypt registered [12505.739082] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12505.760471] alg: No test for adler32 (adler32-zlib) [12506.848085] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12507.032505] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12508.783448] Key type lgssc registered [12509.955801] Lustre: Echo OBD driver; http://www.lustre.org/ [12520.747489] Lustre: DEBUG MARKER: Iteration 29 [12521.200205] LustreError: 363551:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12521.201173] LustreError: 363552:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12521.237590] LustreError: 363551:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4982 [12522.342521] Lustre: Mounted lustre-client [12523.572401] LustreError: 363661:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0e131b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12523.608510] LustreError: 363661:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [12523.713329] Lustre: Unmounted lustre-client [12526.697397] Key type lgssc unregistered [12526.987149] LNet: 363909:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12526.991308] LNetError: 363909:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12527.010455] LNet: Removed LNI 192.168.204.2@tcp [12527.662302] Key type .llcrypt unregistered [12527.668076] Key type ._llcrypt unregistered [12528.585459] Key type ._llcrypt registered [12528.611885] Key type .llcrypt registered [12528.935390] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12528.947022] alg: No test for adler32 (adler32-zlib) [12530.115373] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12530.317815] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12532.015182] Key type lgssc registered [12533.324649] Lustre: Echo OBD driver; http://www.lustre.org/ [12544.206392] Lustre: DEBUG MARKER: Iteration 30 [12544.725394] LustreError: 364739:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12544.727739] LustreError: 364740:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12544.744792] LustreError: 364739:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [12545.793912] Lustre: Mounted lustre-client [12547.181953] LustreError: 364846:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0f5b5e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12547.209133] LustreError: 364846:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12547.303359] Lustre: Unmounted lustre-client [12549.542548] Key type lgssc unregistered [12549.808902] LNet: 365096:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12549.813765] LNetError: 365096:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12549.830684] LNet: Removed LNI 192.168.204.2@tcp [12550.362166] Key type .llcrypt unregistered [12550.364426] Key type ._llcrypt unregistered [12551.059645] Key type ._llcrypt registered [12551.066411] Key type .llcrypt registered [12551.409275] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12551.420239] alg: No test for adler32 (adler32-zlib) [12552.502641] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12552.758571] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12554.399207] Key type lgssc registered [12555.472489] Lustre: Echo OBD driver; http://www.lustre.org/ [12566.091644] Lustre: DEBUG MARKER: Iteration 31 [12566.453322] LustreError: 365928:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12566.454845] LustreError: 365929:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12566.468846] LustreError: 365928:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [12568.592552] Lustre: Mounted lustre-client [12569.673129] LustreError: 366037:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0c8fa0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12569.686771] LustreError: 366037:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12569.724052] Lustre: Unmounted lustre-client [12572.075398] Key type lgssc unregistered [12572.309165] LNet: 366286:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12572.317748] LNetError: 366286:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12572.333838] LNet: Removed LNI 192.168.204.2@tcp [12572.941316] Key type .llcrypt unregistered [12572.944645] Key type ._llcrypt unregistered [12573.674329] Key type ._llcrypt registered [12573.675811] Key type .llcrypt registered [12574.038639] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12574.049888] alg: No test for adler32 (adler32-zlib) [12575.124743] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12575.303160] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12576.975695] Key type lgssc registered [12578.228859] Lustre: Echo OBD driver; http://www.lustre.org/ [12590.064825] Lustre: DEBUG MARKER: Iteration 32 [12590.445318] LustreError: 367119:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12590.445940] LustreError: 367120:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12590.463485] LustreError: 367119:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [12591.483699] Lustre: Mounted lustre-client [12592.480863] LustreError: 367225:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0c2d4e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12592.505200] LustreError: 367225:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12592.588348] Lustre: Unmounted lustre-client [12594.731549] Key type lgssc unregistered [12594.977569] LNet: 367475:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12594.982144] LNetError: 367475:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12594.998719] LNet: Removed LNI 192.168.204.2@tcp [12595.545174] Key type .llcrypt unregistered [12595.546895] Key type ._llcrypt unregistered [12596.452453] Key type ._llcrypt registered [12596.453889] Key type .llcrypt registered [12596.657255] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12596.677165] alg: No test for adler32 (adler32-zlib) [12597.724205] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12597.980115] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12599.663136] Key type lgssc registered [12600.708466] Lustre: Echo OBD driver; http://www.lustre.org/ [12609.826803] Lustre: DEBUG MARKER: Iteration 33 [12610.161353] LustreError: 368308:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12610.161532] LustreError: 368309:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12610.174773] LustreError: 368308:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [12611.179113] Lustre: Mounted lustre-client [12612.217826] LustreError: 368414:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0c3f42800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12612.249503] LustreError: 368414:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [12612.357140] Lustre: Unmounted lustre-client [12614.538884] Key type lgssc unregistered [12614.752844] LNet: 368663:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12614.765771] LNetError: 368663:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12614.780540] LNet: Removed LNI 192.168.204.2@tcp [12615.296270] Key type .llcrypt unregistered [12615.298344] Key type ._llcrypt unregistered [12616.039746] Key type ._llcrypt registered [12616.042194] Key type .llcrypt registered [12616.425257] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12616.436898] alg: No test for adler32 (adler32-zlib) [12617.486900] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12617.732099] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12619.391196] Key type lgssc registered [12620.673655] Lustre: Echo OBD driver; http://www.lustre.org/ [12630.776470] Lustre: DEBUG MARKER: Iteration 34 [12631.103401] LustreError: 369495:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12631.105172] LustreError: 369496:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12631.124175] LustreError: 369495:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4982 [12632.103415] Lustre: Mounted lustre-client [12633.359422] LustreError: 369605:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0d1399000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12633.378517] LustreError: 369605:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [12633.418140] Lustre: Unmounted lustre-client [12635.627799] Key type lgssc unregistered [12635.862562] LNet: 369854:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12635.878159] LNetError: 369854:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12635.891075] LNet: Removed LNI 192.168.204.2@tcp [12636.446412] Key type .llcrypt unregistered [12636.448995] Key type ._llcrypt unregistered [12637.075710] Key type ._llcrypt registered [12637.081625] Key type .llcrypt registered [12637.597846] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12637.608363] alg: No test for adler32 (adler32-zlib) [12638.626191] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12638.819564] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12640.528639] Key type lgssc registered [12641.490147] Lustre: Echo OBD driver; http://www.lustre.org/ [12650.957476] Lustre: DEBUG MARKER: Iteration 35 [12651.303037] LustreError: 370686:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12651.303149] LustreError: 370685:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12651.323525] LustreError: 370686:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4980 [12652.363728] Lustre: Mounted lustre-client [12653.582776] LustreError: 370795:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0fb14a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12653.602107] LustreError: 370795:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12653.653069] Lustre: Unmounted lustre-client [12655.730828] Key type lgssc unregistered [12655.972552] LNet: 371043:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12655.980133] LNetError: 371043:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12656.999526] LNet: Removed LNI 192.168.204.2@tcp [12657.520818] Key type .llcrypt unregistered [12657.522308] Key type ._llcrypt unregistered [12658.098685] Key type ._llcrypt registered [12658.104391] Key type .llcrypt registered [12658.610367] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12658.625593] alg: No test for adler32 (adler32-zlib) [12659.622850] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12659.827328] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12661.527175] Key type lgssc registered [12662.497192] Lustre: Echo OBD driver; http://www.lustre.org/ [12671.650475] Lustre: DEBUG MARKER: Iteration 36 [12671.985745] LustreError: 371873:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12671.988111] LustreError: 371875:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12672.010280] LustreError: 371873:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [12674.005553] Lustre: Mounted lustre-client [12675.364890] LustreError: 371982:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0c3f42800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12675.384155] LustreError: 371982:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12675.438398] Lustre: Unmounted lustre-client [12677.957210] Key type lgssc unregistered [12678.156558] LNet: 372234:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12678.162307] LNetError: 372234:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12678.185805] LNet: Removed LNI 192.168.204.2@tcp [12678.825135] Key type .llcrypt unregistered [12678.826448] Key type ._llcrypt unregistered [12679.510540] Key type ._llcrypt registered [12679.516640] Key type .llcrypt registered [12679.967529] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12679.981957] alg: No test for adler32 (adler32-zlib) [12681.014433] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12681.232412] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12682.919583] Key type lgssc registered [12683.935129] Lustre: Echo OBD driver; http://www.lustre.org/ [12693.277108] Lustre: DEBUG MARKER: Iteration 37 [12693.661751] LustreError: 373063:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12693.666865] LustreError: 373070:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12693.673128] LustreError: 373063:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [12694.732744] Lustre: Mounted lustre-client [12694.743867] Lustre: Skipped 1 previous similar message [12696.012676] LustreError: 373172:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0f6b0b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12696.025902] LustreError: 373172:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [12696.141630] Lustre: Unmounted lustre-client [12698.637300] Key type lgssc unregistered [12698.850562] LNet: 373423:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12698.867236] LNetError: 373423:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12698.885481] LNet: Removed LNI 192.168.204.2@tcp [12699.388499] Key type .llcrypt unregistered [12699.390156] Key type ._llcrypt unregistered [12700.241235] Key type ._llcrypt registered [12700.270583] Key type .llcrypt registered [12700.550415] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12700.572467] alg: No test for adler32 (adler32-zlib) [12701.641939] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12701.886336] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12703.536077] Key type lgssc registered [12704.444515] Lustre: Echo OBD driver; http://www.lustre.org/ [12714.621751] Lustre: DEBUG MARKER: Iteration 38 [12714.986360] LustreError: 374252:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12714.986798] LustreError: 374257:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12715.015500] LustreError: 374252:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [12716.090563] Lustre: Mounted lustre-client [12717.315195] LustreError: 374361:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0c535c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12717.327527] LustreError: 374361:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [12717.381652] Lustre: Unmounted lustre-client [12720.190648] Key type lgssc unregistered [12720.378795] LNet: 374612:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12720.383328] LNetError: 374612:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12720.400130] LNet: Removed LNI 192.168.204.2@tcp [12720.965261] Key type .llcrypt unregistered [12720.967742] Key type ._llcrypt unregistered [12721.560917] Key type ._llcrypt registered [12721.562387] Key type .llcrypt registered [12721.942803] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12721.954459] alg: No test for adler32 (adler32-zlib) [12722.978681] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12723.168701] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12724.839173] Key type lgssc registered [12725.864606] Lustre: Echo OBD driver; http://www.lustre.org/ [12734.682455] Lustre: DEBUG MARKER: Iteration 39 [12735.064022] LustreError: 375442:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12735.064847] LustreError: 375443:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12735.088865] LustreError: 375442:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [12736.116385] Lustre: Mounted lustre-client [12737.231504] LustreError: 375552:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0fe598000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12737.249449] LustreError: 375552:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12737.303352] Lustre: Unmounted lustre-client [12739.733490] Key type lgssc unregistered [12739.991832] LNet: 375802:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12740.007122] LNetError: 375802:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12740.027323] LNet: Removed LNI 192.168.204.2@tcp [12740.495148] Key type .llcrypt unregistered [12740.497475] Key type ._llcrypt unregistered [12740.981958] Key type ._llcrypt registered [12741.001106] Key type .llcrypt registered [12741.723486] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12741.733437] alg: No test for adler32 (adler32-zlib) [12742.779330] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12743.010568] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12744.663467] Key type lgssc registered [12745.648631] Lustre: Echo OBD driver; http://www.lustre.org/ [12754.414274] Lustre: DEBUG MARKER: Iteration 40 [12754.681161] LustreError: 376630:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12754.684033] LustreError: 376631:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12754.697062] LustreError: 376630:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [12756.818396] Lustre: Mounted lustre-client [12756.819504] Lustre: Skipped 1 previous similar message [12758.158153] LustreError: 376742:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0d0411000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12758.182671] LustreError: 376742:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12758.238685] Lustre: Unmounted lustre-client [12760.061775] Key type lgssc unregistered [12760.293847] LNet: 376990:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12760.302249] LNetError: 376990:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12760.316181] LNet: Removed LNI 192.168.204.2@tcp [12760.833393] Key type .llcrypt unregistered [12760.836295] Key type ._llcrypt unregistered [12761.399815] Key type ._llcrypt registered [12761.413930] Key type .llcrypt registered [12761.768109] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12761.778576] alg: No test for adler32 (adler32-zlib) [12762.755882] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12762.916269] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12764.551271] Key type lgssc registered [12765.533425] Lustre: Echo OBD driver; http://www.lustre.org/ [12774.896517] Lustre: DEBUG MARKER: Iteration 41 [12775.164954] LustreError: 377819:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12775.165078] LustreError: 377821:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12775.178760] LustreError: 377819:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [12776.140482] Lustre: Mounted lustre-client [12777.203901] LustreError: 377926:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0d0416000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12777.220952] LustreError: 377926:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [12777.250404] Lustre: Unmounted lustre-client [12779.671864] Key type lgssc unregistered [12779.846792] LNet: 378176:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12779.852480] LNetError: 378176:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12779.867433] LNet: Removed LNI 192.168.204.2@tcp [12780.502352] Key type .llcrypt unregistered [12780.504950] Key type ._llcrypt unregistered [12781.218138] Key type ._llcrypt registered [12781.220166] Key type .llcrypt registered [12781.625627] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12781.638492] alg: No test for adler32 (adler32-zlib) [12782.674610] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12782.863554] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12784.503278] Key type lgssc registered [12785.422636] Lustre: Echo OBD driver; http://www.lustre.org/ [12794.762987] Lustre: DEBUG MARKER: Iteration 42 [12795.065605] LustreError: 379007:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12795.065631] LustreError: 379008:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12795.075403] LustreError: 379007:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [12795.999929] Lustre: Mounted lustre-client [12796.992677] LustreError: 379113:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0c6944800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12797.017806] LustreError: 379113:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [12797.068061] Lustre: Unmounted lustre-client [12798.949816] Key type lgssc unregistered [12799.145587] LNet: 379363:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12799.152315] LNetError: 379363:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12799.162841] LNet: Removed LNI 192.168.204.2@tcp [12799.602117] Key type .llcrypt unregistered [12799.604848] Key type ._llcrypt unregistered [12800.162804] Key type ._llcrypt registered [12800.167697] Key type .llcrypt registered [12800.511711] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12800.540623] alg: No test for adler32 (adler32-zlib) [12801.527254] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12801.701072] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12803.383140] Key type lgssc registered [12804.270101] Lustre: Echo OBD driver; http://www.lustre.org/ [12813.591664] Lustre: DEBUG MARKER: Iteration 43 [12814.034461] LustreError: 380193:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12814.036508] LustreError: 380194:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12814.057893] LustreError: 380193:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [12815.168756] Lustre: Mounted lustre-client [12816.440640] LustreError: 380308:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0f9ad8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12816.472614] LustreError: 380308:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12816.520103] Lustre: Unmounted lustre-client [12818.715703] Key type lgssc unregistered [12818.933841] LNet: 380552:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12818.937073] LNetError: 380552:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12818.952220] LNet: Removed LNI 192.168.204.2@tcp [12819.449140] Key type .llcrypt unregistered [12819.451713] Key type ._llcrypt unregistered [12819.971651] Key type ._llcrypt registered [12819.976418] Key type .llcrypt registered [12820.487750] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12820.504913] alg: No test for adler32 (adler32-zlib) [12821.595468] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12821.820567] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12823.471236] Key type lgssc registered [12824.849495] Lustre: Echo OBD driver; http://www.lustre.org/ [12834.282957] Lustre: DEBUG MARKER: Iteration 44 [12834.688868] LustreError: 381383:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12834.691345] LustreError: 381382:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12834.706946] LustreError: 381383:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [12835.832258] Lustre: Mounted lustre-client [12835.834882] Lustre: Skipped 1 previous similar message [12837.028657] LustreError: 381498:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0c30bc800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12837.039901] LustreError: 381498:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12837.053421] LustreError: 381498:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [12837.059127] LustreError: 381498:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [12837.087488] Lustre: Unmounted lustre-client [12839.147432] Key type lgssc unregistered [12839.344046] LNet: 381741:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12839.354642] LNetError: 381741:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12839.372385] LNet: Removed LNI 192.168.204.2@tcp [12839.934279] Key type .llcrypt unregistered [12839.938730] Key type ._llcrypt unregistered [12840.351057] Key type ._llcrypt registered [12840.358858] Key type .llcrypt registered [12840.728303] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12840.735495] alg: No test for adler32 (adler32-zlib) [12841.752174] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12841.961383] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12843.602614] Key type lgssc registered [12844.663718] Lustre: Echo OBD driver; http://www.lustre.org/ [12852.797667] Lustre: DEBUG MARKER: Iteration 45 [12853.090322] LustreError: 382567:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12853.090608] LustreError: 382570:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12853.097305] LustreError: 382567:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [12855.141885] Lustre: Mounted lustre-client [12855.149285] Lustre: Skipped 1 previous similar message [12856.320072] LustreError: 382677:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0c6941000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12856.343868] LustreError: 382677:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12856.424794] Lustre: Unmounted lustre-client [12858.531759] Key type lgssc unregistered [12858.704685] LNet: 382927:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12858.715056] LNetError: 382927:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12858.725889] LNet: Removed LNI 192.168.204.2@tcp [12859.272535] Key type .llcrypt unregistered [12859.275905] Key type ._llcrypt unregistered [12859.846802] Key type ._llcrypt registered [12859.850338] Key type .llcrypt registered [12860.248387] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12860.262309] alg: No test for adler32 (adler32-zlib) [12861.287085] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12861.496361] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12863.143606] Key type lgssc registered [12864.129761] Lustre: Echo OBD driver; http://www.lustre.org/ [12873.811527] Lustre: DEBUG MARKER: Iteration 46 [12874.076023] LustreError: 383757:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12874.077908] LustreError: 383762:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12874.099641] LustreError: 383757:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4983 [12875.219322] Lustre: Mounted lustre-client [12875.221643] Lustre: Skipped 1 previous similar message [12876.261694] LustreError: 383865:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0d06f0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12876.281526] LustreError: 383865:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [12876.361422] Lustre: Unmounted lustre-client [12878.764968] Key type lgssc unregistered [12878.966279] LNet: 384115:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12878.972382] LNetError: 384115:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12878.993811] LNet: Removed LNI 192.168.204.2@tcp [12879.507289] Key type .llcrypt unregistered [12879.509946] Key type ._llcrypt unregistered [12880.419378] Key type ._llcrypt registered [12880.422130] Key type .llcrypt registered [12880.839924] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12880.853622] alg: No test for adler32 (adler32-zlib) [12882.123955] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12882.681425] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12884.439705] Key type lgssc registered [12888.073670] Lustre: Echo OBD driver; http://www.lustre.org/ [12906.164530] Lustre: DEBUG MARKER: Iteration 47 [12907.244731] LustreError: 384946:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12907.248913] LustreError: 384947:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12907.280818] LustreError: 384946:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4981 [12908.650632] Lustre: Mounted lustre-client [12910.853753] LustreError: 385056:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0d096e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12910.880312] LustreError: 385056:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [12910.971716] Lustre: Unmounted lustre-client [12914.982022] Key type lgssc unregistered [12915.237195] LNet: 385304:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12915.242181] LNetError: 385304:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12915.260855] LNet: Removed LNI 192.168.204.2@tcp [12916.413311] Key type .llcrypt unregistered [12916.417947] Key type ._llcrypt unregistered [12918.150073] Key type ._llcrypt registered [12918.157375] Key type .llcrypt registered [12919.226924] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12919.261856] alg: No test for adler32 (adler32-zlib) [12920.423710] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12920.780394] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12922.575443] Key type lgssc registered [12924.025535] Lustre: Echo OBD driver; http://www.lustre.org/ [12937.693990] Lustre: DEBUG MARKER: Iteration 48 [12938.211037] LustreError: 386137:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12938.216721] LustreError: 386136:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12938.242098] LustreError: 386137:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [12939.344688] Lustre: Mounted lustre-client [12940.830198] LustreError: 386253:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0d0410000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12940.856278] LustreError: 386253:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12940.926850] Lustre: Unmounted lustre-client [12943.843792] Key type lgssc unregistered [12944.131792] LNet: 386494:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12944.139556] LNetError: 386494:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12945.191344] LNet: Removed LNI 192.168.204.2@tcp [12945.850307] Key type .llcrypt unregistered [12945.851882] Key type ._llcrypt unregistered [12946.687930] Key type ._llcrypt registered [12946.690232] Key type .llcrypt registered [12947.446886] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12947.458246] alg: No test for adler32 (adler32-zlib) [12948.620829] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12948.949483] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12950.695190] Key type lgssc registered [12952.223727] Lustre: Echo OBD driver; http://www.lustre.org/ [12965.294808] Lustre: DEBUG MARKER: Iteration 49 [12965.787338] LustreError: 387327:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12965.787814] LustreError: 387326:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12965.805826] LustreError: 387327:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [12966.934378] Lustre: Mounted lustre-client [12968.407773] LustreError: 387435:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0f9ab2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12968.450756] LustreError: 387435:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [12968.549153] Lustre: Unmounted lustre-client [12971.497747] Key type lgssc unregistered [12971.745778] LNet: 387685:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12971.758823] LNetError: 387685:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12971.774643] LNet: Removed LNI 192.168.204.2@tcp [12972.515199] Key type .llcrypt unregistered [12972.516652] Key type ._llcrypt unregistered [12973.468096] Key type ._llcrypt registered [12973.470373] Key type .llcrypt registered [12974.199898] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12974.239401] alg: No test for adler32 (adler32-zlib) [12975.473679] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [12975.749598] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [12977.571783] Key type lgssc registered [12979.136223] Lustre: Echo OBD driver; http://www.lustre.org/ [12990.484921] Lustre: DEBUG MARKER: Iteration 50 [12991.198935] LustreError: 388514:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [12991.208478] LustreError: 388515:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [12991.221265] LustreError: 388514:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [12992.378893] Lustre: Mounted lustre-client [12993.814674] LustreError: 388619:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0c9210000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12993.860435] LustreError: 388619:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [12993.917871] Lustre: Unmounted lustre-client [12996.772640] Key type lgssc unregistered [12997.008067] LNet: 388868:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12997.013756] LNetError: 388868:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [12998.058923] LNet: Removed LNI 192.168.204.2@tcp [12998.645803] Key type .llcrypt unregistered [12998.656682] Key type ._llcrypt unregistered [12999.762158] Key type ._llcrypt registered [12999.769975] Key type .llcrypt registered [13000.145672] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [13000.157824] alg: No test for adler32 (adler32-zlib) [13001.244581] Lustre: Lustre: Build Version: 2.17.0_RC1_2_gf21c5f0 [13001.444271] LNet: Added LNI 192.168.204.2@tcp [8/256/0/180] [13003.103382] Key type lgssc registered [13004.716319] Lustre: Echo OBD driver; http://www.lustre.org/ [13017.454801] Lustre: Mounted lustre-client [13018.023450] Lustre: Mounted lustre-client [13024.556293] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 19:23:07 (1768263787) [13042.659229] Lustre: 390229:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768263790/real 1768263790] req@ffff9cd0ec697b80 x1854158945660800/t0(0) o36->lustre-MDT0000-mdc-ffff9cd0ffe71800@192.168.204.102@tcp:12/10 lens 496/440 e 0 to 1 dl 1768263806 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [13042.698854] Lustre: lustre-MDT0000-mdc-ffff9cd0ffe71800: Connection to lustre-MDT0000 (at 192.168.204.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [13042.735794] Lustre: lustre-MDT0000-mdc-ffff9cd0ffe71800: Connection restored to 192.168.204.102@tcp (at 192.168.204.102@tcp) [13059.039182] Lustre: 390229:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768263807/real 1768263807] req@ffff9cd0ec697b80 x1854158945660800/t0(0) o36->lustre-MDT0000-mdc-ffff9cd0ffe71800@192.168.204.102@tcp:12/10 lens 496/440 e 0 to 1 dl 1768263823 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [13059.066262] Lustre: lustre-MDT0000-mdc-ffff9cd0ffe71800: Connection to lustre-MDT0000 (at 192.168.204.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [13059.093891] Lustre: lustre-MDT0000-mdc-ffff9cd0ffe71800: Connection restored to 192.168.204.102@tcp (at 192.168.204.102@tcp) [13075.423163] Lustre: 390229:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768263823/real 1768263823] req@ffff9cd0ec697b80 x1854158945660800/t0(0) o36->lustre-MDT0000-mdc-ffff9cd0ffe71800@192.168.204.102@tcp:12/10 lens 496/440 e 0 to 1 dl 1768263839 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [13075.456258] Lustre: lustre-MDT0000-mdc-ffff9cd0ffe71800: Connection to lustre-MDT0000 (at 192.168.204.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [13075.478156] Lustre: lustre-MDT0000-mdc-ffff9cd0ffe71800: Connection restored to 192.168.204.102@tcp (at 192.168.204.102@tcp) [13090.783106] Lustre: 390229:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768263839/real 1768263839] req@ffff9cd0ec697b80 x1854158945660800/t0(0) o36->lustre-MDT0000-mdc-ffff9cd0ffe71800@192.168.204.102@tcp:12/10 lens 496/440 e 0 to 1 dl 1768263855 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [13090.821855] Lustre: lustre-MDT0000-mdc-ffff9cd0ffe71800: Connection to lustre-MDT0000 (at 192.168.204.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [13090.863574] Lustre: lustre-MDT0000-mdc-ffff9cd0ffe71800: Connection restored to 192.168.204.102@tcp (at 192.168.204.102@tcp) [13095.399828] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 19:24:18 (1768263858) [13107.594878] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 19:24:30 (1768263870) [13120.699710] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 19:24:43 (1768263883) [13127.194516] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 19:24:50 (1768263890) [13153.197726] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 19:25:16 (1768263916) [13154.967693] Lustre: DEBUG MARKER: SKIP: sanityn test_115 ldiskfs only test [13156.944463] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 19:25:19 (1768263919) [13165.357286] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 19:25:27 (1768263927) [13165.818618] LustreError: 394499:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [13167.824331] LustreError: 394499:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [13175.939485] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 19:25:38 (1768263938) [13197.791341] Lustre: 389060:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768263946/real 1768263946] req@ffff9cd0f6964e00 x1854158945939200/t0(0) o4->lustre-OST0000-osc-ffff9cd0ffe71800@192.168.204.102@tcp:6/4 lens 4584/448 e 0 to 1 dl 1768263962 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [13197.833129] Lustre: lustre-OST0000-osc-ffff9cd0ffe71800: Connection to lustre-OST0000 (at 192.168.204.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [13197.896604] Lustre: lustre-OST0000-osc-ffff9cd0ffe71800: Connection restored to 192.168.204.102@tcp (at 192.168.204.102@tcp) [13213.727275] Lustre: 389059:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768263962/real 1768263962] req@ffff9cd0f6965880 x1854158945939968/t0(0) o4->lustre-OST0000-osc-ffff9cd0ffe71800@192.168.204.102@tcp:6/4 lens 4584/448 e 0 to 1 dl 1768263978 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [13213.764516] Lustre: 389059:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [13213.776816] Lustre: lustre-OST0000-osc-ffff9cd0ffe71800: Connection to lustre-OST0000 (at 192.168.204.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [13213.808332] Lustre: lustre-OST0000-osc-ffff9cd0ffe71800: Connection restored to 192.168.204.102@tcp (at 192.168.204.102@tcp) [13229.920624] Lustre: 389058:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1768263978/real 1768263978] req@ffff9cd0d07ce680 x1854158945940096/t0(0) o4->lustre-OST0000-osc-ffff9cd0ffe71800@192.168.204.102@tcp:6/4 lens 4584/448 e 0 to 1 dl 1768263994 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [13229.951321] Lustre: lustre-OST0000-osc-ffff9cd0ffe71800: Connection to lustre-OST0000 (at 192.168.204.102@tcp) was lost; in progress operations using this service will wait for recovery to complete [13229.985602] Lustre: lustre-OST0000-osc-ffff9cd0ffe71800: Connection restored to 192.168.204.102@tcp (at 192.168.204.102@tcp) [13256.129893] Lustre: DEBUG MARKER: oleg402-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9cd0f9ab4000.ost_server_uuid 50 [13258.869257] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9cd0f9ab4000.ost_server_uuid in IDLE state after 0 sec [13261.709848] Lustre: DEBUG MARKER: cleanup: ====================================================== [13264.291441] Lustre: DEBUG MARKER: == sanityn test complete, duration 12952 sec ============= 19:27:06 (1768264026) [13266.956255] Lustre: DEBUG MARKER: === sanityn: start cleanup 19:27:09 (1768264029) === [13582.335404] LustreError: 396586:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0f9ab4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13582.350525] LustreError: 396586:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [13582.394921] Lustre: Unmounted lustre-client [13586.434123] Lustre: DEBUG MARKER: === sanityn: finish cleanup 19:32:29 (1768264349) === [13588.637732] LustreError: 396894:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd0ffe71800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13588.659086] LustreError: 396894:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [13588.674303] LustreError: 396894:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [13588.680424] LustreError: 396894:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [13588.728765] Lustre: Unmounted lustre-client [13614.219979] Key type lgssc unregistered [13614.471821] LNet: 397432:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13614.481372] LNetError: 397432:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [13614.503440] LNet: Removed LNI 192.168.204.2@tcp [13615.334172] Key type .llcrypt unregistered [13615.336982] Key type ._llcrypt unregistered