[ 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 3.0.0 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.3-2.fc40 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 440697620 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 0x000f5410-0x000f541f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5220 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1D6F 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1C0B 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001BCB (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1C7F 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1D0F 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1D47 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1c0b-0xbffe1c7e] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1c0a] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1c7f-0xbffe1d0e] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1d0f-0xbffe1d46] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1d47-0xbffe1d6e] [ 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: 2895288K/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.001011] APIC: Switch to symmetric I/O mode setup [ 0.002352] x2apic enabled [ 0.003010] Switched APIC routing to physical x2apic. [ 0.004016] kvm-guest: setup PV IPIs [ 0.006981] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.007000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.007000] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.007012] pid_max: default: 32768 minimum: 301 [ 0.008159] LSM: Security Framework initializing [ 0.009054] Yama: becoming mindful. [ 0.010045] SELinux: Initializing. [ 0.011077] *** VALIDATE selinux *** [ 0.020200] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025215] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.026170] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027136] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028156] *** VALIDATE tmpfs *** [ 0.029485] *** VALIDATE proc *** [ 0.031122] *** VALIDATE cgroup *** [ 0.032012] *** VALIDATE cgroup2 *** [ 0.034186] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035161] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036011] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037035] Spectre V2 : User space: Vulnerable [ 0.038011] Speculative Store Bypass: Vulnerable [ 0.040882] debug: unmapping init [mem 0xffffffffb4259000-0xffffffffb4260fff] [ 0.042151] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.043734] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.044024] ... version: 2 [ 0.045014] ... bit width: 48 [ 0.046014] ... generic registers: 4 [ 0.047015] ... value mask: 0000ffffffffffff [ 0.048016] ... max period: 00007fffffffffff [ 0.049015] ... fixed-purpose events: 3 [ 0.050011] ... event mask: 000000070000000f [ 0.051319] rcu: Hierarchical SRCU implementation. [ 0.053480] smp: Bringing up secondary CPUs ... [ 0.054576] x86: Booting SMP configuration: [ 0.055027] .... node #0, CPUs: #1 #2 #3 [ 0.059715] smp: Brought up 1 node, 4 CPUs [ 0.061027] smpboot: Max logical packages: 1 [ 0.062017] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.139514] node 0 deferred pages initialised in 74ms [ 0.142098] devtmpfs: initialized [ 0.143259] x86/mm: Memory block size: 128MB [ 0.146866] gcov: version magic: 0x41383552 [ 0.149248] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.152073] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.155298] pinctrl core: initialized pinctrl subsystem [ 0.157180] [ 0.157778] ************************************************************* [ 0.160015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.162011] ** ** [ 0.164010] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.165008] ** ** [ 0.167011] ** This means that this kernel is built to expose internal ** [ 0.170012] ** IOMMU data structures, which may compromise security on ** [ 0.172010] ** your system. ** [ 0.174012] ** ** [ 0.176012] ** If you see this message and you are not debugging the ** [ 0.178027] ** kernel, report this immediately to your vendor! ** [ 0.181012] ** ** [ 0.183010] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.185010] ************************************************************* [ 0.187655] NET: Registered protocol family 16 [ 0.190473] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.192056] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.195068] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.198069] cpuidle: using governor menu [ 0.199563] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.201456] PCI: Using configuration type 1 for base access [ 0.204125] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.211104] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.212026] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.214112] cryptd: max_cpu_qlen set to 1000 [ 0.215265] ACPI: Added _OSI(Module Device) [ 0.217017] ACPI: Added _OSI(Processor Device) [ 0.219028] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.220018] ACPI: Added _OSI(Processor Aggregator Device) [ 0.224871] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.230634] ACPI: Interpreter enabled [ 0.232080] ACPI: PM: (supports S0 S3 S4 S5) [ 0.234014] ACPI: Using IOAPIC for interrupt routing [ 0.236125] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.239421] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.249243] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.252039] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.254016] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.257084] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.261899] acpiphp: Slot [2] registered [ 0.264144] acpiphp: Slot [5] registered [ 0.265163] acpiphp: Slot [6] registered [ 0.267098] acpiphp: Slot [3] registered [ 0.268082] acpiphp: Slot [4] registered [ 0.269124] acpiphp: Slot [7] registered [ 0.271093] acpiphp: Slot [8] registered [ 0.272138] acpiphp: Slot [9] registered [ 0.274137] acpiphp: Slot [10] registered [ 0.275105] acpiphp: Slot [11] registered [ 0.276124] acpiphp: Slot [12] registered [ 0.277108] acpiphp: Slot [13] registered [ 0.278105] acpiphp: Slot [14] registered [ 0.280142] acpiphp: Slot [15] registered [ 0.281144] acpiphp: Slot [16] registered [ 0.284154] acpiphp: Slot [17] registered [ 0.285102] acpiphp: Slot [18] registered [ 0.286091] acpiphp: Slot [19] registered [ 0.288124] acpiphp: Slot [20] registered [ 0.289115] acpiphp: Slot [21] registered [ 0.291114] acpiphp: Slot [22] registered [ 0.292081] acpiphp: Slot [23] registered [ 0.294122] acpiphp: Slot [24] registered [ 0.296108] acpiphp: Slot [25] registered [ 0.297130] acpiphp: Slot [26] registered [ 0.299093] acpiphp: Slot [27] registered [ 0.300103] acpiphp: Slot [28] registered [ 0.302102] acpiphp: Slot [29] registered [ 0.303092] acpiphp: Slot [30] registered [ 0.304095] acpiphp: Slot [31] registered [ 0.306055] PCI host bridge to bus 0000:00 [ 0.306931] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.309024] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.311022] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.314026] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.316022] pci_bus 0000:00: root bus resource [mem 0x380000000000-0x38007fffffff window] [ 0.319027] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.320172] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.322968] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.326460] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.334016] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.339063] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.342021] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.343016] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.345018] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.348570] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.350786] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.353064] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.356825] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.360016] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.369659] pci 0000:00:02.0: reg 0x20: [mem 0x380000000000-0x380000003fff 64bit pref] [ 0.374015] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.378100] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.384020] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.390017] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.400018] pci 0000:00:05.0: reg 0x20: [mem 0x380000004000-0x380000007fff 64bit pref] [ 0.408329] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.414016] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.419018] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.430016] pci 0000:00:06.0: reg 0x20: [mem 0x380000008000-0x38000000bfff 64bit pref] [ 0.438440] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.441320] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.444391] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.446378] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.448259] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.452094] iommu: Default domain type: Passthrough [ 0.454675] SCSI subsystem initialized [ 0.456129] ACPI: bus type USB registered [ 0.457136] usbcore: registered new interface driver usbfs [ 0.459076] usbcore: registered new interface driver hub [ 0.461099] usbcore: registered new device driver usb [ 0.463190] pps_core: LinuxPPS API ver. 1 registered [ 0.465012] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.468066] PTP clock support registered [ 0.470136] EDAC MC: Ver: 3.0.0 [ 0.472145] PCI: Using ACPI for IRQ routing [ 0.473708] NetLabel: Initializing [ 0.475012] NetLabel: domain hash size = 128 [ 0.476010] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.478092] NetLabel: unlabeled traffic allowed by default [ 0.481116] vgaarb: loaded [ 0.482286] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.484015] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.490380] clocksource: Switched to clocksource kvm-clock [ 0.596179] VFS: Disk quotas dquot_6.6.0 [ 0.597921] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.600658] *** VALIDATE ramfs *** [ 0.602059] *** VALIDATE hugetlbfs *** [ 0.603727] pnp: PnP ACPI init [ 0.606337] pnp: PnP ACPI: found 6 devices [ 0.626669] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.630109] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.632369] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.634370] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.636844] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.639444] pci_bus 0000:00: resource 8 [mem 0x380000000000-0x38007fffffff window] [ 0.641442] NET: Registered protocol family 2 [ 0.643703] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.648597] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.652028] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.656878] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.663514] TCP: Hash tables configured (established 65536 bind 65536) [ 0.668803] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.674404] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.679026] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.682076] NET: Registered protocol family 1 [ 0.684254] RPC: Registered named UNIX socket transport module. [ 0.687048] RPC: Registered udp transport module. [ 0.688847] RPC: Registered tcp transport module. [ 0.690434] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.692782] NET: Registered protocol family 44 [ 0.694307] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.696376] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.698514] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.700920] PCI: CLS 0 bytes, default 64 [ 0.702225] Unpacking initramfs... [ 2.134992] debug: unmapping init [mem 0xffff9b8efcc64000-0xffff9b8efffcffff] [ 2.138832] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.141261] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.144454] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.637047] Initialise system trusted keyrings [ 2.638733] Key type blacklist registered [ 2.640888] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.649552] zbud: loaded [ 2.652539] *** VALIDATE nfs *** [ 2.653785] *** VALIDATE nfs4 *** [ 2.655416] pstore: using deflate compression [ 2.658915] Platform Keyring initialized [ 2.762742] NET: Registered protocol family 38 [ 2.764422] Key type asymmetric registered [ 2.765739] Asymmetric key parser 'x509' registered [ 2.767668] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.770742] io scheduler mq-deadline registered [ 2.772566] io scheduler kyber registered [ 2.774401] io scheduler bfq registered [ 2.776384] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.779818] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.783465] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.786421] ACPI: Power Button [PWRF] [ 2.879922] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.971069] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.058100] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.089147] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.120428] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.125635] Non-volatile memory driver v1.3 [ 3.128230] Linux agpgart interface v0.103 [ 3.166551] virtio_blk virtio1: [vda] 133624 512-byte logical blocks (68.4 MB/65.2 MiB) [ 3.169706] vda: detected capacity change from 0 to 68415488 [ 3.187428] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.190474] vdb: detected capacity change from 0 to 1073741824 [ 3.197220] libphy: Fixed MDIO Bus: probed [ 3.203861] usbcore: registered new interface driver usbserial_generic [ 3.207469] usbserial: USB Serial support registered for generic [ 3.210106] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.214433] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.216342] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.220190] mousedev: PS/2 mouse device common for all mice [ 3.223208] rtc_cmos 00:05: RTC can wake from S4 [ 3.227436] rtc_cmos 00:05: registered as rtc0 [ 3.229508] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.230306] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.235622] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.237970] intel_pstate: CPU model not supported [ 3.242349] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.249413] hid: raw HID events driver (C) Jiri Kosina [ 3.251628] usbcore: registered new interface driver usbhid [ 3.253830] usbhid: USB HID core driver [ 3.255432] drop_monitor: Initializing network drop monitor service [ 3.258782] Initializing XFRM netlink socket [ 3.260994] NET: Registered protocol family 10 [ 3.264147] Segment Routing with IPv6 [ 3.265838] NET: Registered protocol family 17 [ 3.267850] mpls_gso: MPLS GSO support [ 3.273158] RAS: Correctable Errors collector initialized. [ 3.275621] AVX version of gcm_enc/dec engaged. [ 3.277390] AES CTR mode by8 optimization enabled [ 3.357727] sched_clock: Marking stable (3357685131, 0)->(4219213936, -861528805) [ 3.361576] registered taskstats version 1 [ 3.364022] Loading compiled-in X.509 certificates [ 3.366284] zswap: loaded using pool lzo/zbud [ 3.395189] Key type big_key registered [ 3.408694] Key type encrypted registered [ 3.410296] ima: No TPM chip found, activating TPM-bypass! [ 3.412192] ima: Allocated hash algorithm: sha1 [ 3.413761] ima: No architecture policies found [ 3.415411] evm: Initialising EVM extended attributes: [ 3.417304] evm: security.selinux [ 3.418473] evm: security.ima [ 3.419492] evm: security.capability [ 3.421087] evm: HMAC attrs: 0x1 [ 3.423464] rtc_cmos 00:05: setting system clock to 2025-11-14 00:38:06 UTC (1763080686) [ 3.429777] debug: unmapping init [mem 0xffffffffb5203000-0xffffffffb53fffff] [ 3.432822] debug: unmapping init [mem 0xffffffffb3f82000-0xffffffffb4258fff] [ 3.445127] Write protecting the kernel read-only data: 28672k [ 3.448312] debug: unmapping init [mem 0xffffffffb2603000-0xffffffffb27fffff] [ 3.461022] debug: unmapping init [mem 0xffffffffb2f14000-0xffffffffb2ffffff] [ 3.524910] 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.533959] systemd[1]: Detected virtualization kvm. [ 3.536114] systemd[1]: Detected architecture x86-64. [ 3.537950] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.565383] systemd[1]: No hostname configured. [ 3.567249] systemd[1]: Set hostname to . [ 3.571036] random: systemd: uninitialized urandom read (16 bytes read) [ 3.573766] systemd[1]: Initializing machine ID from random generator. [ 3.612704] random: ln: uninitialized urandom read (6 bytes read) [ 3.721591] random: systemd: uninitialized urandom read (16 bytes read) [ 3.724300] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 3.729232] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 3.734547] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. [ OK ] Reached target Local File Systems. [ OK ] Reached target Initrd Root Device. Starting Apply Kernel Variables... [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Sockets. Starting Create Volatile Files and Directories... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Reached target Swap. [ OK ] Reached target Timers. Starting Setup Virtual Console... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. [ OK ] Started Journal Service. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.409722] device-mapper: uevent: version 1.0.3 [ 4.415403] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 5.159439] virtio_net virtio0 ens2: renamed from eth0 [ 5.193209] scsi host0: ata_piix [ 5.210951] scsi host1: ata_piix [ 5.212736] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.215834] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 9.451278] dracut-initqueue[588]: RTNETLINK answers: File exists [ 9.926550] random: crng init done [ 9.928171] 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.804861] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. 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 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 Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped target Swap. Stopping udev Kernel Device Manager... [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 13.167888] printk: systemd: 22 output lines suppressed due to ratelimiting [ 13.863707] SELinux: Disabled at runtime. [ 13.971072] 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) [ 13.979931] systemd[1]: Detected virtualization kvm. [ 13.981791] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 15.266362] systemd[1]: initrd-switch-root.service: Succeeded. [ 15.273370] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 15.290463] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 15.300520] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 15.307206] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 15.318979] systemd[1]: Starting Journal Service... Starting Journal Service... [ 15.329456] systemd[1]: Reached target rpc_pipefs.target. [ OK ] Reached target rpc_pipefs.target. Mounting Kernel Debug File System... [ OK ] Created slice system-getty.slice. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. Mounting Huge Pages File System... [ OK ] Listening on Process Core Dump Socket. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on udev Kernel Socket. Starting Apply Kernel Variables... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Created slice system-serial\x2dgetty.slice. Starting Remount Root and Kernel File Systems... [ OK ] Stopped target Initrd File Systems. Mounting POSIX Message Queue File System... Activating swap /dev/disk/by-label/SWAP... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on RPCbind Server Activation Socket. [ 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 ] Listening on ude[ 15.760582] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS v Control Socket. Starting udev Coldplug all Devices... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Started Journal Service. [ OK ] Mounted Kernel Debug File System. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 16.723432] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 18.001237] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 18.030280] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 18.654647] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 18.704523] EDAC sbridge: Ver: 1.1.2 [ 21.949253] Key type dns_resolver registered [ 22.645493] NFS: Registering the id_resolver key type [ 22.649240] Key type id_resolver registered [ 22.654633] Key type id_legacy registered [* ] A start job is running for Configur…-only root support (7s / no limit) [** ] A start job is running for Configur…-only root support (7s / no limit) [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... Starting Load/Save Random Seed... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Login Service... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Restore /run/initramfs on shutdown... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Login Service. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Network Manager Wait Online... 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 ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg131-client login: [ 81.991322] libcfs: loading out-of-tree module taints kernel. [ 82.149915] Key type ._llcrypt registered [ 82.151698] Key type .llcrypt registered [ 82.485667] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 82.494253] alg: No test for adler32 (adler32-zlib) [ 83.766422] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 84.544405] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 86.292287] Key type lgssc registered [ 87.950232] Lustre: Echo OBD driver; http://www.lustre.org/ [ 165.833953] hrtimer: interrupt took 12934437 ns [ 211.643080] Lustre: Mounted lustre-client [ 217.901861] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 237.227972] Lustre: DEBUG MARKER: oleg131-client.virtnet: executing check_logdir /tmp/testlogs/ [ 237.535333] Lustre: lustre-OST0000-osc-ffff9b8f4509a800: disconnect after 23s idle [ 241.642922] Lustre: DEBUG MARKER: oleg131-client.virtnet: executing yml_node [ 246.247577] Lustre: DEBUG MARKER: Client: 2.16.61.1 [ 249.251422] Lustre: DEBUG MARKER: MDS: 2.16.61.1 [ 251.778282] Lustre: DEBUG MARKER: OSS: 2.16.61.1 [ 253.364820] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Thu Nov 13 19:42:15 EST 2025 [ 267.588252] Lustre: DEBUG MARKER: excepting tests: 27 40a 102 [ 268.749724] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 270.041196] Lustre: DEBUG MARKER: === sanityn: start setup 19:42:31 (1763080951) === [ 270.677746] Lustre: Mounted lustre-client [ 273.779346] Lustre: DEBUG MARKER: oleg131-client.virtnet: executing check_config_client /mnt/lustre [ 288.250378] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 291.296249] Lustre: lustre-OST0000-osc-ffff9b8f47a72800: disconnect after 21s idle [ 291.300511] Lustre: Skipped 1 previous similar message [ 296.204541] Lustre: DEBUG MARKER: === sanityn: finish setup 19:42:58 (1763080978) === [ 298.130964] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 19:43:00 (1763080980) [ 303.659729] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 19:43:05 (1763080985) [ 308.644924] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 19:43:10 (1763080990) [ 313.686989] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 19:43:15 (1763080995) [ 318.465089] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 19:43:20 (1763081000) [ 322.542393] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 19:43:24 (1763081004) [ 327.478721] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 19:43:29 (1763081009) [ 332.757334] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 19:43:34 (1763081014) [ 333.924826] Lustre: DEBUG MARKER: SKIP: sanityn test_2f needs >= 2 MDTs [ 335.302152] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 19:43:37 (1763081017) [ 340.861227] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 19:43:42 (1763081022) [ 346.047679] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 19:43:48 (1763081028) [ 351.569863] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 19:43:53 (1763081033) [ 356.636735] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 19:43:58 (1763081038) [ 361.297605] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 19:44:03 (1763081043) [ 366.372658] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 19:44:08 (1763081048) [ 368.095199] Lustre: lustre-OST0001-osc-ffff9b8f47a72800: disconnect after 21s idle [ 368.103505] Lustre: Skipped 1 previous similar message [ 371.705556] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 19:44:13 (1763081053) [ 377.505935] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 19:44:19 (1763081059) [ 382.520636] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 19:44:24 (1763081064) [ 388.579312] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 19:44:30 (1763081070) [ 392.891214] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 19:44:34 (1763081074) [ 397.869554] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 19:44:39 (1763081079) [ 398.263822] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205272502304 file: /mnt/lustre/lockdir/lockfile=144115205272502302 [ 555.506669] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 19:47:17 (1763081237) [ 563.240958] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 19:47:25 (1763081245) [ 569.099614] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 19:47:31 (1763081251) [ 575.049819] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 19:47:36 (1763081256) [ 581.048152] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 19:47:43 (1763081263) [ 588.345786] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 19:47:50 (1763081270) [ 590.371208] Lustre: DEBUG MARKER: chmod [ 597.587454] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 19:47:59 (1763081279) [ 631.436172] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7528448KB free > 800000KB, increase MAXFREE (or reduce fs size) [ 645.163052] Lustre: DEBUG MARKER: == sanityn test 16a: 500 iterations of dual-mount fsx ==== 19:48:47 (1763081327) [ 691.071844] Lustre: DEBUG MARKER: == sanityn test 16b: 500 iterations of dual-mount fsx at small size ========================================================== 19:49:32 (1763081372) [ 717.255287] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 19:49:58 (1763081398) [ 719.589371] Lustre: DEBUG MARKER: SKIP: sanityn test_16c dio on ldiskfs only [ 720.919899] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 19:50:02 (1763081402) [ 757.010542] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 19:50:38 (1763081438) [ 761.706749] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 19:50:43 (1763081443) [ 762.455436] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 762.557119] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 762.622610] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 762.679684] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 762.772519] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 762.858190] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 762.931976] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 762.988952] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.055409] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.128097] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.197788] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.255627] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.331110] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.387276] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.457374] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.503718] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.547100] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.610903] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.690156] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.767771] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.828285] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.880319] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 763.943938] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.008690] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.080370] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.177045] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.233087] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.293530] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.359294] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.434538] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.493644] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.538108] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.577446] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.649203] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.745052] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.847469] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.949983] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 764.997089] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.056292] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.116571] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.252899] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.392661] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.468329] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.547903] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.653756] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.716936] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.783082] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.835901] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.874809] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.912524] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 765.970517] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.048842] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.107813] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.182837] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.240196] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.341643] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.407769] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.477738] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.580305] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.669090] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.733687] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.827657] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.889985] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 766.980687] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 767.076262] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 767.169221] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 767.262339] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 767.349363] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 767.427971] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 767.508585] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 767.577245] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 767.675958] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 767.751267] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 767.844932] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 767.916880] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 767.986425] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 768.065256] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 768.162722] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 768.259179] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 768.348750] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 768.398342] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 768.470497] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 768.591309] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 768.691074] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 768.804743] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 768.888615] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 768.957278] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.021136] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.103959] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.156729] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.248632] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.370094] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.445193] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.497729] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.563758] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.655360] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.739288] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.801943] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.858616] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 769.952955] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.038561] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.093469] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.156046] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.244074] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.302267] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.405861] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.510370] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.601564] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.680502] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.739832] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.800573] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.890672] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 770.989602] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.045836] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.110224] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.174141] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.265856] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.321811] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.377102] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.429053] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.479706] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.523623] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.576574] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.612474] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.667184] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.743291] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.792934] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.831517] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.882980] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 771.938928] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 772.021157] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 772.132808] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 772.229688] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 772.285272] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 772.392032] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 772.462252] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 772.530226] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 772.604470] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 772.667776] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 772.751560] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 772.816985] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 772.914189] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.042405] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.085705] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.152684] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.273107] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.346162] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.398852] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.461536] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.507104] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.559373] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.658276] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.731319] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.759438] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.812564] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 773.937819] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 774.034846] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 774.102087] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 774.219333] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 774.315970] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 774.384537] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 774.494967] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 774.607577] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 774.703261] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 774.769164] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 774.834317] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 774.923482] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.014695] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.058418] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.146900] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.253952] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.311296] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.409915] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.447088] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.505218] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.570924] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.647098] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.711029] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.798415] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.868245] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 775.938365] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 776.003915] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 776.083484] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 776.229574] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 776.316180] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 776.420475] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 776.504691] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 776.620265] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 776.692620] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 776.759537] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 776.845223] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 776.940369] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 776.999475] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 777.050996] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 777.147404] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 777.224652] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 777.314800] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 777.385972] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 777.449043] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 777.533427] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 777.620159] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 777.701638] Lustre: lustre-OST0000-osc-ffff9b8f47a72800: disconnect after 20s idle [ 777.710585] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 777.784485] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 777.878296] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 777.938652] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 778.038172] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 778.165428] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 778.260422] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 778.355818] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 778.485397] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 778.571988] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 778.655321] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 778.719759] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 778.795935] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 778.890714] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 779.002061] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 779.064330] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 779.123115] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 779.203973] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 779.312947] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 779.421588] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 779.481112] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 779.553819] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 779.605812] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 779.660267] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 779.762291] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 779.871864] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 779.940854] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 780.034055] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 780.099821] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 780.170990] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 780.234821] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 780.286924] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 780.347219] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 780.405601] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 780.461941] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 780.543460] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 780.630359] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 780.730869] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 780.824203] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 780.887478] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 781.000711] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 781.114134] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 781.220202] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 781.350216] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 781.483625] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 781.602193] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 781.694290] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 781.812113] rw_seq_cst_vs_d (29493): drop_caches: 3 [ 782.818582] Lustre: lustre-OST0000-osc-ffff9b8f4509a800: disconnect after 22s idle [ 789.433963] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 19:51:11 (1763081471) [ 789.995405] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 790.082648] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 790.210247] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 790.263795] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 790.398982] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 790.548929] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 790.610103] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 790.766956] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 791.044448] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 791.120315] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 791.160446] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 791.234306] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 791.309821] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 791.768202] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 791.853679] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 791.941802] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 791.986556] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 792.035746] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 792.063838] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 792.134775] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 792.220719] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 792.258505] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 792.380839] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 792.465536] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 792.496097] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 792.537492] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 792.575287] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 792.628796] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 792.681849] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 792.770693] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 792.796853] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 792.834646] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 792.978200] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 793.014864] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 793.104385] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 793.155251] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 793.227828] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 793.289853] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 793.325294] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 793.695759] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 793.848283] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 793.910922] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 794.031766] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 794.224989] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 794.500365] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 794.556813] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 794.639675] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 794.806757] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 794.972931] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 795.158294] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 795.255497] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 795.312711] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 795.522386] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 795.742788] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 795.926716] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 795.987634] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 796.048167] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 796.093269] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 796.118110] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 796.190642] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 796.434913] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 796.653190] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 796.780874] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 796.954534] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 797.106384] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 797.174170] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 797.321178] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 797.350400] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 797.455600] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 797.576687] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 797.610986] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 797.711518] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 797.815927] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 797.978701] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 798.092970] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 798.145498] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 798.231708] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 798.275281] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 798.401429] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 798.459635] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 798.493231] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 798.659834] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 798.739704] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 798.861486] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 798.895367] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 799.057989] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 799.102565] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 799.150690] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 799.303148] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 799.442167] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 799.501171] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 799.563156] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 799.627630] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 799.667892] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 799.733755] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 799.778744] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 799.828874] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 799.929499] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 799.996620] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 800.236956] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 800.294800] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 800.345942] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 800.374941] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 800.663523] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 800.849086] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 800.901703] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 800.975534] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 801.025353] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 801.085606] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 801.120319] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 801.171940] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 801.296615] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 801.342923] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 801.603678] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 801.680934] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 801.712753] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 801.741621] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 801.837289] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 801.876219] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 802.070429] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 802.144989] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 802.256423] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 802.334142] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 802.490188] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 802.616388] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 802.663544] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 802.731201] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 802.777494] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 802.841134] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 802.907379] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 803.005751] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 803.059568] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 803.103759] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 803.154404] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 803.222475] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 803.296233] Lustre: lustre-OST0001-osc-ffff9b8f47a72800: disconnect after 22s idle [ 803.307296] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 803.466508] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 803.522064] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 803.595390] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 803.701138] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 803.734941] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 803.811264] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 803.896481] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 803.988859] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 804.176744] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 804.204742] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 804.228777] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 804.253178] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 804.284696] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 804.425321] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 804.588428] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 804.705102] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 804.749580] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 804.800748] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 804.929650] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 804.997530] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 805.033495] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 805.173589] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 805.290324] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 805.391697] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 805.451557] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 805.515354] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 805.568853] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 805.619417] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 805.702471] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 805.873955] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 805.914090] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 806.028638] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 806.114398] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 806.170460] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 806.342747] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 806.555940] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 806.617424] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 806.694078] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 806.744930] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 806.788938] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 806.859493] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 806.930906] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 806.953981] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 807.025290] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 807.061870] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 807.113316] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 807.165309] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 807.228200] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 807.432570] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 807.471632] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 807.531436] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 807.611969] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 807.797443] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 807.942407] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 808.126525] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 808.193428] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 808.353485] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 808.499186] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 808.731037] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 808.860662] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 809.110176] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 809.216936] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 809.313705] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 809.388331] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 809.428306] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 809.534352] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 809.572171] rw_seq_cst_vs_d (30072): drop_caches: 3 [ 816.991735] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 19:51:38 (1763081498) [ 824.638289] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 19:51:45 (1763081505) [ 832.525497] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 19:51:54 (1763081514) [ 870.306236] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 19:52:32 (1763081552) [ 870.812857] bash (32509): drop_caches: 3 [ 874.064824] bash (32509): drop_caches: 3 [ 877.362112] bash (32509): drop_caches: 3 [ 881.296432] bash (32509): drop_caches: 3 [ 884.417568] bash (32509): drop_caches: 3 [ 887.533717] bash (32509): drop_caches: 3 [ 890.686236] bash (32509): drop_caches: 3 [ 893.829850] bash (32509): drop_caches: 3 [ 896.962065] bash (32509): drop_caches: 3 [ 900.096360] bash (32509): drop_caches: 3 [ 903.250346] bash (32509): drop_caches: 3 [ 906.377857] bash (32509): drop_caches: 3 [ 909.468214] bash (32509): drop_caches: 3 [ 912.664181] bash (32509): drop_caches: 3 [ 915.851814] bash (32509): drop_caches: 3 [ 921.110622] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 19:53:22 (1763081602) [ 929.999143] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 19:53:32 (1763081612) [ 965.593912] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 19:54:07 (1763081647) [ 968.073333] Lustre: DEBUG MARKER: SKIP: sanityn test_19 not cache-capable obdfilter [ 969.696990] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 19:54:11 (1763081651) [ 977.235772] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 19:54:18 (1763081658) [ 977.381060] Lustre: lustre-OST0000-osc-ffff9b8f4509a800: disconnect after 20s idle [ 977.392470] Lustre: Skipped 1 previous similar message [ 983.252367] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 19:54:25 (1763081665) [ 1050.629546] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 19:55:32 (1763081732) [ 1055.805105] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 19:55:37 (1763081737) [ 1060.568883] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 19:55:42 (1763081742) [ 1065.512705] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 19:55:47 (1763081747) [ 1066.574039] Lustre: DEBUG MARKER: SKIP: sanityn test_25b needs >= 2 MDTs [ 1068.071684] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 19:55:49 (1763081749) [ 1074.367204] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 19:55:56 (1763081756) [ 1081.337523] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 19:56:03 (1763081763) [ 1087.035721] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1088.198969] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 19:56:10 (1763081770) [ 1095.125276] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 19:56:17 (1763081777) [ 1095.339392] Lustre: *** cfs_fail_loc=314, val=0*** [ 1096.415253] Lustre: *** cfs_fail_loc=314, val=0*** [ 1096.417280] Lustre: Skipped 2 previous similar messages [ 1100.912630] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 19:56:23 (1763081783) [ 1112.003478] Lustre: *** cfs_fail_loc=314, val=0*** [ 1112.072596] LustreError: lustre-OST0000-osc-ffff9b8f47a72800: operation ldlm_enqueue to node 192.168.201.131@tcp failed: rc = -107 [ 1112.077975] Lustre: lustre-OST0000-osc-ffff9b8f47a72800: Connection to lustre-OST0000 (at 192.168.201.131@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1112.093984] LustreError: lustre-OST0000-osc-ffff9b8f47a72800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1112.103346] LustreError: 41755:0:(ldlm_resource.c:981:ldlm_resource_complain()) lustre-OST0000-osc-ffff9b8f47a72800: namespace resource [0x240000400:0x35:0x0].0x0 (ffff9b8f516e3900) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 1112.113460] Lustre: lustre-OST0000-osc-ffff9b8f47a72800: Connection restored to 192.168.201.131@tcp (at 192.168.201.131@tcp) [ 1117.195971] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 19:56:39 (1763081799) [ 1117.441372] LustreError: 42337:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 1120.471308] LustreError: 42337:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 1125.410243] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 19:56:47 (1763081807) [ 1132.031828] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 19:56:53 (1763081813) [ 1137.925522] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 1139.387815] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 19:57:01 (1763081821) [ 1140.952231] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 1142.158365] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 19:57:04 (1763081824) [ 1143.330607] Lustre: DEBUG MARKER: SKIP: sanityn test_33c needs >= 2 MDTs [ 1144.581032] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 19:57:06 (1763081826) [ 1145.721850] Lustre: DEBUG MARKER: SKIP: sanityn test_33d needs >= 2 MDTs [ 1147.302876] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 19:57:09 (1763081829) [ 1148.737831] Lustre: DEBUG MARKER: SKIP: sanityn test_33e needs >= 2 MDTs [ 1150.493267] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 19:57:12 (1763081832) [ 1206.767243] Lustre: lustre-OST0000-osc-ffff9b8f47a72800: Connection to lustre-OST0000 (at 192.168.201.131@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1206.782552] LustreError: lustre-OST0000-osc-ffff9b8f4509a800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1206.793290] LustreError: lustre-OST0000-osc-ffff9b8f47a72800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1206.794087] Lustre: lustre-OST0000-osc-ffff9b8f4509a800: Connection restored to 192.168.201.131@tcp (at 192.168.201.131@tcp) [ 1206.805321] Lustre: Skipped 1 previous similar message [ 1222.085456] Lustre: lustre-OST0001-osc-ffff9b8f4509a800: Connection to lustre-OST0001 (at 192.168.201.131@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1222.094400] Lustre: Skipped 1 previous similar message [ 1222.103982] LustreError: lustre-OST0001-osc-ffff9b8f4509a800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1222.114434] Lustre: lustre-OST0001-osc-ffff9b8f4509a800: Connection restored to 192.168.201.131@tcp (at 192.168.201.131@tcp) [ 1228.259831] Lustre: lustre-OST0000-osc-ffff9b8f4509a800: disconnect after 22s idle [ 1228.262690] Lustre: Skipped 4 previous similar messages [ 1239.681283] Lustre: DEBUG MARKER: oleg131-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9b8f4509a800.ost_server_uuid 50 [ 1241.115914] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9b8f4509a800.ost_server_uuid in IDLE state after 0 sec [ 1244.733273] Lustre: DEBUG MARKER: oleg131-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9b8f4509a800.ost_server_uuid 50 [ 1246.461430] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9b8f4509a800.ost_server_uuid in IDLE state after 0 sec [ 1251.559830] Lustre: DEBUG MARKER: oleg131-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9b8f4509a800.ost_server_uuid 50 [ 1253.027459] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9b8f4509a800.ost_server_uuid in IDLE state after 0 sec [ 1255.877480] Lustre: DEBUG MARKER: oleg131-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9b8f4509a800.ost_server_uuid 50 [ 1257.036310] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9b8f4509a800.ost_server_uuid in IDLE state after 0 sec [ 1264.972976] Lustre: DEBUG MARKER: oleg131-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9b8f4509a800.ost_server_uuid 50 [ 1266.387779] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9b8f4509a800.ost_server_uuid in IDLE state after 0 sec [ 1269.394237] Lustre: DEBUG MARKER: oleg131-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9b8f4509a800.ost_server_uuid 50 [ 1270.446641] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9b8f4509a800.ost_server_uuid in IDLE state after 0 sec [ 1271.642400] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 19:59:13 (1763081953) [ 1273.816964] Lustre: DEBUG MARKER: Race attempt 0 [ 1276.518641] Lustre: DEBUG MARKER: Wait for 48956 49001 for 60 sec... [ 1341.787773] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 20:00:23 (1763082023) [ 1349.292595] Lustre: DEBUG MARKER: start test - cycle (0) [ 1373.921905] Lustre: DEBUG MARKER: start test - cycle (1) [ 1397.975573] Lustre: DEBUG MARKER: start test - cycle (2) [ 1402.335566] Lustre: lustre-OST0000-osc-ffff9b8f47a72800: disconnect after 21s idle [ 1402.342900] Lustre: Skipped 2 previous similar messages [ 1424.407155] Lustre: DEBUG MARKER: start test - cycle (3) [ 1449.911022] Lustre: DEBUG MARKER: start test - cycle (4) [ 1475.817310] Lustre: DEBUG MARKER: start test - cycle (5) [ 1501.709471] Lustre: DEBUG MARKER: start test - cycle (6) [ 1526.197758] Lustre: DEBUG MARKER: start test - cycle (7) [ 1530.341508] Lustre: lustre-OST0000-osc-ffff9b8f47a72800: disconnect after 21s idle [ 1530.352263] Lustre: Skipped 4 previous similar messages [ 1551.587664] Lustre: DEBUG MARKER: start test - cycle (8) [ 1578.578771] Lustre: DEBUG MARKER: start test - cycle (9) [ 1603.648308] Lustre: DEBUG MARKER: start test - cycle (10) [ 1636.347529] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 20:05:17 (1763082317) [ 1728.120720] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 20:06:49 (1763082409) [ 1734.724076] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 20:06:56 (1763082416) [ 1742.876456] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 20:07:04 (1763082424) [ 1750.946274] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 20:07:12 (1763082432) [ 1751.370249] Lustre: *** cfs_fail_loc=411, val=0*** [ 1758.586800] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 1760.632842] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 20:07:22 (1763082442) [ 1778.425529] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 20:07:40 (1763082460) [ 1795.304273] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 20:07:57 (1763082477) [ 1796.577963] Lustre: lustre-OST0001-osc-ffff9b8f4509a800: disconnect after 24s idle [ 1796.584072] Lustre: Skipped 9 previous similar messages [ 1810.630149] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 20:08:12 (1763082492) [ 1827.420677] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 20:08:28 (1763082508) [ 1840.610275] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 20:08:42 (1763082522) [ 1853.505693] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 20:08:55 (1763082535) [ 1867.138367] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 20:09:08 (1763082548) [ 1879.106091] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 20:09:20 (1763082560) [ 1891.445767] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 20:09:33 (1763082573) [ 1903.596852] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 20:09:45 (1763082585) [ 1916.788414] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 20:09:58 (1763082598) [ 1929.300444] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 20:10:11 (1763082611) [ 2549.216645] Lustre: lustre-OST0000-osc-ffff9b8f47a72800: disconnect after 24s idle [ 2549.219703] Lustre: Skipped 5 previous similar messages [ 2891.627907] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 20:26:14 (1763083574) [ 2899.661083] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 20:26:21 (1763083581) [ 2908.627561] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 20:26:30 (1763083590) [ 2918.729934] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 20:26:40 (1763083600) [ 2928.033479] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 20:26:50 (1763083610) [ 2936.558597] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 20:26:58 (1763083618) [ 2943.861516] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 20:27:06 (1763083626) [ 2951.093645] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 20:27:13 (1763083633) [ 2958.547045] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 20:27:20 (1763083640) [ 2996.473561] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 20:27:58 (1763083678) [ 3006.070092] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 20:28:07 (1763083687) [ 3019.889659] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 20:28:21 (1763083701) [ 3029.303983] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 20:28:31 (1763083711) [ 3037.703709] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 20:28:39 (1763083719) [ 3046.184285] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 20:28:48 (1763083728) [ 3054.925964] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 20:28:57 (1763083737) [ 3062.582573] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 20:29:04 (1763083744) [ 3063.384859] Lustre: DEBUG MARKER: SKIP: sanityn test_43i needs >= 2 MDTs [ 3064.342425] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 20:29:06 (1763083746) [ 3141.486599] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 20:30:23 (1763083823) [ 3230.177390] Lustre: lustre-OST0000-osc-ffff9b8f4509a800: disconnect after 23s idle [ 3230.181622] Lustre: Skipped 5 previous similar messages [ 3735.203052] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 20:40:17 (1763084417) [ 3741.819872] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 20:40:24 (1763084424) [ 3748.256646] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 20:40:30 (1763084430) [ 3754.649347] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 20:40:37 (1763084437) [ 3761.507794] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 20:40:43 (1763084443) [ 3767.791813] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 20:40:50 (1763084450) [ 3774.122629] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 20:40:56 (1763084456) [ 3780.270580] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 20:41:02 (1763084462) [ 3786.447973] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 20:41:08 (1763084468) [ 3787.048516] Lustre: DEBUG MARKER: SKIP: sanityn test_44i needs >= 2 MDTs [ 3787.708942] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 20:41:10 (1763084470) [ 3838.745748] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 20:42:01 (1763084521) [ 3845.251761] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 20:42:07 (1763084527) [ 3851.177697] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 20:42:13 (1763084533) [ 3857.457743] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 20:42:19 (1763084539) [ 3863.581883] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 20:42:26 (1763084546) [ 3869.754495] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 20:42:32 (1763084552) [ 3875.700861] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 20:42:38 (1763084558) [ 3880.924158] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 20:42:43 (1763084563) [ 3881.494220] Lustre: DEBUG MARKER: SKIP: sanityn test_45i needs >= 2 MDTs [ 3882.142821] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 20:42:44 (1763084564) [ 4156.895199] Lustre: lustre-OST0001-osc-ffff9b8f4509a800: disconnect after 22s idle [ 4156.898820] Lustre: Skipped 7 previous similar messages [ 4373.117711] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 20:50:55 (1763085055) [ 4378.778339] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 20:51:01 (1763085061) [ 4384.988554] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 20:51:07 (1763085067) [ 4390.260586] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 20:51:12 (1763085072) [ 4395.444699] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 20:51:18 (1763085078) [ 4400.555131] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 20:51:23 (1763085083) [ 4405.943126] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 20:51:28 (1763085088) [ 4411.453384] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 20:51:34 (1763085094) [ 4416.778394] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 20:51:39 (1763085099) [ 4417.333287] Lustre: DEBUG MARKER: SKIP: sanityn test_46i needs >= 2 MDTs [ 4417.923039] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 20:51:40 (1763085100) [ 4418.464486] Lustre: DEBUG MARKER: SKIP: sanityn test_47a needs >= 2 MDTs [ 4419.077136] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 20:51:41 (1763085101) [ 4419.549269] Lustre: DEBUG MARKER: SKIP: sanityn test_47b needs >= 2 MDTs [ 4420.134647] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 20:51:42 (1763085102) [ 4420.640864] Lustre: DEBUG MARKER: SKIP: sanityn test_47c needs >= 2 MDTs [ 4421.231111] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 20:51:43 (1763085103) [ 4421.755261] Lustre: DEBUG MARKER: SKIP: sanityn test_47d needs >= 2 MDTs [ 4422.334251] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 20:51:44 (1763085104) [ 4422.860106] Lustre: DEBUG MARKER: SKIP: sanityn test_47e needs >= 2 MDTs [ 4423.383698] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 20:51:46 (1763085106) [ 4423.829577] Lustre: DEBUG MARKER: SKIP: sanityn test_47f needs >= 2 MDTs [ 4424.570113] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 20:51:47 (1763085107) [ 4425.088285] Lustre: DEBUG MARKER: SKIP: sanityn test_47g needs >= 2 MDTs [ 4425.656847] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 20:51:48 (1763085108) [ 4425.728206] LustreError: 11077:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 4427.815112] LustreError: 11077:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 4432.852938] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 20:51:55 (1763085115) [ 4437.012266] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 20:51:59 (1763085119) [ 4437.104645] LustreError: 235403:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4441.167119] LustreError: 235403:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 4441.173490] LustreError: 235403:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4445.231073] LustreError: 235403:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 4445.243936] LustreError: 235410:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4449.303156] LustreError: 235410:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 4451.561778] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 20:52:14 (1763085134) [ 4458.138839] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 20:52:20 (1763085140) [ 4461.170311] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 20:52:23 (1763085143) [ 4465.286318] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 20:52:27 (1763085147) [ 4489.339467] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 20:52:51 (1763085171) [ 4496.979323] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 20:52:59 (1763085179) [ 4504.447444] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 20:53:07 (1763085187) [ 4517.144163] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 20:53:19 (1763085199) [ 4526.823330] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 20:53:29 (1763085209) [ 4527.333063] Lustre: DEBUG MARKER: SKIP: sanityn test_55e needs >= 2 MDTs [ 4527.943503] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 20:53:30 (1763085210) [ 4540.803914] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 20:53:43 (1763085223) [ 4554.312977] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 20:53:56 (1763085236) [ 4579.164258] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 20:54:21 (1763085261) [ 4619.864345] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 20:55:02 (1763085302) [ 4622.117622] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 4624.761113] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 20:55:07 (1763085307) [ 4627.853746] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 20:55:10 (1763085310) [ 4628.385645] Lustre: DEBUG MARKER: SKIP: sanityn test_71a ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 4628.876717] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 20:55:11 (1763085311) [ 4629.372230] Lustre: DEBUG MARKER: SKIP: sanityn test_71b ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 4629.871655] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 20:55:12 (1763085312) [ 4630.361467] Lustre: DEBUG MARKER: SKIP: sanityn test_71c support only ldiskfs ost [ 4630.894969] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 20:55:13 (1763085313) [ 4631.395306] Lustre: DEBUG MARKER: SKIP: sanityn test_71d ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 4631.935201] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 20:55:14 (1763085314) [ 4634.056466] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 20:55:16 (1763085316) [ 4636.252697] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 20:55:18 (1763085318) [ 4639.326465] LustreError: lustre-MDT0000-mdc-ffff9b8f4509a800: operation ldlm_enqueue to node 192.168.201.131@tcp failed: rc = -35 [ 4642.513566] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 20:55:25 (1763085325) [ 4642.672549] LustreError: 2384:0:(osc_request.c:3093:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 4644.759162] LustreError: 2384:0:(osc_request.c:3093:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 4649.893764] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 20:55:32 (1763085332) [ 4670.044343] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 20:55:52 (1763085352) [ 4673.583106] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 20:55:56 (1763085356) [ 4678.261966] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 20:56:00 (1763085360) [ 4683.351597] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 20:56:05 (1763085365) [ 4688.292529] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 20:56:10 (1763085370) [ 4695.750510] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 20:56:18 (1763085378) [ 4703.304316] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 20:56:25 (1763085385) [ 4706.846676] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 20:56:29 (1763085389) [ 4710.480250] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 20:56:33 (1763085393) [ 4718.343912] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 20:56:40 (1763085400) [ 4764.167911] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 20:57:26 (1763085446) [ 4791.775172] Lustre: lustre-OST0001-osc-ffff9b8f4509a800: disconnect after 24s idle [ 4791.777311] Lustre: Skipped 4 previous similar messages [ 4902.237075] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 20:59:44 (1763085584) [ 5039.583921] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 21:02:02 (1763085722) [ 5394.476363] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 21:07:57 (1763086077) [ 5416.415253] Lustre: lustre-OST0001-osc-ffff9b8f4509a800: disconnect after 21s idle [ 5416.417722] Lustre: Skipped 15 previous similar messages [ 5430.852816] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 21:08:33 (1763086113) [ 5471.941370] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 21:09:14 (1763086154) [ 5503.765230] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 21:09:46 (1763086186) [ 5584.949305] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 21:11:07 (1763086267) [ 5650.990782] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 21:12:13 (1763086333) [ 5769.544541] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 21:14:12 (1763086452) [ 5770.579200] LustreError: 277713:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f4509a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5770.583785] LustreError: 277713:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5770.595175] Lustre: Unmounted lustre-client [ 5771.220737] LustreError: 277726:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f47a72800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5771.224903] LustreError: 277726:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 5771.228196] LustreError: 277726:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5771.229910] LustreError: 277726:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 5771.240950] Lustre: Unmounted lustre-client [ 5815.692871] Lustre: Mounted lustre-client [ 5817.205861] Lustre: Mounted lustre-client [ 5818.259480] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 5904.180907] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 21:16:26 (1763086586) [ 5911.904657] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 21:16:34 (1763086594) [ 5915.026844] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 21:16:37 (1763086597) [ 5965.098322] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 21:17:27 (1763086647) [ 6016.991272] Lustre: lustre-OST0001-osc-ffff9b8f6068c000: disconnect after 20s idle [ 6016.993716] Lustre: Skipped 16 previous similar messages [ 6031.908886] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 21:18:34 (1763086714) [ 6035.867489] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 21:18:38 (1763086718) [ 6074.432924] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 21:19:17 (1763086757) [ 6086.033271] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 21:19:28 (1763086768) [ 6126.708932] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 21:20:09 (1763086809) [ 6129.854201] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 21:20:12 (1763086812) [ 6142.626532] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 21:20:25 (1763086825) [ 6143.144705] Lustre: DEBUG MARKER: SKIP: sanityn test_80a needs >= 2 MDTs [ 6143.778884] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 21:20:26 (1763086826) [ 6144.323703] Lustre: DEBUG MARKER: SKIP: sanityn test_80b needs >= 2 MDTs [ 6144.935387] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 21:20:27 (1763086827) [ 6145.477728] Lustre: DEBUG MARKER: SKIP: sanityn test_81a needs >= 2 MDTs [ 6146.065342] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 21:20:28 (1763086828) [ 6146.577171] Lustre: DEBUG MARKER: SKIP: sanityn test_81b We need at least 2 MDTs for this test [ 6147.235846] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 21:20:29 (1763086829) [ 6147.806273] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 6148.400935] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 21:20:30 (1763086830) [ 6173.547915] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 21:20:56 (1763086856) [ 6175.597746] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 21:20:58 (1763086858) [ 6176.144515] Lustre: DEBUG MARKER: SKIP: sanityn test_83 needs >= 2 MDTs [ 6176.678105] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 21:20:59 (1763086859) [ 6184.120225] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 21:21:06 (1763086866) [ 6186.832557] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 21:21:09 (1763086869) [ 6187.331415] Lustre: DEBUG MARKER: SKIP: sanityn test_90 needs >= 2 MDTs [ 6187.888615] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 21:21:10 (1763086870) [ 6188.391237] Lustre: DEBUG MARKER: SKIP: sanityn test_91 needs >= 2 MDTs [ 6188.908709] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 21:21:11 (1763086871) [ 6189.393376] Lustre: DEBUG MARKER: SKIP: sanityn test_92 needs >= 2 MDTs [ 6189.929991] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 21:21:12 (1763086872) [ 6198.881433] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 21:21:21 (1763086881) [ 6198.962468] Lustre: DEBUG MARKER: write [ 6198.980208] LustreError: 284117:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 6200.984679] Lustre: DEBUG MARKER: kill 307267 [ 6200.986560] LustreError: 307267:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 6204.079086] LustreError: 284117:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 6207.023097] LustreError: 307267:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 6209.108615] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 21:21:31 (1763086891) [ 6211.265044] LustreError: 307872:0:(rw.c:1967:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 6221.359090] LustreError: 307872:0:(rw.c:1967:ll_readpage()) cfs_fail_timeout id 1422 awake [ 6223.564922] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 21:21:46 (1763086906) [ 6223.676232] LustreError: 308452:0:(rw.c:2201:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 6225.759146] LustreError: 308452:0:(rw.c:2201:ll_readpage()) cfs_fail_timeout interrupted [ 6231.955855] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 21:21:54 (1763086914) [ 6232.499469] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 6233.125119] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 21:21:55 (1763086915) [ 6235.483378] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 21:21:58 (1763086918) [ 6237.812849] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 21:22:00 (1763086920) [ 6240.165716] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 21:22:02 (1763086922) [ 6242.546694] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 21:22:05 (1763086925) [ 6244.736635] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 21:22:07 (1763086927) [ 6247.080921] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 21:22:09 (1763086929) [ 6250.282430] Lustre: DEBUG MARKER: SKIP: sanityn test_102 skipping ALWAYS excluded test 102 [ 6250.780850] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 21:22:13 (1763086933) [ 6251.389117] Lustre: *** cfs_fail_loc=415, val=0*** [ 6258.078900] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 21:22:20 (1763086940) [ 6258.580792] Lustre: DEBUG MARKER: SKIP: sanityn test_104 ldiskfs only test [ 6259.132575] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 21:22:21 (1763086941) [ 6259.207155] LustreError: 279127:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [ 6259.209988] LustreError: 279127:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [ 6264.303056] LustreError: 279126:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 6264.305665] LustreError: 279126:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 2 previous similar messages [ 6274.495126] LustreError: 279644:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 6274.497312] LustreError: 279644:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 4 previous similar messages [ 6276.758893] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 21:22:39 (1763086959) [ 6277.263284] Lustre: DEBUG MARKER: SKIP: sanityn test_106a Test only for ldiskfs and statx() supported [ 6277.824688] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 21:22:40 (1763086960) [ 6280.343531] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 21:22:42 (1763086962) [ 6282.654762] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 21:22:45 (1763086965) [ 6286.842123] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 21:22:49 (1763086969) [ 6295.125642] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 21:22:57 (1763086977) [ 6295.263608] LustreError: 297242:0:(osc_request.c:2944:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 6295.265789] LustreError: 297242:0:(osc_request.c:2944:osc_build_rpc()) Skipped 5 previous similar messages [ 6299.327127] LustreError: 297242:0:(osc_request.c:2944:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 6299.329611] LustreError: 297242:0:(osc_request.c:2944:osc_build_rpc()) Skipped 1 previous similar message [ 6301.427196] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 21:23:04 (1763086984) [ 6302.049182] LustreError: 318391:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f6068c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6302.052186] LustreError: 318391:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 6302.055944] LustreError: 318391:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6302.057638] LustreError: 318391:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6302.068902] Lustre: Unmounted lustre-client [ 6302.766434] Lustre: DEBUG MARKER: Iteration 1 [ 6302.863782] LustreError: 318571:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6302.863840] LustreError: 318572:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6302.868177] LustreError: 318571:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6302.911152] Lustre: Mounted lustre-client [ 6304.349425] Key type lgssc unregistered [ 6304.469661] LNet: 318921:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6304.473039] LNetError: 318921:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6304.482056] LNet: Removed LNI 192.168.201.31@tcp [ 6304.780096] Key type .llcrypt unregistered [ 6304.781047] Key type ._llcrypt unregistered [ 6305.058164] Key type ._llcrypt registered [ 6305.060226] Key type .llcrypt registered [ 6305.303748] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6305.308588] alg: No test for adler32 (adler32-zlib) [ 6306.272438] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6306.521307] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6308.127195] Key type lgssc registered [ 6308.612120] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6312.444628] Lustre: DEBUG MARKER: Iteration 2 [ 6312.537706] LustreError: 319738:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6312.537735] LustreError: 319739:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6312.543692] LustreError: 319738:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6313.581710] Lustre: Mounted lustre-client [ 6313.583653] Lustre: Skipped 1 previous similar message [ 6314.012200] LustreError: 319840:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f48417000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6314.016929] LustreError: 319841:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6314.016981] LustreError: 319840:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6314.034075] Lustre: Unmounted lustre-client [ 6314.959138] Key type lgssc unregistered [ 6315.083479] LNet: 320082:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6315.085775] LNetError: 320082:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6315.092629] LNet: Removed LNI 192.168.201.31@tcp [ 6315.297129] Key type .llcrypt unregistered [ 6315.298195] Key type ._llcrypt unregistered [ 6315.570143] Key type ._llcrypt registered [ 6315.571669] Key type .llcrypt registered [ 6315.736611] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6315.741622] alg: No test for adler32 (adler32-zlib) [ 6316.602879] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6316.696591] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6318.295142] Key type lgssc registered [ 6318.673027] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6322.037562] Lustre: DEBUG MARKER: Iteration 3 [ 6322.129610] LustreError: 320899:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6322.132886] LustreError: 320900:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6322.136288] LustreError: 320899:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6323.185338] Lustre: Mounted lustre-client [ 6323.616906] LustreError: 321007:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f56773800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6323.619422] LustreError: 321008:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6323.621164] LustreError: 321007:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6323.636096] Lustre: Unmounted lustre-client [ 6324.563055] Key type lgssc unregistered [ 6324.678517] LNet: 321249:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6324.682260] LNetError: 321249:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6324.692751] LNet: Removed LNI 192.168.201.31@tcp [ 6324.931094] Key type .llcrypt unregistered [ 6324.932127] Key type ._llcrypt unregistered [ 6325.201396] Key type ._llcrypt registered [ 6325.202580] Key type .llcrypt registered [ 6325.398314] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6325.403293] alg: No test for adler32 (adler32-zlib) [ 6326.255320] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6326.336111] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6327.919120] Key type lgssc registered [ 6328.272708] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6331.542592] Lustre: DEBUG MARKER: Iteration 4 [ 6331.663452] LustreError: 322066:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6331.663542] LustreError: 322067:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6331.668382] LustreError: 322066:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6332.724316] Lustre: Mounted lustre-client [ 6332.725663] Lustre: Skipped 1 previous similar message [ 6333.274362] LustreError: 322174:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f4861b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6333.277429] LustreError: 322174:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6333.282209] LustreError: 322175:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6333.284368] LustreError: 322175:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6333.310101] Lustre: Unmounted lustre-client [ 6334.323713] Key type lgssc unregistered [ 6334.429554] LNet: 322417:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6334.433614] LNetError: 322417:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6334.445670] LNet: Removed LNI 192.168.201.31@tcp [ 6334.659099] Key type .llcrypt unregistered [ 6334.660699] Key type ._llcrypt unregistered [ 6334.911111] Key type ._llcrypt registered [ 6334.912212] Key type .llcrypt registered [ 6335.115111] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6335.120656] alg: No test for adler32 (adler32-zlib) [ 6336.008797] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6336.124607] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6337.727202] Key type lgssc registered [ 6338.173313] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6341.596902] Lustre: DEBUG MARKER: Iteration 5 [ 6341.702235] LustreError: 323234:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6341.702292] LustreError: 323235:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6341.709604] LustreError: 323234:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6342.759252] Lustre: Mounted lustre-client [ 6343.211529] LustreError: 323339:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f47f25000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6343.215975] LustreError: 323340:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6343.216821] LustreError: 323339:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6343.231223] Lustre: Unmounted lustre-client [ 6344.175896] Key type lgssc unregistered [ 6344.297514] LNet: 323582:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6344.300954] LNetError: 323582:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6344.309804] LNet: Removed LNI 192.168.201.31@tcp [ 6344.540121] Key type .llcrypt unregistered [ 6344.541803] Key type ._llcrypt unregistered [ 6344.823619] Key type ._llcrypt registered [ 6344.825663] Key type .llcrypt registered [ 6345.018751] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6345.023454] alg: No test for adler32 (adler32-zlib) [ 6345.882293] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6345.968657] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6347.551201] Key type lgssc registered [ 6347.951388] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6351.396197] Lustre: DEBUG MARKER: Iteration 6 [ 6351.501088] LustreError: 324399:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6351.501126] LustreError: 324400:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6351.507702] LustreError: 324399:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6352.552527] Lustre: Mounted lustre-client [ 6353.095315] LustreError: 324506:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f4329c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6353.100662] LustreError: 324506:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6353.102991] LustreError: 324506:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6353.116120] Lustre: Unmounted lustre-client [ 6354.125588] Key type lgssc unregistered [ 6354.239514] LNet: 324748:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6354.241826] LNetError: 324748:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6354.251718] LNet: Removed LNI 192.168.201.31@tcp [ 6354.478154] Key type .llcrypt unregistered [ 6354.479198] Key type ._llcrypt unregistered [ 6354.730517] Key type ._llcrypt registered [ 6354.736742] Key type .llcrypt registered [ 6354.927807] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6354.933139] alg: No test for adler32 (adler32-zlib) [ 6355.778439] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6355.854670] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6357.431112] Key type lgssc registered [ 6357.835610] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6361.523768] Lustre: DEBUG MARKER: Iteration 7 [ 6361.654805] LustreError: 325566:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6361.654841] LustreError: 325567:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6361.661165] LustreError: 325566:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6362.713108] Lustre: Mounted lustre-client [ 6362.715378] Lustre: Skipped 1 previous similar message [ 6363.175383] LustreError: 325674:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f48619000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6363.181117] LustreError: 325674:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6363.182063] LustreError: 325673:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6363.214224] Lustre: Unmounted lustre-client [ 6363.215272] Lustre: Skipped 1 previous similar message [ 6364.128589] Key type lgssc unregistered [ 6364.250537] LNet: 325914:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6364.252781] LNetError: 325914:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6364.264729] LNet: Removed LNI 192.168.201.31@tcp [ 6364.490100] Key type .llcrypt unregistered [ 6364.491172] Key type ._llcrypt unregistered [ 6364.709155] Key type ._llcrypt registered [ 6364.710223] Key type .llcrypt registered [ 6364.929455] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6364.936250] alg: No test for adler32 (adler32-zlib) [ 6365.803929] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6365.911287] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6367.503187] Key type lgssc registered [ 6367.979527] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6371.728765] Lustre: DEBUG MARKER: Iteration 8 [ 6371.838301] LustreError: 326732:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6371.838573] LustreError: 326734:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6371.843886] LustreError: 326732:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6372.894081] Lustre: Mounted lustre-client [ 6372.895453] Lustre: Skipped 1 previous similar message [ 6373.372950] LustreError: 326840:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f6b132800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6373.376921] LustreError: 326841:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6373.379032] LustreError: 326840:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6373.393067] Lustre: Unmounted lustre-client [ 6374.335129] Key type lgssc unregistered [ 6374.455404] LNet: 327081:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6374.457694] LNetError: 327081:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6374.465691] LNet: Removed LNI 192.168.201.31@tcp [ 6374.699119] Key type .llcrypt unregistered [ 6374.701108] Key type ._llcrypt unregistered [ 6374.946925] Key type ._llcrypt registered [ 6374.948034] Key type .llcrypt registered [ 6375.151900] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6375.156814] alg: No test for adler32 (adler32-zlib) [ 6376.022674] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6376.111617] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6377.695096] Key type lgssc registered [ 6378.057238] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6381.700800] Lustre: DEBUG MARKER: Iteration 9 [ 6381.798867] LustreError: 327899:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6381.799048] LustreError: 327900:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6381.803370] LustreError: 327899:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6382.849967] Lustre: Mounted lustre-client [ 6382.851693] Lustre: Skipped 1 previous similar message [ 6383.311317] LustreError: 328008:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f608d1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6383.316625] LustreError: 328008:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6383.321147] LustreError: 328007:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6383.323946] LustreError: 328007:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6383.333103] Lustre: Unmounted lustre-client [ 6384.289474] Key type lgssc unregistered [ 6384.410344] LNet: 328250:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6384.413374] LNetError: 328250:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6384.420753] LNet: Removed LNI 192.168.201.31@tcp [ 6384.637086] Key type .llcrypt unregistered [ 6384.638112] Key type ._llcrypt unregistered [ 6384.970215] Key type ._llcrypt registered [ 6384.972029] Key type .llcrypt registered [ 6385.091078] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6385.095830] alg: No test for adler32 (adler32-zlib) [ 6385.942536] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6386.022860] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6387.607145] Key type lgssc registered [ 6387.970734] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6391.362498] Lustre: DEBUG MARKER: Iteration 10 [ 6391.466161] LustreError: 329068:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6391.466207] LustreError: 329069:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6391.470407] LustreError: 329068:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6392.529589] Lustre: Mounted lustre-client [ 6392.531069] Lustre: Skipped 1 previous similar message [ 6393.009501] LustreError: 329176:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f6068a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6393.012401] LustreError: 329177:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6393.014042] LustreError: 329176:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6393.037263] Lustre: Unmounted lustre-client [ 6394.014712] Key type lgssc unregistered [ 6394.143841] LNet: 329419:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6394.147950] LNetError: 329419:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6394.160539] LNet: Removed LNI 192.168.201.31@tcp [ 6394.424127] Key type .llcrypt unregistered [ 6394.425745] Key type ._llcrypt unregistered [ 6394.773158] Key type ._llcrypt registered [ 6394.778278] Key type .llcrypt registered [ 6395.019814] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6395.025517] alg: No test for adler32 (adler32-zlib) [ 6395.880803] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6395.968811] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6397.559112] Key type lgssc registered [ 6397.981531] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6401.480403] Lustre: DEBUG MARKER: Iteration 11 [ 6401.576836] LustreError: 330237:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6401.577433] LustreError: 330239:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6401.581518] LustreError: 330237:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6402.626099] Lustre: Mounted lustre-client [ 6403.083087] LustreError: 330346:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f60688800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6403.086729] LustreError: 330347:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 6403.087754] LustreError: 330346:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6403.102567] Lustre: Unmounted lustre-client [ 6404.054708] Key type lgssc unregistered [ 6404.172671] LNet: 330588:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6404.175583] LNetError: 330588:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6404.184649] LNet: Removed LNI 192.168.201.31@tcp [ 6404.440107] Key type .llcrypt unregistered [ 6404.441170] Key type ._llcrypt unregistered [ 6404.667670] Key type ._llcrypt registered [ 6404.668904] Key type .llcrypt registered [ 6404.860652] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6404.871285] alg: No test for adler32 (adler32-zlib) [ 6405.730490] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6405.810586] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6407.391143] Key type lgssc registered [ 6407.793518] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6411.087221] Lustre: DEBUG MARKER: Iteration 12 [ 6411.215254] LustreError: 331405:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6411.215301] LustreError: 331406:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6411.221073] LustreError: 331405:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6412.272125] Lustre: Mounted lustre-client [ 6412.725438] LustreError: 331507:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f42f60800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6412.731318] LustreError: 331507:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6412.733901] LustreError: 331508:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 6412.737913] LustreError: 331508:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6412.761092] Lustre: Unmounted lustre-client [ 6413.706381] Key type lgssc unregistered [ 6413.821515] LNet: 331749:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6413.824087] LNetError: 331749:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6413.832800] LNet: Removed LNI 192.168.201.31@tcp [ 6414.071106] Key type .llcrypt unregistered [ 6414.072281] Key type ._llcrypt unregistered [ 6414.318125] Key type ._llcrypt registered [ 6414.319240] Key type .llcrypt registered [ 6414.512909] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6414.529833] alg: No test for adler32 (adler32-zlib) [ 6415.385869] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6415.470414] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6417.055111] Key type lgssc registered [ 6417.406255] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6420.628433] Lustre: DEBUG MARKER: Iteration 13 [ 6420.731520] LustreError: 332565:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6420.734918] LustreError: 332566:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6420.738300] LustreError: 332565:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 6421.784602] Lustre: Mounted lustre-client [ 6421.785790] Lustre: Skipped 1 previous similar message [ 6422.222262] LustreError: 332670:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f6068a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6422.224243] LustreError: 332669:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6422.226101] LustreError: 332670:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6422.240735] Lustre: Unmounted lustre-client [ 6423.133622] Key type lgssc unregistered [ 6423.240557] LNet: 332910:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6423.244445] LNetError: 332910:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6423.252744] LNet: Removed LNI 192.168.201.31@tcp [ 6423.465135] Key type .llcrypt unregistered [ 6423.466499] Key type ._llcrypt unregistered [ 6423.712889] Key type ._llcrypt registered [ 6423.714210] Key type .llcrypt registered [ 6423.890666] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6423.901717] alg: No test for adler32 (adler32-zlib) [ 6424.761869] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6424.851593] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6426.439170] Key type lgssc registered [ 6426.870199] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6430.555722] Lustre: DEBUG MARKER: Iteration 14 [ 6430.682288] LustreError: 333738:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6430.682354] LustreError: 333740:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6430.688793] LustreError: 333738:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6431.753248] Lustre: Mounted lustre-client [ 6432.187873] LustreError: 333845:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f42f62000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6432.190939] LustreError: 333845:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6432.191424] LustreError: 333846:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6432.194915] LustreError: 333846:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6432.220092] Lustre: Unmounted lustre-client [ 6433.083016] Key type lgssc unregistered [ 6433.200549] LNet: 334088:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6433.203348] LNetError: 334088:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6433.210405] LNet: Removed LNI 192.168.201.31@tcp [ 6433.425086] Key type .llcrypt unregistered [ 6433.426192] Key type ._llcrypt unregistered [ 6433.684263] Key type ._llcrypt registered [ 6433.685359] Key type .llcrypt registered [ 6433.903850] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6433.909068] alg: No test for adler32 (adler32-zlib) [ 6434.780857] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6434.876342] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6436.463163] Key type lgssc registered [ 6436.869512] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6440.606686] Lustre: DEBUG MARKER: Iteration 15 [ 6440.706699] LustreError: 334905:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6440.713852] LustreError: 334906:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6440.716911] LustreError: 334905:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 6441.764643] Lustre: Mounted lustre-client [ 6441.766170] Lustre: Skipped 1 previous similar message [ 6442.212458] LustreError: 335009:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f7ad68000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6442.215739] LustreError: 335009:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6442.217896] LustreError: 335010:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6442.268514] Lustre: Unmounted lustre-client [ 6443.201111] Key type lgssc unregistered [ 6443.309462] LNet: 335251:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6443.311664] LNetError: 335251:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6443.319723] LNet: Removed LNI 192.168.201.31@tcp [ 6443.534174] Key type .llcrypt unregistered [ 6443.535218] Key type ._llcrypt unregistered [ 6443.836453] Key type ._llcrypt registered [ 6443.837626] Key type .llcrypt registered [ 6444.031565] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6444.036922] alg: No test for adler32 (adler32-zlib) [ 6444.902223] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6444.985592] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6446.567111] Key type lgssc registered [ 6446.947747] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6450.741529] Lustre: DEBUG MARKER: Iteration 16 [ 6450.898284] LustreError: 336070:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6450.898329] LustreError: 336071:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6450.904574] LustreError: 336070:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6451.963193] Lustre: Mounted lustre-client [ 6451.964957] Lustre: Skipped 1 previous similar message [ 6452.515466] LustreError: 336174:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f42f65000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6452.517931] LustreError: 336175:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6452.518937] LustreError: 336174:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6452.540127] Lustre: Unmounted lustre-client [ 6453.526964] Key type lgssc unregistered [ 6453.641735] LNet: 336417:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6453.644594] LNetError: 336417:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6453.656373] LNet: Removed LNI 192.168.201.31@tcp [ 6453.880163] Key type .llcrypt unregistered [ 6453.881177] Key type ._llcrypt unregistered [ 6454.145499] Key type ._llcrypt registered [ 6454.149413] Key type .llcrypt registered [ 6454.341470] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6454.346870] alg: No test for adler32 (adler32-zlib) [ 6455.209421] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6455.302393] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6456.887152] Key type lgssc registered [ 6457.325100] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6460.886973] Lustre: DEBUG MARKER: Iteration 17 [ 6461.006045] LustreError: 337235:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6461.006110] LustreError: 337236:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6461.011097] LustreError: 337235:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6462.071282] Lustre: Mounted lustre-client [ 6462.611600] LustreError: 337339:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f7ad6d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6462.616712] LustreError: 337339:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6462.619286] LustreError: 337339:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6462.635121] Lustre: Unmounted lustre-client [ 6463.576584] Key type lgssc unregistered [ 6463.693528] LNet: 337581:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6463.697446] LNetError: 337581:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6463.707372] LNet: Removed LNI 192.168.201.31@tcp [ 6463.931118] Key type .llcrypt unregistered [ 6463.932302] Key type ._llcrypt unregistered [ 6464.266835] Key type ._llcrypt registered [ 6464.267972] Key type .llcrypt registered [ 6464.497021] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6464.502428] alg: No test for adler32 (adler32-zlib) [ 6465.365228] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6465.458323] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6467.055131] Key type lgssc registered [ 6467.484292] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6471.045623] Lustre: DEBUG MARKER: Iteration 18 [ 6471.143491] LustreError: 338398:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6471.143555] LustreError: 338399:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6471.149611] LustreError: 338398:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6472.199712] Lustre: Mounted lustre-client [ 6472.201540] Lustre: Skipped 1 previous similar message [ 6472.641904] LustreError: 338504:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f60064800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6472.644454] LustreError: 338505:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6472.645036] LustreError: 338504:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6472.661566] Lustre: Unmounted lustre-client [ 6473.576803] Key type lgssc unregistered [ 6473.694611] LNet: 338745:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6473.699256] LNetError: 338745:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6473.708827] LNet: Removed LNI 192.168.201.31@tcp [ 6473.937095] Key type .llcrypt unregistered [ 6473.938278] Key type ._llcrypt unregistered [ 6474.143300] Key type ._llcrypt registered [ 6474.144300] Key type .llcrypt registered [ 6474.359475] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6474.372669] alg: No test for adler32 (adler32-zlib) [ 6475.220852] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6475.295630] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6476.871136] Key type lgssc registered [ 6477.233225] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6480.625961] Lustre: DEBUG MARKER: Iteration 19 [ 6480.765470] LustreError: 339561:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6480.768492] LustreError: 339562:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6480.771579] LustreError: 339561:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6481.827188] Lustre: Mounted lustre-client [ 6482.349487] LustreError: 339668:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f6cf9c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6482.357616] LustreError: 339668:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 6482.360392] LustreError: 339668:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6482.378124] Lustre: Unmounted lustre-client [ 6483.290861] Key type lgssc unregistered [ 6483.404424] LNet: 339910:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6483.406739] LNetError: 339910:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6483.413524] LNet: Removed LNI 192.168.201.31@tcp [ 6483.632109] Key type .llcrypt unregistered [ 6483.633226] Key type ._llcrypt unregistered [ 6483.889683] Key type ._llcrypt registered [ 6483.890720] Key type .llcrypt registered [ 6484.080847] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6484.085338] alg: No test for adler32 (adler32-zlib) [ 6484.937793] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6485.021666] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6486.615104] Key type lgssc registered [ 6486.980919] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6490.259615] Lustre: DEBUG MARKER: Iteration 20 [ 6490.393927] LustreError: 340728:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6490.394053] LustreError: 340729:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6490.398794] LustreError: 340728:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6491.448857] Lustre: Mounted lustre-client [ 6491.450213] Lustre: Skipped 1 previous similar message [ 6491.992359] LustreError: 340834:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f48bbe000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6491.998056] LustreError: 340834:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6491.999901] LustreError: 340834:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6492.021067] Lustre: Unmounted lustre-client [ 6492.937327] Key type lgssc unregistered [ 6493.056504] LNet: 341076:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6493.058662] LNetError: 341076:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6493.066780] LNet: Removed LNI 192.168.201.31@tcp [ 6493.298091] Key type .llcrypt unregistered [ 6493.299111] Key type ._llcrypt unregistered [ 6493.521837] Key type ._llcrypt registered [ 6493.522916] Key type .llcrypt registered [ 6493.741266] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6493.746021] alg: No test for adler32 (adler32-zlib) [ 6494.596752] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6494.687565] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6496.263141] Key type lgssc registered [ 6496.655136] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6500.246980] Lustre: DEBUG MARKER: Iteration 21 [ 6500.357207] LustreError: 341893:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6500.357242] LustreError: 341894:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6500.362927] LustreError: 341893:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6501.410292] Lustre: Mounted lustre-client [ 6501.412459] Lustre: Skipped 1 previous similar message [ 6501.854856] LustreError: 341999:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f74f71000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6501.860708] LustreError: 341999:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6501.918042] Lustre: Unmounted lustre-client [ 6501.919481] Lustre: Skipped 1 previous similar message [ 6502.820129] Key type lgssc unregistered [ 6502.944484] LNet: 342242:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6502.946752] LNetError: 342242:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6502.954618] LNet: Removed LNI 192.168.201.31@tcp [ 6503.192106] Key type .llcrypt unregistered [ 6503.193158] Key type ._llcrypt unregistered [ 6503.441576] Key type ._llcrypt registered [ 6503.442639] Key type .llcrypt registered [ 6503.646084] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6503.652874] alg: No test for adler32 (adler32-zlib) [ 6504.520453] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6504.613270] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6506.199135] Key type lgssc registered [ 6506.577098] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6510.101565] Lustre: DEBUG MARKER: Iteration 22 [ 6510.226326] LustreError: 343060:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6510.226757] LustreError: 343061:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6510.232719] LustreError: 343060:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6511.302990] Lustre: Mounted lustre-client [ 6511.752872] LustreError: 343167:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f51c83800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6511.757240] LustreError: 343168:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6511.757383] LustreError: 343167:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6511.779426] Lustre: Unmounted lustre-client [ 6511.781424] Lustre: Skipped 1 previous similar message [ 6512.747575] Key type lgssc unregistered [ 6512.863754] LNet: 343409:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6512.866594] LNetError: 343409:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6512.877599] LNet: Removed LNI 192.168.201.31@tcp [ 6513.131129] Key type .llcrypt unregistered [ 6513.132104] Key type ._llcrypt unregistered [ 6513.387938] Key type ._llcrypt registered [ 6513.388891] Key type .llcrypt registered [ 6513.679669] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6513.706505] alg: No test for adler32 (adler32-zlib) [ 6514.578629] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6514.667381] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6516.247181] Key type lgssc registered [ 6516.649704] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6520.189886] Lustre: DEBUG MARKER: Iteration 23 [ 6520.281701] LustreError: 344226:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6520.281753] LustreError: 344227:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6520.285944] LustreError: 344226:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6521.334929] Lustre: Mounted lustre-client [ 6521.335913] Lustre: Skipped 1 previous similar message [ 6521.761177] LustreError: 344335:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f51c83000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6521.764122] LustreError: 344335:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6521.766731] LustreError: 344334:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6521.769081] LustreError: 344334:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6521.795212] Lustre: Unmounted lustre-client [ 6522.628896] Key type lgssc unregistered [ 6522.736534] LNet: 344577:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6522.738728] LNetError: 344577:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6522.745691] LNet: Removed LNI 192.168.201.31@tcp [ 6522.954084] Key type .llcrypt unregistered [ 6522.955228] Key type ._llcrypt unregistered [ 6523.162769] Key type ._llcrypt registered [ 6523.164209] Key type .llcrypt registered [ 6523.353482] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6523.365814] alg: No test for adler32 (adler32-zlib) [ 6524.232314] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6524.320503] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6525.903134] Key type lgssc registered [ 6526.304279] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6529.728877] Lustre: DEBUG MARKER: Iteration 24 [ 6529.832753] LustreError: 345395:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6529.832982] LustreError: 345396:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6529.836776] LustreError: 345395:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6530.883131] Lustre: Mounted lustre-client [ 6530.885365] Lustre: Skipped 1 previous similar message [ 6531.361168] LustreError: 345502:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f51e86800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6531.366326] LustreError: 345502:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6531.368160] LustreError: 345502:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6531.386802] Lustre: Unmounted lustre-client [ 6532.331941] Key type lgssc unregistered [ 6532.439391] LNet: 345744:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6532.441814] LNetError: 345744:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6532.450684] LNet: Removed LNI 192.168.201.31@tcp [ 6532.662100] Key type .llcrypt unregistered [ 6532.663141] Key type ._llcrypt unregistered [ 6532.905445] Key type ._llcrypt registered [ 6532.907126] Key type .llcrypt registered [ 6533.128548] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6533.134606] alg: No test for adler32 (adler32-zlib) [ 6533.980465] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6534.060054] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6535.639134] Key type lgssc registered [ 6536.030505] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6539.286298] Lustre: DEBUG MARKER: Iteration 25 [ 6539.384249] LustreError: 346562:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6539.384301] LustreError: 346563:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6539.388447] LustreError: 346562:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6540.434525] Lustre: Mounted lustre-client [ 6540.868704] LustreError: 346669:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f44f64000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6540.873149] LustreError: 346670:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6540.873180] LustreError: 346669:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6540.890082] Lustre: Unmounted lustre-client [ 6541.807417] Key type lgssc unregistered [ 6541.924567] LNet: 346911:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6541.928247] LNetError: 346911:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6541.940739] LNet: Removed LNI 192.168.201.31@tcp [ 6542.175114] Key type .llcrypt unregistered [ 6542.176229] Key type ._llcrypt unregistered [ 6542.416453] Key type ._llcrypt registered [ 6542.418022] Key type .llcrypt registered [ 6542.588849] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6542.595833] alg: No test for adler32 (adler32-zlib) [ 6543.446037] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6543.526525] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6545.111172] Key type lgssc registered [ 6545.518842] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6548.811774] Lustre: DEBUG MARKER: Iteration 26 [ 6548.907361] LustreError: 347727:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6548.908960] LustreError: 347728:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6548.911671] LustreError: 347727:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6549.961155] Lustre: Mounted lustre-client [ 6549.963451] Lustre: Skipped 1 previous similar message [ 6550.419172] LustreError: 347829:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f6068e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6550.423253] LustreError: 347829:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6550.426065] LustreError: 347828:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6550.429096] LustreError: 347828:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 6550.437610] Lustre: Unmounted lustre-client [ 6551.387302] Key type lgssc unregistered [ 6551.501431] LNet: 348070:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6551.503563] LNetError: 348070:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6551.512672] LNet: Removed LNI 192.168.201.31@tcp [ 6551.747102] Key type .llcrypt unregistered [ 6551.748813] Key type ._llcrypt unregistered [ 6551.986529] Key type ._llcrypt registered [ 6551.988816] Key type .llcrypt registered [ 6552.197430] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6552.202104] alg: No test for adler32 (adler32-zlib) [ 6553.059308] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6553.143690] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6554.727150] Key type lgssc registered [ 6555.118551] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6558.666729] Lustre: DEBUG MARKER: Iteration 27 [ 6558.784755] LustreError: 348889:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6558.784809] LustreError: 348890:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6558.790240] LustreError: 348889:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6559.846197] Lustre: Mounted lustre-client [ 6560.291323] LustreError: 348993:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f47f23000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6560.294835] LustreError: 348994:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6560.296366] LustreError: 348993:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6560.311053] Lustre: Unmounted lustre-client [ 6561.373528] Key type lgssc unregistered [ 6561.482473] LNet: 349235:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6561.484657] LNetError: 349235:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6561.492813] LNet: Removed LNI 192.168.201.31@tcp [ 6561.716098] Key type .llcrypt unregistered [ 6561.717127] Key type ._llcrypt unregistered [ 6562.051199] Key type ._llcrypt registered [ 6562.052174] Key type .llcrypt registered [ 6562.197045] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6562.202214] alg: No test for adler32 (adler32-zlib) [ 6563.050181] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6563.128387] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6564.703130] Key type lgssc registered [ 6565.130138] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6568.559898] Lustre: DEBUG MARKER: Iteration 28 [ 6568.664088] LustreError: 350052:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6568.664130] LustreError: 350053:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6568.669219] LustreError: 350052:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6569.720381] Lustre: Mounted lustre-client [ 6570.182621] LustreError: 350164:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f733ed800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6570.185094] LustreError: 350163:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6570.186151] LustreError: 350164:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6570.204738] Lustre: Unmounted lustre-client [ 6571.201925] Key type lgssc unregistered [ 6571.333445] LNet: 350404:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6571.338084] LNetError: 350404:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6571.346596] LNet: Removed LNI 192.168.201.31@tcp [ 6571.607106] Key type .llcrypt unregistered [ 6571.608159] Key type ._llcrypt unregistered [ 6571.896973] Key type ._llcrypt registered [ 6571.898193] Key type .llcrypt registered [ 6572.091984] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6572.096720] alg: No test for adler32 (adler32-zlib) [ 6572.976120] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6573.067461] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6574.655080] Key type lgssc registered [ 6575.063606] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6578.441475] Lustre: DEBUG MARKER: Iteration 29 [ 6578.538101] LustreError: 351221:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6578.539296] LustreError: 351223:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6578.542388] LustreError: 351221:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6579.596665] Lustre: Mounted lustre-client [ 6579.597788] Lustre: Skipped 1 previous similar message [ 6580.034801] LustreError: 351329:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f7af53000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6580.038248] LustreError: 351328:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6580.039357] LustreError: 351329:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6580.051072] Lustre: Unmounted lustre-client [ 6580.979750] Key type lgssc unregistered [ 6581.090543] LNet: 351570:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6581.092942] LNetError: 351570:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6581.102643] LNet: Removed LNI 192.168.201.31@tcp [ 6581.328084] Key type .llcrypt unregistered [ 6581.329191] Key type ._llcrypt unregistered [ 6581.635520] Key type ._llcrypt registered [ 6581.637539] Key type .llcrypt registered [ 6581.809914] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6581.816248] alg: No test for adler32 (adler32-zlib) [ 6582.681504] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6582.781059] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6584.367125] Key type lgssc registered [ 6584.750257] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6588.378366] Lustre: DEBUG MARKER: Iteration 30 [ 6588.504485] LustreError: 352388:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6588.504559] LustreError: 352389:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6588.510756] LustreError: 352388:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6589.577385] Lustre: Mounted lustre-client [ 6589.579576] Lustre: Skipped 1 previous similar message [ 6590.126228] LustreError: 352498:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f56770800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6590.129523] LustreError: 352498:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6590.130034] LustreError: 352499:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6590.134689] LustreError: 352499:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6590.151350] Lustre: Unmounted lustre-client [ 6591.169193] Key type lgssc unregistered [ 6591.297844] LNet: 352739:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6591.301183] LNetError: 352739:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6591.310373] LNet: Removed LNI 192.168.201.31@tcp [ 6591.589141] Key type .llcrypt unregistered [ 6591.591644] Key type ._llcrypt unregistered [ 6591.936561] Key type ._llcrypt registered [ 6591.937562] Key type .llcrypt registered [ 6592.169390] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6592.174073] alg: No test for adler32 (adler32-zlib) [ 6593.031062] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6593.111788] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6594.695128] Key type lgssc registered [ 6595.085296] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6598.701466] Lustre: DEBUG MARKER: Iteration 31 [ 6598.810616] LustreError: 353560:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6598.810672] LustreError: 353559:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6598.814740] LustreError: 353560:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6599.868286] Lustre: Mounted lustre-client [ 6600.389761] LustreError: 353667:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f4a609000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6600.394989] LustreError: 353667:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6600.417097] Lustre: Unmounted lustre-client [ 6601.383526] Key type lgssc unregistered [ 6601.490508] LNet: 353908:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6601.492853] LNetError: 353908:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6601.503785] LNet: Removed LNI 192.168.201.31@tcp [ 6601.721134] Key type .llcrypt unregistered [ 6601.722207] Key type ._llcrypt unregistered [ 6602.005933] Key type ._llcrypt registered [ 6602.007074] Key type .llcrypt registered [ 6602.257302] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6602.262842] alg: No test for adler32 (adler32-zlib) [ 6603.134288] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6603.230624] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6604.823227] Key type lgssc registered [ 6605.317996] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6609.240631] Lustre: DEBUG MARKER: Iteration 32 [ 6609.371362] LustreError: 354726:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6609.372457] LustreError: 354727:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6609.376077] LustreError: 354726:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6610.436758] Lustre: Mounted lustre-client [ 6610.437783] Lustre: Skipped 1 previous similar message [ 6610.996372] LustreError: 354831:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f4a665800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6611.001660] LustreError: 354831:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6611.004373] LustreError: 354831:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6611.032112] Lustre: Unmounted lustre-client [ 6612.092039] Key type lgssc unregistered [ 6612.218691] LNet: 355073:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6612.221228] LNetError: 355073:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6612.232659] LNet: Removed LNI 192.168.201.31@tcp [ 6612.484138] Key type .llcrypt unregistered [ 6612.486038] Key type ._llcrypt unregistered [ 6612.785921] Key type ._llcrypt registered [ 6612.787495] Key type .llcrypt registered [ 6613.061606] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6613.067936] alg: No test for adler32 (adler32-zlib) [ 6613.939542] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6614.043411] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6615.639118] Key type lgssc registered [ 6616.033568] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6619.751193] Lustre: DEBUG MARKER: Iteration 33 [ 6619.883122] LustreError: 355890:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6619.883247] LustreError: 355891:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6619.890862] LustreError: 355890:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6620.949156] Lustre: Mounted lustre-client [ 6620.950390] Lustre: Skipped 1 previous similar message [ 6621.502745] LustreError: 355998:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f7f0f6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6621.506418] LustreError: 355998:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6621.506437] LustreError: 355997:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6621.511384] LustreError: 355997:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6621.534196] Lustre: Unmounted lustre-client [ 6622.503832] Key type lgssc unregistered [ 6622.618485] LNet: 356239:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6622.622259] LNetError: 356239:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6622.630747] LNet: Removed LNI 192.168.201.31@tcp [ 6622.885106] Key type .llcrypt unregistered [ 6622.886170] Key type ._llcrypt unregistered [ 6623.164817] Key type ._llcrypt registered [ 6623.166637] Key type .llcrypt registered [ 6623.465269] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6623.470905] alg: No test for adler32 (adler32-zlib) [ 6624.328906] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6624.412507] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6625.999207] Key type lgssc registered [ 6626.435579] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6630.061059] Lustre: DEBUG MARKER: Iteration 34 [ 6630.188361] LustreError: 357056:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6630.188703] LustreError: 357057:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6630.192866] LustreError: 357056:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6631.240606] Lustre: Mounted lustre-client [ 6631.673168] LustreError: 357157:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f44f66800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6631.675393] LustreError: 357158:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6631.676362] LustreError: 357157:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6631.693054] Lustre: Unmounted lustre-client [ 6632.598258] Key type lgssc unregistered [ 6632.705791] LNet: 357400:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6632.710164] LNetError: 357400:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6632.718949] LNet: Removed LNI 192.168.201.31@tcp [ 6632.957090] Key type .llcrypt unregistered [ 6632.958548] Key type ._llcrypt unregistered [ 6633.178050] Key type ._llcrypt registered [ 6633.179031] Key type .llcrypt registered [ 6633.392517] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6633.400238] alg: No test for adler32 (adler32-zlib) [ 6634.255069] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6634.342425] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6635.935160] Key type lgssc registered [ 6636.289396] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6639.621171] Lustre: DEBUG MARKER: Iteration 35 [ 6639.730484] LustreError: 358216:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6639.731505] LustreError: 358218:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6639.734573] LustreError: 358216:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6640.783321] Lustre: Mounted lustre-client [ 6641.232124] LustreError: 358324:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f45c64800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6641.235203] LustreError: 358324:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6641.236152] LustreError: 358325:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6641.239303] LustreError: 358325:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6641.258713] Lustre: Unmounted lustre-client [ 6641.259685] Lustre: Skipped 1 previous similar message [ 6642.167509] Key type lgssc unregistered [ 6642.283463] LNet: 358565:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6642.286506] LNetError: 358565:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6642.297674] LNet: Removed LNI 192.168.201.31@tcp [ 6642.500093] Key type .llcrypt unregistered [ 6642.501079] Key type ._llcrypt unregistered [ 6642.740829] Key type ._llcrypt registered [ 6642.742025] Key type .llcrypt registered [ 6642.924378] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6642.929500] alg: No test for adler32 (adler32-zlib) [ 6643.785871] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6643.863195] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6645.447106] Key type lgssc registered [ 6645.819057] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6649.264734] Lustre: DEBUG MARKER: Iteration 36 [ 6649.365595] LustreError: 359382:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6649.365654] LustreError: 359383:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6649.372240] LustreError: 359382:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 6650.425649] Lustre: Mounted lustre-client [ 6650.427020] Lustre: Skipped 1 previous similar message [ 6650.852334] LustreError: 359492:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f481ef800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6650.855668] LustreError: 359493:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6650.856015] LustreError: 359492:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6650.872065] Lustre: Unmounted lustre-client [ 6651.752392] Key type lgssc unregistered [ 6651.862336] LNet: 359734:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6651.864495] LNetError: 359734:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6651.872622] LNet: Removed LNI 192.168.201.31@tcp [ 6652.087104] Key type .llcrypt unregistered [ 6652.088226] Key type ._llcrypt unregistered [ 6652.353790] Key type ._llcrypt registered [ 6652.354951] Key type .llcrypt registered [ 6652.542233] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6652.547794] alg: No test for adler32 (adler32-zlib) [ 6653.397464] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6653.478764] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6655.063152] Key type lgssc registered [ 6655.426829] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6658.673175] Lustre: DEBUG MARKER: Iteration 37 [ 6658.774276] LustreError: 360553:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6658.774313] LustreError: 360554:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6658.778782] LustreError: 360553:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6659.826187] Lustre: Mounted lustre-client [ 6659.827438] Lustre: Skipped 1 previous similar message [ 6660.266815] LustreError: 360654:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f45531000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6660.270921] LustreError: 360654:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6660.271481] LustreError: 360655:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 6660.275444] LustreError: 360655:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6660.283094] Lustre: Unmounted lustre-client [ 6661.132916] Key type lgssc unregistered [ 6661.235488] LNet: 360896:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6661.237431] LNetError: 360896:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6661.246684] LNet: Removed LNI 192.168.201.31@tcp [ 6661.475100] Key type .llcrypt unregistered [ 6661.476129] Key type ._llcrypt unregistered [ 6661.722726] Key type ._llcrypt registered [ 6661.723785] Key type .llcrypt registered [ 6661.902359] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6661.908747] alg: No test for adler32 (adler32-zlib) [ 6662.754905] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6662.831472] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6664.407145] Key type lgssc registered [ 6664.774625] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6668.399125] Lustre: DEBUG MARKER: Iteration 38 [ 6668.515799] LustreError: 361714:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6668.516119] LustreError: 361715:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6668.521277] LustreError: 361714:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6669.578290] Lustre: Mounted lustre-client [ 6670.109127] LustreError: 361821:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f44b88800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6670.113733] LustreError: 361821:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6670.115582] LustreError: 361822:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6670.147960] Lustre: Unmounted lustre-client [ 6670.150158] Lustre: Skipped 1 previous similar message [ 6671.171318] Key type lgssc unregistered [ 6671.284667] LNet: 362063:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6671.288115] LNetError: 362063:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6671.295134] LNet: Removed LNI 192.168.201.31@tcp [ 6671.548111] Key type .llcrypt unregistered [ 6671.549600] Key type ._llcrypt unregistered [ 6671.841172] Key type ._llcrypt registered [ 6671.842066] Key type .llcrypt registered [ 6672.091644] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6672.098590] alg: No test for adler32 (adler32-zlib) [ 6672.966118] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6673.064914] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6674.655143] Key type lgssc registered [ 6675.021902] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6678.400414] Lustre: DEBUG MARKER: Iteration 39 [ 6678.497781] LustreError: 362881:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6678.497819] LustreError: 362882:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6678.502205] LustreError: 362881:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6679.548070] Lustre: Mounted lustre-client [ 6679.550241] Lustre: Skipped 1 previous similar message [ 6679.997120] LustreError: 362989:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f47f23000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6680.001984] LustreError: 362989:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6680.005540] LustreError: 362989:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6680.018402] Lustre: Unmounted lustre-client [ 6680.875835] Key type lgssc unregistered [ 6680.986311] LNet: 363231:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6680.990027] LNetError: 363231:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6680.999752] LNet: Removed LNI 192.168.201.31@tcp [ 6681.216106] Key type .llcrypt unregistered [ 6681.217293] Key type ._llcrypt unregistered [ 6681.489682] Key type ._llcrypt registered [ 6681.491873] Key type .llcrypt registered [ 6681.677578] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6681.682596] alg: No test for adler32 (adler32-zlib) [ 6682.525398] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6682.604212] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6684.183139] Key type lgssc registered [ 6684.552664] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6688.004201] Lustre: DEBUG MARKER: Iteration 40 [ 6688.120321] LustreError: 364050:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6688.120372] LustreError: 364049:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6688.126051] LustreError: 364050:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6689.167786] Lustre: Mounted lustre-client [ 6689.581864] LustreError: 364154:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f436cc800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6689.588862] LustreError: 364154:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6689.592412] LustreError: 364154:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6689.603069] Lustre: Unmounted lustre-client [ 6690.495235] Key type lgssc unregistered [ 6690.602496] LNet: 364395:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6690.604808] LNetError: 364395:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6690.611535] LNet: Removed LNI 192.168.201.31@tcp [ 6690.815109] Key type .llcrypt unregistered [ 6690.816138] Key type ._llcrypt unregistered [ 6691.027669] Key type ._llcrypt registered [ 6691.029119] Key type .llcrypt registered [ 6691.236153] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6691.243609] alg: No test for adler32 (adler32-zlib) [ 6692.096491] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6692.176417] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6693.759151] Key type lgssc registered [ 6694.184540] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6698.129941] Lustre: DEBUG MARKER: Iteration 41 [ 6698.251058] LustreError: 365213:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6698.251393] LustreError: 365214:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6698.257501] LustreError: 365213:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6699.314651] Lustre: Mounted lustre-client [ 6699.316662] Lustre: Skipped 1 previous similar message [ 6699.836668] LustreError: 365316:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f45fde000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6699.842196] LustreError: 365316:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6699.869488] Lustre: Unmounted lustre-client [ 6700.827096] Key type lgssc unregistered [ 6700.935522] LNet: 365558:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6700.937653] LNetError: 365558:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6700.948808] LNet: Removed LNI 192.168.201.31@tcp [ 6701.182093] Key type .llcrypt unregistered [ 6701.183061] Key type ._llcrypt unregistered [ 6701.423841] Key type ._llcrypt registered [ 6701.425067] Key type .llcrypt registered [ 6701.627589] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6701.632894] alg: No test for adler32 (adler32-zlib) [ 6702.481919] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6702.568852] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6704.151126] Key type lgssc registered [ 6704.564264] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6708.118192] Lustre: DEBUG MARKER: Iteration 42 [ 6708.222162] LustreError: 366375:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6708.223083] LustreError: 366376:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6708.229401] LustreError: 366375:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6709.282821] Lustre: Mounted lustre-client [ 6709.284016] Lustre: Skipped 1 previous similar message [ 6709.758326] LustreError: 366481:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f6096e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6709.761217] LustreError: 366482:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 6709.761831] LustreError: 366481:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6709.782511] Lustre: Unmounted lustre-client [ 6710.708642] Key type lgssc unregistered [ 6710.830699] LNet: 366724:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6710.834622] LNetError: 366724:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6710.843901] LNet: Removed LNI 192.168.201.31@tcp [ 6711.095143] Key type .llcrypt unregistered [ 6711.096934] Key type ._llcrypt unregistered [ 6711.365046] Key type ._llcrypt registered [ 6711.366162] Key type .llcrypt registered [ 6711.552830] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6711.559270] alg: No test for adler32 (adler32-zlib) [ 6712.428246] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6712.527615] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6714.119199] Key type lgssc registered [ 6714.560478] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6717.755034] Lustre: DEBUG MARKER: Iteration 43 [ 6717.872050] LustreError: 367542:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6717.872107] LustreError: 367541:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6717.877305] LustreError: 367542:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6718.931337] Lustre: Mounted lustre-client [ 6718.932505] Lustre: Skipped 1 previous similar message [ 6719.369336] LustreError: 367649:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f7f0f2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6719.372852] LustreError: 367649:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6719.375672] LustreError: 367648:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 6719.379210] LustreError: 367648:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 6719.391111] Lustre: Unmounted lustre-client [ 6720.330685] Key type lgssc unregistered [ 6720.459635] LNet: 367889:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6720.463204] LNetError: 367889:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6720.470308] LNet: Removed LNI 192.168.201.31@tcp [ 6720.689101] Key type .llcrypt unregistered [ 6720.690185] Key type ._llcrypt unregistered [ 6720.961230] Key type ._llcrypt registered [ 6720.962327] Key type .llcrypt registered [ 6721.140849] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6721.146378] alg: No test for adler32 (adler32-zlib) [ 6721.990662] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6722.065247] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6723.647156] Key type lgssc registered [ 6724.046982] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6727.661300] Lustre: DEBUG MARKER: Iteration 44 [ 6727.774979] LustreError: 368707:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6727.775041] LustreError: 368708:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6727.782259] LustreError: 368707:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6728.833758] Lustre: Mounted lustre-client [ 6728.835736] Lustre: Skipped 1 previous similar message [ 6729.263442] LustreError: 368815:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f6b2d0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6729.266788] LustreError: 368816:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6729.267315] LustreError: 368815:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6729.286052] Lustre: Unmounted lustre-client [ 6729.287145] Lustre: Skipped 1 previous similar message [ 6730.130708] Key type lgssc unregistered [ 6730.250470] LNet: 369056:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6730.253416] LNetError: 369056:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6730.260863] LNet: Removed LNI 192.168.201.31@tcp [ 6730.500124] Key type .llcrypt unregistered [ 6730.501340] Key type ._llcrypt unregistered [ 6730.845213] Key type ._llcrypt registered [ 6730.846224] Key type .llcrypt registered [ 6730.975688] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6730.980327] alg: No test for adler32 (adler32-zlib) [ 6731.829693] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6731.908586] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6733.495165] Key type lgssc registered [ 6733.910869] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6737.184557] Lustre: DEBUG MARKER: Iteration 45 [ 6737.282157] LustreError: 369873:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6737.282503] LustreError: 369875:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6737.287678] LustreError: 369873:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6738.344354] Lustre: Mounted lustre-client [ 6738.345871] Lustre: Skipped 1 previous similar message [ 6738.865601] LustreError: 369985:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f48879800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6738.869695] LustreError: 369985:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6738.870284] LustreError: 369986:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6738.874050] LustreError: 369986:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6738.884364] Lustre: Unmounted lustre-client [ 6739.795124] Key type lgssc unregistered [ 6739.910475] LNet: 370227:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6739.912775] LNetError: 370227:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6739.921612] LNet: Removed LNI 192.168.201.31@tcp [ 6740.153115] Key type .llcrypt unregistered [ 6740.155221] Key type ._llcrypt unregistered [ 6740.425493] Key type ._llcrypt registered [ 6740.429430] Key type .llcrypt registered [ 6740.624256] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6740.629298] alg: No test for adler32 (adler32-zlib) [ 6741.472859] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6741.548682] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6743.127117] Key type lgssc registered [ 6743.461357] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6746.575432] Lustre: DEBUG MARKER: Iteration 46 [ 6746.691226] LustreError: 371045:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6746.691309] LustreError: 371044:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6746.698601] LustreError: 371045:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6747.746893] Lustre: Mounted lustre-client [ 6747.748977] Lustre: Skipped 1 previous similar message [ 6748.175579] LustreError: 371150:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f6b359000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6748.179183] LustreError: 371150:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6748.181729] LustreError: 371151:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6748.183595] LustreError: 371151:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6748.209143] Lustre: Unmounted lustre-client [ 6749.034310] Key type lgssc unregistered [ 6749.140401] LNet: 371392:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6749.142791] LNetError: 371392:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6749.152617] LNet: Removed LNI 192.168.201.31@tcp [ 6749.379123] Key type .llcrypt unregistered [ 6749.380124] Key type ._llcrypt unregistered [ 6749.690192] Key type ._llcrypt registered [ 6749.691210] Key type .llcrypt registered [ 6749.823247] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6749.829809] alg: No test for adler32 (adler32-zlib) [ 6750.678925] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6750.758158] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6752.343125] Key type lgssc registered [ 6752.699889] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6755.921357] Lustre: DEBUG MARKER: Iteration 47 [ 6756.048053] LustreError: 372211:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6756.048435] LustreError: 372212:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6756.055533] LustreError: 372211:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6757.097749] Lustre: Mounted lustre-client [ 6757.099619] Lustre: Skipped 1 previous similar message [ 6757.570553] LustreError: 372318:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f43fff000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6757.574570] LustreError: 372319:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6757.575823] LustreError: 372318:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6757.591468] Lustre: Unmounted lustre-client [ 6758.550200] Key type lgssc unregistered [ 6758.655519] LNet: 372560:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6758.657816] LNetError: 372560:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6758.666760] LNet: Removed LNI 192.168.201.31@tcp [ 6758.907120] Key type .llcrypt unregistered [ 6758.908241] Key type ._llcrypt unregistered [ 6759.176613] Key type ._llcrypt registered [ 6759.178795] Key type .llcrypt registered [ 6759.363415] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6759.368264] alg: No test for adler32 (adler32-zlib) [ 6760.229386] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6760.316750] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6761.903202] Key type lgssc registered [ 6762.365562] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6765.781123] Lustre: DEBUG MARKER: Iteration 48 [ 6765.890312] LustreError: 373378:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6765.890362] LustreError: 373377:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6765.895030] LustreError: 373378:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6766.945094] Lustre: Mounted lustre-client [ 6766.946757] Lustre: Skipped 1 previous similar message [ 6767.430549] LustreError: 373485:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f58360800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6767.432827] LustreError: 373486:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6767.435876] LustreError: 373485:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6767.441779] LustreError: 373486:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 6767.446244] Lustre: Unmounted lustre-client [ 6768.374599] Key type lgssc unregistered [ 6768.495516] LNet: 373727:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6768.497731] LNetError: 373727:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6768.506694] LNet: Removed LNI 192.168.201.31@tcp [ 6768.743109] Key type .llcrypt unregistered [ 6768.744400] Key type ._llcrypt unregistered [ 6769.019702] Key type ._llcrypt registered [ 6769.021721] Key type .llcrypt registered [ 6769.211573] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6769.217958] alg: No test for adler32 (adler32-zlib) [ 6770.073493] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6770.153927] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6771.735149] Key type lgssc registered [ 6772.112942] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6775.199045] Lustre: DEBUG MARKER: Iteration 49 [ 6775.302355] LustreError: 374546:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6775.302405] LustreError: 374547:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6775.306398] LustreError: 374546:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6776.347269] Lustre: Mounted lustre-client [ 6776.348839] Lustre: Skipped 1 previous similar message [ 6776.790549] LustreError: 374654:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f4560b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6776.793541] LustreError: 374655:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6776.795098] LustreError: 374654:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6776.819480] Lustre: Unmounted lustre-client [ 6777.723752] Key type lgssc unregistered [ 6777.838491] LNet: 374896:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6777.840714] LNetError: 374896:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6777.848611] LNet: Removed LNI 192.168.201.31@tcp [ 6778.063112] Key type .llcrypt unregistered [ 6778.064237] Key type ._llcrypt unregistered [ 6778.311717] Key type ._llcrypt registered [ 6778.313946] Key type .llcrypt registered [ 6778.491890] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6778.496316] alg: No test for adler32 (adler32-zlib) [ 6779.345546] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6779.420443] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6780.999154] Key type lgssc registered [ 6781.363996] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6784.599712] Lustre: DEBUG MARKER: Iteration 50 [ 6784.710251] LustreError: 375713:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6784.710312] LustreError: 375715:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6784.715336] LustreError: 375713:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6785.770189] Lustre: Mounted lustre-client [ 6785.771938] Lustre: Skipped 1 previous similar message [ 6786.246523] LustreError: 375822:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f45fde000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6786.250398] LustreError: 375821:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6786.250846] LustreError: 375822:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6786.268607] Lustre: Unmounted lustre-client [ 6787.126736] Key type lgssc unregistered [ 6787.228535] LNet: 376062:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6787.231296] LNetError: 376062:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6787.238682] LNet: Removed LNI 192.168.201.31@tcp [ 6787.461091] Key type .llcrypt unregistered [ 6787.462161] Key type ._llcrypt unregistered [ 6787.694922] Key type ._llcrypt registered [ 6787.696164] Key type .llcrypt registered [ 6787.915781] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6787.921252] alg: No test for adler32 (adler32-zlib) [ 6788.771299] Lustre: Lustre: Build Version: 2.16.61_1_gaa570bc [ 6788.851269] LNet: Added LNI 192.168.201.31@tcp [8/256/0/180] [ 6790.431135] Key type lgssc registered [ 6790.850719] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6794.963163] Lustre: Mounted lustre-client [ 6797.281610] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 21:31:19 (1763087479) [ 6813.151173] Lustre: 377392:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763087480/real 1763087480] req@ffff9b8f42ca0e00 x1848731208000640/t0(0) o36->lustre-MDT0000-mdc-ffff9b8f44f63800@192.168.201.131@tcp:12/10 lens 496/440 e 0 to 1 dl 1763087496 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6813.163723] Lustre: lustre-MDT0000-mdc-ffff9b8f44f63800: Connection to lustre-MDT0000 (at 192.168.201.131@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6813.177573] Lustre: lustre-MDT0000-mdc-ffff9b8f44f63800: Connection restored to 192.168.201.131@tcp (at 192.168.201.131@tcp) [ 6829.535183] Lustre: 377392:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763087496/real 1763087496] req@ffff9b8f42ca0e00 x1848731208000640/t0(0) o36->lustre-MDT0000-mdc-ffff9b8f44f63800@192.168.201.131@tcp:12/10 lens 496/440 e 0 to 1 dl 1763087512 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6829.549046] Lustre: lustre-MDT0000-mdc-ffff9b8f44f63800: Connection to lustre-MDT0000 (at 192.168.201.131@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6829.560326] Lustre: lustre-MDT0000-mdc-ffff9b8f44f63800: Connection restored to 192.168.201.131@tcp (at 192.168.201.131@tcp) [ 6845.919137] Lustre: 377392:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763087512/real 1763087512] req@ffff9b8f42ca0e00 x1848731208000640/t0(0) o36->lustre-MDT0000-mdc-ffff9b8f44f63800@192.168.201.131@tcp:12/10 lens 496/440 e 0 to 1 dl 1763087528 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6845.925546] Lustre: lustre-MDT0000-mdc-ffff9b8f44f63800: Connection to lustre-MDT0000 (at 192.168.201.131@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6845.933619] Lustre: lustre-MDT0000-mdc-ffff9b8f44f63800: Connection restored to 192.168.201.131@tcp (at 192.168.201.131@tcp) [ 6862.303118] Lustre: 377392:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763087529/real 1763087529] req@ffff9b8f42ca0e00 x1848731208000640/t0(0) o36->lustre-MDT0000-mdc-ffff9b8f44f63800@192.168.201.131@tcp:12/10 lens 496/440 e 0 to 1 dl 1763087545 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6862.316545] Lustre: lustre-MDT0000-mdc-ffff9b8f44f63800: Connection to lustre-MDT0000 (at 192.168.201.131@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6862.325276] Lustre: lustre-MDT0000-mdc-ffff9b8f44f63800: Connection restored to 192.168.201.131@tcp (at 192.168.201.131@tcp) [ 6862.828525] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 21:32:25 (1763087545) [ 6863.331112] Lustre: DEBUG MARKER: SKIP: sanityn test_111 needs >= 2 MDTs [ 6863.905297] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 21:32:26 (1763087546) [ 6864.383385] Lustre: DEBUG MARKER: SKIP: sanityn test_112 We need at least 2 MDTs for this test [ 6864.942413] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 21:32:27 (1763087547) [ 6866.970610] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 21:32:29 (1763087549) [ 6867.437449] Lustre: DEBUG MARKER: SKIP: sanityn test_114 We need at least 2 MDTs for this test [ 6867.934338] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 21:32:30 (1763087550) [ 6868.424149] Lustre: DEBUG MARKER: SKIP: sanityn test_115 ldiskfs only test [ 6868.994576] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 21:32:31 (1763087551) [ 6869.510918] Lustre: DEBUG MARKER: SKIP: sanityn test_116 needs >= 2 MDTs [ 6870.060148] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 21:32:32 (1763087552) [ 6888.927133] Lustre: 376254:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763087555/real 1763087555] req@ffff9b8f6799a680 x1848731208025728/t0(0) o4->lustre-OST0000-osc-ffff9b8f44f63800@192.168.201.131@tcp:6/4 lens 4584/448 e 0 to 1 dl 1763087571 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 6888.927184] Lustre: lustre-OST0000-osc-ffff9b8f44f63800: Connection to lustre-OST0000 (at 192.168.201.131@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6888.934525] Lustre: 376254:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 6905.311100] Lustre: 376253:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763087572/real 1763087572] req@ffff9b8f67999500 x1848731208026496/t0(0) o4->lustre-OST0000-osc-ffff9b8f44f63800@192.168.201.131@tcp:6/4 lens 4584/448 e 0 to 1 dl 1763087588 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 6905.311203] Lustre: lustre-OST0000-osc-ffff9b8f44f63800: Connection to lustre-OST0000 (at 192.168.201.131@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6905.321188] Lustre: 376253:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [ 6905.331475] Lustre: lustre-OST0000-osc-ffff9b8f44f63800: Connection restored to 192.168.201.131@tcp (at 192.168.201.131@tcp) [ 6921.695184] Lustre: 376252:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763087588/real 1763087588] req@ffff9b8f7d936680 x1848731208025856/t0(0) o4->lustre-OST0000-osc-ffff9b8f44f63800@192.168.201.131@tcp:6/4 lens 4584/448 e 0 to 1 dl 1763087604 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 6921.695218] Lustre: lustre-OST0000-osc-ffff9b8f44f63800: Connection to lustre-OST0000 (at 192.168.201.131@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6921.702616] Lustre: 376252:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 1 previous similar message [ 6921.712835] Lustre: lustre-OST0000-osc-ffff9b8f44f63800: Connection restored to 192.168.201.131@tcp (at 192.168.201.131@tcp) [ 6935.668903] Lustre: DEBUG MARKER: oleg131-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9b8f44f63800.ost_server_uuid 50 [ 6936.220882] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9b8f44f63800.ost_server_uuid in FULL state after 0 sec [ 6936.795323] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 6937.430425] Lustre: DEBUG MARKER: == sanityn test complete, duration 6683 sec ============== 21:33:39 (1763087619) [ 6937.988094] Lustre: DEBUG MARKER: === sanityn: start cleanup 21:33:40 (1763087620) === [ 6983.725128] LustreError: 381457:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f481ea800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6983.730231] LustreError: 381457:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6983.741086] Lustre: Unmounted lustre-client [ 6984.882986] Lustre: DEBUG MARKER: === sanityn: finish cleanup 21:34:27 (1763087667) === [ 6985.208392] LustreError: 381757:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9b8f44f63800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6985.211682] LustreError: 381757:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 6985.216849] LustreError: 381757:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6985.218569] LustreError: 381757:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 6985.240117] Lustre: Unmounted lustre-client [ 7011.336408] Key type lgssc unregistered [ 7011.445381] LNet: 382239:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7011.449211] LNetError: 382239:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7011.455718] LNet: Removed LNI 192.168.201.31@tcp [ 7011.685087] Key type .llcrypt unregistered [ 7011.686231] Key type ._llcrypt unregistered