[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.2-1.fc38 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 444656047 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f5b50-0x000f5b5f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5970 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1BB7 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1A53 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001A13 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1AC7 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1B57 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1B8F 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1a53-0xbffe1ac6] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a52] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1ac7-0xbffe1b56] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1b57-0xbffe1b8e] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1b8f-0xbffe1bb6] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524584K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001010] APIC: Switch to symmetric I/O mode setup [ 0.002329] x2apic enabled [ 0.003007] Switched APIC routing to physical x2apic. [ 0.004010] kvm-guest: setup PV IPIs [ 0.007000] ..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.007020] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.008011] pid_max: default: 32768 minimum: 301 [ 0.010053] LSM: Security Framework initializing [ 0.011045] Yama: becoming mindful. [ 0.012029] SELinux: Initializing. [ 0.012860] *** VALIDATE selinux *** [ 0.020079] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.024424] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.025146] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.026105] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027126] *** VALIDATE tmpfs *** [ 0.029138] *** VALIDATE proc *** [ 0.030248] *** VALIDATE cgroup *** [ 0.031012] *** VALIDATE cgroup2 *** [ 0.033103] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.034164] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.035008] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.036027] Spectre V2 : User space: Vulnerable [ 0.037009] Speculative Store Bypass: Vulnerable [ 0.040151] debug: unmapping init [mem 0xffffffffa7e59000-0xffffffffa7e60fff] [ 0.042143] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.043689] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.044026] ... version: 2 [ 0.045014] ... bit width: 48 [ 0.046013] ... generic registers: 4 [ 0.047014] ... value mask: 0000ffffffffffff [ 0.048019] ... max period: 00007fffffffffff [ 0.049020] ... fixed-purpose events: 3 [ 0.050013] ... event mask: 000000070000000f [ 0.051315] rcu: Hierarchical SRCU implementation. [ 0.053569] smp: Bringing up secondary CPUs ... [ 0.054511] x86: Booting SMP configuration: [ 0.055029] .... node #0, CPUs: #1 #2 #3 [ 0.058256] smp: Brought up 1 node, 4 CPUs [ 0.060015] smpboot: Max logical packages: 1 [ 0.061017] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.144886] node 0 deferred pages initialised in 81ms [ 0.148178] devtmpfs: initialized [ 0.149214] x86/mm: Memory block size: 128MB [ 0.151931] gcov: version magic: 0x41383552 [ 0.154285] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.155078] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.157331] pinctrl core: initialized pinctrl subsystem [ 0.159206] [ 0.159850] ************************************************************* [ 0.162015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.165014] ** ** [ 0.167012] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.169012] ** ** [ 0.171012] ** This means that this kernel is built to expose internal ** [ 0.173012] ** IOMMU data structures, which may compromise security on ** [ 0.176014] ** your system. ** [ 0.178012] ** ** [ 0.180016] ** If you see this message and you are not debugging the ** [ 0.182012] ** kernel, report this immediately to your vendor! ** [ 0.184012] ** ** [ 0.186020] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.188012] ************************************************************* [ 0.190663] NET: Registered protocol family 16 [ 0.192426] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.195071] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.197054] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.200033] cpuidle: using governor menu [ 0.201683] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.204628] PCI: Using configuration type 1 for base access [ 0.207155] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.217111] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.220016] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.223196] cryptd: max_cpu_qlen set to 1000 [ 0.226640] ACPI: Added _OSI(Module Device) [ 0.228012] ACPI: Added _OSI(Processor Device) [ 0.230014] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.231014] ACPI: Added _OSI(Processor Aggregator Device) [ 0.235439] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.241372] ACPI: Interpreter enabled [ 0.242062] ACPI: PM: (supports S0 S3 S4 S5) [ 0.243014] ACPI: Using IOAPIC for interrupt routing [ 0.244117] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.246350] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.256326] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.258039] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.260020] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.263085] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.268066] acpiphp: Slot [2] registered [ 0.269098] acpiphp: Slot [3] registered [ 0.270110] acpiphp: Slot [4] registered [ 0.272104] acpiphp: Slot [5] registered [ 0.273135] acpiphp: Slot [6] registered [ 0.275125] acpiphp: Slot [7] registered [ 0.276109] acpiphp: Slot [8] registered [ 0.277102] acpiphp: Slot [9] registered [ 0.278072] acpiphp: Slot [10] registered [ 0.279038] acpiphp: Slot [11] registered [ 0.279980] acpiphp: Slot [12] registered [ 0.281103] acpiphp: Slot [13] registered [ 0.283131] acpiphp: Slot [14] registered [ 0.284097] acpiphp: Slot [15] registered [ 0.285079] acpiphp: Slot [16] registered [ 0.287095] acpiphp: Slot [17] registered [ 0.288090] acpiphp: Slot [18] registered [ 0.290131] acpiphp: Slot [19] registered [ 0.291090] acpiphp: Slot [20] registered [ 0.292119] acpiphp: Slot [21] registered [ 0.294103] acpiphp: Slot [22] registered [ 0.295100] acpiphp: Slot [23] registered [ 0.297113] acpiphp: Slot [24] registered [ 0.298108] acpiphp: Slot [25] registered [ 0.299106] acpiphp: Slot [26] registered [ 0.301126] acpiphp: Slot [27] registered [ 0.302101] acpiphp: Slot [28] registered [ 0.304116] acpiphp: Slot [29] registered [ 0.305064] acpiphp: Slot [30] registered [ 0.306000] acpiphp: Slot [31] registered [ 0.308083] PCI host bridge to bus 0000:00 [ 0.309018] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.312023] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.314021] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.316022] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.318028] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.320030] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.322182] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.326217] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.330460] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.337000] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.340578] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.343023] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.344012] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.346017] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.348481] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.350638] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.353048] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.355574] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.359015] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.367629] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.371017] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.375668] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.381018] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.386018] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.395016] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.405496] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.412016] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.416015] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.430014] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.438165] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.440411] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.443387] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.445406] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.448187] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.452727] iommu: Default domain type: Passthrough [ 0.454392] SCSI subsystem initialized [ 0.455130] ACPI: bus type USB registered [ 0.457140] usbcore: registered new interface driver usbfs [ 0.459116] usbcore: registered new interface driver hub [ 0.461080] usbcore: registered new device driver usb [ 0.462190] pps_core: LinuxPPS API ver. 1 registered [ 0.464016] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.467056] PTP clock support registered [ 0.469047] EDAC MC: Ver: 3.0.0 [ 0.470117] PCI: Using ACPI for IRQ routing [ 0.471688] NetLabel: Initializing [ 0.473014] NetLabel: domain hash size = 128 [ 0.474009] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.476082] NetLabel: unlabeled traffic allowed by default [ 0.480065] vgaarb: loaded [ 0.481239] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.483013] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.489149] clocksource: Switched to clocksource kvm-clock [ 0.594877] VFS: Disk quotas dquot_6.6.0 [ 0.596449] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.598513] *** VALIDATE ramfs *** [ 0.599710] *** VALIDATE hugetlbfs *** [ 0.601135] pnp: PnP ACPI init [ 0.603226] pnp: PnP ACPI: found 6 devices [ 0.626059] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.629699] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.632029] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.634397] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.636229] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.637849] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.640319] NET: Registered protocol family 2 [ 0.643222] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.648159] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.650978] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.656057] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.660616] TCP: Hash tables configured (established 65536 bind 65536) [ 0.662915] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.666475] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.669178] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.672352] NET: Registered protocol family 1 [ 0.674917] RPC: Registered named UNIX socket transport module. [ 0.677007] RPC: Registered udp transport module. [ 0.678699] RPC: Registered tcp transport module. [ 0.680320] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.683157] NET: Registered protocol family 44 [ 0.685058] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.687737] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.689833] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.692089] PCI: CLS 0 bytes, default 64 [ 0.693768] Unpacking initramfs... [ 2.157485] debug: unmapping init [mem 0xffff8cd03cc64000-0xffff8cd03ffcffff] [ 2.164071] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.166906] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.171472] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.676647] Initialise system trusted keyrings [ 2.678463] Key type blacklist registered [ 2.680148] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.689799] zbud: loaded [ 2.697655] *** VALIDATE nfs *** [ 2.699097] *** VALIDATE nfs4 *** [ 2.700852] pstore: using deflate compression [ 2.703925] Platform Keyring initialized [ 2.811891] NET: Registered protocol family 38 [ 2.813975] Key type asymmetric registered [ 2.815561] Asymmetric key parser 'x509' registered [ 2.817209] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.820231] io scheduler mq-deadline registered [ 2.822090] io scheduler kyber registered [ 2.823775] io scheduler bfq registered [ 2.825598] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.829103] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.831777] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.834613] ACPI: Power Button [PWRF] [ 2.930160] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.027820] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.122959] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.162899] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.195797] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.206629] Non-volatile memory driver v1.3 [ 3.207889] Linux agpgart interface v0.103 [ 3.244398] virtio_blk virtio1: [vda] 133848 512-byte logical blocks (68.5 MB/65.4 MiB) [ 3.248086] vda: detected capacity change from 0 to 68530176 [ 3.264347] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.267340] vdb: detected capacity change from 0 to 1073741824 [ 3.274847] libphy: Fixed MDIO Bus: probed [ 3.285305] usbcore: registered new interface driver usbserial_generic [ 3.287895] usbserial: USB Serial support registered for generic [ 3.289800] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.293769] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.295639] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.298266] mousedev: PS/2 mouse device common for all mice [ 3.301934] rtc_cmos 00:05: RTC can wake from S4 [ 3.304581] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.307478] rtc_cmos 00:05: registered as rtc0 [ 3.309204] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.314569] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.314913] intel_pstate: CPU model not supported [ 3.318920] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.321085] hid: raw HID events driver (C) Jiri Kosina [ 3.324725] usbcore: registered new interface driver usbhid [ 3.326691] usbhid: USB HID core driver [ 3.328566] drop_monitor: Initializing network drop monitor service [ 3.330866] Initializing XFRM netlink socket [ 3.332895] NET: Registered protocol family 10 [ 3.336082] Segment Routing with IPv6 [ 3.337222] NET: Registered protocol family 17 [ 3.339079] mpls_gso: MPLS GSO support [ 3.343752] RAS: Correctable Errors collector initialized. [ 3.345731] AVX version of gcm_enc/dec engaged. [ 3.347481] AES CTR mode by8 optimization enabled [ 3.441195] sched_clock: Marking stable (3441161604, 0)->(4316930808, -875769204) [ 3.444802] registered taskstats version 1 [ 3.447485] Loading compiled-in X.509 certificates [ 3.450376] zswap: loaded using pool lzo/zbud [ 3.475890] Key type big_key registered [ 3.494822] Key type encrypted registered [ 3.496614] ima: No TPM chip found, activating TPM-bypass! [ 3.503473] ima: Allocated hash algorithm: sha1 [ 3.505132] ima: No architecture policies found [ 3.513948] evm: Initialising EVM extended attributes: [ 3.515751] evm: security.selinux [ 3.516965] evm: security.ima [ 3.518077] evm: security.capability [ 3.519356] evm: HMAC attrs: 0x1 [ 3.522308] rtc_cmos 00:05: setting system clock to 2025-11-17 02:05:27 UTC (1763345127) [ 3.528312] debug: unmapping init [mem 0xffffffffa8e03000-0xffffffffa8ffffff] [ 3.531266] debug: unmapping init [mem 0xffffffffa7b82000-0xffffffffa7e58fff] [ 3.541149] Write protecting the kernel read-only data: 28672k [ 3.544541] debug: unmapping init [mem 0xffffffffa6203000-0xffffffffa63fffff] [ 3.547693] debug: unmapping init [mem 0xffffffffa6b14000-0xffffffffa6bfffff] [ 3.580488] 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.591447] systemd[1]: Detected virtualization kvm. [ 3.593554] systemd[1]: Detected architecture x86-64. [ 3.595585] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.626758] systemd[1]: No hostname configured. [ 3.628482] systemd[1]: Set hostname to . [ 3.630674] random: systemd: uninitialized urandom read (16 bytes read) [ 3.633188] systemd[1]: Initializing machine ID from random generator. [ 3.777536] random: systemd: uninitialized urandom read (16 bytes read) [ 3.780594] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 3.786412] random: systemd: uninitialized urandom read (16 bytes read) [ 3.789198] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 3.794300] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Setup Virtual Console... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Timers. [ OK ] Reached target Swap. Starting Apply Kernel Variables... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.420392] device-mapper: uevent: version 1.0.3 [ 4.422629] 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.145644] virtio_net virtio0 ens2: renamed from eth0 [ 5.193240] scsi host0: ata_piix [ 5.279467] scsi host1: ata_piix [ 5.281119] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.283769] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 5.518637] dracut-initqueue[501]: RTNETLINK answers: File exists [ 10.035289] random: crng init done [ 10.036721] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 12.105787] 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 Remote File Systems. [ OK ] Stopped target Initrd Root Device. [ 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 System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 14.304921] printk: systemd: 26 output lines suppressed due to ratelimiting [ 15.129480] SELinux: Disabled at runtime. [ 15.257993] 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) [ 15.270644] systemd[1]: Detected virtualization kvm. [ 15.272827] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 16.528546] systemd[1]: initrd-switch-root.service: Succeeded. [ 16.531921] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 16.545358] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 16.551442] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 16.560647] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 16.577742] systemd[1]: Starting Journal Service... Starting Journal Service... [ 16.586280] systemd[1]: Created slice system-getty.slice. [ OK ] Created slice system-getty.slice. [ OK ] Listening on udev Control Socket. Starting Remount Root and Kernel File Systems... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Created slice User and Session Slice. [ OK ] Listening on udev Kernel 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 ] Reached target rpc_pipefs.target. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on Process Core Dump Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Created slice system-serial\x2dgetty.slice. Starting udev Coldplug all Devices... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. Mounting Kernel Debug File System... Activating swap /dev/disk/by-label/SWAP... Mounting Huge Pages File System... [ OK ] Listening on initctl Compatibility Named Pipe. Mounting POSIX Message Queue File System... [ 17.076686] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting Apply Kernel Variables... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Slices. [ OK ] Stopped target Initrd File Systems. [ OK ] Started Journal Service. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. [ 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 Create Static Device Nodes in /dev. [ OK ] Started udev Coldplug all Devices. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 17.824522] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 18.630217] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 18.695398] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 19.390229] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 19.475372] EDAC sbridge: Ver: 1.1.2 [ 22.769330] Key type dns_resolver registered [ 23.337250] NFS: Registering the id_resolver key type [ 23.343519] Key type id_resolver registered [ 23.347057] Key type id_legacy registered [* ] A start job is running for Configur…-only root support (7s / no limit) [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ 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 Hardware RNG Entropy Gatherer Daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Restore /run/initramfs on shutdown... [ OK ] Reached target sshd-keygen.target. [ OK ] Started irqbalance daemon. Starting Login Service... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting GSSAPI Proxy Daemon... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Login Service. [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg225-client login: [ 37.014500] hrtimer: interrupt took 24120836 ns [ 73.943989] libcfs: loading out-of-tree module taints kernel. [ 74.030920] Key type ._llcrypt registered [ 74.046860] Key type .llcrypt registered [ 74.769829] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 74.790496] alg: No test for adler32 (adler32-zlib) [ 76.384363] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 77.388773] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 79.143227] Key type lgssc registered [ 80.877388] Lustre: Echo OBD driver; http://www.lustre.org/ [ 190.517129] Lustre: Mounted lustre-client [ 195.561913] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 215.131953] Lustre: DEBUG MARKER: oleg225-client.virtnet: executing check_logdir /tmp/testlogs/ [ 216.033407] Lustre: lustre-OST0000-osc-ffff8cd09143a000: disconnect after 24s idle [ 220.521838] Lustre: DEBUG MARKER: oleg225-client.virtnet: executing yml_node [ 224.354063] Lustre: DEBUG MARKER: Client: 2.16.61.45 [ 226.890430] Lustre: DEBUG MARKER: MDS: 2.16.61.45 [ 229.596824] Lustre: DEBUG MARKER: OSS: 2.16.61.45 [ 231.295808] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Sun Nov 16 21:09:13 EST 2025 [ 246.476217] Lustre: DEBUG MARKER: excepting tests: 27 40a 102 [ 248.051421] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 249.471803] Lustre: DEBUG MARKER: === sanityn: start setup 21:09:32 (1763345372) === [ 249.993652] Lustre: Mounted lustre-client [ 253.475260] Lustre: DEBUG MARKER: oleg225-client.virtnet: executing check_config_client /mnt/lustre [ 270.253773] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 280.301919] Lustre: DEBUG MARKER: === sanityn: finish setup 21:10:02 (1763345402) === [ 282.381293] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 21:10:05 (1763345405) [ 289.864499] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 21:10:12 (1763345412) [ 296.297544] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 21:10:18 (1763345418) [ 303.006937] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 21:10:25 (1763345425) [ 309.900602] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 21:10:32 (1763345432) [ 317.048853] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 21:10:39 (1763345439) [ 323.858775] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 21:10:46 (1763345446) [ 331.419441] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 21:10:54 (1763345454) [ 333.167357] Lustre: DEBUG MARKER: SKIP: sanityn test_2f needs >= 2 MDTs [ 334.785625] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 21:10:57 (1763345457) [ 341.719678] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 21:11:04 (1763345464) [ 349.710993] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 21:11:11 (1763345471) [ 357.861443] Lustre: lustre-OST0001-osc-ffff8cd09143a000: disconnect after 22s idle [ 357.864344] Lustre: Skipped 1 previous similar message [ 358.432101] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 21:11:20 (1763345480) [ 367.854372] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 21:11:29 (1763345489) [ 373.222995] Lustre: lustre-OST0000-osc-ffff8cd091297000: disconnect after 22s idle [ 374.969321] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 21:11:37 (1763345497) [ 381.701395] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 21:11:44 (1763345504) [ 387.624595] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 21:11:50 (1763345510) [ 393.695533] Lustre: lustre-OST0001-osc-ffff8cd09143a000: disconnect after 22s idle [ 396.605211] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 21:11:58 (1763345518) [ 404.257917] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 21:12:06 (1763345526) [ 413.108604] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 21:12:15 (1763345535) [ 420.132949] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 21:12:22 (1763345542) [ 427.452907] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 21:12:30 (1763345550) [ 428.119852] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205272502304 file: /mnt/lustre/lockdir/lockfile=144115205272502302 [ 575.273506] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 21:14:58 (1763345698) [ 583.481858] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 21:15:05 (1763345705) [ 589.267309] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 21:15:12 (1763345712) [ 595.449762] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 21:15:18 (1763345718) [ 601.857406] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 21:15:24 (1763345724) [ 608.039500] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 21:15:30 (1763345730) [ 609.751190] Lustre: DEBUG MARKER: chmod [ 615.531211] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 21:15:37 (1763345737) [ 649.120145] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7528448KB free > 800000KB, increase MAXFREE (or reduce fs size) [ 663.829050] Lustre: DEBUG MARKER: == sanityn test 16a: 500 iterations of dual-mount fsx ==== 21:16:26 (1763345786) [ 720.879696] Lustre: DEBUG MARKER: == sanityn test 16b: 500 iterations of dual-mount fsx at small size ========================================================== 21:17:23 (1763345843) [ 751.719854] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 21:17:54 (1763345874) [ 754.029755] Lustre: DEBUG MARKER: SKIP: sanityn test_16c dio on ldiskfs only [ 755.716616] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 21:17:58 (1763345878) [ 802.799860] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 21:18:44 (1763345924) [ 809.837485] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 21:18:52 (1763345932) [ 811.310498] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 811.450600] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 811.557266] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 811.669951] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 811.757725] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 811.912837] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 811.990361] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 812.092841] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 812.143409] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 812.219095] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 812.318769] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 812.445995] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 812.530366] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 812.634336] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 812.720655] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 812.806323] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 812.874263] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 812.934786] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 813.029577] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 813.122068] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 813.242620] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 813.362787] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 813.427272] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 813.535717] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 813.618869] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 813.721361] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 813.842368] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 813.936624] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 813.988721] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 814.044557] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 814.114598] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 814.212890] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 814.320775] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 814.427041] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 814.530612] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 814.669208] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 814.759793] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 814.853700] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 814.958954] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 815.066444] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 815.204607] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 815.324442] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 815.424600] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 815.518909] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 815.628631] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 815.719942] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 815.809317] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 815.908940] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 816.004877] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 816.081893] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 816.192173] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 816.320724] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 816.392855] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 816.478067] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 816.600763] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 816.745738] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 816.847754] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 816.955250] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 817.054903] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 817.156559] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 817.244431] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 817.339853] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 817.435055] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 817.481357] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 817.546446] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 817.600121] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 817.684589] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 817.783453] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 817.831899] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 817.921803] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 818.001254] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 818.079213] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 818.131474] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 818.233543] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 818.338337] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 818.405700] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 818.489442] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 818.561855] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 818.629428] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 818.706848] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 818.793961] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 818.881639] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 818.977221] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 819.051566] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 819.117690] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 819.196603] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 819.259494] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 819.353631] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 819.436090] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 819.535160] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 819.636766] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 819.709633] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 819.778914] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 819.887766] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 819.933078] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 820.022678] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 820.086135] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 820.149119] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 820.225794] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 820.298203] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 820.372672] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 820.488190] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 820.587991] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 820.694578] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 820.800710] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 820.898528] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 821.010040] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 821.104826] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 821.196311] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 821.292722] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 821.405339] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 821.517098] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 821.627677] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 821.726404] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 821.810107] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 821.868713] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 821.980125] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 822.071638] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 822.145908] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 822.245840] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 822.313310] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 822.432492] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 822.511374] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 822.608986] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 822.692966] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 822.779596] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 822.900604] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 822.961558] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 823.037934] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 823.132210] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 823.221795] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 823.316775] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 823.416916] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 823.527480] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 823.617486] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 823.710286] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 823.779799] Lustre: lustre-OST0001-osc-ffff8cd091297000: disconnect after 20s idle [ 823.797939] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 823.883748] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 823.978498] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 824.036516] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 824.201482] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 824.291162] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 824.407786] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 824.505487] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 824.652096] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 824.731540] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 824.813418] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 824.921817] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 825.034849] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 825.129032] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 825.217511] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 825.291607] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 825.376793] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 825.484169] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 825.603574] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 825.676717] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 825.740711] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 825.823387] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 825.904419] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 826.006602] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 826.085751] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 826.157148] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 826.248541] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 826.325072] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 826.400744] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 826.470426] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 826.579587] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 826.650759] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 826.743887] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 826.841475] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 826.950789] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 827.050401] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 827.125293] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 827.218329] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 827.316718] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 827.391673] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 827.477160] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 827.573465] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 827.662200] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 827.768420] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 827.835180] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 827.921266] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 827.974595] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 828.064499] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 828.128469] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 828.198347] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 828.264524] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 828.334601] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 828.385659] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 828.438947] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 828.535982] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 828.614733] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 828.687674] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 828.750621] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 828.817307] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 828.874900] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 828.917193] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 828.983777] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 829.058624] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 829.102311] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 829.179798] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 829.241622] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 829.326449] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 829.376171] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 829.451332] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 829.533291] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 829.636581] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 829.737959] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 829.815215] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 829.906451] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 830.026374] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 830.094925] rw_seq_cst_vs_d (29490): drop_caches: 3 [ 837.363938] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 21:19:20 (1763345960) [ 837.916164] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 837.981031] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 838.017133] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 838.202599] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 838.327320] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 838.423938] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 838.574213] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 838.709666] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 838.780826] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 838.928419] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 839.009055] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 839.086899] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 839.170948] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 839.325408] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 839.475558] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 839.605566] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 839.638654] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 839.723394] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 839.756140] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 839.934126] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 839.975826] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 840.146734] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 840.232676] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 840.323658] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 840.484892] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 840.557231] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 840.659720] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 840.703534] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 840.828812] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 840.948843] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 841.021521] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 841.234644] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 841.315615] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 841.370408] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 841.502204] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 841.585589] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 841.681479] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 841.775401] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 841.878164] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 842.078817] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 842.170503] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 842.262536] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 842.356784] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 842.461631] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 842.683607] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 842.801262] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 843.041304] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 843.185134] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 843.291151] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 843.374836] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 843.450191] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 843.522839] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 843.672317] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 843.799669] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 843.869897] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 843.966526] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 844.176534] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 844.216334] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 844.360489] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 844.463397] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 844.668057] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 844.901405] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 844.928948] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 844.977310] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 845.036646] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 845.102085] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 845.193298] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 845.257036] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 845.342966] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 845.489905] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 845.614076] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 845.707473] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 845.833368] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 845.897626] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 845.966860] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 846.012804] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 846.054540] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 846.178417] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 846.313331] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 846.485223] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 846.621763] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 846.770156] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 846.818666] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 846.920973] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 847.034664] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 847.137440] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 847.254524] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 847.371625] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 847.530315] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 847.740790] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 847.917429] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 848.080765] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 848.168096] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 848.381454] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 848.584797] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 848.780779] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 848.905568] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 848.971453] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 849.109808] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 849.309270] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 849.461031] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 849.594055] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 849.703342] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 849.767229] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 849.839947] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 849.989931] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 850.048256] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 850.132736] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 850.317616] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 850.426920] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 850.481083] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 850.563648] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 850.665821] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 850.793746] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 850.943745] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 851.027924] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 851.201700] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 851.323965] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 851.486961] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 851.508933] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 851.591376] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 851.750878] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 851.781842] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 851.812632] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 851.912865] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 851.991867] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 852.019930] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 852.249238] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 852.392299] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 852.502727] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 852.632822] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 852.715990] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 852.789528] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 852.867497] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 852.950380] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 853.122202] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 853.177534] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 853.399450] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 853.457495] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 853.510977] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 853.615700] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 853.675522] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 853.771057] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 853.906157] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 854.031074] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 854.134598] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 854.252977] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 854.499147] Lustre: lustre-OST0000-osc-ffff8cd09143a000: disconnect after 22s idle [ 854.505489] Lustre: Skipped 1 previous similar message [ 854.507668] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 854.650698] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 854.758766] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 854.833988] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 854.884957] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 855.032047] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 855.123693] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 855.203115] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 855.248329] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 855.292283] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 855.361712] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 855.411635] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 855.541125] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 855.678151] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 855.748172] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 855.796923] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 855.851819] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 855.918954] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 855.999247] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 856.146246] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 856.247286] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 856.398591] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 856.503260] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 856.551280] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 856.761263] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 856.950441] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 856.983496] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 857.066160] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 857.151839] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 857.349285] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 857.491161] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 857.550569] rw_seq_cst_vs_d (30068): drop_caches: 3 [ 866.757895] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 21:19:49 (1763345989) [ 872.701260] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 21:19:55 (1763345995) [ 879.501356] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 21:20:01 (1763346001) [ 917.353520] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 21:20:40 (1763346040) [ 917.755362] bash (32520): drop_caches: 3 [ 920.931178] bash (32520): drop_caches: 3 [ 924.276965] bash (32520): drop_caches: 3 [ 927.666248] bash (32520): drop_caches: 3 [ 930.885588] bash (32520): drop_caches: 3 [ 933.988206] bash (32520): drop_caches: 3 [ 937.162487] bash (32520): drop_caches: 3 [ 940.265884] bash (32520): drop_caches: 3 [ 943.396557] bash (32520): drop_caches: 3 [ 946.604938] bash (32520): drop_caches: 3 [ 949.790842] bash (32520): drop_caches: 3 [ 952.964370] bash (32520): drop_caches: 3 [ 956.206938] bash (32520): drop_caches: 3 [ 959.418975] bash (32520): drop_caches: 3 [ 962.613740] bash (32520): drop_caches: 3 [ 967.465577] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 21:21:29 (1763346089) [ 977.244738] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 21:21:39 (1763346099) [ 1016.877271] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 21:22:19 (1763346139) [ 1019.532421] Lustre: DEBUG MARKER: SKIP: sanityn test_19 not cache-capable obdfilter [ 1021.079925] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 21:22:23 (1763346143) [ 1027.844871] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 21:22:30 (1763346150) [ 1028.579098] Lustre: lustre-OST0001-osc-ffff8cd09143a000: disconnect after 20s idle [ 1028.588431] Lustre: Skipped 1 previous similar message [ 1034.225899] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 21:22:36 (1763346156) [ 1102.964089] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 21:23:45 (1763346225) [ 1109.281541] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 21:23:51 (1763346231) [ 1114.529912] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 21:23:57 (1763346237) [ 1121.098525] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 21:24:03 (1763346243) [ 1122.690865] Lustre: DEBUG MARKER: SKIP: sanityn test_25b needs >= 2 MDTs [ 1124.305089] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 21:24:06 (1763346246) [ 1130.981231] Lustre: lustre-OST0001-osc-ffff8cd09143a000: disconnect after 21s idle [ 1130.984826] Lustre: Skipped 4 previous similar messages [ 1131.600795] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 21:24:13 (1763346253) [ 1140.072573] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 21:24:22 (1763346262) [ 1147.676200] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1149.298925] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 21:24:31 (1763346271) [ 1157.494547] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 21:24:40 (1763346280) [ 1157.769799] Lustre: *** cfs_fail_loc=314, val=0*** [ 1158.815332] Lustre: *** cfs_fail_loc=314, val=0*** [ 1158.817940] Lustre: Skipped 2 previous similar messages [ 1163.944748] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 21:24:46 (1763346286) [ 1176.683313] Lustre: *** cfs_fail_loc=314, val=0*** [ 1177.062478] Lustre: lustre-OST0000-osc-ffff8cd091297000: Connection to lustre-OST0000 (at 192.168.202.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1177.087532] LustreError: lustre-OST0000-osc-ffff8cd091297000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1177.104898] LustreError: 41767:0:(ldlm_resource.c:981:ldlm_resource_complain()) lustre-OST0000-osc-ffff8cd091297000: namespace resource [0x240000400:0x35:0x0].0x0 (ffff8cd091597300) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 1177.125625] Lustre: lustre-OST0000-osc-ffff8cd091297000: Connection restored to 192.168.202.125@tcp (at 192.168.202.125@tcp) [ 1182.981688] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 21:25:05 (1763346305) [ 1183.320444] LustreError: 42340:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 1186.367239] LustreError: 42340:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 1192.411563] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 21:25:14 (1763346314) [ 1200.037796] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 21:25:22 (1763346322) [ 1208.445320] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 1210.954664] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 21:25:33 (1763346333) [ 1213.115642] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 1215.340682] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 21:25:37 (1763346337) [ 1217.428929] Lustre: DEBUG MARKER: SKIP: sanityn test_33c needs >= 2 MDTs [ 1219.143104] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 21:25:41 (1763346341) [ 1220.969310] Lustre: DEBUG MARKER: SKIP: sanityn test_33d needs >= 2 MDTs [ 1223.135246] Lustre: lustre-OST0000-osc-ffff8cd091297000: disconnect after 22s idle [ 1223.138059] Lustre: Skipped 1 previous similar message [ 1223.514544] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 21:25:45 (1763346345) [ 1225.298770] Lustre: DEBUG MARKER: SKIP: sanityn test_33e needs >= 2 MDTs [ 1227.476958] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 21:25:49 (1763346349) [ 1284.581850] Lustre: lustre-OST0001-osc-ffff8cd09143a000: Connection to lustre-OST0001 (at 192.168.202.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1284.613212] LustreError: lustre-OST0001-osc-ffff8cd09143a000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1284.634994] LustreError: lustre-OST0001-osc-ffff8cd091297000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1284.638217] Lustre: lustre-OST0001-osc-ffff8cd09143a000: Connection restored to 192.168.202.125@tcp (at 192.168.202.125@tcp) [ 1298.885947] Lustre: lustre-OST0000-osc-ffff8cd09143a000: Connection to lustre-OST0000 (at 192.168.202.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1298.894117] Lustre: Skipped 1 previous similar message [ 1298.901977] LustreError: lustre-OST0000-osc-ffff8cd09143a000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1298.910693] Lustre: lustre-OST0000-osc-ffff8cd09143a000: Connection restored to 192.168.202.125@tcp (at 192.168.202.125@tcp) [ 1298.917480] Lustre: Skipped 1 previous similar message [ 1318.140850] Lustre: DEBUG MARKER: oleg225-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8cd091297000.ost_server_uuid 50 [ 1319.620350] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8cd091297000.ost_server_uuid in FULL state after 0 sec [ 1323.587078] Lustre: DEBUG MARKER: oleg225-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8cd091297000.ost_server_uuid 50 [ 1325.282927] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8cd091297000.ost_server_uuid in IDLE state after 0 sec [ 1330.842849] Lustre: DEBUG MARKER: oleg225-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8cd091297000.ost_server_uuid 50 [ 1332.033273] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8cd091297000.ost_server_uuid in FULL state after 0 sec [ 1335.378330] Lustre: DEBUG MARKER: oleg225-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8cd091297000.ost_server_uuid 50 [ 1337.063973] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8cd091297000.ost_server_uuid in IDLE state after 0 sec [ 1345.429303] Lustre: DEBUG MARKER: oleg225-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8cd091297000.ost_server_uuid 50 [ 1346.924176] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8cd091297000.ost_server_uuid in FULL state after 0 sec [ 1350.214333] Lustre: DEBUG MARKER: oleg225-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8cd091297000.ost_server_uuid 50 [ 1351.526472] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8cd091297000.ost_server_uuid in IDLE state after 0 sec [ 1353.075637] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 21:27:55 (1763346475) [ 1355.756729] Lustre: DEBUG MARKER: Race attempt 0 [ 1358.473492] Lustre: DEBUG MARKER: Wait for 48963 49001 for 60 sec... [ 1424.877371] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 21:29:07 (1763346547) [ 1432.288489] Lustre: DEBUG MARKER: start test - cycle (0) [ 1459.005417] Lustre: DEBUG MARKER: start test - cycle (1) [ 1463.775567] Lustre: lustre-OST0001-osc-ffff8cd091297000: disconnect after 24s idle [ 1463.783563] Lustre: Skipped 3 previous similar messages [ 1486.127568] Lustre: DEBUG MARKER: start test - cycle (2) [ 1512.373572] Lustre: DEBUG MARKER: start test - cycle (3) [ 1537.128894] Lustre: DEBUG MARKER: start test - cycle (4) [ 1563.125111] Lustre: DEBUG MARKER: start test - cycle (5) [ 1588.989162] Lustre: DEBUG MARKER: start test - cycle (6) [ 1613.781966] Lustre: DEBUG MARKER: start test - cycle (7) [ 1639.418990] Lustre: DEBUG MARKER: start test - cycle (8) [ 1666.203195] Lustre: DEBUG MARKER: start test - cycle (9) [ 1690.726398] Lustre: DEBUG MARKER: start test - cycle (10) [ 1719.776881] Lustre: lustre-OST0001-osc-ffff8cd091297000: disconnect after 23s idle [ 1719.789247] Lustre: Skipped 9 previous similar messages [ 1722.419490] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 21:34:05 (1763346845) [ 1816.721296] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 21:35:39 (1763346939) [ 1824.791696] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 21:35:47 (1763346947) [ 1831.973972] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 21:35:54 (1763346954) [ 1839.753213] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 21:36:02 (1763346962) [ 1840.121038] Lustre: *** cfs_fail_loc=411, val=0*** [ 1845.998469] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 1847.343562] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 21:36:10 (1763346970) [ 1863.583327] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 21:36:26 (1763346986) [ 1880.638366] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 21:36:43 (1763347003) [ 1899.313688] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 21:37:01 (1763347021) [ 1916.081943] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 21:37:18 (1763347038) [ 1929.468653] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 21:37:31 (1763347051) [ 1941.247462] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 21:37:44 (1763347064) [ 1954.352409] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 21:37:57 (1763347077) [ 1967.896725] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 21:38:10 (1763347090) [ 1980.074516] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 21:38:22 (1763347102) [ 1994.056768] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 21:38:36 (1763347116) [ 2008.573761] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 21:38:51 (1763347131) [ 2021.935061] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 21:39:04 (1763347144) [ 2636.256822] Lustre: lustre-OST0000-osc-ffff8cd091297000: disconnect after 20s idle [ 2636.262500] Lustre: Skipped 11 previous similar messages [ 3065.135507] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 21:56:27 (1763348187) [ 3076.204399] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 21:56:38 (1763348198) [ 3088.590989] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 21:56:51 (1763348211) [ 3102.540723] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 21:57:04 (1763348224) [ 3115.451680] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 21:57:18 (1763348238) [ 3128.406786] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 21:57:31 (1763348251) [ 3141.536294] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 21:57:44 (1763348264) [ 3154.229726] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 21:57:57 (1763348277) [ 3166.277706] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 21:58:09 (1763348289) [ 3233.743867] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 21:59:16 (1763348356) [ 3246.260916] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 21:59:28 (1763348368) [ 3257.297262] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 21:59:40 (1763348380) [ 3268.319192] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 21:59:51 (1763348391) [ 3280.530140] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 22:00:03 (1763348403) [ 3291.745685] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 22:00:14 (1763348414) [ 3302.550861] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 22:00:25 (1763348425) [ 3314.072897] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 22:00:37 (1763348437) [ 3315.253794] Lustre: DEBUG MARKER: SKIP: sanityn test_43i needs >= 2 MDTs [ 3317.010848] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 22:00:39 (1763348439) [ 3322.336451] Lustre: lustre-OST0001-osc-ffff8cd09143a000: disconnect after 24s idle [ 3322.348843] Lustre: Skipped 4 previous similar messages [ 3422.352506] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 22:02:25 (1763348545) [ 3936.735197] Lustre: lustre-OST0000-osc-ffff8cd091297000: disconnect after 23s idle [ 3936.746116] Lustre: Skipped 10 previous similar messages [ 4471.965447] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 22:19:54 (1763349594) [ 4484.751342] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 22:20:07 (1763349607) [ 4498.212262] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 22:20:20 (1763349620) [ 4511.513812] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 22:20:34 (1763349634) [ 4524.653454] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 22:20:47 (1763349647) [ 4538.095923] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 22:21:00 (1763349660) [ 4552.770254] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 22:21:15 (1763349675) [ 4561.375564] Lustre: lustre-OST0000-osc-ffff8cd09143a000: disconnect after 21s idle [ 4561.383038] Lustre: Skipped 3 previous similar messages [ 4565.331935] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 22:21:27 (1763349687) [ 4577.403533] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 22:21:40 (1763349700) [ 4578.833646] Lustre: DEBUG MARKER: SKIP: sanityn test_44i needs >= 2 MDTs [ 4580.919720] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 22:21:43 (1763349703) [ 4711.437752] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 22:23:54 (1763349834) [ 4723.912487] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 22:24:06 (1763349846) [ 4735.819891] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 22:24:18 (1763349858) [ 4748.762230] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 22:24:31 (1763349871) [ 4762.390397] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 22:24:45 (1763349885) [ 4775.814483] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 22:24:58 (1763349898) [ 4789.043680] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 22:25:11 (1763349911) [ 4802.472659] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 22:25:24 (1763349924) [ 4804.396923] Lustre: DEBUG MARKER: SKIP: sanityn test_45i needs >= 2 MDTs [ 4806.454519] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 22:25:28 (1763349928) [ 5170.659408] Lustre: lustre-OST0000-osc-ffff8cd09143a000: disconnect after 23s idle [ 5170.665215] Lustre: Skipped 6 previous similar messages [ 5902.130734] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 22:43:45 (1763351025) [ 5912.185536] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 22:43:54 (1763351034) [ 5918.175278] Lustre: lustre-OST0000-osc-ffff8cd091297000: disconnect after 22s idle [ 5918.178423] Lustre: Skipped 4 previous similar messages [ 5922.694538] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 22:44:05 (1763351045) [ 5932.959653] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 22:44:15 (1763351055) [ 5943.519763] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 22:44:26 (1763351066) [ 5955.190641] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 22:44:37 (1763351077) [ 5965.812584] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 22:44:48 (1763351088) [ 5977.197354] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 22:45:00 (1763351100) [ 5988.304664] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 22:45:11 (1763351111) [ 5989.613095] Lustre: DEBUG MARKER: SKIP: sanityn test_46i needs >= 2 MDTs [ 5991.202314] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 22:45:13 (1763351113) [ 5992.427533] Lustre: DEBUG MARKER: SKIP: sanityn test_47a needs >= 2 MDTs [ 5993.854623] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 22:45:16 (1763351116) [ 5994.932133] Lustre: DEBUG MARKER: SKIP: sanityn test_47b needs >= 2 MDTs [ 5996.417564] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 22:45:19 (1763351119) [ 5997.642757] Lustre: DEBUG MARKER: SKIP: sanityn test_47c needs >= 2 MDTs [ 5998.955381] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 22:45:21 (1763351121) [ 6000.408886] Lustre: DEBUG MARKER: SKIP: sanityn test_47d needs >= 2 MDTs [ 6001.931621] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 22:45:24 (1763351124) [ 6002.979787] Lustre: DEBUG MARKER: SKIP: sanityn test_47e needs >= 2 MDTs [ 6004.270453] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 22:45:27 (1763351127) [ 6005.218835] Lustre: DEBUG MARKER: SKIP: sanityn test_47f needs >= 2 MDTs [ 6006.597339] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 22:45:29 (1763351129) [ 6007.547875] Lustre: DEBUG MARKER: SKIP: sanityn test_47g needs >= 2 MDTs [ 6008.693488] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 22:45:31 (1763351131) [ 6008.989886] LustreError: 21368:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 6011.079165] LustreError: 21368:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 6018.729304] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 22:45:41 (1763351141) [ 6025.792778] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 22:45:48 (1763351148) [ 6026.020450] LustreError: 216745:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 6030.087196] LustreError: 216745:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 6030.119228] LustreError: 216745:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 6034.183288] LustreError: 216745:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 6034.221049] LustreError: 216752:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 6038.287859] LustreError: 216752:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 6045.212466] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 22:46:07 (1763351167) [ 6057.133946] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 22:46:19 (1763351179) [ 6065.205102] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 22:46:27 (1763351187) [ 6073.976653] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 22:46:36 (1763351196) [ 6105.247880] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 22:47:07 (1763351227) [ 6117.049455] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 22:47:19 (1763351239) [ 6128.788424] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 22:47:31 (1763351251) [ 6146.531937] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 22:47:49 (1763351269) [ 6161.596773] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 22:48:04 (1763351284) [ 6163.355749] Lustre: DEBUG MARKER: SKIP: sanityn test_55e needs >= 2 MDTs [ 6164.895462] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 22:48:07 (1763351287) [ 6183.422996] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 22:48:26 (1763351306) [ 6204.402456] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 22:48:47 (1763351327) [ 6319.298223] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 22:50:41 (1763351441) [ 6418.602663] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 22:52:21 (1763351541) [ 6425.828494] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 6432.684457] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 22:52:35 (1763351555) [ 6440.149223] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 22:52:42 (1763351562) [ 6441.496525] Lustre: DEBUG MARKER: SKIP: sanityn test_71a ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 6443.108707] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 22:52:45 (1763351565) [ 6444.518468] Lustre: DEBUG MARKER: SKIP: sanityn test_71b ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 6445.877567] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 22:52:48 (1763351568) [ 6447.228815] Lustre: DEBUG MARKER: SKIP: sanityn test_71c support only ldiskfs ost [ 6448.469124] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 22:52:51 (1763351571) [ 6449.651844] Lustre: DEBUG MARKER: SKIP: sanityn test_71d ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 6451.080941] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 22:52:53 (1763351573) [ 6457.068635] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 22:52:59 (1763351579) [ 6463.168896] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 22:53:05 (1763351585) [ 6466.417392] LustreError: lustre-MDT0000-mdc-ffff8cd09143a000: operation ldlm_enqueue to node 192.168.202.125@tcp failed: rc = -35 [ 6466.427944] LustreError: Skipped 1 previous similar message [ 6473.286411] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 22:53:15 (1763351595) [ 6474.070413] LustreError: 2368:0:(osc_request.c:3092:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 6476.071162] LustreError: 2368:0:(osc_request.c:3092:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 6483.969602] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 22:53:26 (1763351606) [ 6540.745823] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 22:54:23 (1763351663) [ 6549.048323] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 22:54:31 (1763351671) [ 6561.282259] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 22:54:43 (1763351683) [ 6563.299079] Lustre: lustre-OST0000-osc-ffff8cd091297000: disconnect after 24s idle [ 6563.301165] Lustre: Skipped 6 previous similar messages [ 6576.646275] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 22:54:58 (1763351698) [ 6590.399508] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 22:55:13 (1763351713) [ 6612.151310] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 22:55:34 (1763351734) [ 6634.900420] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 22:55:57 (1763351757) [ 6644.178141] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 22:56:06 (1763351766) [ 6653.884891] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 22:56:16 (1763351776) [ 6672.227308] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 22:56:34 (1763351794) [ 6731.197304] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 22:57:34 (1763351854) [ 6878.522839] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 23:00:01 (1763352001) [ 7021.037593] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 23:02:24 (1763352144) [ 7182.816162] Lustre: lustre-OST0000-osc-ffff8cd09143a000: disconnect after 22s idle [ 7182.822709] Lustre: Skipped 20 previous similar messages [ 7400.765210] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 23:08:43 (1763352523) [ 7441.134078] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 23:09:24 (1763352564) [ 7481.991242] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 23:10:05 (1763352605) [ 7517.963657] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 23:10:41 (1763352641) [ 7600.528424] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 23:12:03 (1763352723) [ 7666.549978] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 23:13:09 (1763352789) [ 7786.975221] Lustre: lustre-OST0000-osc-ffff8cd09143a000: disconnect after 21s idle [ 7786.980428] Lustre: Skipped 18 previous similar messages [ 7789.641079] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 23:15:13 (1763352913) [ 7791.408251] LustreError: 257878:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd09143a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7791.417063] LustreError: 257878:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 7791.436133] Lustre: Unmounted lustre-client [ 7792.418069] LustreError: 257891:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd091297000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7792.422772] LustreError: 257891:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 7792.426418] LustreError: 257891:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7792.429321] LustreError: 257891:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 7792.444387] Lustre: Unmounted lustre-client [ 7827.977186] Lustre: Mounted lustre-client [ 7829.587817] Lustre: Mounted lustre-client [ 7830.750055] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7918.547630] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 23:17:21 (1763353041) [ 7927.653346] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 23:17:31 (1763353051) [ 7931.578912] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 23:17:34 (1763353054) [ 7983.209661] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 23:18:26 (1763353106) [ 8050.465373] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 23:19:33 (1763353173) [ 8055.245298] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 23:19:38 (1763353178) [ 8103.208299] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 23:20:26 (1763353226) [ 8117.005853] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 23:20:40 (1763353240) [ 8159.330474] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 23:21:22 (1763353282) [ 8163.285599] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 23:21:26 (1763353286) [ 8176.901483] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 23:21:40 (1763353300) [ 8177.508448] Lustre: DEBUG MARKER: SKIP: sanityn test_80a needs >= 2 MDTs [ 8178.214148] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 23:21:41 (1763353301) [ 8178.824401] Lustre: DEBUG MARKER: SKIP: sanityn test_80b needs >= 2 MDTs [ 8179.509168] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 23:21:42 (1763353302) [ 8180.125326] Lustre: DEBUG MARKER: SKIP: sanityn test_81a needs >= 2 MDTs [ 8180.818116] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 23:21:44 (1763353304) [ 8181.401579] Lustre: DEBUG MARKER: SKIP: sanityn test_81b We need at least 2 MDTs for this test [ 8182.087754] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 23:21:45 (1763353305) [ 8182.732388] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 8183.482798] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 23:21:46 (1763353306) [ 8228.349438] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 23:22:31 (1763353351) [ 8231.189346] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 23:22:34 (1763353354) [ 8231.835782] Lustre: DEBUG MARKER: SKIP: sanityn test_83 needs >= 2 MDTs [ 8232.563579] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 23:22:35 (1763353355) [ 8240.472407] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 23:22:43 (1763353363) [ 8243.971896] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 23:22:47 (1763353367) [ 8244.587773] Lustre: DEBUG MARKER: SKIP: sanityn test_90 needs >= 2 MDTs [ 8245.239980] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 23:22:48 (1763353368) [ 8245.797595] Lustre: DEBUG MARKER: SKIP: sanityn test_91 needs >= 2 MDTs [ 8246.422810] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 23:22:49 (1763353369) [ 8247.005791] Lustre: DEBUG MARKER: SKIP: sanityn test_92 needs >= 2 MDTs [ 8247.660131] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 23:22:51 (1763353371) [ 8257.211380] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 23:23:00 (1763353380) [ 8257.301303] Lustre: DEBUG MARKER: write [ 8257.324221] LustreError: 259293:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 8259.328306] Lustre: DEBUG MARKER: kill 286935 [ 8259.331446] LustreError: 286935:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 8262.423120] LustreError: 259293:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 8265.367149] LustreError: 286935:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 8267.994893] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 23:23:11 (1763353391) [ 8270.183587] LustreError: 287541:0:(rw.c:1968:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 8280.271429] LustreError: 287541:0:(rw.c:1968:ll_readpage()) cfs_fail_timeout id 1422 awake [ 8283.222533] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 23:23:26 (1763353406) [ 8283.374359] LustreError: 288120:0:(rw.c:2213:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 8285.455588] LustreError: 288120:0:(rw.c:2213:ll_readpage()) cfs_fail_timeout interrupted [ 8291.941563] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 23:23:35 (1763353415) [ 8292.593907] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 8293.279662] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 23:23:36 (1763353416) [ 8296.146322] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 23:23:39 (1763353419) [ 8299.287127] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 23:23:42 (1763353422) [ 8302.405893] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 23:23:45 (1763353425) [ 8305.509157] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 23:23:48 (1763353428) [ 8308.308738] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 23:23:51 (1763353431) [ 8311.067457] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 23:23:54 (1763353434) [ 8314.656115] Lustre: DEBUG MARKER: SKIP: sanityn test_102 skipping ALWAYS excluded test 102 [ 8315.322134] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 23:23:58 (1763353438) [ 8315.989404] Lustre: *** cfs_fail_loc=415, val=0*** [ 8323.240058] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 23:24:06 (1763353446) [ 8323.898346] Lustre: DEBUG MARKER: SKIP: sanityn test_104 ldiskfs only test [ 8324.720261] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 23:24:08 (1763353448) [ 8324.813683] LustreError: 259293:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [ 8324.817075] LustreError: 259293:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [ 8329.815202] LustreError: 259293:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 8340.015137] LustreError: 259293:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 8340.018597] LustreError: 259293:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 5 previous similar messages [ 8347.846353] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 23:24:31 (1763353471) [ 8348.455685] Lustre: DEBUG MARKER: SKIP: sanityn test_106a Test only for ldiskfs and statx() supported [ 8349.130980] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 23:24:32 (1763353472) [ 8352.181967] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 23:24:35 (1763353475) [ 8354.970210] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 23:24:38 (1763353478) [ 8359.844199] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 23:24:43 (1763353483) [ 8368.710619] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 23:24:52 (1763353492) [ 8368.920919] LustreError: 261343:0:(osc_request.c:2943:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 8368.924360] LustreError: 261343:0:(osc_request.c:2943:osc_build_rpc()) Skipped 5 previous similar messages [ 8372.983216] LustreError: 261343:0:(osc_request.c:2943:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 8372.986346] LustreError: 261343:0:(osc_request.c:2943:osc_build_rpc()) Skipped 2 previous similar messages [ 8375.732510] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 23:24:59 (1763353499) [ 8376.566718] LustreError: 298064:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd099191800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8376.571133] LustreError: 298064:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 8376.583784] LustreError: 298064:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 8376.585941] LustreError: 298064:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 8376.604164] Lustre: Unmounted lustre-client [ 8377.763106] Lustre: DEBUG MARKER: Iteration 1 [ 8377.903866] LustreError: 298245:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8377.903918] LustreError: 298244:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8377.910912] LustreError: 298245:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8377.997782] Lustre: Mounted lustre-client [ 8378.636075] LustreError: 298347:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd090e89000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8378.641368] LustreError: 298347:0:(lov_obd.c:783:lov_cleanup()) Skipped 3 previous similar messages [ 8378.648852] LustreError: 298347:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8378.651665] LustreError: 298347:0:(obd_class.h:479:obd_check_dev()) Skipped 13 previous similar messages [ 8378.679456] Lustre: Unmounted lustre-client [ 8378.681833] Lustre: Skipped 1 previous similar message [ 8379.890566] Key type lgssc unregistered [ 8380.025530] LNet: 298594:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8380.029819] LNetError: 298594:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8380.040302] LNet: Removed LNI 192.168.202.25@tcp [ 8380.402246] Key type .llcrypt unregistered [ 8380.403695] Key type ._llcrypt unregistered [ 8380.707737] Key type ._llcrypt registered [ 8380.710414] Key type .llcrypt registered [ 8381.010189] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8381.016761] alg: No test for adler32 (adler32-zlib) [ 8382.034258] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8382.348320] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8383.967243] Key type lgssc registered [ 8384.594095] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8389.169565] Lustre: DEBUG MARKER: Iteration 2 [ 8389.307554] LustreError: 299413:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8389.307648] LustreError: 299412:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8389.313058] LustreError: 299413:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8390.366878] Lustre: Mounted lustre-client [ 8390.881087] LustreError: 299518:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd0918a0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8390.886386] LustreError: 299519:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8390.887121] LustreError: 299518:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8390.902087] Lustre: Unmounted lustre-client [ 8392.002058] Key type lgssc unregistered [ 8392.137035] LNet: 299760:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8392.140815] LNetError: 299760:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8392.153797] LNet: Removed LNI 192.168.202.25@tcp [ 8392.451177] Key type .llcrypt unregistered [ 8392.452760] Key type ._llcrypt unregistered [ 8392.752879] Key type ._llcrypt registered [ 8392.757595] Key type .llcrypt registered [ 8392.973803] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8392.980351] alg: No test for adler32 (adler32-zlib) [ 8393.859048] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8393.954716] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8395.543167] Key type lgssc registered [ 8396.012174] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8399.934542] Lustre: DEBUG MARKER: Iteration 3 [ 8400.055187] LustreError: 300579:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8400.055236] LustreError: 300578:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8400.061337] LustreError: 300579:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8401.125825] Lustre: Mounted lustre-client [ 8401.127381] Lustre: Skipped 1 previous similar message [ 8401.604976] LustreError: 300684:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd0bc9b6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8401.610250] LustreError: 300684:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8401.610414] LustreError: 300685:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8401.660052] Lustre: Unmounted lustre-client [ 8402.750721] Key type lgssc unregistered [ 8402.880546] LNet: 300926:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8402.884650] LNetError: 300926:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8402.894164] LNet: Removed LNI 192.168.202.25@tcp [ 8403.154174] Key type .llcrypt unregistered [ 8403.155687] Key type ._llcrypt unregistered [ 8403.452913] Key type ._llcrypt registered [ 8403.454655] Key type .llcrypt registered [ 8403.649397] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8403.656824] alg: No test for adler32 (adler32-zlib) [ 8404.543835] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8404.633747] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8406.223179] Key type lgssc registered [ 8406.675117] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8410.650738] Lustre: DEBUG MARKER: Iteration 4 [ 8410.775607] LustreError: 301743:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8410.775671] LustreError: 301744:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8410.782155] LustreError: 301743:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8411.840386] Lustre: Mounted lustre-client [ 8412.343099] LustreError: 301850:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd0b5be4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8412.348165] LustreError: 301849:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8412.348640] LustreError: 301850:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8412.366130] Lustre: Unmounted lustre-client [ 8413.479795] Key type lgssc unregistered [ 8413.604874] LNet: 302093:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8413.608787] LNetError: 302093:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8413.619433] LNet: Removed LNI 192.168.202.25@tcp [ 8413.931214] Key type .llcrypt unregistered [ 8413.933475] Key type ._llcrypt unregistered [ 8414.250236] Key type ._llcrypt registered [ 8414.256504] Key type .llcrypt registered [ 8414.454103] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8414.461685] alg: No test for adler32 (adler32-zlib) [ 8415.331547] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8415.428549] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8417.023197] Key type lgssc registered [ 8417.478014] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8421.705236] Lustre: DEBUG MARKER: Iteration 5 [ 8421.858141] LustreError: 302910:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8421.858265] LustreError: 302911:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8421.863883] LustreError: 302910:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8422.961802] Lustre: Mounted lustre-client [ 8423.571655] LustreError: 303020:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd09137a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8423.575178] LustreError: 303021:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8423.577200] LustreError: 303020:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8423.603354] Lustre: Unmounted lustre-client [ 8424.759354] Key type lgssc unregistered [ 8424.895377] LNet: 303263:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8424.898700] LNetError: 303263:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8424.908209] LNet: Removed LNI 192.168.202.25@tcp [ 8425.168125] Key type .llcrypt unregistered [ 8425.169507] Key type ._llcrypt unregistered [ 8425.492841] Key type ._llcrypt registered [ 8425.497189] Key type .llcrypt registered [ 8425.691959] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8425.698426] alg: No test for adler32 (adler32-zlib) [ 8426.574888] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8426.677129] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8428.271212] Key type lgssc registered [ 8428.745889] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8432.758357] Lustre: DEBUG MARKER: Iteration 6 [ 8432.875566] LustreError: 304081:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8432.875603] LustreError: 304082:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8432.882119] LustreError: 304081:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8433.943187] Lustre: Mounted lustre-client [ 8434.472385] LustreError: 304191:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd0989dc000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8434.477059] LustreError: 304192:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8434.478037] LustreError: 304191:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8434.492082] Lustre: Unmounted lustre-client [ 8435.601520] Key type lgssc unregistered [ 8435.727766] LNet: 304433:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8435.730576] LNetError: 304433:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8435.740819] LNet: Removed LNI 192.168.202.25@tcp [ 8435.998166] Key type .llcrypt unregistered [ 8435.999963] Key type ._llcrypt unregistered [ 8436.320254] Key type ._llcrypt registered [ 8436.321850] Key type .llcrypt registered [ 8436.584288] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8436.590945] alg: No test for adler32 (adler32-zlib) [ 8437.475775] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8437.572743] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8439.167490] Key type lgssc registered [ 8439.645957] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8443.538284] Lustre: DEBUG MARKER: Iteration 7 [ 8443.673528] LustreError: 305250:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8443.674067] LustreError: 305251:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8443.678815] LustreError: 305250:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8444.742960] Lustre: Mounted lustre-client [ 8445.266639] LustreError: 305359:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd0b8ca8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8445.271851] LustreError: 305359:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8445.273698] LustreError: 305360:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8445.278094] LustreError: 305360:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8445.296770] Lustre: Unmounted lustre-client [ 8446.347734] Key type lgssc unregistered [ 8446.479823] LNet: 305600:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8446.482476] LNetError: 305600:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8446.490830] LNet: Removed LNI 192.168.202.25@tcp [ 8446.758193] Key type .llcrypt unregistered [ 8446.759755] Key type ._llcrypt unregistered [ 8447.039658] Key type ._llcrypt registered [ 8447.041350] Key type .llcrypt registered [ 8447.264579] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8447.271055] alg: No test for adler32 (adler32-zlib) [ 8448.151150] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8448.257401] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8449.855198] Key type lgssc registered [ 8450.301711] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8454.328699] Lustre: DEBUG MARKER: Iteration 8 [ 8454.441241] LustreError: 306417:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8454.441315] LustreError: 306418:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8454.447390] LustreError: 306417:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8455.527465] Lustre: Mounted lustre-client [ 8455.529039] Lustre: Skipped 1 previous similar message [ 8456.121935] LustreError: 306526:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd09143c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8456.127459] LustreError: 306526:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8456.129240] LustreError: 306525:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8456.149258] Lustre: Unmounted lustre-client [ 8457.308541] Key type lgssc unregistered [ 8457.433506] LNet: 306767:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8457.437036] LNetError: 306767:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8457.445754] LNet: Removed LNI 192.168.202.25@tcp [ 8457.711156] Key type .llcrypt unregistered [ 8457.712895] Key type ._llcrypt unregistered [ 8458.010181] Key type ._llcrypt registered [ 8458.014945] Key type .llcrypt registered [ 8458.215411] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8458.222146] alg: No test for adler32 (adler32-zlib) [ 8459.116990] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8459.223741] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8460.823208] Key type lgssc registered [ 8461.282602] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8465.003886] Lustre: DEBUG MARKER: Iteration 9 [ 8465.122578] LustreError: 307585:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8465.122759] LustreError: 307586:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8465.129351] LustreError: 307585:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8466.192369] Lustre: Mounted lustre-client [ 8466.193930] Lustre: Skipped 1 previous similar message [ 8466.671320] LustreError: 307693:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd0adb51800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8466.676045] LustreError: 307693:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8466.679911] LustreError: 307692:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 8466.683438] LustreError: 307692:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8466.694089] Lustre: Unmounted lustre-client [ 8467.733382] Key type lgssc unregistered [ 8467.869792] LNet: 307934:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8467.873343] LNetError: 307934:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8467.883332] LNet: Removed LNI 192.168.202.25@tcp [ 8468.155207] Key type .llcrypt unregistered [ 8468.156880] Key type ._llcrypt unregistered [ 8468.456245] Key type ._llcrypt registered [ 8468.457791] Key type .llcrypt registered [ 8468.620926] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8468.628319] alg: No test for adler32 (adler32-zlib) [ 8469.507557] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8469.605106] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8471.199191] Key type lgssc registered [ 8471.666760] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8475.979418] Lustre: DEBUG MARKER: Iteration 10 [ 8476.140978] LustreError: 308751:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8476.141533] LustreError: 308752:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8476.148443] LustreError: 308751:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8477.222504] Lustre: Mounted lustre-client [ 8477.803800] LustreError: 308858:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd0911a7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8477.807169] LustreError: 308859:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 8477.809599] LustreError: 308858:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8477.839078] Lustre: Unmounted lustre-client [ 8478.935237] Key type lgssc unregistered [ 8479.066968] LNet: 309100:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8479.071188] LNetError: 309100:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8479.080723] LNet: Removed LNI 192.168.202.25@tcp [ 8479.355305] Key type .llcrypt unregistered [ 8479.356966] Key type ._llcrypt unregistered [ 8479.656781] Key type ._llcrypt registered [ 8479.658619] Key type .llcrypt registered [ 8479.859996] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8479.866844] alg: No test for adler32 (adler32-zlib) [ 8480.743679] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8480.838278] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8482.439219] Key type lgssc registered [ 8482.904813] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8486.994856] Lustre: DEBUG MARKER: Iteration 11 [ 8487.122783] LustreError: 309918:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8487.123102] LustreError: 309919:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8487.129643] LustreError: 309918:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8488.183411] Lustre: Mounted lustre-client [ 8488.184749] Lustre: Skipped 1 previous similar message [ 8488.635536] LustreError: 310026:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd091439800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8488.637899] LustreError: 310025:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8488.639848] LustreError: 310026:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8488.654258] Lustre: Unmounted lustre-client [ 8489.738822] Key type lgssc unregistered [ 8489.870505] LNet: 310267:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8489.874410] LNetError: 310267:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8489.886551] LNet: Removed LNI 192.168.202.25@tcp [ 8490.173154] Key type .llcrypt unregistered [ 8490.174740] Key type ._llcrypt unregistered [ 8490.439260] Key type ._llcrypt registered [ 8490.445537] Key type .llcrypt registered [ 8490.653273] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8490.659764] alg: No test for adler32 (adler32-zlib) [ 8491.540212] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8491.631940] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8493.231199] Key type lgssc registered [ 8493.634316] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8497.353995] Lustre: DEBUG MARKER: Iteration 12 [ 8497.454176] LustreError: 311086:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8497.454230] LustreError: 311087:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8497.459416] LustreError: 311086:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8498.511565] Lustre: Mounted lustre-client [ 8499.042413] LustreError: 311191:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd0ba67d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8499.047500] LustreError: 311191:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8499.047773] LustreError: 311192:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8499.080163] Lustre: Unmounted lustre-client [ 8500.087470] Key type lgssc unregistered [ 8500.210575] LNet: 311433:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8500.213937] LNetError: 311433:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8500.222756] LNet: Removed LNI 192.168.202.25@tcp [ 8500.471151] Key type .llcrypt unregistered [ 8500.472581] Key type ._llcrypt unregistered [ 8500.708654] Key type ._llcrypt registered [ 8500.710396] Key type .llcrypt registered [ 8500.878953] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8500.884538] alg: No test for adler32 (adler32-zlib) [ 8501.739583] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8501.823988] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8503.407228] Key type lgssc registered [ 8503.791977] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8507.182090] Lustre: DEBUG MARKER: Iteration 13 [ 8507.294176] LustreError: 312250:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8507.294222] LustreError: 312249:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8507.299785] LustreError: 312250:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8508.348098] Lustre: Mounted lustre-client [ 8508.350270] Lustre: Skipped 1 previous similar message [ 8508.791534] LustreError: 312353:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd090652000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8508.796526] LustreError: 312353:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8508.797969] LustreError: 312354:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8508.802839] LustreError: 312354:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8508.820153] Lustre: Unmounted lustre-client [ 8509.796975] Key type lgssc unregistered [ 8509.917484] LNet: 312595:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8509.920179] LNetError: 312595:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8509.928541] LNet: Removed LNI 192.168.202.25@tcp [ 8510.173129] Key type .llcrypt unregistered [ 8510.174210] Key type ._llcrypt unregistered [ 8510.444415] Key type ._llcrypt registered [ 8510.446020] Key type .llcrypt registered [ 8510.626687] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8510.633259] alg: No test for adler32 (adler32-zlib) [ 8511.519338] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8511.616580] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8513.207201] Key type lgssc registered [ 8513.640251] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8517.121560] Lustre: DEBUG MARKER: Iteration 14 [ 8517.227125] LustreError: 313413:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8517.229505] LustreError: 313414:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8517.231531] LustreError: 313413:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 8518.298208] Lustre: Mounted lustre-client [ 8518.300037] Lustre: Skipped 1 previous similar message [ 8518.810389] LustreError: 313521:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd08848a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8518.813911] LustreError: 313520:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8518.815775] LustreError: 313521:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8518.830917] Lustre: Unmounted lustre-client [ 8519.836795] Key type lgssc unregistered [ 8519.956443] LNet: 313762:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8519.959254] LNetError: 313762:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8519.967745] LNet: Removed LNI 192.168.202.25@tcp [ 8520.213119] Key type .llcrypt unregistered [ 8520.214543] Key type ._llcrypt unregistered [ 8520.480227] Key type ._llcrypt registered [ 8520.481535] Key type .llcrypt registered [ 8520.680326] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8520.685768] alg: No test for adler32 (adler32-zlib) [ 8521.553071] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8521.652949] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8523.247152] Key type lgssc registered [ 8523.669473] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8527.385973] Lustre: DEBUG MARKER: Iteration 15 [ 8527.487460] LustreError: 314579:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8527.487923] LustreError: 314580:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8527.493329] LustreError: 314579:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8528.550913] Lustre: Mounted lustre-client [ 8528.552124] Lustre: Skipped 1 previous similar message [ 8529.099096] LustreError: 314681:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd084eac800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8529.101887] LustreError: 314682:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8529.104082] LustreError: 314681:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8529.131718] Lustre: Unmounted lustre-client [ 8530.184588] Key type lgssc unregistered [ 8530.306563] LNet: 314925:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8530.309837] LNetError: 314925:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8530.319283] LNet: Removed LNI 192.168.202.25@tcp [ 8530.582288] Key type .llcrypt unregistered [ 8530.583801] Key type ._llcrypt unregistered [ 8530.834632] Key type ._llcrypt registered [ 8530.836075] Key type .llcrypt registered [ 8531.025914] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8531.032591] alg: No test for adler32 (adler32-zlib) [ 8531.904992] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8532.000364] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8533.599165] Key type lgssc registered [ 8534.000412] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8537.540109] Lustre: DEBUG MARKER: Iteration 16 [ 8537.655468] LustreError: 315743:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8537.656056] LustreError: 315744:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8537.661637] LustreError: 315743:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8538.704459] Lustre: Mounted lustre-client [ 8539.193977] LustreError: 315852:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd099195000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8539.196689] LustreError: 315851:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 8539.198681] LustreError: 315852:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8539.219312] Lustre: Unmounted lustre-client [ 8540.226047] Key type lgssc unregistered [ 8540.340380] LNet: 316093:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8540.342811] LNetError: 316093:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8540.351640] LNet: Removed LNI 192.168.202.25@tcp [ 8540.590114] Key type .llcrypt unregistered [ 8540.591199] Key type ._llcrypt unregistered [ 8540.948327] Key type ._llcrypt registered [ 8540.950179] Key type .llcrypt registered [ 8541.052426] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8541.059630] alg: No test for adler32 (adler32-zlib) [ 8541.920871] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8542.001987] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8543.583150] Key type lgssc registered [ 8543.979904] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8547.513419] Lustre: DEBUG MARKER: Iteration 17 [ 8547.609735] LustreError: 316910:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8547.610230] LustreError: 316911:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8547.615920] LustreError: 316910:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8548.666170] Lustre: Mounted lustre-client [ 8549.106546] LustreError: 317019:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd09143b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8549.109946] LustreError: 317018:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8549.112087] LustreError: 317019:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8549.128192] Lustre: Unmounted lustre-client [ 8550.176282] Key type lgssc unregistered [ 8550.301558] LNet: 317260:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8550.304512] LNetError: 317260:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8550.314766] LNet: Removed LNI 192.168.202.25@tcp [ 8550.583150] Key type .llcrypt unregistered [ 8550.584926] Key type ._llcrypt unregistered [ 8550.881245] Key type ._llcrypt registered [ 8550.882736] Key type .llcrypt registered [ 8551.069612] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8551.075257] alg: No test for adler32 (adler32-zlib) [ 8551.938181] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8552.029077] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8553.615175] Key type lgssc registered [ 8554.048235] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8557.497660] Lustre: DEBUG MARKER: Iteration 18 [ 8557.599060] LustreError: 318077:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8557.602328] LustreError: 318083:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8557.605091] LustreError: 318077:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8558.654186] Lustre: Mounted lustre-client [ 8558.656204] Lustre: Skipped 1 previous similar message [ 8559.126840] LustreError: 318184:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd09843c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8559.130228] LustreError: 318185:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8559.131733] LustreError: 318184:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8559.155468] Lustre: Unmounted lustre-client [ 8560.166903] Key type lgssc unregistered [ 8560.283549] LNet: 318425:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8560.287487] LNetError: 318425:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8560.294731] LNet: Removed LNI 192.168.202.25@tcp [ 8560.542119] Key type .llcrypt unregistered [ 8560.543441] Key type ._llcrypt unregistered [ 8560.750195] Key type ._llcrypt registered [ 8560.751848] Key type .llcrypt registered [ 8560.946607] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8560.952757] alg: No test for adler32 (adler32-zlib) [ 8561.813754] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8561.907879] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8563.495204] Key type lgssc registered [ 8563.920160] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8567.596718] Lustre: DEBUG MARKER: Iteration 19 [ 8567.711577] LustreError: 319248:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8567.712159] LustreError: 319249:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8567.718540] LustreError: 319248:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8568.774415] Lustre: Mounted lustre-client [ 8568.775744] Lustre: Skipped 1 previous similar message [ 8569.237713] LustreError: 319357:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd099196800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8569.239670] LustreError: 319356:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8569.241090] LustreError: 319357:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8569.256098] Lustre: Unmounted lustre-client [ 8570.292450] Key type lgssc unregistered [ 8570.407428] LNet: 319599:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8570.409933] LNetError: 319599:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8570.420531] LNet: Removed LNI 192.168.202.25@tcp [ 8570.661235] Key type .llcrypt unregistered [ 8570.662449] Key type ._llcrypt unregistered [ 8570.930875] Key type ._llcrypt registered [ 8570.932280] Key type .llcrypt registered [ 8571.120292] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8571.125502] alg: No test for adler32 (adler32-zlib) [ 8571.985160] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8572.071259] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8573.655173] Key type lgssc registered [ 8574.069884] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8577.701373] Lustre: DEBUG MARKER: Iteration 20 [ 8577.810955] LustreError: 320416:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8577.810986] LustreError: 320417:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8577.817652] LustreError: 320416:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8578.882305] Lustre: Mounted lustre-client [ 8579.352654] LustreError: 320526:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd0989d2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8579.357692] LustreError: 320527:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8579.358217] LustreError: 320526:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8579.369878] Lustre: Unmounted lustre-client [ 8580.403939] Key type lgssc unregistered [ 8580.519582] LNet: 320768:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8580.523196] LNetError: 320768:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8580.531093] LNet: Removed LNI 192.168.202.25@tcp [ 8580.788099] Key type .llcrypt unregistered [ 8580.789353] Key type ._llcrypt unregistered [ 8581.033679] Key type ._llcrypt registered [ 8581.035016] Key type .llcrypt registered [ 8581.253962] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8581.264763] alg: No test for adler32 (adler32-zlib) [ 8582.137430] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8582.225718] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8583.823185] Key type lgssc registered [ 8584.250615] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8588.062926] Lustre: DEBUG MARKER: Iteration 21 [ 8588.173794] LustreError: 321583:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8588.173837] LustreError: 321585:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8588.179416] LustreError: 321583:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8589.224779] Lustre: Mounted lustre-client [ 8589.691583] LustreError: 321690:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd08848b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8589.694498] LustreError: 321691:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 8589.695675] LustreError: 321690:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8589.714048] Lustre: Unmounted lustre-client [ 8590.743162] Key type lgssc unregistered [ 8590.860518] LNet: 321932:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8590.864214] LNetError: 321932:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8590.879034] LNet: Removed LNI 192.168.202.25@tcp [ 8591.145179] Key type .llcrypt unregistered [ 8591.146613] Key type ._llcrypt unregistered [ 8591.446252] Key type ._llcrypt registered [ 8591.447432] Key type .llcrypt registered [ 8591.699331] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8591.704835] alg: No test for adler32 (adler32-zlib) [ 8592.571429] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8592.660965] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8594.247188] Key type lgssc registered [ 8594.660581] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8598.567884] Lustre: DEBUG MARKER: Iteration 22 [ 8598.715343] LustreError: 322751:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8598.715390] LustreError: 322750:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8598.722527] LustreError: 322751:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8599.786504] Lustre: Mounted lustre-client [ 8599.788289] Lustre: Skipped 1 previous similar message [ 8600.303362] LustreError: 322859:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd0b6d31000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8600.312522] LustreError: 322859:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8600.315174] LustreError: 322859:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8600.329822] Lustre: Unmounted lustre-client [ 8601.416814] Key type lgssc unregistered [ 8601.548960] LNet: 323101:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8601.552286] LNetError: 323101:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8601.562165] LNet: Removed LNI 192.168.202.25@tcp [ 8601.842151] Key type .llcrypt unregistered [ 8601.843690] Key type ._llcrypt unregistered [ 8602.108123] Key type ._llcrypt registered [ 8602.110912] Key type .llcrypt registered [ 8602.334697] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8602.340874] alg: No test for adler32 (adler32-zlib) [ 8603.226110] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8603.328429] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8604.927275] Key type lgssc registered [ 8605.395648] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8609.484995] Lustre: DEBUG MARKER: Iteration 23 [ 8609.580846] LustreError: 323918:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8609.584654] LustreError: 323919:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8609.588430] LustreError: 323918:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 8610.645265] Lustre: Mounted lustre-client [ 8610.646603] Lustre: Skipped 1 previous similar message [ 8611.148607] LustreError: 324027:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd084eab800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8611.151758] LustreError: 324026:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8611.154594] LustreError: 324027:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8611.171075] Lustre: Unmounted lustre-client [ 8612.263535] Key type lgssc unregistered [ 8612.389511] LNet: 324267:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8612.392937] LNetError: 324267:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8612.400719] LNet: Removed LNI 192.168.202.25@tcp [ 8612.674177] Key type .llcrypt unregistered [ 8612.675594] Key type ._llcrypt unregistered [ 8613.010050] Key type ._llcrypt registered [ 8613.011570] Key type .llcrypt registered [ 8613.237808] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8613.250876] alg: No test for adler32 (adler32-zlib) [ 8614.128310] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8614.225345] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8615.823190] Key type lgssc registered [ 8616.264317] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8620.029153] Lustre: DEBUG MARKER: Iteration 24 [ 8620.150702] LustreError: 325084:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8620.151860] LustreError: 325085:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8620.155975] LustreError: 325084:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8621.212403] Lustre: Mounted lustre-client [ 8621.728309] LustreError: 325187:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd084ead000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8621.732090] LustreError: 325186:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8621.732447] LustreError: 325187:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8621.755142] Lustre: Unmounted lustre-client [ 8622.742509] Key type lgssc unregistered [ 8622.866517] LNet: 325428:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8622.869074] LNetError: 325428:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8622.878580] LNet: Removed LNI 192.168.202.25@tcp [ 8623.096132] Key type .llcrypt unregistered [ 8623.097584] Key type ._llcrypt unregistered [ 8623.343815] Key type ._llcrypt registered [ 8623.345187] Key type .llcrypt registered [ 8623.540582] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8623.546871] alg: No test for adler32 (adler32-zlib) [ 8624.411452] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8624.501058] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8626.095216] Key type lgssc registered [ 8626.524483] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8630.079613] Lustre: DEBUG MARKER: Iteration 25 [ 8630.185454] LustreError: 326245:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8630.186743] LustreError: 326246:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8630.190192] LustreError: 326245:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8631.256097] Lustre: Mounted lustre-client [ 8631.257728] Lustre: Skipped 1 previous similar message [ 8631.730793] LustreError: 326350:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd0a715c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8631.738408] LustreError: 326350:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8631.741556] LustreError: 326350:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 8631.751359] Lustre: Unmounted lustre-client [ 8632.690611] Key type lgssc unregistered [ 8632.801472] LNet: 326593:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8632.803980] LNetError: 326593:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8632.813740] LNet: Removed LNI 192.168.202.25@tcp [ 8633.038139] Key type .llcrypt unregistered [ 8633.039817] Key type ._llcrypt unregistered [ 8633.281139] Key type ._llcrypt registered [ 8633.282165] Key type .llcrypt registered [ 8633.511625] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8633.517047] alg: No test for adler32 (adler32-zlib) [ 8634.366516] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8634.441171] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8636.023199] Key type lgssc registered [ 8636.480544] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8640.305347] Lustre: DEBUG MARKER: Iteration 26 [ 8640.427457] LustreError: 327410:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8640.427539] LustreError: 327411:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8640.433312] LustreError: 327410:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8641.487589] Lustre: Mounted lustre-client [ 8641.969848] LustreError: 327520:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd091bdb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8641.973358] LustreError: 327519:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8641.975556] LustreError: 327520:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8641.989791] Lustre: Unmounted lustre-client [ 8643.015473] Key type lgssc unregistered [ 8643.145543] LNet: 327761:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8643.148694] LNetError: 327761:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8643.159265] LNet: Removed LNI 192.168.202.25@tcp [ 8643.401199] Key type .llcrypt unregistered [ 8643.402587] Key type ._llcrypt unregistered [ 8643.696271] Key type ._llcrypt registered [ 8643.714195] Key type .llcrypt registered [ 8643.887492] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8643.893722] alg: No test for adler32 (adler32-zlib) [ 8644.759783] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8644.850303] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8646.439150] Key type lgssc registered [ 8646.840336] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8650.705246] Lustre: DEBUG MARKER: Iteration 27 [ 8650.821482] LustreError: 328578:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8650.824560] LustreError: 328579:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8650.827901] LustreError: 328578:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8651.900064] Lustre: Mounted lustre-client [ 8651.902030] Lustre: Skipped 1 previous similar message [ 8652.354400] LustreError: 328685:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd0989d6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8652.357654] LustreError: 328684:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8652.359551] LustreError: 328685:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8652.382383] Lustre: Unmounted lustre-client [ 8653.368528] Key type lgssc unregistered [ 8653.486375] LNet: 328927:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8653.488810] LNetError: 328927:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8653.498226] LNet: Removed LNI 192.168.202.25@tcp [ 8653.723111] Key type .llcrypt unregistered [ 8653.724316] Key type ._llcrypt unregistered [ 8653.959134] Key type ._llcrypt registered [ 8653.965245] Key type .llcrypt registered [ 8654.175244] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8654.181047] alg: No test for adler32 (adler32-zlib) [ 8655.043834] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8655.134558] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8656.719403] Key type lgssc registered [ 8657.125031] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8660.789306] Lustre: DEBUG MARKER: Iteration 28 [ 8660.901110] LustreError: 329745:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8660.901365] LustreError: 329746:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8660.907889] LustreError: 329745:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8661.962341] Lustre: Mounted lustre-client [ 8662.421917] LustreError: 329851:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd099192000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8662.425613] LustreError: 329852:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8662.427135] LustreError: 329851:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8662.439790] Lustre: Unmounted lustre-client [ 8663.409062] Key type lgssc unregistered [ 8663.535466] LNet: 330094:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8663.538368] LNetError: 330094:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8663.546752] LNet: Removed LNI 192.168.202.25@tcp [ 8663.783123] Key type .llcrypt unregistered [ 8663.784712] Key type ._llcrypt unregistered [ 8664.054682] Key type ._llcrypt registered [ 8664.055730] Key type .llcrypt registered [ 8664.245323] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8664.251193] alg: No test for adler32 (adler32-zlib) [ 8665.105078] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8665.191088] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8666.775143] Key type lgssc registered [ 8667.207107] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8671.227743] Lustre: DEBUG MARKER: Iteration 29 [ 8671.365482] LustreError: 330911:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8671.365737] LustreError: 330912:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8671.371278] LustreError: 330911:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8672.445937] Lustre: Mounted lustre-client [ 8672.447770] Lustre: Skipped 1 previous similar message [ 8672.877161] LustreError: 331018:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd0b5bd1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8672.881935] LustreError: 331019:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8672.881942] LustreError: 331018:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8672.897090] Lustre: Unmounted lustre-client [ 8673.861570] Key type lgssc unregistered [ 8673.985401] LNet: 331261:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8673.988149] LNetError: 331261:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8673.995663] LNet: Removed LNI 192.168.202.25@tcp [ 8674.241120] Key type .llcrypt unregistered [ 8674.242597] Key type ._llcrypt unregistered [ 8674.520362] Key type ._llcrypt registered [ 8674.521797] Key type .llcrypt registered [ 8674.711892] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8674.717869] alg: No test for adler32 (adler32-zlib) [ 8675.597097] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8675.692732] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8677.279356] Key type lgssc registered [ 8677.710589] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8681.452834] Lustre: DEBUG MARKER: Iteration 30 [ 8681.559465] LustreError: 332078:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8681.559524] LustreError: 332079:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8681.565069] LustreError: 332078:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8682.629566] Lustre: Mounted lustre-client [ 8683.128698] LustreError: 332186:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd0852eb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8683.131880] LustreError: 332185:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8683.133563] LustreError: 332186:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8683.153597] Lustre: Unmounted lustre-client [ 8684.140701] Key type lgssc unregistered [ 8684.257359] LNet: 332427:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8684.260203] LNetError: 332427:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8684.272626] LNet: Removed LNI 192.168.202.25@tcp [ 8684.514154] Key type .llcrypt unregistered [ 8684.515479] Key type ._llcrypt unregistered [ 8684.792158] Key type ._llcrypt registered [ 8684.793576] Key type .llcrypt registered [ 8684.979758] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8684.985209] alg: No test for adler32 (adler32-zlib) [ 8685.844577] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8685.930744] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8687.511158] Key type lgssc registered [ 8687.912301] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8691.476441] Lustre: DEBUG MARKER: Iteration 31 [ 8691.577357] LustreError: 333244:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8691.577698] LustreError: 333245:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8691.582894] LustreError: 333244:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8692.647291] Lustre: Mounted lustre-client [ 8693.076315] LustreError: 333352:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd09a832000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8693.080900] LustreError: 333353:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8693.081392] LustreError: 333352:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8693.102640] Lustre: Unmounted lustre-client [ 8694.019946] Key type lgssc unregistered [ 8694.132421] LNet: 333594:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8694.134771] LNetError: 333594:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8694.146564] LNet: Removed LNI 192.168.202.25@tcp [ 8694.372120] Key type .llcrypt unregistered [ 8694.373048] Key type ._llcrypt unregistered [ 8694.632061] Key type ._llcrypt registered [ 8694.632992] Key type .llcrypt registered [ 8694.810812] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8694.816270] alg: No test for adler32 (adler32-zlib) [ 8695.683634] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8695.772891] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8697.359199] Key type lgssc registered [ 8697.812728] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8701.258477] Lustre: DEBUG MARKER: Iteration 32 [ 8701.361411] LustreError: 334413:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8701.361463] LustreError: 334414:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8701.366417] LustreError: 334413:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8702.416916] Lustre: Mounted lustre-client [ 8702.902127] LustreError: 334520:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd09898c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8702.906432] LustreError: 334520:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8702.906875] LustreError: 334519:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8702.911932] LustreError: 334519:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8702.936807] Lustre: Unmounted lustre-client [ 8703.845059] Key type lgssc unregistered [ 8703.963403] LNet: 334762:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8703.965891] LNetError: 334762:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8703.974698] LNet: Removed LNI 192.168.202.25@tcp [ 8704.210131] Key type .llcrypt unregistered [ 8704.211444] Key type ._llcrypt unregistered [ 8704.478478] Key type ._llcrypt registered [ 8704.484484] Key type .llcrypt registered [ 8704.664534] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8704.669969] alg: No test for adler32 (adler32-zlib) [ 8705.538686] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8705.623337] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8707.207150] Key type lgssc registered [ 8707.634460] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8711.226412] Lustre: DEBUG MARKER: Iteration 33 [ 8711.362480] LustreError: 335593:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8711.362562] LustreError: 335594:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8711.368206] LustreError: 335593:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8712.433787] Lustre: Mounted lustre-client [ 8712.435301] Lustre: Skipped 1 previous similar message [ 8712.869236] LustreError: 335698:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd0852eb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8712.871360] LustreError: 335699:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8712.873310] LustreError: 335698:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8712.890187] Lustre: Unmounted lustre-client [ 8713.899108] Key type lgssc unregistered [ 8714.021800] LNet: 335941:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8714.024352] LNetError: 335941:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8714.032125] LNet: Removed LNI 192.168.202.25@tcp [ 8714.281120] Key type .llcrypt unregistered [ 8714.282421] Key type ._llcrypt unregistered [ 8714.527634] Key type ._llcrypt registered [ 8714.529189] Key type .llcrypt registered [ 8714.724394] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8714.729163] alg: No test for adler32 (adler32-zlib) [ 8715.587639] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8715.685479] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8717.271162] Key type lgssc registered [ 8717.682900] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8721.417858] Lustre: DEBUG MARKER: Iteration 34 [ 8721.512666] LustreError: 336759:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8721.518613] LustreError: 336761:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8721.521954] LustreError: 336759:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 8722.571723] Lustre: Mounted lustre-client [ 8722.574016] Lustre: Skipped 1 previous similar message [ 8723.052768] LustreError: 336867:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd084eab800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8723.056486] LustreError: 336866:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8723.057511] LustreError: 336867:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8723.082199] Lustre: Unmounted lustre-client [ 8724.131125] Key type lgssc unregistered [ 8724.269531] LNet: 337107:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8724.271816] LNetError: 337107:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8724.287543] LNet: Removed LNI 192.168.202.25@tcp [ 8724.540129] Key type .llcrypt unregistered [ 8724.541210] Key type ._llcrypt unregistered [ 8724.791316] Key type ._llcrypt registered [ 8724.798602] Key type .llcrypt registered [ 8725.002122] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8725.007603] alg: No test for adler32 (adler32-zlib) [ 8725.877225] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8725.968567] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8727.559214] Key type lgssc registered [ 8728.001339] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8731.770919] Lustre: DEBUG MARKER: Iteration 35 [ 8731.904819] LustreError: 337924:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8731.906136] LustreError: 337925:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8731.911361] LustreError: 337924:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8732.960722] Lustre: Mounted lustre-client [ 8732.962463] Lustre: Skipped 1 previous similar message [ 8733.470332] LustreError: 338032:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd0acbc4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8733.477105] LustreError: 338032:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8733.500829] Lustre: Unmounted lustre-client [ 8734.549918] Key type lgssc unregistered [ 8734.666464] LNet: 338272:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8734.669087] LNetError: 338272:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8734.677086] LNet: Removed LNI 192.168.202.25@tcp [ 8734.942153] Key type .llcrypt unregistered [ 8734.943613] Key type ._llcrypt unregistered [ 8735.259975] Key type ._llcrypt registered [ 8735.260976] Key type .llcrypt registered [ 8735.433918] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8735.441458] alg: No test for adler32 (adler32-zlib) [ 8736.293779] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8736.374858] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8737.967170] Key type lgssc registered [ 8738.372460] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8741.828717] Lustre: DEBUG MARKER: Iteration 36 [ 8741.927887] LustreError: 339103:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8741.929109] LustreError: 339105:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8741.933783] LustreError: 339103:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8742.996179] Lustre: Mounted lustre-client [ 8742.997711] Lustre: Skipped 1 previous similar message [ 8743.437870] LustreError: 339211:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd0acbc1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8743.440586] LustreError: 339210:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8743.442362] LustreError: 339211:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8743.456467] Lustre: Unmounted lustre-client [ 8744.446946] Key type lgssc unregistered [ 8744.567957] LNet: 339453:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8744.570575] LNetError: 339453:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8744.578113] LNet: Removed LNI 192.168.202.25@tcp [ 8744.806105] Key type .llcrypt unregistered [ 8744.807401] Key type ._llcrypt unregistered [ 8745.075257] Key type ._llcrypt registered [ 8745.076992] Key type .llcrypt registered [ 8745.239577] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8745.244066] alg: No test for adler32 (adler32-zlib) [ 8746.099531] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8746.177389] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8747.759174] Key type lgssc registered [ 8748.155778] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8751.496305] Lustre: DEBUG MARKER: Iteration 37 [ 8751.612038] LustreError: 340271:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8751.612066] LustreError: 340270:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8751.618234] LustreError: 340271:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8752.672206] Lustre: Mounted lustre-client [ 8752.673333] Lustre: Skipped 1 previous similar message [ 8753.219318] LustreError: 340379:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd088166000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8753.222907] LustreError: 340380:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8753.223947] LustreError: 340379:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8753.236124] Lustre: Unmounted lustre-client [ 8754.292981] Key type lgssc unregistered [ 8754.420750] LNet: 340620:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8754.423058] LNetError: 340620:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8754.430583] LNet: Removed LNI 192.168.202.25@tcp [ 8754.700198] Key type .llcrypt unregistered [ 8754.701612] Key type ._llcrypt unregistered [ 8754.966543] Key type ._llcrypt registered [ 8754.971444] Key type .llcrypt registered [ 8755.158979] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8755.165100] alg: No test for adler32 (adler32-zlib) [ 8756.028622] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8756.109294] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8757.695208] Key type lgssc registered [ 8758.124688] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8761.839130] Lustre: DEBUG MARKER: Iteration 38 [ 8761.957126] LustreError: 341437:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8761.957195] LustreError: 341438:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8761.963450] LustreError: 341437:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8763.019058] Lustre: Mounted lustre-client [ 8763.020617] Lustre: Skipped 1 previous similar message [ 8763.474645] LustreError: 341542:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd0a7676800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8763.478304] LustreError: 341542:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8763.482025] LustreError: 341543:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8763.485096] LustreError: 341543:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8763.499140] Lustre: Unmounted lustre-client [ 8764.505648] Key type lgssc unregistered [ 8764.622371] LNet: 341783:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8764.625078] LNetError: 341783:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8764.632705] LNet: Removed LNI 192.168.202.25@tcp [ 8764.852083] Key type .llcrypt unregistered [ 8764.853180] Key type ._llcrypt unregistered [ 8765.097665] Key type ._llcrypt registered [ 8765.102546] Key type .llcrypt registered [ 8765.287148] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8765.291655] alg: No test for adler32 (adler32-zlib) [ 8766.154533] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8766.233122] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8767.815165] Key type lgssc registered [ 8768.242368] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8771.877404] Lustre: DEBUG MARKER: Iteration 39 [ 8771.987358] LustreError: 342601:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8771.987412] LustreError: 342600:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8771.992446] LustreError: 342601:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8773.045320] Lustre: Mounted lustre-client [ 8773.046232] Lustre: Skipped 1 previous similar message [ 8773.588428] LustreError: 342707:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd092f2d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8773.592041] LustreError: 342707:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8773.594760] LustreError: 342706:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 8773.614689] Lustre: Unmounted lustre-client [ 8774.598770] Key type lgssc unregistered [ 8774.715738] LNet: 342950:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8774.718112] LNetError: 342950:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8774.726302] LNet: Removed LNI 192.168.202.25@tcp [ 8774.956095] Key type .llcrypt unregistered [ 8774.957160] Key type ._llcrypt unregistered [ 8775.250099] Key type ._llcrypt registered [ 8775.251758] Key type .llcrypt registered [ 8775.416814] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8775.422742] alg: No test for adler32 (adler32-zlib) [ 8776.282853] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8776.368453] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8777.951161] Key type lgssc registered [ 8778.390530] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8781.880088] Lustre: DEBUG MARKER: Iteration 40 [ 8781.981566] LustreError: 343767:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8781.981586] LustreError: 343768:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8781.986692] LustreError: 343767:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8783.037701] Lustre: Mounted lustre-client [ 8783.037701] Lustre: Mounted lustre-client [ 8783.508854] LustreError: 343874:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd097990000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8783.510855] LustreError: 343875:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8783.514158] LustreError: 343874:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8783.534641] Lustre: Unmounted lustre-client [ 8784.507056] Key type lgssc unregistered [ 8784.631684] LNet: 344116:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8784.634699] LNetError: 344116:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8784.647048] LNet: Removed LNI 192.168.202.25@tcp [ 8784.881122] Key type .llcrypt unregistered [ 8784.882588] Key type ._llcrypt unregistered [ 8785.147597] Key type ._llcrypt registered [ 8785.148707] Key type .llcrypt registered [ 8785.321946] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8785.326808] alg: No test for adler32 (adler32-zlib) [ 8786.185538] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8786.265620] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8787.847221] Key type lgssc registered [ 8788.253637] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8791.730500] Lustre: DEBUG MARKER: Iteration 41 [ 8791.837535] LustreError: 344933:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8791.837574] LustreError: 344934:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8791.843543] LustreError: 344933:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8792.906875] Lustre: Mounted lustre-client [ 8792.907897] Lustre: Skipped 1 previous similar message [ 8793.339584] LustreError: 345041:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd0c0bde000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8793.343499] LustreError: 345042:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8793.344799] LustreError: 345041:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8793.359389] Lustre: Unmounted lustre-client [ 8794.364376] Key type lgssc unregistered [ 8794.478411] LNet: 345283:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8794.481845] LNetError: 345283:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8794.491713] LNet: Removed LNI 192.168.202.25@tcp [ 8794.751138] Key type .llcrypt unregistered [ 8794.752810] Key type ._llcrypt unregistered [ 8795.025367] Key type ._llcrypt registered [ 8795.027664] Key type .llcrypt registered [ 8795.219684] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8795.225364] alg: No test for adler32 (adler32-zlib) [ 8796.088175] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8796.165630] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8797.751222] Key type lgssc registered [ 8798.179779] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8801.750758] Lustre: DEBUG MARKER: Iteration 42 [ 8801.857953] LustreError: 346101:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8801.858298] LustreError: 346102:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8801.862947] LustreError: 346101:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8802.938474] Lustre: Mounted lustre-client [ 8802.941214] Lustre: Skipped 1 previous similar message [ 8803.446485] LustreError: 346210:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd098992000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8803.449781] LustreError: 346209:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8803.450656] LustreError: 346210:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8803.465790] Lustre: Unmounted lustre-client [ 8804.411690] Key type lgssc unregistered [ 8804.523406] LNet: 346451:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8804.526428] LNetError: 346451:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8804.533540] LNet: Removed LNI 192.168.202.25@tcp [ 8804.767106] Key type .llcrypt unregistered [ 8804.768302] Key type ._llcrypt unregistered [ 8805.009274] Key type ._llcrypt registered [ 8805.010371] Key type .llcrypt registered [ 8805.208336] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8805.214896] alg: No test for adler32 (adler32-zlib) [ 8806.067225] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8806.146207] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8807.727138] Key type lgssc registered [ 8808.097473] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8811.448738] Lustre: DEBUG MARKER: Iteration 43 [ 8811.559884] LustreError: 347269:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8811.561207] LustreError: 347270:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8811.565495] LustreError: 347269:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8812.610332] Lustre: Mounted lustre-client [ 8812.611979] Lustre: Skipped 1 previous similar message [ 8813.028533] LustreError: 347375:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd083af3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8813.031443] LustreError: 347376:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8813.033523] LustreError: 347375:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8813.047817] Lustre: Unmounted lustre-client [ 8813.953320] Key type lgssc unregistered [ 8814.065544] LNet: 347617:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8814.068780] LNetError: 347617:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8814.078694] LNet: Removed LNI 192.168.202.25@tcp [ 8814.326101] Key type .llcrypt unregistered [ 8814.327291] Key type ._llcrypt unregistered [ 8814.594645] Key type ._llcrypt registered [ 8814.596343] Key type .llcrypt registered [ 8814.783152] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8814.788596] alg: No test for adler32 (adler32-zlib) [ 8815.648970] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8815.737094] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8817.327149] Key type lgssc registered [ 8817.699431] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8821.181781] Lustre: DEBUG MARKER: Iteration 44 [ 8821.300727] LustreError: 348434:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8821.300870] LustreError: 348435:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8821.307373] LustreError: 348434:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8822.358607] Lustre: Mounted lustre-client [ 8822.827321] LustreError: 348542:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd0911a4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8822.831456] LustreError: 348542:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8822.832022] LustreError: 348543:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8822.837213] LustreError: 348543:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8822.854098] Lustre: Unmounted lustre-client [ 8823.846172] Key type lgssc unregistered [ 8823.963495] LNet: 348785:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8823.966762] LNetError: 348785:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8823.975703] LNet: Removed LNI 192.168.202.25@tcp [ 8824.208146] Key type .llcrypt unregistered [ 8824.209548] Key type ._llcrypt unregistered [ 8824.451749] Key type ._llcrypt registered [ 8824.453183] Key type .llcrypt registered [ 8824.661867] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8824.666908] alg: No test for adler32 (adler32-zlib) [ 8825.542176] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8825.637235] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8827.231130] Key type lgssc registered [ 8827.646847] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8831.113206] Lustre: DEBUG MARKER: Iteration 45 [ 8831.211253] LustreError: 349602:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8831.211301] LustreError: 349603:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8831.217380] LustreError: 349602:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8832.264561] Lustre: Mounted lustre-client [ 8832.266340] Lustre: Skipped 1 previous similar message [ 8832.702247] LustreError: 349710:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd083e95000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8832.705567] LustreError: 349710:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8832.709197] LustreError: 349711:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8832.711157] LustreError: 349711:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 8832.719699] Lustre: Unmounted lustre-client [ 8833.681381] Key type lgssc unregistered [ 8833.808745] LNet: 349952:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8833.811807] LNetError: 349952:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8833.819701] LNet: Removed LNI 192.168.202.25@tcp [ 8834.072125] Key type .llcrypt unregistered [ 8834.073356] Key type ._llcrypt unregistered [ 8834.353562] Key type ._llcrypt registered [ 8834.355174] Key type .llcrypt registered [ 8834.508740] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8834.515523] alg: No test for adler32 (adler32-zlib) [ 8835.366671] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8835.442995] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8837.015066] Key type lgssc registered [ 8837.359023] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8840.719670] Lustre: DEBUG MARKER: Iteration 46 [ 8840.824079] LustreError: 350771:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8840.824109] LustreError: 350772:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8840.828982] LustreError: 350771:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8841.884664] Lustre: Mounted lustre-client [ 8841.885805] Lustre: Skipped 1 previous similar message [ 8842.349325] LustreError: 350876:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd0ab056000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8842.353404] LustreError: 350877:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 8842.354854] LustreError: 350876:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8842.373769] Lustre: Unmounted lustre-client [ 8843.360894] Key type lgssc unregistered [ 8843.483291] LNet: 351118:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8843.485907] LNetError: 351118:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8843.496598] LNet: Removed LNI 192.168.202.25@tcp [ 8843.736150] Key type .llcrypt unregistered [ 8843.737312] Key type ._llcrypt unregistered [ 8844.014893] Key type ._llcrypt registered [ 8844.016054] Key type .llcrypt registered [ 8844.210659] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8844.216424] alg: No test for adler32 (adler32-zlib) [ 8845.085599] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8845.178660] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8846.775187] Key type lgssc registered [ 8847.215065] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8850.843162] Lustre: DEBUG MARKER: Iteration 47 [ 8850.947414] LustreError: 351936:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8850.947448] LustreError: 351937:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8850.953188] LustreError: 351936:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8852.019646] Lustre: Mounted lustre-client [ 8852.476340] LustreError: 352045:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd0989ea000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8852.480398] LustreError: 352044:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8852.480530] LustreError: 352045:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8852.498076] Lustre: Unmounted lustre-client [ 8853.477406] Key type lgssc unregistered [ 8853.586484] LNet: 352286:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8853.590234] LNetError: 352286:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8853.599573] LNet: Removed LNI 192.168.202.25@tcp [ 8853.840173] Key type .llcrypt unregistered [ 8853.841706] Key type ._llcrypt unregistered [ 8854.095473] Key type ._llcrypt registered [ 8854.096856] Key type .llcrypt registered [ 8854.290970] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8854.296532] alg: No test for adler32 (adler32-zlib) [ 8855.164131] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8855.254517] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8856.839205] Key type lgssc registered [ 8857.221707] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8860.733702] Lustre: DEBUG MARKER: Iteration 48 [ 8860.851460] LustreError: 353103:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8860.851501] LustreError: 353104:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8860.858952] LustreError: 353103:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8861.914652] Lustre: Mounted lustre-client [ 8861.915840] Lustre: Skipped 1 previous similar message [ 8862.365857] LustreError: 353210:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd099193000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8862.369513] LustreError: 353209:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8862.369708] LustreError: 353210:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8862.383210] Lustre: Unmounted lustre-client [ 8863.378499] Key type lgssc unregistered [ 8863.496654] LNet: 353450:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8863.500431] LNetError: 353450:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8863.509291] LNet: Removed LNI 192.168.202.25@tcp [ 8863.783439] Key type .llcrypt unregistered [ 8863.784985] Key type ._llcrypt unregistered [ 8864.151321] Key type ._llcrypt registered [ 8864.153865] Key type .llcrypt registered [ 8864.399548] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8864.406742] alg: No test for adler32 (adler32-zlib) [ 8865.271709] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8865.377701] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8866.975171] Key type lgssc registered [ 8867.376930] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8870.908483] Lustre: DEBUG MARKER: Iteration 49 [ 8871.015690] LustreError: 354268:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8871.016747] LustreError: 354269:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8871.021695] LustreError: 354268:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8872.074545] Lustre: Mounted lustre-client [ 8872.076624] Lustre: Skipped 1 previous similar message [ 8872.475363] LustreError: 354378:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd0884a3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8872.480997] LustreError: 354378:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 8872.484279] LustreError: 354378:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 8872.496067] Lustre: Unmounted lustre-client [ 8873.378916] Key type lgssc unregistered [ 8873.498462] LNet: 354620:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8873.501925] LNetError: 354620:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8873.511599] LNet: Removed LNI 192.168.202.25@tcp [ 8873.728101] Key type .llcrypt unregistered [ 8873.729427] Key type ._llcrypt unregistered [ 8873.951836] Key type ._llcrypt registered [ 8873.952855] Key type .llcrypt registered [ 8874.151744] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8874.157194] alg: No test for adler32 (adler32-zlib) [ 8875.010116] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8875.091828] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8876.671155] Key type lgssc registered [ 8877.104915] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8880.628804] Lustre: DEBUG MARKER: Iteration 50 [ 8880.734082] LustreError: 355438:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 8880.734174] LustreError: 355439:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 8880.739504] LustreError: 355438:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8881.790290] Lustre: Mounted lustre-client [ 8881.792171] Lustre: Skipped 1 previous similar message [ 8882.300474] LustreError: 355546:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd0830dc000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8882.303047] LustreError: 355545:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 8882.305313] LustreError: 355546:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8882.317086] Lustre: Unmounted lustre-client [ 8883.240149] Key type lgssc unregistered [ 8883.354354] LNet: 355786:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8883.356685] LNetError: 355786:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8883.363847] LNet: Removed LNI 192.168.202.25@tcp [ 8883.589137] Key type .llcrypt unregistered [ 8883.590281] Key type ._llcrypt unregistered [ 8883.850592] Key type ._llcrypt registered [ 8883.852400] Key type .llcrypt registered [ 8884.040469] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8884.046565] alg: No test for adler32 (adler32-zlib) [ 8884.915644] Lustre: Lustre: Build Version: 2.16.61_45_g58940e0 [ 8885.002118] LNet: Added LNI 192.168.202.25@tcp [8/256/0/180] [ 8886.583140] Key type lgssc registered [ 8886.952097] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8890.736689] Lustre: Mounted lustre-client [ 8893.201162] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 23:33:36 (1763354016) [ 8909.791134] Lustre: 357112:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763354017/real 1763354017] req@ffff8cd0b6ff4380 x1849010692301568/t0(0) o36->lustre-MDT0000-mdc-ffff8cd083e92800@192.168.202.125@tcp:12/10 lens 496/440 e 0 to 1 dl 1763354033 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 8909.799142] Lustre: lustre-MDT0000-mdc-ffff8cd083e92800: Connection to lustre-MDT0000 (at 192.168.202.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8909.807810] Lustre: lustre-MDT0000-mdc-ffff8cd083e92800: Connection restored to 192.168.202.125@tcp (at 192.168.202.125@tcp) [ 8925.151185] Lustre: 357112:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763354033/real 1763354033] req@ffff8cd0b6ff4380 x1849010692301568/t0(0) o36->lustre-MDT0000-mdc-ffff8cd083e92800@192.168.202.125@tcp:12/10 lens 496/440 e 0 to 1 dl 1763354049 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 8925.160887] Lustre: lustre-MDT0000-mdc-ffff8cd083e92800: Connection to lustre-MDT0000 (at 192.168.202.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8925.170217] Lustre: lustre-MDT0000-mdc-ffff8cd083e92800: Connection restored to 192.168.202.125@tcp (at 192.168.202.125@tcp) [ 8941.535161] Lustre: 357112:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763354049/real 1763354049] req@ffff8cd0b6ff4380 x1849010692301568/t0(0) o36->lustre-MDT0000-mdc-ffff8cd083e92800@192.168.202.125@tcp:12/10 lens 496/440 e 0 to 1 dl 1763354065 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 8941.544677] Lustre: lustre-MDT0000-mdc-ffff8cd083e92800: Connection to lustre-MDT0000 (at 192.168.202.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8941.554815] Lustre: lustre-MDT0000-mdc-ffff8cd083e92800: Connection restored to 192.168.202.125@tcp (at 192.168.202.125@tcp) [ 8957.919202] Lustre: 357112:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763354065/real 1763354065] req@ffff8cd0b6ff4380 x1849010692301568/t0(0) o36->lustre-MDT0000-mdc-ffff8cd083e92800@192.168.202.125@tcp:12/10 lens 496/440 e 0 to 1 dl 1763354081 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 8957.927027] Lustre: lustre-MDT0000-mdc-ffff8cd083e92800: Connection to lustre-MDT0000 (at 192.168.202.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8957.938622] Lustre: lustre-MDT0000-mdc-ffff8cd083e92800: Connection restored to 192.168.202.125@tcp (at 192.168.202.125@tcp) [ 8958.503909] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 23:34:41 (1763354081) [ 8959.035211] Lustre: DEBUG MARKER: SKIP: sanityn test_111 needs >= 2 MDTs [ 8959.696183] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 23:34:43 (1763354083) [ 8960.404313] Lustre: DEBUG MARKER: SKIP: sanityn test_112 We need at least 2 MDTs for this test [ 8961.114393] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 23:34:44 (1763354084) [ 8963.782393] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 23:34:47 (1763354087) [ 8964.336832] Lustre: DEBUG MARKER: SKIP: sanityn test_114 We need at least 2 MDTs for this test [ 8965.051804] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 23:34:48 (1763354088) [ 8965.647702] Lustre: DEBUG MARKER: SKIP: sanityn test_115 ldiskfs only test [ 8966.267653] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 23:34:49 (1763354089) [ 8966.841202] Lustre: DEBUG MARKER: SKIP: sanityn test_116 needs >= 2 MDTs [ 8967.491721] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 23:34:50 (1763354090) [ 8985.055080] Lustre: 355975:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763354093/real 1763354093] req@ffff8cd0b6c24380 x1849010692326016/t0(0) o4->lustre-OST0000-osc-ffff8cd083e92800@192.168.202.125@tcp:6/4 lens 4584/448 e 0 to 1 dl 1763354109 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 8985.055114] Lustre: lustre-OST0000-osc-ffff8cd083e92800: Connection to lustre-OST0000 (at 192.168.202.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8985.063641] Lustre: 355975:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 1 previous similar message [ 8985.074688] Lustre: lustre-OST0000-osc-ffff8cd083e92800: Connection restored to 192.168.202.125@tcp (at 192.168.202.125@tcp) [ 9001.375150] Lustre: 355976:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763354109/real 1763354109] req@ffff8cd0b6c25f80 x1849010692327552/t0(0) o4->lustre-OST0000-osc-ffff8cd083e92800@192.168.202.125@tcp:6/4 lens 4584/448 e 0 to 1 dl 1763354125 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 9001.383070] Lustre: 355976:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 1 previous similar message [ 9001.386137] Lustre: lustre-OST0000-osc-ffff8cd083e92800: Connection to lustre-OST0000 (at 192.168.202.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9001.397227] Lustre: lustre-OST0000-osc-ffff8cd083e92800: Connection restored to 192.168.202.125@tcp (at 192.168.202.125@tcp) [ 9017.823183] Lustre: 355976:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763354125/real 1763354125] req@ffff8cd0b6c25f80 x1849010692327552/t0(0) o4->lustre-OST0000-osc-ffff8cd083e92800@192.168.202.125@tcp:6/4 lens 4584/448 e 0 to 1 dl 1763354141 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 9017.823239] Lustre: lustre-OST0000-osc-ffff8cd083e92800: Connection to lustre-OST0000 (at 192.168.202.125@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9017.831613] Lustre: 355976:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 1 previous similar message [ 9017.843785] Lustre: lustre-OST0000-osc-ffff8cd083e92800: Connection restored to 192.168.202.125@tcp (at 192.168.202.125@tcp) [ 9033.043486] Lustre: DEBUG MARKER: oleg225-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8cd083e92800.ost_server_uuid 50 [ 9033.551409] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8cd083e92800.ost_server_uuid in FULL state after 0 sec [ 9034.066859] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 9034.640053] Lustre: DEBUG MARKER: == sanityn test complete, duration 8803 sec ============== 23:35:58 (1763354158) [ 9035.155341] Lustre: DEBUG MARKER: === sanityn: start cleanup 23:35:58 (1763354158) === [ 9076.770076] LustreError: 361179:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd08848b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9076.774674] LustreError: 361179:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 9076.785132] Lustre: Unmounted lustre-client [ 9078.029701] Lustre: DEBUG MARKER: === sanityn: finish cleanup 23:36:41 (1763354201) === [ 9078.339520] LustreError: 361480:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8cd083e92800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9078.342256] LustreError: 361480:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 9078.346884] LustreError: 361480:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9078.348361] LustreError: 361480:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 9078.363109] Lustre: Unmounted lustre-client [ 9090.342615] Key type lgssc unregistered [ 9090.466821] LNet: 361960:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9090.470051] LNetError: 361960:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9090.479419] LNet: Removed LNI 192.168.202.25@tcp [ 9090.739187] Key type .llcrypt unregistered [ 9090.740922] Key type ._llcrypt unregistered