[ 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 465534439 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.001018] APIC: Switch to symmetric I/O mode setup [ 0.002000] x2apic enabled [ 0.002000] Switched APIC routing to physical x2apic. [ 0.002017] kvm-guest: setup PV IPIs [ 0.004904] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.005000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.005027] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.006013] pid_max: default: 32768 minimum: 301 [ 0.007138] LSM: Security Framework initializing [ 0.008037] Yama: becoming mindful. [ 0.009025] SELinux: Initializing. [ 0.010049] *** VALIDATE selinux *** [ 0.018164] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.023447] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.024187] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.026025] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027178] *** VALIDATE tmpfs *** [ 0.029531] *** VALIDATE proc *** [ 0.031032] *** VALIDATE cgroup *** [ 0.032010] *** VALIDATE cgroup2 *** [ 0.033319] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.034180] 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.036033] Spectre V2 : User space: Vulnerable [ 0.037009] Speculative Store Bypass: Vulnerable [ 0.040610] debug: unmapping init [mem 0xffffffff8c059000-0xffffffff8c060fff] [ 0.042173] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.043707] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.044017] ... version: 2 [ 0.045009] ... bit width: 48 [ 0.046005] ... generic registers: 4 [ 0.047006] ... value mask: 0000ffffffffffff [ 0.048006] ... max period: 00007fffffffffff [ 0.049006] ... fixed-purpose events: 3 [ 0.050006] ... event mask: 000000070000000f [ 0.051294] rcu: Hierarchical SRCU implementation. [ 0.053434] smp: Bringing up secondary CPUs ... [ 0.054490] x86: Booting SMP configuration: [ 0.055011] .... node #0, CPUs: #1 #2 #3 [ 0.085268] smp: Brought up 1 node, 4 CPUs [ 0.087011] smpboot: Max logical packages: 1 [ 0.088019] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.122297] node 0 deferred pages initialised in 30ms [ 0.127007] devtmpfs: initialized [ 0.128279] x86/mm: Memory block size: 128MB [ 0.131338] gcov: version magic: 0x41383552 [ 0.133413] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.134076] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.135601] pinctrl core: initialized pinctrl subsystem [ 0.136801] [ 0.137044] ************************************************************* [ 0.138035] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.139017] ** ** [ 0.140017] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.141035] ** ** [ 0.142021] ** This means that this kernel is built to expose internal ** [ 0.143015] ** IOMMU data structures, which may compromise security on ** [ 0.144020] ** your system. ** [ 0.145036] ** ** [ 0.146045] ** If you see this message and you are not debugging the ** [ 0.147015] ** kernel, report this immediately to your vendor! ** [ 0.148019] ** ** [ 0.149018] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.150056] ************************************************************* [ 0.152869] NET: Registered protocol family 16 [ 0.153727] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.154094] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.155136] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.157158] cpuidle: using governor menu [ 0.160024] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.162485] PCI: Using configuration type 1 for base access [ 0.163134] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.172118] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.173013] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.175062] cryptd: max_cpu_qlen set to 1000 [ 0.178397] ACPI: Added _OSI(Module Device) [ 0.179031] ACPI: Added _OSI(Processor Device) [ 0.180020] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.181014] ACPI: Added _OSI(Processor Aggregator Device) [ 0.184917] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.189046] ACPI: Interpreter enabled [ 0.190050] ACPI: PM: (supports S0 S3 S4 S5) [ 0.191009] ACPI: Using IOAPIC for interrupt routing [ 0.192114] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.193477] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.203296] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.204050] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.205010] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.206084] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.208413] acpiphp: Slot [2] registered [ 0.209180] acpiphp: Slot [3] registered [ 0.210101] acpiphp: Slot [4] registered [ 0.211117] acpiphp: Slot [5] registered [ 0.212236] acpiphp: Slot [6] registered [ 0.213169] acpiphp: Slot [7] registered [ 0.214130] acpiphp: Slot [8] registered [ 0.215064] acpiphp: Slot [9] registered [ 0.216108] acpiphp: Slot [10] registered [ 0.217093] acpiphp: Slot [11] registered [ 0.217835] acpiphp: Slot [12] registered [ 0.218056] acpiphp: Slot [13] registered [ 0.219052] acpiphp: Slot [14] registered [ 0.220056] acpiphp: Slot [15] registered [ 0.221059] acpiphp: Slot [16] registered [ 0.222085] acpiphp: Slot [17] registered [ 0.223063] acpiphp: Slot [18] registered [ 0.224055] acpiphp: Slot [19] registered [ 0.225054] acpiphp: Slot [20] registered [ 0.226105] acpiphp: Slot [21] registered [ 0.227063] acpiphp: Slot [22] registered [ 0.228072] acpiphp: Slot [23] registered [ 0.229085] acpiphp: Slot [24] registered [ 0.230062] acpiphp: Slot [25] registered [ 0.231065] acpiphp: Slot [26] registered [ 0.232063] acpiphp: Slot [27] registered [ 0.233060] acpiphp: Slot [28] registered [ 0.234081] acpiphp: Slot [29] registered [ 0.235074] acpiphp: Slot [30] registered [ 0.236058] acpiphp: Slot [31] registered [ 0.237045] PCI host bridge to bus 0000:00 [ 0.238011] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.239013] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.240011] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.241012] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.242011] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.243017] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.244151] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.247289] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.250682] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.255013] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.258048] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.259015] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.260012] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.261013] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.262596] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.263814] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.264029] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.265587] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.267012] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.272986] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.274590] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.277776] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.280014] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.283013] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.290013] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.294000] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.299019] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.304013] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.316013] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.323446] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.324320] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.326334] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.329257] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.331179] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.336159] iommu: Default domain type: Passthrough [ 0.338429] SCSI subsystem initialized [ 0.340114] ACPI: bus type USB registered [ 0.342103] usbcore: registered new interface driver usbfs [ 0.344066] usbcore: registered new interface driver hub [ 0.346071] usbcore: registered new device driver usb [ 0.349162] pps_core: LinuxPPS API ver. 1 registered [ 0.351008] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.354055] PTP clock support registered [ 0.357101] EDAC MC: Ver: 3.0.0 [ 0.359279] PCI: Using ACPI for IRQ routing [ 0.360000] NetLabel: Initializing [ 0.361008] NetLabel: domain hash size = 128 [ 0.363007] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.365090] NetLabel: unlabeled traffic allowed by default [ 0.368177] vgaarb: loaded [ 0.369256] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.371010] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.383000] clocksource: Switched to clocksource kvm-clock [ 0.528649] VFS: Disk quotas dquot_6.6.0 [ 0.530704] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.533396] *** VALIDATE ramfs *** [ 0.534610] *** VALIDATE hugetlbfs *** [ 0.536092] pnp: PnP ACPI init [ 0.538404] pnp: PnP ACPI: found 6 devices [ 0.555592] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.558953] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.561096] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.563199] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.565566] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.568101] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.570723] NET: Registered protocol family 2 [ 0.573063] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.577477] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.580954] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.586147] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.589402] TCP: Hash tables configured (established 65536 bind 65536) [ 0.592245] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.595099] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.597862] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.600726] NET: Registered protocol family 1 [ 0.603169] RPC: Registered named UNIX socket transport module. [ 0.605068] RPC: Registered udp transport module. [ 0.606230] RPC: Registered tcp transport module. [ 0.607645] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.610012] NET: Registered protocol family 44 [ 0.611571] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.613287] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.615086] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.616824] PCI: CLS 0 bytes, default 64 [ 0.618138] Unpacking initramfs... [ 2.610874] debug: unmapping init [mem 0xffff96107cc64000-0xffff96107ffcffff] [ 2.614745] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.616700] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.619405] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 3.294333] Initialise system trusted keyrings [ 3.295995] Key type blacklist registered [ 3.302835] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.319231] zbud: loaded [ 3.325725] *** VALIDATE nfs *** [ 3.326791] *** VALIDATE nfs4 *** [ 3.328158] pstore: using deflate compression [ 3.333554] Platform Keyring initialized [ 3.533274] NET: Registered protocol family 38 [ 3.535481] Key type asymmetric registered [ 3.544460] Asymmetric key parser 'x509' registered [ 3.546779] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.550799] io scheduler mq-deadline registered [ 3.552716] io scheduler kyber registered [ 3.554793] io scheduler bfq registered [ 3.556850] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.560777] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.563752] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.567417] ACPI: Power Button [PWRF] [ 3.681733] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.785698] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.920021] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.956088] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.995513] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 4.005844] Non-volatile memory driver v1.3 [ 4.011288] Linux agpgart interface v0.103 [ 4.044980] virtio_blk virtio1: [vda] 132696 512-byte logical blocks (67.9 MB/64.8 MiB) [ 4.049355] vda: detected capacity change from 0 to 67940352 [ 4.075688] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 4.080260] vdb: detected capacity change from 0 to 1073741824 [ 4.089444] libphy: Fixed MDIO Bus: probed [ 4.098984] usbcore: registered new interface driver usbserial_generic [ 4.101700] usbserial: USB Serial support registered for generic [ 4.104283] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 4.109630] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 4.112521] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 4.116247] mousedev: PS/2 mouse device common for all mice [ 4.120725] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 4.124580] rtc_cmos 00:05: RTC can wake from S4 [ 4.136282] rtc_cmos 00:05: registered as rtc0 [ 4.138754] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 4.141343] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 4.151371] intel_pstate: CPU model not supported [ 4.162503] hid: raw HID events driver (C) Jiri Kosina [ 4.166090] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 4.168180] usbcore: registered new interface driver usbhid [ 4.177287] usbhid: USB HID core driver [ 4.179092] drop_monitor: Initializing network drop monitor service [ 4.182512] Initializing XFRM netlink socket [ 4.184736] NET: Registered protocol family 10 [ 4.189375] Segment Routing with IPv6 [ 4.190832] NET: Registered protocol family 17 [ 4.194138] mpls_gso: MPLS GSO support [ 4.200532] RAS: Correctable Errors collector initialized. [ 4.203035] AVX version of gcm_enc/dec engaged. [ 4.205128] AES CTR mode by8 optimization enabled [ 4.312896] sched_clock: Marking stable (4312874704, 0)->(5479117965, -1166243261) [ 4.318240] registered taskstats version 1 [ 4.320402] Loading compiled-in X.509 certificates [ 4.324463] zswap: loaded using pool lzo/zbud [ 4.362374] Key type big_key registered [ 4.382786] Key type encrypted registered [ 4.385134] ima: No TPM chip found, activating TPM-bypass! [ 4.388802] ima: Allocated hash algorithm: sha1 [ 4.390893] ima: No architecture policies found [ 4.392698] evm: Initialising EVM extended attributes: [ 4.394710] evm: security.selinux [ 4.396503] evm: security.ima [ 4.397984] evm: security.capability [ 4.399433] evm: HMAC attrs: 0x1 [ 4.413784] rtc_cmos 00:05: setting system clock to 2025-07-16 17:41:50 UTC (1752687710) [ 4.421732] debug: unmapping init [mem 0xffffffff8d003000-0xffffffff8d1fffff] [ 4.425207] debug: unmapping init [mem 0xffffffff8bd82000-0xffffffff8c058fff] [ 4.440154] Write protecting the kernel read-only data: 28672k [ 4.443930] debug: unmapping init [mem 0xffffffff8a403000-0xffffffff8a5fffff] [ 4.451334] debug: unmapping init [mem 0xffffffff8ad14000-0xffffffff8adfffff] [ 4.510600] 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) [ 4.525437] systemd[1]: Detected virtualization kvm. [ 4.527300] systemd[1]: Detected architecture x86-64. [ 4.528950] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 4.573708] systemd[1]: No hostname configured. [ 4.576208] systemd[1]: Set hostname to . [ 4.578478] random: systemd: uninitialized urandom read (16 bytes read) [ 4.585542] systemd[1]: Initializing machine ID from random generator. [ 4.840888] random: systemd: uninitialized urandom read (16 bytes read) [ 4.844445] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 4.852734] random: systemd: uninitialized urandom read (16 bytes read) [ 4.856610] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 4.866649] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Swap. Starting Apply Kernel Variables... [ OK ] Started Memstrack Anylazing Service. Starting Journal Service... [ OK ] Reached target Slices. Starting Create list of required st…ce nodes for the current kernel... Starting Setup Virtual Console... [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Sockets. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 6.463983] device-mapper: uevent: version 1.0.3 [ 6.465948] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 7.733161] virtio_net virtio0 ens2: renamed from eth0 [ 8.291441] scsi host0: ata_piix [ 8.302342] scsi host1: ata_piix [ 8.303600] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 8.305679] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 12.767877] random: fast init done [ 13.922797] random: crng init done [ 13.924303] random: 7 urandom warning(s) missed due to ratelimiting [ 14.731938] dracut-initqueue[583]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 16.147094] 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 Remote File Systems. [ OK ] Stopped target Initrd Default Target. [ 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 Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 18.128395] printk: systemd: 26 output lines suppressed due to ratelimiting [ 18.559406] SELinux: Disabled at runtime. [ 18.641185] 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) [ 18.656391] systemd[1]: Detected virtualization kvm. [ 18.659591] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 19.654804] systemd[1]: initrd-switch-root.service: Succeeded. [ 19.659323] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 19.665975] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 19.669529] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 19.673495] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 19.691981] systemd[1]: Starting Journal Service... Starting Journal Service... [ 19.703414] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Created slice User and Session Slice. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice system-serial\x2dgetty.slice. Mounting Huge Pages File System... Mounting Kernel Debug File System... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Activating swap /dev/disk/by-label/SWAP... Starting Apply Kernel Variables... [ OK ] Reached target rpc_pipefs.target. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Reached target Local Encrypted Volumes. [ 20.014473] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Listening on udev Control Socket. Starting Remount Root and Kernel File Systems... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on Process Core Dump Socket. [ OK ] Created slice system-getty.slice. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Stopped target Initrd File Systems. [ OK ] Reached target Slices. [ OK ] Reached target Paths. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... Mounting POSIX Message Queue File System... [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted Kernel Debug File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Apply Kernel Variables. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted POSIX Message Queue File System. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 21.051139] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 21.899147] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 22.056210] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 22.578582] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 22.655313] EDAC sbridge: Ver: 1.1.2 [ 25.025135] Key type dns_resolver registered [ 25.574153] NFS: Registering the id_resolver key type [ 25.576044] Key type id_resolver registered [ 25.578019] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting 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. Starting Restore /run/initramfs on shutdown... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started irqbalance daemon. Starting Login Service... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting Hostname Service... [ OK ] Started 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 Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg408-client login: [ 55.500364] libcfs: loading out-of-tree module taints kernel. [ 55.523150] Key type ._llcrypt registered [ 55.526434] Key type .llcrypt registered [ 55.778683] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 55.783030] alg: No test for adler32 (adler32-zlib) [ 56.749545] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 57.010856] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 57.013242] LNet: Accept secure, port 988 [ 58.615125] Key type lgssc registered [ 59.080269] Lustre: Echo OBD driver; http://www.lustre.org/ [ 100.991686] Lustre: Mounted lustre-client [ 103.327887] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 116.808089] Lustre: DEBUG MARKER: oleg408-client.virtnet: executing check_logdir /tmp/testlogs/ [ 118.139114] Lustre: DEBUG MARKER: oleg408-client.virtnet: executing yml_node [ 119.392274] Lustre: DEBUG MARKER: Client: 2.16.57.1 [ 120.218413] Lustre: DEBUG MARKER: MDS: 2.16.57.1 [ 121.051626] Lustre: DEBUG MARKER: OSS: 2.16.57.1 [ 121.596846] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Wed Jul 16 13:43:47 EDT 2025 [ 126.943176] Lustre: lustre-OST0000-osc-ffff9610d2893000: disconnect after 25s idle [ 127.557657] Lustre: DEBUG MARKER: excepting tests: 27 40a 102 [ 128.088549] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 128.648088] Lustre: DEBUG MARKER: === sanityn: start setup 13:43:54 (1752687834) === [ 128.866844] Lustre: Mounted lustre-client [ 130.152733] Lustre: DEBUG MARKER: oleg408-client.virtnet: executing check_config_client /mnt/lustre [ 135.615336] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 138.942397] Lustre: DEBUG MARKER: === sanityn: finish setup 13:44:04 (1752687844) === [ 139.600251] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 13:44:05 (1752687845) [ 142.132875] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 13:44:07 (1752687847) [ 144.135584] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 13:44:09 (1752687849) [ 146.195297] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 13:44:11 (1752687851) [ 148.195616] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 13:44:13 (1752687853) [ 150.083095] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 13:44:15 (1752687855) [ 152.031898] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 13:44:17 (1752687857) [ 153.945845] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 13:44:19 (1752687859) [ 154.383459] Lustre: DEBUG MARKER: SKIP: sanityn test_2f needs >= 2 MDTs [ 154.868665] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 13:44:20 (1752687860) [ 156.951331] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 13:44:22 (1752687862) [ 158.949263] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 13:44:24 (1752687864) [ 161.984266] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 13:44:27 (1752687867) [ 164.056669] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 13:44:29 (1752687869) [ 166.303751] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 13:44:31 (1752687871) [ 168.505376] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 13:44:34 (1752687874) [ 170.659245] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 13:44:36 (1752687876) [ 172.929144] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 13:44:38 (1752687878) [ 175.026461] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 13:44:40 (1752687880) [ 177.360930] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 13:44:42 (1752687882) [ 179.522643] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 13:44:45 (1752687885) [ 181.771706] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 13:44:47 (1752687887) [ 181.908099] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205272502304 file: /mnt/lustre/lockdir/lockfile=144115205272502302 [ 323.488614] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 13:47:09 (1752688029) [ 325.727983] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 13:47:11 (1752688031) [ 327.758409] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 13:47:13 (1752688033) [ 329.844105] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 13:47:15 (1752688035) [ 332.015346] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 13:47:17 (1752688037) [ 334.582815] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 13:47:20 (1752688040) [ 335.236655] Lustre: DEBUG MARKER: chmod [ 337.643772] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 13:47:23 (1752688043) [ 359.963430] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7524352KB free > 800000KB, increase MAXFREE (or reduce fs size) [ 370.797218] Lustre: DEBUG MARKER: == sanityn test 16a: 500 iterations of dual-mount fsx ==== 13:47:56 (1752688076) [ 425.760586] Lustre: DEBUG MARKER: == sanityn test 16b: 500 iterations of dual-mount fsx at small size ========================================================== 13:48:50 (1752688130) [ 447.889701] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 13:49:13 (1752688153) [ 449.119743] Lustre: DEBUG MARKER: SKIP: sanityn test_16c dio on ldiskfs only [ 450.266264] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 13:49:15 (1752688155) [ 473.371625] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 13:49:38 (1752688178) [ 477.572983] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 13:49:42 (1752688182) [ 478.097711] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 478.137977] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 478.182193] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 478.227181] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 478.271816] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 478.316443] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 478.355622] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 478.402602] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 478.446988] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 478.494315] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 478.532254] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 478.571963] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 478.605684] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 478.642397] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 478.688250] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 478.726741] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 478.779689] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 478.835509] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 478.873071] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 478.909404] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 478.946215] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 478.983407] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 479.029049] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 479.080653] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 479.110714] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 479.146101] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 479.177947] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 479.217435] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 479.253512] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 479.298909] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 479.335767] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 479.371447] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 479.410234] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 479.463382] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 479.515056] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 479.564112] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 479.612018] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 479.659207] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 479.706623] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 479.755366] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 479.806313] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 479.845688] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 479.910778] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 479.944284] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 479.986663] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 480.020023] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 480.065724] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 480.106365] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 480.137299] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 480.170825] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 480.209297] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 480.252803] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 480.295503] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 480.341976] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 480.378076] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 480.413381] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 480.443779] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 480.494343] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 480.529883] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 480.561720] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 480.605120] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 480.648886] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 480.688904] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 480.731078] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 480.786080] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 480.827870] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 480.881553] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 480.928167] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 480.974538] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 481.010162] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 481.057714] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 481.094566] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 481.135442] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 481.179159] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 481.232386] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 481.271528] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 481.314228] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 481.361326] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 481.409548] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 481.458605] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 481.509566] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 481.550120] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 481.609446] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 481.660907] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 481.717865] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 481.752665] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 481.791315] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 481.838653] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 481.888521] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 481.935496] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 481.991929] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 482.039099] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 482.085233] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 482.121468] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 482.158695] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 482.214735] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 482.279090] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 482.325983] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 482.370952] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 482.422364] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 482.468954] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 482.513223] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 482.551996] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 482.601547] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 482.653992] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 482.707400] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 482.743339] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 482.782410] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 482.840517] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 482.883641] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 482.925212] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 482.973159] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 483.035921] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 483.086414] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 483.124490] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 483.163098] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 483.211459] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 483.259876] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 483.299155] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 483.335985] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 483.393457] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 483.441344] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 483.480041] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 483.518931] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 483.573819] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 483.629949] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 483.670805] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 483.708378] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 483.750924] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 483.788790] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 483.825728] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 483.864110] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 483.916753] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 483.961293] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 483.992232] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 484.020890] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 484.058225] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 484.098874] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 484.142239] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 484.195629] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 484.232177] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 484.267436] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 484.301741] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 484.339585] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 484.374164] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 484.421857] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 484.456420] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 484.489995] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 484.525856] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 484.562367] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 484.619415] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 484.656186] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 484.708025] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 484.761429] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 484.801284] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 484.841364] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 484.879797] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 484.917197] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 484.950362] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 484.994353] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 485.025172] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 485.067872] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 485.119625] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 485.152964] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 485.188929] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 485.242130] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 485.282914] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 485.317770] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 485.369055] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 485.423145] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 485.462349] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 485.501354] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 485.539491] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 485.576178] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 485.614128] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 485.655210] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 485.689579] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 485.726434] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 485.758281] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 485.800116] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 485.836414] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 485.883952] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 485.926741] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 485.962213] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 486.000758] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 486.038322] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 486.074442] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 486.112740] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 486.142178] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 486.174876] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 486.203339] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 486.247579] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 486.290160] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 486.348649] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 486.391088] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 486.442824] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 486.477097] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 486.514266] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 486.550211] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 486.587391] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 486.627483] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 486.661478] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 486.691581] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 486.723977] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 486.765862] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 486.804407] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 486.839124] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 486.880342] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 486.930244] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 486.982835] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 487.025931] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 487.076797] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 487.127127] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 487.183206] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 487.232716] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 487.273842] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 487.302780] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 487.353035] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 487.394984] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 487.450657] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 487.504766] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 487.550713] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 487.586822] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 487.630121] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 487.670827] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 487.719445] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 487.758082] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 487.813825] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 487.841725] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 487.885068] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 487.942138] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 487.993978] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 488.035036] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 488.089949] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 488.140773] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 488.196114] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 488.231586] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 488.270436] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 488.305237] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 488.338272] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 488.386860] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 488.425817] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 488.469057] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 488.514273] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 488.563148] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 488.609675] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 488.655910] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 488.715650] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 488.763196] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 488.803542] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 488.851576] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 488.925266] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 488.978452] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 489.029159] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 489.073698] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 489.123122] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 489.172964] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 489.223391] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 489.272808] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 489.310630] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 489.361398] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 489.415571] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 489.459680] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 489.506358] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 489.555196] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 489.599179] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 489.636887] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 489.672516] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 489.705934] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 489.760865] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 489.819657] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 489.875923] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 489.914607] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 489.958649] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 490.003533] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 490.063379] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 490.109303] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 490.157292] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 490.191500] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 490.242618] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 490.287584] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 490.322292] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 490.372250] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 490.418762] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 490.476215] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 490.534558] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 490.580895] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 490.633538] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 490.681236] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 490.748767] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 490.800284] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 490.850510] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 490.890130] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 490.936972] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 490.981054] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 491.043332] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 491.093673] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 491.144703] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 491.218873] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 491.265623] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 491.305913] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 491.348982] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 491.404448] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 491.451135] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 491.505067] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 491.562617] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 491.621660] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 491.669682] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 491.714214] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 491.768709] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 491.819742] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 491.859608] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 491.904242] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 491.953387] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 492.002845] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 492.052574] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 492.102269] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 492.159908] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 492.230232] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 492.287192] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 492.348645] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 492.407722] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 492.441662] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 492.488186] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 492.540364] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 492.588964] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 492.639083] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 492.693090] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 492.749657] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 492.788890] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 492.837409] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 492.889549] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 492.939722] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 492.999872] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 493.041773] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 493.101396] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 493.156296] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 493.209550] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 493.254639] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 493.302312] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 493.349245] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 493.404686] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 493.463882] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 493.520635] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 493.594546] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 493.687961] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 493.751021] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 493.803961] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 493.847178] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 493.890632] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 493.944151] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 493.991921] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 494.046532] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 494.078963] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 494.131640] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 494.170056] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 494.210246] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 494.269042] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 494.329779] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 494.375464] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 494.417113] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 494.473324] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 494.517561] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 494.562631] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 494.595120] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 494.639590] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 494.695762] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 494.750626] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 494.801511] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 494.835705] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 494.870907] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 494.907755] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 494.944033] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 494.984208] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 495.021553] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 495.051518] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 495.093213] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 495.132727] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 495.183798] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 495.224253] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 495.277925] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 495.321358] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 495.361404] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 495.398859] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 495.444206] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 495.494271] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 495.532832] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 495.563682] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 495.583407] Lustre: lustre-OST0000-osc-ffff9610c73b4800: disconnect after 21s idle [ 495.586942] Lustre: Skipped 1 previous similar message [ 495.608301] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 495.653674] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 495.699819] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 495.749982] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 495.804501] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 495.846759] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 495.888638] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 495.940458] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 496.012201] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 496.063505] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 496.111742] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 496.149516] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 496.184459] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 496.223513] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 496.257898] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 496.305680] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 496.359874] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 496.413481] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 496.461973] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 496.498379] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 496.537863] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 496.587134] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 496.627289] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 496.676692] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 496.722174] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 496.760057] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 496.792820] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 496.830733] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 496.863311] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 496.939740] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 496.998858] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 497.035683] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 497.073495] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 497.115804] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 497.147604] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 497.189417] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 497.237744] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 497.294071] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 497.340247] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 497.382288] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 497.432789] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 497.476689] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 497.523058] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 497.561866] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 497.605754] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 497.644367] rw_seq_cst_vs_d (29438): drop_caches: 3 [ 500.704201] Lustre: lustre-OST0000-osc-ffff9610d2893000: disconnect after 23s idle [ 501.664675] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 13:50:07 (1752688207) [ 501.913422] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 502.344076] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 502.372285] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 502.418479] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 502.503327] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 502.533539] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 503.047093] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 503.081628] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 503.109876] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 503.234585] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 503.271722] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 503.344224] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 503.381981] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 503.467060] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 503.499668] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 503.589753] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 503.617939] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 503.704738] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 503.736421] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 504.207203] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 504.242452] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 504.336894] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 504.362198] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 504.429057] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 504.450842] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 504.471326] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 504.491937] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 504.511928] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 504.530990] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 504.607461] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 504.647821] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 504.670573] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 504.824803] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 504.847746] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 504.967715] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 505.030730] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 505.719715] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 505.743156] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 505.795091] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 505.955180] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 506.068900] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 506.115070] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 506.139397] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 506.168884] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 506.274222] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 506.367420] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 506.464257] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 506.568727] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 506.625400] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 506.803487] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 506.885693] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 506.980359] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 507.044364] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 507.066549] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 507.175489] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 507.220038] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 507.244241] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 507.266195] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 507.350761] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 507.371036] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 507.491568] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 507.563082] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 507.629087] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 507.699797] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 507.741969] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 507.767131] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 507.796608] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 508.141058] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 508.170769] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 508.200787] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 508.411861] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 508.440604] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 508.465782] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 508.492941] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 508.593678] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 508.788462] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 508.821143] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 508.886986] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 508.908881] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 509.042383] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 509.077666] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 509.202456] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 509.282630] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 509.317256] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 509.668608] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 509.699959] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 509.724480] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 509.746896] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 509.940696] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 509.976356] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 510.004543] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 510.027595] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 510.052527] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 510.075572] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 510.139682] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 510.159194] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 510.218575] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 510.305211] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 510.385290] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 510.416908] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 510.525636] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 510.547797] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 510.572119] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 510.722457] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 510.776421] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 510.794804] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 510.815902] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 510.847160] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 510.941305] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 511.141989] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 511.686413] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 512.160319] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 512.190661] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 512.417348] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 512.471263] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 512.770536] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 512.814499] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 512.980820] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 513.003872] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 513.038210] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 513.061541] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 513.294800] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 513.363631] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 513.469081] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 513.499538] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 513.526066] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 513.548536] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 513.805638] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 513.825765] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 514.115167] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 514.134976] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 514.154654] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 514.176371] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 514.251829] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 514.492571] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 514.512940] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 514.537357] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 514.568125] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 514.726856] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 514.806354] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 514.827865] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 514.846169] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 514.865378] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 514.912839] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 514.959567] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 515.182703] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 515.204714] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 515.246694] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 515.290669] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 515.341442] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 515.388711] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 515.522386] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 515.555942] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 515.634878] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 515.684858] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 515.823859] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 515.848976] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 515.871529] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 515.895039] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 515.918164] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 515.948758] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 515.992539] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 516.020170] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 516.053276] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 516.216379] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 516.309645] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 516.366459] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 516.400642] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 516.425104] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 516.886119] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 516.910042] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 516.932257] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 517.045660] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 517.064883] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 517.084228] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 517.155225] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 517.347487] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 517.375131] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 517.746884] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 517.773338] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 517.801178] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 517.942132] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 517.980984] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 518.010549] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 518.174062] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 518.201750] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 518.230378] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 518.262966] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 518.504034] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 518.546906] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 518.576492] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 518.603054] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 518.643165] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 518.689409] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 518.742395] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 518.766352] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 518.791055] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 519.044937] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 519.216683] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 519.271852] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 519.442864] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 519.466042] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 519.490825] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 519.524742] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 519.637800] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 519.667895] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 519.909760] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 519.959995] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 520.009062] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 520.047069] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 520.082364] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 520.123928] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 520.225712] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 520.253509] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 520.623326] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 520.662720] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 520.876078] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 520.997426] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 521.022330] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 521.048955] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 521.072221] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 521.183414] Lustre: lustre-OST0001-osc-ffff9610c73b4800: disconnect after 24s idle [ 521.570123] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 521.602023] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 521.632792] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 521.658167] rw_seq_cst_vs_d (30016): drop_caches: 3 [ 525.002217] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 13:50:30 (1752688230) [ 527.554071] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 13:50:33 (1752688233) [ 530.302659] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 13:50:35 (1752688235) [ 546.905873] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 13:50:52 (1752688252) [ 547.251359] bash (32456): drop_caches: 3 [ 550.397642] bash (32456): drop_caches: 3 [ 553.614615] bash (32456): drop_caches: 3 [ 556.728814] bash (32456): drop_caches: 3 [ 559.856730] bash (32456): drop_caches: 3 [ 562.937695] bash (32456): drop_caches: 3 [ 566.001414] bash (32456): drop_caches: 3 [ 569.079049] bash (32456): drop_caches: 3 [ 572.154393] bash (32456): drop_caches: 3 [ 576.195870] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 13:51:21 (1752688281) [ 582.460635] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 13:51:27 (1752688287) [ 609.094343] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 13:51:54 (1752688314) [ 610.755225] Lustre: DEBUG MARKER: SKIP: sanityn test_19 not cache-capable obdfilter [ 611.848400] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 13:51:57 (1752688317) [ 615.857902] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 13:52:01 (1752688321) [ 619.471323] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 13:52:04 (1752688324) [ 623.583417] Lustre: lustre-OST0000-osc-ffff9610c73b4800: disconnect after 20s idle [ 633.823327] Lustre: lustre-OST0001-osc-ffff9610d2893000: disconnect after 21s idle [ 685.331752] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 13:53:10 (1752688390) [ 688.957884] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 13:53:14 (1752688394) [ 692.459641] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 13:53:17 (1752688397) [ 696.162281] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 13:53:21 (1752688401) [ 696.851687] Lustre: DEBUG MARKER: SKIP: sanityn test_25b needs >= 2 MDTs [ 697.625454] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 13:53:23 (1752688403) [ 701.668719] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 13:53:27 (1752688407) [ 706.782047] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 13:53:32 (1752688412) [ 710.985976] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 711.886423] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 13:53:37 (1752688417) [ 717.604315] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 13:53:42 (1752688422) [ 717.776240] Lustre: *** cfs_fail_loc=314, val=0*** [ 718.667686] Lustre: *** cfs_fail_loc=314, val=0*** [ 718.670808] Lustre: Skipped 2 previous similar messages [ 723.012851] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 13:53:48 (1752688428) [ 734.706407] Lustre: *** cfs_fail_loc=314, val=0*** [ 734.709073] Lustre: Skipped 1 previous similar message [ 734.775470] LustreError: lustre-OST0000-osc-ffff9610c73b4800: operation ldlm_enqueue to node 192.168.204.108@tcp failed: rc = -107 [ 734.779561] Lustre: lustre-OST0000-osc-ffff9610c73b4800: Connection to lustre-OST0000 (at 192.168.204.108@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 734.798151] LustreError: lustre-OST0000-osc-ffff9610c73b4800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 734.805211] LustreError: 41685:0:(ldlm_resource.c:979:ldlm_resource_complain()) lustre-OST0000-osc-ffff9610c73b4800: namespace resource [0x240000400:0x35:0x0].0x0 (ffff9610e1315100) refcount nonzero (2) after lock cleanup; forcing cleanup. [ 734.814487] Lustre: lustre-OST0000-osc-ffff9610c73b4800: Connection restored to 192.168.204.108@tcp (at 192.168.204.108@tcp) [ 740.245502] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 13:54:05 (1752688445) [ 740.398409] LustreError: 42267:0:(file.c:760:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 743.423103] LustreError: 42267:0:(file.c:760:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 747.671367] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 13:54:12 (1752688452) [ 752.505281] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 13:54:17 (1752688457) [ 757.756862] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 758.677976] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 13:54:23 (1752688463) [ 759.651108] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 760.657957] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 13:54:25 (1752688465) [ 761.498499] Lustre: DEBUG MARKER: SKIP: sanityn test_33c needs >= 2 MDTs [ 762.426507] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 13:54:27 (1752688467) [ 763.314819] Lustre: DEBUG MARKER: SKIP: sanityn test_33d needs >= 2 MDTs [ 764.346489] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 13:54:29 (1752688469) [ 765.231540] Lustre: DEBUG MARKER: SKIP: sanityn test_33e needs >= 2 MDTs [ 766.271878] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 13:54:31 (1752688471) [ 817.087591] Lustre: lustre-OST0000-osc-ffff9610d2893000: Connection to lustre-OST0000 (at 192.168.204.108@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 817.097356] LustreError: lustre-OST0000-osc-ffff9610d2893000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 817.102661] LustreError: lustre-OST0000-osc-ffff9610c73b4800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 817.105974] Lustre: lustre-OST0000-osc-ffff9610d2893000: Connection restored to 192.168.204.108@tcp (at 192.168.204.108@tcp) [ 817.111608] Lustre: Skipped 1 previous similar message [ 833.510858] Lustre: lustre-OST0001-osc-ffff9610d2893000: Connection to lustre-OST0001 (at 192.168.204.108@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 833.517815] Lustre: Skipped 1 previous similar message [ 833.522340] LustreError: lustre-OST0001-osc-ffff9610d2893000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 833.528381] Lustre: lustre-OST0001-osc-ffff9610d2893000: Connection restored to 192.168.204.108@tcp (at 192.168.204.108@tcp) [ 838.625924] Lustre: lustre-OST0000-osc-ffff9610d2893000: disconnect after 21s idle [ 838.629871] Lustre: Skipped 2 previous similar messages [ 848.311900] Lustre: DEBUG MARKER: oleg408-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9610c73b4800.ost_server_uuid 50 [ 848.926270] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9610c73b4800.ost_server_uuid in IDLE state after 0 sec [ 850.392514] Lustre: DEBUG MARKER: oleg408-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9610c73b4800.ost_server_uuid 50 [ 850.970966] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9610c73b4800.ost_server_uuid in FULL state after 0 sec [ 852.997907] Lustre: DEBUG MARKER: oleg408-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9610c73b4800.ost_server_uuid 50 [ 853.710301] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9610c73b4800.ost_server_uuid in IDLE state after 0 sec [ 855.283709] Lustre: DEBUG MARKER: oleg408-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9610c73b4800.ost_server_uuid 50 [ 856.053623] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9610c73b4800.ost_server_uuid in FULL state after 0 sec [ 860.461445] Lustre: DEBUG MARKER: oleg408-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9610c73b4800.ost_server_uuid 50 [ 861.218276] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9610c73b4800.ost_server_uuid in IDLE state after 0 sec [ 863.043720] Lustre: DEBUG MARKER: oleg408-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9610c73b4800.ost_server_uuid 50 [ 863.837268] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9610c73b4800.ost_server_uuid in FULL state after 0 sec [ 864.606182] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 13:56:10 (1752688570) [ 865.832178] Lustre: DEBUG MARKER: Race attempt 0 [ 867.655638] Lustre: DEBUG MARKER: Wait for 48960 49079 for 60 sec... [ 930.730721] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 13:57:16 (1752688636) [ 936.412659] Lustre: DEBUG MARKER: start test - cycle (0) [ 955.435298] Lustre: DEBUG MARKER: start test - cycle (1) [ 977.386334] Lustre: DEBUG MARKER: start test - cycle (2) [ 997.059669] Lustre: DEBUG MARKER: start test - cycle (3) [ 1018.268495] Lustre: DEBUG MARKER: start test - cycle (4) [ 1038.259934] Lustre: DEBUG MARKER: start test - cycle (5) [ 1059.898160] Lustre: DEBUG MARKER: start test - cycle (6) [ 1085.282024] Lustre: DEBUG MARKER: start test - cycle (7) [ 1089.505816] Lustre: lustre-OST0000-osc-ffff9610c73b4800: disconnect after 22s idle [ 1089.518500] Lustre: Skipped 2 previous similar messages [ 1110.038393] Lustre: DEBUG MARKER: start test - cycle (8) [ 1130.153854] Lustre: DEBUG MARKER: start test - cycle (9) [ 1151.708181] Lustre: DEBUG MARKER: start test - cycle (10) [ 1174.740897] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 14:01:20 (1752688880) [ 1181.664752] Lustre: lustre-OST0000-osc-ffff9610c73b4800: disconnect after 23s idle [ 1181.668296] Lustre: Skipped 2 previous similar messages [ 1223.496208] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 14:02:08 (1752688928) [ 1227.403847] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 14:02:12 (1752688932) [ 1231.899591] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 14:02:17 (1752688937) [ 1236.232424] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 14:02:21 (1752688941) [ 1236.373715] Lustre: *** cfs_fail_loc=411, val=0*** [ 1239.859883] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 1240.792062] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 14:02:26 (1752688946) [ 1250.137050] hrtimer: interrupt took 2406971 ns [ 1252.681854] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 14:02:37 (1752688957) [ 1263.587693] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 14:02:48 (1752688968) [ 1273.962246] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 14:02:59 (1752688979) [ 1283.509176] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 14:03:08 (1752688988) [ 1290.411779] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 14:03:15 (1752688995) [ 1297.449471] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 14:03:22 (1752689002) [ 1304.385149] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 14:03:29 (1752689009) [ 1311.105889] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 14:03:36 (1752689016) [ 1317.405244] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 14:03:42 (1752689022) [ 1323.946207] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 14:03:49 (1752689029) [ 1325.023305] Lustre: lustre-OST0001-osc-ffff9610d2893000: disconnect after 23s idle [ 1325.026366] Lustre: Skipped 5 previous similar messages [ 1330.245411] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 14:03:55 (1752689035) [ 1336.641267] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 14:04:02 (1752689042) [ 1959.904199] Lustre: lustre-OST0000-osc-ffff9610c73b4800: disconnect after 24s idle [ 1959.908594] Lustre: Skipped 2 previous similar messages [ 2092.514751] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 14:16:37 (1752689797) [ 2099.313428] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 14:16:44 (1752689804) [ 2106.195976] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 14:16:51 (1752689811) [ 2113.611208] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 14:16:59 (1752689819) [ 2120.273112] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 14:17:05 (1752689825) [ 2126.855928] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 14:17:12 (1752689832) [ 2134.501283] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 14:17:19 (1752689839) [ 2141.420502] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 14:17:26 (1752689846) [ 2147.710174] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 14:17:33 (1752689853) [ 2172.807917] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 14:17:58 (1752689878) [ 2178.784564] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 14:18:04 (1752689884) [ 2185.094578] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 14:18:10 (1752689890) [ 2191.097864] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 14:18:16 (1752689896) [ 2196.972980] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 14:18:22 (1752689902) [ 2202.994274] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 14:18:28 (1752689908) [ 2209.288788] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 14:18:34 (1752689914) [ 2215.335528] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 14:18:40 (1752689920) [ 2215.858623] Lustre: DEBUG MARKER: SKIP: sanityn test_43i needs >= 2 MDTs [ 2216.546853] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 14:18:42 (1752689922) [ 2276.473631] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 14:19:41 (1752689981) [ 2507.743189] Lustre: lustre-OST0000-osc-ffff9610d2893000: disconnect after 22s idle [ 2507.746432] Lustre: Skipped 8 previous similar messages [ 2799.167389] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 14:28:24 (1752690504) [ 2804.543454] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 14:28:30 (1752690510) [ 2809.918650] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 14:28:35 (1752690515) [ 2815.220070] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 14:28:40 (1752690520) [ 2820.594541] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 14:28:46 (1752690526) [ 2826.048274] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 14:28:51 (1752690531) [ 2831.350814] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 14:28:56 (1752690536) [ 2836.511270] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 14:29:02 (1752690542) [ 2841.665682] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 14:29:07 (1752690547) [ 2842.147940] Lustre: DEBUG MARKER: SKIP: sanityn test_44i needs >= 2 MDTs [ 2842.760485] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 14:29:08 (1752690548) [ 2873.090742] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 14:29:38 (1752690578) [ 2878.448293] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 14:29:44 (1752690584) [ 2883.710130] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 14:29:49 (1752690589) [ 2889.093453] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 14:29:54 (1752690594) [ 2894.270879] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 14:29:59 (1752690599) [ 2899.391992] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 14:30:05 (1752690605) [ 2904.614703] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 14:30:10 (1752690610) [ 2909.400362] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 14:30:15 (1752690615) [ 2909.853240] Lustre: DEBUG MARKER: SKIP: sanityn test_45i needs >= 2 MDTs [ 2910.356615] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 14:30:16 (1752690616) [ 3111.903298] Lustre: lustre-OST0001-osc-ffff9610d2893000: disconnect after 22s idle [ 3111.906332] Lustre: Skipped 4 previous similar messages [ 3381.028118] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 14:38:06 (1752691086) [ 3386.111081] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 14:38:11 (1752691091) [ 3391.132737] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 14:38:16 (1752691096) [ 3396.165670] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 14:38:21 (1752691101) [ 3401.294723] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 14:38:26 (1752691106) [ 3406.307198] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 14:38:31 (1752691111) [ 3411.644073] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 14:38:37 (1752691117) [ 3416.682150] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 14:38:42 (1752691122) [ 3421.932580] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 14:38:47 (1752691127) [ 3422.425733] Lustre: DEBUG MARKER: SKIP: sanityn test_46i needs >= 2 MDTs [ 3423.038216] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 14:38:48 (1752691128) [ 3423.484392] Lustre: DEBUG MARKER: SKIP: sanityn test_47a needs >= 2 MDTs [ 3424.002317] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 14:38:49 (1752691129) [ 3424.499803] Lustre: DEBUG MARKER: SKIP: sanityn test_47b needs >= 2 MDTs [ 3425.038182] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 14:38:50 (1752691130) [ 3425.520530] Lustre: DEBUG MARKER: SKIP: sanityn test_47c needs >= 2 MDTs [ 3426.124403] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 14:38:51 (1752691131) [ 3426.594145] Lustre: DEBUG MARKER: SKIP: sanityn test_47d needs >= 2 MDTs [ 3427.099838] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 14:38:52 (1752691132) [ 3427.581786] Lustre: DEBUG MARKER: SKIP: sanityn test_47e needs >= 2 MDTs [ 3428.106736] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 14:38:53 (1752691133) [ 3428.597932] Lustre: DEBUG MARKER: SKIP: sanityn test_47f needs >= 2 MDTs [ 3429.135328] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 14:38:54 (1752691134) [ 3429.601323] Lustre: DEBUG MARKER: SKIP: sanityn test_47g needs >= 2 MDTs [ 3430.121221] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 14:38:55 (1752691135) [ 3430.218784] LustreError: 5494:0:(ldlm_lockd.c:2073:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 3432.303098] LustreError: 5494:0:(ldlm_lockd.c:2073:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 3437.274056] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 14:39:02 (1752691142) [ 3441.463567] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 14:39:07 (1752691147) [ 3441.553872] LustreError: 260241:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3445.615080] LustreError: 260241:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 3445.621986] LustreError: 260241:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3449.679117] LustreError: 260241:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 3449.692196] LustreError: 260248:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3453.751104] LustreError: 260248:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 3455.807299] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 14:39:21 (1752691161) [ 3462.198882] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 14:39:27 (1752691167) [ 3465.347168] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 14:39:30 (1752691170) [ 3469.465133] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 14:39:35 (1752691175) [ 3493.605409] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 14:39:59 (1752691199) [ 3501.062993] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 14:40:06 (1752691206) [ 3508.629752] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 14:40:14 (1752691214) [ 3521.505828] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 14:40:27 (1752691227) [ 3531.249993] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 14:40:36 (1752691236) [ 3531.786789] Lustre: DEBUG MARKER: SKIP: sanityn test_55e needs >= 2 MDTs [ 3532.429303] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 14:40:37 (1752691237) [ 3545.668349] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 14:40:51 (1752691251) [ 3560.165581] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 14:41:05 (1752691265) [ 3586.940894] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 14:41:32 (1752691292) [ 3627.004978] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 14:42:12 (1752691332) [ 3629.835977] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 3632.267788] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 14:42:17 (1752691337) [ 3635.304433] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 14:42:20 (1752691340) [ 3635.827798] Lustre: DEBUG MARKER: SKIP: sanityn test_71a ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3636.340150] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 14:42:21 (1752691341) [ 3636.890476] Lustre: DEBUG MARKER: SKIP: sanityn test_71b ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3637.408357] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 14:42:23 (1752691343) [ 3637.898810] Lustre: DEBUG MARKER: SKIP: sanityn test_71c support only ldiskfs ost [ 3638.468290] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 14:42:24 (1752691344) [ 3638.986443] Lustre: DEBUG MARKER: SKIP: sanityn test_71d ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3639.568111] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 14:42:25 (1752691345) [ 3641.836254] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 14:42:27 (1752691347) [ 3644.164241] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 14:42:29 (1752691349) [ 3647.259553] LustreError: lustre-MDT0000-mdc-ffff9610d2893000: operation ldlm_enqueue to node 192.168.204.108@tcp failed: rc = -35 [ 3647.263922] LustreError: Skipped 1 previous similar message [ 3650.235690] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 14:42:35 (1752691355) [ 3650.364608] LustreError: 2358:0:(osc_request.c:3088:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 3652.447116] LustreError: 2358:0:(osc_request.c:3088:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 3657.418323] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 14:42:43 (1752691363) [ 3676.311227] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 14:43:01 (1752691381) [ 3679.442433] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 14:43:05 (1752691385) [ 3683.448658] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 14:43:09 (1752691389) [ 3688.726171] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 14:43:14 (1752691394) [ 3693.937636] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 14:43:19 (1752691399) [ 3702.003752] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 14:43:27 (1752691407) [ 3709.509460] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 14:43:35 (1752691415) [ 3713.236285] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 14:43:38 (1752691418) [ 3717.184665] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 14:43:42 (1752691422) [ 3725.036321] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 14:43:50 (1752691430) [ 3757.023298] Lustre: lustre-OST0001-osc-ffff9610d2893000: disconnect after 25s idle [ 3757.026428] Lustre: Skipped 4 previous similar messages [ 3772.766249] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 14:44:38 (1752691478) [ 3908.936119] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 14:46:54 (1752691614) [ 4044.129113] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 14:49:09 (1752691749) [ 4391.903115] Lustre: lustre-OST0000-osc-ffff9610d2893000: disconnect after 24s idle [ 4391.905146] Lustre: Skipped 15 previous similar messages [ 4393.013360] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 14:54:58 (1752692098) [ 4396.181620] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 14:55:01 (1752692101) [ 4447.125780] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 14:55:52 (1752692152) [ 4513.046651] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 14:56:58 (1752692218) [ 4516.959149] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 14:57:02 (1752692222) [ 4555.311596] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 14:57:40 (1752692260) [ 4567.220302] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 14:57:52 (1752692272) [ 4609.251239] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 14:58:34 (1752692314) [ 4612.267703] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 14:58:37 (1752692317) [ 4625.048641] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 14:58:50 (1752692330) [ 4625.603747] Lustre: DEBUG MARKER: SKIP: sanityn test_80a needs >= 2 MDTs [ 4626.158305] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 14:58:51 (1752692331) [ 4626.640458] Lustre: DEBUG MARKER: SKIP: sanityn test_80b needs >= 2 MDTs [ 4627.191868] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 14:58:52 (1752692332) [ 4627.746588] Lustre: DEBUG MARKER: SKIP: sanityn test_81a needs >= 2 MDTs [ 4628.315138] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 14:58:53 (1752692333) [ 4628.861565] Lustre: DEBUG MARKER: SKIP: sanityn test_81b We need at least 2 MDTs for this test [ 4629.388773] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 14:58:55 (1752692335) [ 4629.872136] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 4630.468548] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 14:58:56 (1752692336) [ 4654.975182] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 14:59:20 (1752692360) [ 4657.138386] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 14:59:22 (1752692362) [ 4657.617281] Lustre: DEBUG MARKER: SKIP: sanityn test_83 needs >= 2 MDTs [ 4658.138911] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 14:59:23 (1752692363) [ 4665.709481] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 14:59:31 (1752692371) [ 4668.623817] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 14:59:34 (1752692374) [ 4669.171986] Lustre: DEBUG MARKER: SKIP: sanityn test_90 needs >= 2 MDTs [ 4669.743330] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 14:59:35 (1752692375) [ 4670.278307] Lustre: DEBUG MARKER: SKIP: sanityn test_91 needs >= 2 MDTs [ 4670.819378] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 14:59:36 (1752692376) [ 4671.322109] Lustre: DEBUG MARKER: SKIP: sanityn test_92 needs >= 2 MDTs [ 4671.873195] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 14:59:37 (1752692377) [ 4680.680291] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 14:59:46 (1752692386) [ 4680.792689] Lustre: DEBUG MARKER: write [ 4680.809892] LustreError: 5505:0:(ldlm_request.c:1401:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 4682.816066] Lustre: DEBUG MARKER: kill 319514 [ 4682.818730] LustreError: 319514:0:(ldlm_request.c:1286:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 4685.911078] LustreError: 5505:0:(ldlm_request.c:1401:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 4688.855201] LustreError: 319514:0:(ldlm_request.c:1286:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 4691.197790] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 14:59:56 (1752692396) [ 4693.369403] LustreError: 320119:0:(rw.c:1967:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 4703.463123] LustreError: 320119:0:(rw.c:1967:ll_readpage()) cfs_fail_timeout id 1422 awake [ 4705.737054] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 15:00:11 (1752692411) [ 4705.841687] LustreError: 320699:0:(rw.c:2201:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 4707.927121] LustreError: 320699:0:(rw.c:2201:ll_readpage()) cfs_fail_timeout interrupted [ 4713.841841] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 15:00:19 (1752692419) [ 4714.326356] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 4714.852201] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 15:00:20 (1752692420) [ 4717.054792] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 15:00:22 (1752692422) [ 4719.176299] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 15:00:24 (1752692424) [ 4721.441898] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 15:00:27 (1752692427) [ 4723.782741] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 15:00:29 (1752692429) [ 4726.075279] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 15:00:31 (1752692431) [ 4728.376908] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 15:00:33 (1752692433) [ 4731.523716] Lustre: DEBUG MARKER: SKIP: sanityn test_102 skipping ALWAYS excluded test 102 [ 4732.041086] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 15:00:37 (1752692437) [ 4732.653666] Lustre: *** cfs_fail_loc=415, val=0*** [ 4739.265884] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 15:00:44 (1752692444) [ 4739.793599] Lustre: DEBUG MARKER: SKIP: sanityn test_104 ldiskfs only test [ 4740.354883] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 15:00:45 (1752692445) [ 4740.429460] LustreError: 5504:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [ 4740.432481] LustreError: 5504:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 2 previous similar messages [ 4745.431136] LustreError: 5504:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 4755.631134] LustreError: 5504:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 4755.634996] LustreError: 5504:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 5 previous similar messages [ 4757.896349] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 15:01:03 (1752692463) [ 4758.388968] Lustre: DEBUG MARKER: SKIP: sanityn test_106a Test only for ldiskfs and statx() supported [ 4758.996864] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 15:01:04 (1752692464) [ 4761.397960] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 15:01:07 (1752692467) [ 4763.713907] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 15:01:09 (1752692469) [ 4768.096846] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 15:01:13 (1752692473) [ 4776.610894] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 15:01:22 (1752692482) [ 4776.766845] LustreError: 2359:0:(osc_request.c:2939:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 4776.770028] LustreError: 2359:0:(osc_request.c:2939:osc_build_rpc()) Skipped 6 previous similar messages [ 4780.831100] LustreError: 2359:0:(osc_request.c:2939:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 4780.833344] LustreError: 2359:0:(osc_request.c:2939:osc_build_rpc()) Skipped 2 previous similar messages [ 4783.201897] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 15:01:28 (1752692488) [ 4783.966527] LustreError: 330645:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610d2893000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4783.972709] LustreError: 330645:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 4783.988663] Lustre: Unmounted lustre-client [ 4784.778055] LustreError: 330665:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610c73b4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4784.781378] LustreError: 330665:0:(lov_obd.c:784:lov_cleanup()) Skipped 1 previous similar message [ 4784.785637] LustreError: 330665:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 4784.789014] LustreError: 330665:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 4784.803659] Lustre: Unmounted lustre-client [ 4785.289280] Lustre: DEBUG MARKER: Iteration 1 [ 4785.478307] LustreError: 330825:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 4785.478349] LustreError: 330826:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 4785.484233] LustreError: 330825:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 4785.525251] Lustre: Mounted lustre-client [ 4786.029084] LustreError: 330935:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610fd401800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4786.034318] LustreError: 330935:0:(lov_obd.c:784:lov_cleanup()) Skipped 3 previous similar messages [ 4786.038918] LustreError: 330936:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 4786.040921] LustreError: 330936:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 4786.068396] Lustre: Unmounted lustre-client [ 4787.126898] Key type lgssc unregistered [ 4787.246470] LNet: 331177:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4788.261763] LNet: Removed LNI 192.168.204.8@tcp [ 4788.561082] Key type .llcrypt unregistered [ 4788.562845] Key type ._llcrypt unregistered [ 4788.924305] Key type ._llcrypt registered [ 4788.925402] Key type .llcrypt registered [ 4789.179795] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4789.184737] alg: No test for adler32 (adler32-zlib) [ 4790.163102] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4790.446332] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 4790.448230] LNet: Accept secure, port 988 [ 4792.063171] Key type lgssc registered [ 4792.657911] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4796.725409] Lustre: DEBUG MARKER: Iteration 2 [ 4796.825737] LustreError: 331994:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 4796.825778] LustreError: 331995:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 4796.829695] LustreError: 331994:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 4797.871420] Lustre: Mounted lustre-client [ 4798.312684] LustreError: 332102:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610c6826000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4798.318573] LustreError: 332102:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 4798.321021] LustreError: 332102:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 4798.330062] Lustre: Unmounted lustre-client [ 4799.336072] Key type lgssc unregistered [ 4799.460506] LNet: 332343:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4800.485730] LNet: Removed LNI 192.168.204.8@tcp [ 4800.752953] Key type .llcrypt unregistered [ 4800.754351] Key type ._llcrypt unregistered [ 4801.041753] Key type ._llcrypt registered [ 4801.043830] Key type .llcrypt registered [ 4801.221491] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4801.226727] alg: No test for adler32 (adler32-zlib) [ 4802.078493] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4802.156624] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 4802.158128] LNet: Accept secure, port 988 [ 4803.735099] Key type lgssc registered [ 4804.096151] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4807.549531] Lustre: DEBUG MARKER: Iteration 3 [ 4807.676459] LustreError: 333158:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 4807.676505] LustreError: 333159:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 4807.683246] LustreError: 333158:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 4808.736143] Lustre: Mounted lustre-client [ 4808.737940] Lustre: Skipped 1 previous similar message [ 4809.210054] LustreError: 333266:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610c8099000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4809.217991] LustreError: 333266:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 4809.248073] Lustre: Unmounted lustre-client [ 4810.292703] Key type lgssc unregistered [ 4810.416527] LNet: 333510:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4811.429774] LNet: Removed LNI 192.168.204.8@tcp [ 4811.692111] Key type .llcrypt unregistered [ 4811.693123] Key type ._llcrypt unregistered [ 4812.023938] Key type ._llcrypt registered [ 4812.024948] Key type .llcrypt registered [ 4812.200207] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4812.206027] alg: No test for adler32 (adler32-zlib) [ 4813.066858] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4813.154752] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 4813.156619] LNet: Accept secure, port 988 [ 4814.735142] Key type lgssc registered [ 4815.076943] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4818.373057] Lustre: DEBUG MARKER: Iteration 4 [ 4818.469076] LustreError: 334323:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 4818.469103] LustreError: 334324:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 4818.474241] LustreError: 334323:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 4819.534244] Lustre: Mounted lustre-client [ 4819.966706] LustreError: 334432:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610c66d1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4819.970584] LustreError: 334433:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 4819.971817] LustreError: 334432:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 4819.986046] Lustre: Unmounted lustre-client [ 4820.937221] Key type lgssc unregistered [ 4821.056726] LNet: 334674:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4822.117687] LNet: Removed LNI 192.168.204.8@tcp [ 4822.346091] Key type .llcrypt unregistered [ 4822.347389] Key type ._llcrypt unregistered [ 4822.575597] Key type ._llcrypt registered [ 4822.576663] Key type .llcrypt registered [ 4822.807724] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4822.812857] alg: No test for adler32 (adler32-zlib) [ 4823.664348] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4823.753854] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 4823.755631] LNet: Accept secure, port 988 [ 4825.335114] Key type lgssc registered [ 4825.695221] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4828.994891] Lustre: DEBUG MARKER: Iteration 5 [ 4829.109219] LustreError: 335487:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 4829.109554] LustreError: 335488:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 4829.113222] LustreError: 335487:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 4830.164210] Lustre: Mounted lustre-client [ 4830.165446] Lustre: Skipped 1 previous similar message [ 4830.673599] LustreError: 335593:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610ca1b9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4830.679505] LustreError: 335593:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 4830.681843] LustreError: 335593:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 4830.690092] Lustre: Unmounted lustre-client [ 4831.778118] Key type lgssc unregistered [ 4831.913503] LNet: 335836:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4832.933690] LNet: Removed LNI 192.168.204.8@tcp [ 4833.176066] Key type .llcrypt unregistered [ 4833.177408] Key type ._llcrypt unregistered [ 4833.467984] Key type ._llcrypt registered [ 4833.472384] Key type .llcrypt registered [ 4833.638607] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4833.643755] alg: No test for adler32 (adler32-zlib) [ 4834.510340] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4834.592650] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 4834.595659] LNet: Accept secure, port 988 [ 4836.183151] Key type lgssc registered [ 4836.531297] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4839.829182] Lustre: DEBUG MARKER: Iteration 6 [ 4839.943640] LustreError: 336649:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 4839.943679] LustreError: 336650:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 4839.949555] LustreError: 336649:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 4841.008996] Lustre: Mounted lustre-client [ 4841.010485] Lustre: Skipped 1 previous similar message [ 4841.455822] LustreError: 336756:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610d190a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4841.461071] LustreError: 336756:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 4841.463266] LustreError: 336756:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 4841.471052] Lustre: Unmounted lustre-client [ 4842.455396] Key type lgssc unregistered [ 4842.574440] LNet: 336999:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4843.621543] LNet: Removed LNI 192.168.204.8@tcp [ 4843.849339] Key type .llcrypt unregistered [ 4843.851203] Key type ._llcrypt unregistered [ 4844.120707] Key type ._llcrypt registered [ 4844.121727] Key type .llcrypt registered [ 4844.275888] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4844.281841] alg: No test for adler32 (adler32-zlib) [ 4845.131948] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4845.210509] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 4845.212090] LNet: Accept secure, port 988 [ 4846.791171] Key type lgssc registered [ 4847.146503] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4850.336292] Lustre: DEBUG MARKER: Iteration 7 [ 4850.434228] LustreError: 337812:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 4850.434280] LustreError: 337813:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 4850.438398] LustreError: 337812:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 4851.485602] Lustre: Mounted lustre-client [ 4851.486812] Lustre: Skipped 1 previous similar message [ 4851.945964] LustreError: 337921:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610f100d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4851.951727] LustreError: 337921:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 4851.953853] LustreError: 337921:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 4851.971411] Lustre: Unmounted lustre-client [ 4852.977538] Key type lgssc unregistered [ 4853.097519] LNet: 338162:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4854.117825] LNet: Removed LNI 192.168.204.8@tcp [ 4854.339884] Key type .llcrypt unregistered [ 4854.341088] Key type ._llcrypt unregistered [ 4854.633980] Key type ._llcrypt registered [ 4854.635496] Key type .llcrypt registered [ 4854.862208] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4854.868274] alg: No test for adler32 (adler32-zlib) [ 4855.730725] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4855.821217] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 4855.822917] LNet: Accept secure, port 988 [ 4857.415157] Key type lgssc registered [ 4857.867660] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4861.111029] Lustre: DEBUG MARKER: Iteration 8 [ 4861.217298] LustreError: 338975:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 4861.217338] LustreError: 338976:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 4861.223254] LustreError: 338975:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 4862.269769] Lustre: Mounted lustre-client [ 4862.270840] Lustre: Skipped 1 previous similar message [ 4862.711740] LustreError: 339083:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610d0a0b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4862.719299] LustreError: 339083:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 4862.722526] LustreError: 339083:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 4862.731126] Lustre: Unmounted lustre-client [ 4863.694739] Key type lgssc unregistered [ 4863.813688] LNet: 339326:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4864.869703] LNet: Removed LNI 192.168.204.8@tcp [ 4865.101139] Key type .llcrypt unregistered [ 4865.102217] Key type ._llcrypt unregistered [ 4865.355174] Key type ._llcrypt registered [ 4865.357265] Key type .llcrypt registered [ 4865.532458] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4865.550652] alg: No test for adler32 (adler32-zlib) [ 4866.409040] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4866.501376] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 4866.503258] LNet: Accept secure, port 988 [ 4868.087129] Key type lgssc registered [ 4868.452294] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4871.825029] Lustre: DEBUG MARKER: Iteration 9 [ 4871.935566] LustreError: 340157:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 4871.935615] LustreError: 340156:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 4871.939703] LustreError: 340157:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 4872.990141] Lustre: Mounted lustre-client [ 4872.991296] Lustre: Skipped 1 previous similar message [ 4873.531423] LustreError: 340264:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610c66d5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4873.537991] LustreError: 340264:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 4873.565418] Lustre: Unmounted lustre-client [ 4874.586490] Key type lgssc unregistered [ 4874.707338] LNet: 340505:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4875.749671] LNet: Removed LNI 192.168.204.8@tcp [ 4875.981869] Key type .llcrypt unregistered [ 4875.983022] Key type ._llcrypt unregistered [ 4876.226185] Key type ._llcrypt registered [ 4876.227232] Key type .llcrypt registered [ 4876.434317] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4876.439042] alg: No test for adler32 (adler32-zlib) [ 4877.299954] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4877.377381] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 4877.378914] LNet: Accept secure, port 988 [ 4878.959131] Key type lgssc registered [ 4879.296101] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4882.616543] Lustre: DEBUG MARKER: Iteration 10 [ 4882.718367] LustreError: 341319:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 4882.719369] LustreError: 341320:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 4882.723989] LustreError: 341319:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 4883.764962] Lustre: Mounted lustre-client [ 4884.361713] LustreError: 341425:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610c8025000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4884.364495] LustreError: 341426:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 4884.365325] LustreError: 341425:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 4884.394932] Lustre: Unmounted lustre-client [ 4885.490846] Key type lgssc unregistered [ 4885.627411] LNet: 341667:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4886.693713] LNet: Removed LNI 192.168.204.8@tcp [ 4886.935796] Key type .llcrypt unregistered [ 4886.936860] Key type ._llcrypt unregistered [ 4887.140920] Key type ._llcrypt registered [ 4887.141933] Key type .llcrypt registered [ 4887.374307] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4887.378925] alg: No test for adler32 (adler32-zlib) [ 4888.239732] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4888.328918] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 4888.331489] LNet: Accept secure, port 988 [ 4889.919218] Key type lgssc registered [ 4890.296058] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4893.989528] Lustre: DEBUG MARKER: Iteration 11 [ 4894.117142] LustreError: 342481:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 4894.117178] LustreError: 342482:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 4894.122077] LustreError: 342481:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 4895.181891] Lustre: Mounted lustre-client [ 4895.183761] Lustre: Skipped 1 previous similar message [ 4895.662625] LustreError: 342586:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610ca1bc000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4895.666203] LustreError: 342587:0:(obd_class.h:479:obd_check_dev()) Device 7 not setup [ 4895.668064] LustreError: 342586:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 4895.681778] Lustre: Unmounted lustre-client [ 4896.727928] Key type lgssc unregistered [ 4896.861384] LNet: 342827:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4897.893803] LNet: Removed LNI 192.168.204.8@tcp [ 4898.143991] Key type .llcrypt unregistered [ 4898.145088] Key type ._llcrypt unregistered [ 4898.490746] Key type ._llcrypt registered [ 4898.492451] Key type .llcrypt registered [ 4898.673498] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4898.680296] alg: No test for adler32 (adler32-zlib) [ 4899.544617] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4899.635299] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 4899.636941] LNet: Accept secure, port 988 [ 4901.231162] Key type lgssc registered [ 4901.648735] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4905.130865] Lustre: DEBUG MARKER: Iteration 12 [ 4905.233538] LustreError: 343641:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 4905.233572] LustreError: 343642:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 4905.237943] LustreError: 343641:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 4906.308911] Lustre: Mounted lustre-client [ 4906.310047] Lustre: Skipped 1 previous similar message [ 4906.771032] LustreError: 343744:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610c4feb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4906.774893] LustreError: 343745:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 4906.776704] LustreError: 343744:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 4906.792114] Lustre: Unmounted lustre-client [ 4907.887936] Key type lgssc unregistered [ 4908.025391] LNet: 343987:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4909.094442] LNet: Removed LNI 192.168.204.8@tcp [ 4909.363070] Key type .llcrypt unregistered [ 4909.364847] Key type ._llcrypt unregistered [ 4909.637264] Key type ._llcrypt registered [ 4909.639846] Key type .llcrypt registered [ 4909.921055] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4909.927584] alg: No test for adler32 (adler32-zlib) [ 4910.794080] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4910.890625] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 4910.893088] LNet: Accept secure, port 988 [ 4912.487134] Key type lgssc registered [ 4912.844267] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4916.723587] Lustre: DEBUG MARKER: Iteration 13 [ 4916.857855] LustreError: 344800:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 4916.857918] LustreError: 344801:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 4916.864095] LustreError: 344800:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 4917.921378] Lustre: Mounted lustre-client [ 4917.922722] Lustre: Skipped 1 previous similar message [ 4918.407590] LustreError: 344906:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610ca1bc800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4918.410891] LustreError: 344907:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 4918.412049] LustreError: 344906:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 4918.441188] Lustre: Unmounted lustre-client [ 4919.511685] Key type lgssc unregistered [ 4919.643930] LNet: 345149:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4920.677458] LNet: Removed LNI 192.168.204.8@tcp [ 4920.910124] Key type .llcrypt unregistered [ 4920.911834] Key type ._llcrypt unregistered [ 4921.213879] Key type ._llcrypt registered [ 4921.214885] Key type .llcrypt registered [ 4921.412124] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4921.417254] alg: No test for adler32 (adler32-zlib) [ 4922.274484] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4922.372974] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 4922.375245] LNet: Accept secure, port 988 [ 4923.967251] Key type lgssc registered [ 4924.428364] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4928.097096] Lustre: DEBUG MARKER: Iteration 14 [ 4928.227887] LustreError: 345961:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 4928.227935] LustreError: 345963:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 4928.234975] LustreError: 345961:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 4929.295170] Lustre: Mounted lustre-client [ 4929.820643] LustreError: 346064:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610c66d1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4929.825019] LustreError: 346064:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 4929.825583] LustreError: 346065:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 4929.830559] LustreError: 346065:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 4929.845736] Lustre: Unmounted lustre-client [ 4930.923069] Key type lgssc unregistered [ 4931.043527] LNet: 346307:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4932.069822] LNet: Removed LNI 192.168.204.8@tcp [ 4932.330880] Key type .llcrypt unregistered [ 4932.332412] Key type ._llcrypt unregistered [ 4932.588729] Key type ._llcrypt registered [ 4932.590634] Key type .llcrypt registered [ 4932.832458] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4932.839026] alg: No test for adler32 (adler32-zlib) [ 4933.707822] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4933.797807] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 4933.800181] LNet: Accept secure, port 988 [ 4935.391132] Key type lgssc registered [ 4935.781415] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4939.515232] Lustre: DEBUG MARKER: Iteration 15 [ 4939.633672] LustreError: 347122:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 4939.634111] LustreError: 347121:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 4939.640626] LustreError: 347122:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 4940.701563] Lustre: Mounted lustre-client [ 4940.703318] Lustre: Skipped 1 previous similar message [ 4941.172338] LustreError: 347226:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610d2a03800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4941.174168] LustreError: 347225:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 4941.176749] LustreError: 347226:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 4941.188540] Lustre: Unmounted lustre-client [ 4942.296855] Key type lgssc unregistered [ 4942.433499] LNet: 347468:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4943.461762] LNet: Removed LNI 192.168.204.8@tcp [ 4943.755127] Key type .llcrypt unregistered [ 4943.757053] Key type ._llcrypt unregistered [ 4944.001427] Key type ._llcrypt registered [ 4944.003158] Key type .llcrypt registered [ 4944.236987] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4944.243379] alg: No test for adler32 (adler32-zlib) [ 4945.108476] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4945.187212] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 4945.189985] LNet: Accept secure, port 988 [ 4946.783188] Key type lgssc registered [ 4947.183168] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4950.628838] Lustre: DEBUG MARKER: Iteration 16 [ 4950.742993] LustreError: 348283:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 4950.744102] LustreError: 348282:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 4950.749720] LustreError: 348283:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 4951.798277] Lustre: Mounted lustre-client [ 4951.799244] Lustre: Skipped 1 previous similar message [ 4952.329383] LustreError: 348384:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610d1831000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4952.333519] LustreError: 348385:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 4952.334640] LustreError: 348384:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 4952.351949] Lustre: Unmounted lustre-client [ 4953.438251] Key type lgssc unregistered [ 4953.581685] LNet: 348626:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4954.597784] LNet: Removed LNI 192.168.204.8@tcp [ 4954.884035] Key type .llcrypt unregistered [ 4954.885925] Key type ._llcrypt unregistered [ 4955.211753] Key type ._llcrypt registered [ 4955.213528] Key type .llcrypt registered [ 4955.394945] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4955.402622] alg: No test for adler32 (adler32-zlib) [ 4956.263248] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4956.349828] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 4956.351664] LNet: Accept secure, port 988 [ 4957.935134] Key type lgssc registered [ 4958.305545] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4962.009537] Lustre: DEBUG MARKER: Iteration 17 [ 4962.120106] LustreError: 349440:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 4962.120399] LustreError: 349441:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 4962.125048] LustreError: 349440:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 4963.172508] Lustre: Mounted lustre-client [ 4963.641765] LustreError: 349548:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610c4cb8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4963.644521] LustreError: 349549:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 4963.645978] LustreError: 349548:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 4963.661899] Lustre: Unmounted lustre-client [ 4964.708330] Key type lgssc unregistered [ 4964.830373] LNet: 349790:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4965.861510] LNet: Removed LNI 192.168.204.8@tcp [ 4966.163432] Key type .llcrypt unregistered [ 4966.164748] Key type ._llcrypt unregistered [ 4966.476877] Key type ._llcrypt registered [ 4966.478893] Key type .llcrypt registered [ 4966.677598] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4966.683548] alg: No test for adler32 (adler32-zlib) [ 4967.548389] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4967.645063] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 4967.647875] LNet: Accept secure, port 988 [ 4969.239168] Key type lgssc registered [ 4969.644608] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4973.304418] Lustre: DEBUG MARKER: Iteration 18 [ 4973.444415] LustreError: 350603:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 4973.446112] LustreError: 350604:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 4973.451048] LustreError: 350603:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 4974.508956] Lustre: Mounted lustre-client [ 4974.510712] Lustre: Skipped 1 previous similar message [ 4974.945111] LustreError: 350712:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610d190c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4974.949121] LustreError: 350711:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 4974.951423] LustreError: 350712:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 4974.974671] Lustre: Unmounted lustre-client [ 4975.982923] Key type lgssc unregistered [ 4976.118514] LNet: 350953:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4977.189692] LNet: Removed LNI 192.168.204.8@tcp [ 4977.420859] Key type .llcrypt unregistered [ 4977.422414] Key type ._llcrypt unregistered [ 4977.660089] Key type ._llcrypt registered [ 4977.660928] Key type .llcrypt registered [ 4977.857117] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4977.863312] alg: No test for adler32 (adler32-zlib) [ 4978.717284] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4978.801060] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 4978.803473] LNet: Accept secure, port 988 [ 4980.383136] Key type lgssc registered [ 4980.757071] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4984.079764] Lustre: DEBUG MARKER: Iteration 19 [ 4984.191396] LustreError: 351767:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 4984.191429] LustreError: 351766:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 4984.197551] LustreError: 351767:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 4985.251902] Lustre: Mounted lustre-client [ 4985.253424] Lustre: Skipped 1 previous similar message [ 4985.721722] LustreError: 351876:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610d0a0b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4985.724883] LustreError: 351875:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 4985.725064] LustreError: 351876:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 4985.740101] Lustre: Unmounted lustre-client [ 4986.774547] Key type lgssc unregistered [ 4986.906467] LNet: 352118:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4987.942465] LNet: Removed LNI 192.168.204.8@tcp [ 4988.192901] Key type .llcrypt unregistered [ 4988.193927] Key type ._llcrypt unregistered [ 4988.531094] Key type ._llcrypt registered [ 4988.533375] Key type .llcrypt registered [ 4988.686602] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4988.691882] alg: No test for adler32 (adler32-zlib) [ 4989.559110] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 4989.660394] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 4989.662285] LNet: Accept secure, port 988 [ 4991.247136] Key type lgssc registered [ 4991.585657] Lustre: Echo OBD driver; http://www.lustre.org/ [ 4994.819596] Lustre: DEBUG MARKER: Iteration 20 [ 4994.935479] LustreError: 352932:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 4994.935500] LustreError: 352933:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 4994.940626] LustreError: 352932:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 4995.983818] Lustre: Mounted lustre-client [ 4995.985211] Lustre: Skipped 1 previous similar message [ 4996.568070] LustreError: 353040:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610d72e4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4996.573525] LustreError: 353040:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 4996.592394] Lustre: Unmounted lustre-client [ 4996.593564] Lustre: Skipped 1 previous similar message [ 4997.691470] Key type lgssc unregistered [ 4997.821836] LNet: 353282:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 4998.885685] LNet: Removed LNI 192.168.204.8@tcp [ 4999.094100] Key type .llcrypt unregistered [ 4999.095618] Key type ._llcrypt unregistered [ 4999.444081] Key type ._llcrypt registered [ 4999.445323] Key type .llcrypt registered [ 4999.708360] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 4999.713179] alg: No test for adler32 (adler32-zlib) [ 5000.555524] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5000.626349] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5000.627844] LNet: Accept secure, port 988 [ 5002.199132] Key type lgssc registered [ 5002.554737] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5005.924133] Lustre: DEBUG MARKER: Iteration 21 [ 5006.056333] LustreError: 354097:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5006.058037] LustreError: 354096:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5006.062861] LustreError: 354097:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5007.116499] Lustre: Mounted lustre-client [ 5007.118223] Lustre: Skipped 1 previous similar message [ 5007.556979] LustreError: 354197:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610c50df000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5007.562515] LustreError: 354197:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5007.575785] Lustre: Unmounted lustre-client [ 5008.567426] Key type lgssc unregistered [ 5008.693412] LNet: 354440:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5009.765848] LNet: Removed LNI 192.168.204.8@tcp [ 5010.022371] Key type .llcrypt unregistered [ 5010.023625] Key type ._llcrypt unregistered [ 5010.294911] Key type ._llcrypt registered [ 5010.296809] Key type .llcrypt registered [ 5010.495954] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5010.503350] alg: No test for adler32 (adler32-zlib) [ 5011.370947] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5011.465890] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5011.467508] LNet: Accept secure, port 988 [ 5013.063134] Key type lgssc registered [ 5013.421382] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5017.147924] Lustre: DEBUG MARKER: Iteration 22 [ 5017.252816] LustreError: 355254:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5017.252882] LustreError: 355255:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5017.258599] LustreError: 355254:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5018.310363] Lustre: Mounted lustre-client [ 5018.770716] LustreError: 355362:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610d86fe000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5018.773142] LustreError: 355361:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5018.773925] LustreError: 355362:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5018.796068] Lustre: Unmounted lustre-client [ 5019.751535] Key type lgssc unregistered [ 5019.880059] LNet: 355603:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5020.901797] LNet: Removed LNI 192.168.204.8@tcp [ 5021.179192] Key type .llcrypt unregistered [ 5021.180164] Key type ._llcrypt unregistered [ 5021.594449] Key type ._llcrypt registered [ 5021.608057] Key type .llcrypt registered [ 5021.834931] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5021.839734] alg: No test for adler32 (adler32-zlib) [ 5022.711351] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5022.798304] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5022.799874] LNet: Accept secure, port 988 [ 5024.383185] Key type lgssc registered [ 5024.804258] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5028.103490] Lustre: DEBUG MARKER: Iteration 23 [ 5028.192530] LustreError: 356418:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5028.192558] LustreError: 356419:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5028.198408] LustreError: 356418:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5029.242520] Lustre: Mounted lustre-client [ 5029.665287] LustreError: 356524:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610d0015000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5029.667465] LustreError: 356525:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5029.668498] LustreError: 356524:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5029.690545] Lustre: Unmounted lustre-client [ 5030.736836] Key type lgssc unregistered [ 5030.856475] LNet: 356766:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5031.909755] LNet: Removed LNI 192.168.204.8@tcp [ 5032.156026] Key type .llcrypt unregistered [ 5032.157883] Key type ._llcrypt unregistered [ 5032.453575] Key type ._llcrypt registered [ 5032.454841] Key type .llcrypt registered [ 5032.614294] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5032.619415] alg: No test for adler32 (adler32-zlib) [ 5033.471541] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5033.553924] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5033.555575] LNet: Accept secure, port 988 [ 5035.143176] Key type lgssc registered [ 5035.515398] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5038.832171] Lustre: DEBUG MARKER: Iteration 24 [ 5038.951740] LustreError: 357580:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5038.952343] LustreError: 357581:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5038.957400] LustreError: 357580:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5040.008356] Lustre: Mounted lustre-client [ 5040.438837] LustreError: 357688:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610c66d3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5040.442263] LustreError: 357688:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5040.445416] LustreError: 357689:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5040.447859] LustreError: 357689:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5040.457050] Lustre: Unmounted lustre-client [ 5041.424048] Key type lgssc unregistered [ 5041.552451] LNet: 357930:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5042.597661] LNet: Removed LNI 192.168.204.8@tcp [ 5042.824854] Key type .llcrypt unregistered [ 5042.826020] Key type ._llcrypt unregistered [ 5043.079669] Key type ._llcrypt registered [ 5043.080904] Key type .llcrypt registered [ 5043.261387] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5043.266111] alg: No test for adler32 (adler32-zlib) [ 5044.113564] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5044.202849] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5044.205836] LNet: Accept secure, port 988 [ 5045.791171] Key type lgssc registered [ 5046.151044] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5049.376577] Lustre: DEBUG MARKER: Iteration 25 [ 5049.476851] LustreError: 358744:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5049.476874] LustreError: 358745:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5049.480932] LustreError: 358744:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 5050.528244] Lustre: Mounted lustre-client [ 5050.973474] LustreError: 358851:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610d1dbd800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5050.975552] LustreError: 358850:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 5050.977036] LustreError: 358851:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5050.982432] LustreError: 358850:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 5050.988078] Lustre: Unmounted lustre-client [ 5051.970305] Key type lgssc unregistered [ 5052.095510] LNet: 359092:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5053.157666] LNet: Removed LNI 192.168.204.8@tcp [ 5053.390899] Key type .llcrypt unregistered [ 5053.391899] Key type ._llcrypt unregistered [ 5053.674185] Key type ._llcrypt registered [ 5053.691046] Key type .llcrypt registered [ 5053.844414] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5053.850917] alg: No test for adler32 (adler32-zlib) [ 5054.711533] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5054.795657] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5054.797361] LNet: Accept secure, port 988 [ 5056.383211] Key type lgssc registered [ 5056.793372] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5060.309911] Lustre: DEBUG MARKER: Iteration 26 [ 5060.424681] LustreError: 359907:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5060.425080] LustreError: 359908:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5060.429309] LustreError: 359907:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5061.476600] Lustre: Mounted lustre-client [ 5061.939661] LustreError: 360015:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610d2a02000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5061.944467] LustreError: 360015:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5061.976504] Lustre: Unmounted lustre-client [ 5062.973593] Key type lgssc unregistered [ 5063.088535] LNet: 360257:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5064.101930] LNet: Removed LNI 192.168.204.8@tcp [ 5064.388848] Key type .llcrypt unregistered [ 5064.390422] Key type ._llcrypt unregistered [ 5064.651758] Key type ._llcrypt registered [ 5064.652747] Key type .llcrypt registered [ 5064.831882] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5064.836937] alg: No test for adler32 (adler32-zlib) [ 5065.689140] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5065.769385] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5065.771037] LNet: Accept secure, port 988 [ 5067.351155] Key type lgssc registered [ 5067.711617] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5071.288227] Lustre: DEBUG MARKER: Iteration 27 [ 5071.396235] LustreError: 361072:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5071.399269] LustreError: 361073:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5071.401377] LustreError: 361072:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5072.448267] Lustre: Mounted lustre-client [ 5072.972030] LustreError: 361178:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610c4fef800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5072.974664] LustreError: 361177:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5072.975581] LustreError: 361178:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5072.991092] Lustre: Unmounted lustre-client [ 5074.131772] Key type lgssc unregistered [ 5074.261613] LNet: 361420:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5075.302781] LNet: Removed LNI 192.168.204.8@tcp [ 5075.698590] Key type .llcrypt unregistered [ 5075.700744] Key type ._llcrypt unregistered [ 5076.059140] Key type ._llcrypt registered [ 5076.065781] Key type .llcrypt registered [ 5076.256331] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5076.262079] alg: No test for adler32 (adler32-zlib) [ 5077.140649] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5077.236748] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5077.238318] LNet: Accept secure, port 988 [ 5078.839218] Key type lgssc registered [ 5079.334857] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5083.283838] Lustre: DEBUG MARKER: Iteration 28 [ 5083.408993] LustreError: 362234:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5083.412293] LustreError: 362235:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5083.415809] LustreError: 362234:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5084.480314] Lustre: Mounted lustre-client [ 5084.981291] LustreError: 362340:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610c66d2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5084.984803] LustreError: 362340:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5084.986174] LustreError: 362341:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5085.019899] Lustre: Unmounted lustre-client [ 5086.079226] Key type lgssc unregistered [ 5086.197529] LNet: 362582:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5087.269744] LNet: Removed LNI 192.168.204.8@tcp [ 5087.531515] Key type .llcrypt unregistered [ 5087.532637] Key type ._llcrypt unregistered [ 5087.854079] Key type ._llcrypt registered [ 5087.857046] Key type .llcrypt registered [ 5088.083829] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5088.089126] alg: No test for adler32 (adler32-zlib) [ 5088.953580] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5089.034353] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5089.037031] LNet: Accept secure, port 988 [ 5090.623144] Key type lgssc registered [ 5091.078546] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5094.384781] Lustre: DEBUG MARKER: Iteration 29 [ 5094.523447] LustreError: 363396:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5094.523824] LustreError: 363397:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5094.527271] LustreError: 363396:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5095.586893] Lustre: Mounted lustre-client [ 5095.588474] Lustre: Skipped 1 previous similar message [ 5096.040968] LustreError: 363503:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610c4feb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5096.045959] LustreError: 363503:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5096.078390] Lustre: Unmounted lustre-client [ 5096.079405] Lustre: Skipped 1 previous similar message [ 5097.207851] Key type lgssc unregistered [ 5097.343740] LNet: 363745:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5098.405916] LNet: Removed LNI 192.168.204.8@tcp [ 5098.664171] Key type .llcrypt unregistered [ 5098.665930] Key type ._llcrypt unregistered [ 5098.889832] Key type ._llcrypt registered [ 5098.891486] Key type .llcrypt registered [ 5099.125423] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5099.131323] alg: No test for adler32 (adler32-zlib) [ 5099.998084] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5100.081875] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5100.083339] LNet: Accept secure, port 988 [ 5101.671170] Key type lgssc registered [ 5102.104990] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5105.605677] Lustre: DEBUG MARKER: Iteration 30 [ 5105.701095] LustreError: 364559:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5105.701381] LustreError: 364560:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5105.705187] LustreError: 364559:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5106.755567] Lustre: Mounted lustre-client [ 5106.756693] Lustre: Skipped 1 previous similar message [ 5107.227788] LustreError: 364665:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610d0a0a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5107.231773] LustreError: 364666:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5107.234825] LustreError: 364665:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5107.241297] Lustre: Unmounted lustre-client [ 5108.290694] Key type lgssc unregistered [ 5108.405519] LNet: 364907:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5109.477771] LNet: Removed LNI 192.168.204.8@tcp [ 5109.727983] Key type .llcrypt unregistered [ 5109.729677] Key type ._llcrypt unregistered [ 5110.092311] Key type ._llcrypt registered [ 5110.093328] Key type .llcrypt registered [ 5110.253202] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5110.258692] alg: No test for adler32 (adler32-zlib) [ 5111.128117] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5111.218865] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5111.221374] LNet: Accept secure, port 988 [ 5112.807176] Key type lgssc registered [ 5113.223837] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5116.849700] Lustre: DEBUG MARKER: Iteration 31 [ 5116.964995] LustreError: 365720:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5116.970822] LustreError: 365721:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5116.972576] LustreError: 365720:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5118.020232] Lustre: Mounted lustre-client [ 5118.021350] Lustre: Skipped 1 previous similar message [ 5118.613640] LustreError: 365824:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610d2a00800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5118.616606] LustreError: 365824:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5118.620767] LustreError: 365823:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5118.660600] Lustre: Unmounted lustre-client [ 5118.661782] Lustre: Skipped 1 previous similar message [ 5119.834198] Key type lgssc unregistered [ 5119.969492] LNet: 366065:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5120.997859] LNet: Removed LNI 192.168.204.8@tcp [ 5121.263973] Key type .llcrypt unregistered [ 5121.264989] Key type ._llcrypt unregistered [ 5121.664162] Key type ._llcrypt registered [ 5121.665289] Key type .llcrypt registered [ 5121.930028] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5121.935831] alg: No test for adler32 (adler32-zlib) [ 5122.798986] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5122.891511] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5122.893162] LNet: Accept secure, port 988 [ 5124.479092] Key type lgssc registered [ 5124.857903] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5128.329766] Lustre: DEBUG MARKER: Iteration 32 [ 5128.431847] LustreError: 366879:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5128.431882] LustreError: 366880:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5128.435866] LustreError: 366879:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5129.487614] Lustre: Mounted lustre-client [ 5129.488677] Lustre: Skipped 1 previous similar message [ 5130.022692] LustreError: 366986:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610da780800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5130.028327] LustreError: 366986:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5130.044088] Lustre: Unmounted lustre-client [ 5131.114897] Key type lgssc unregistered [ 5131.233541] LNet: 367228:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5132.261725] LNet: Removed LNI 192.168.204.8@tcp [ 5132.513975] Key type .llcrypt unregistered [ 5132.515960] Key type ._llcrypt unregistered [ 5132.841154] Key type ._llcrypt registered [ 5132.842322] Key type .llcrypt registered [ 5133.050795] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5133.057442] alg: No test for adler32 (adler32-zlib) [ 5133.933146] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5134.022989] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5134.024732] LNet: Accept secure, port 988 [ 5135.607152] Key type lgssc registered [ 5135.998484] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5139.497595] Lustre: DEBUG MARKER: Iteration 33 [ 5139.626104] LustreError: 368051:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5139.629233] LustreError: 368052:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5139.633238] LustreError: 368051:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5140.698072] Lustre: Mounted lustre-client [ 5141.303251] LustreError: 368157:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610c93d1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5141.306240] LustreError: 368157:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5141.306744] LustreError: 368158:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5141.310737] LustreError: 368158:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5141.325140] Lustre: Unmounted lustre-client [ 5142.501249] Key type lgssc unregistered [ 5142.657511] LNet: 368400:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5143.722124] LNet: Removed LNI 192.168.204.8@tcp [ 5144.005086] Key type .llcrypt unregistered [ 5144.006114] Key type ._llcrypt unregistered [ 5144.313065] Key type ._llcrypt registered [ 5144.317697] Key type .llcrypt registered [ 5144.487700] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5144.495468] alg: No test for adler32 (adler32-zlib) [ 5145.357555] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5145.438980] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5145.440453] LNet: Accept secure, port 988 [ 5147.023174] Key type lgssc registered [ 5147.427468] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5150.982422] Lustre: DEBUG MARKER: Iteration 34 [ 5151.103105] LustreError: 369214:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5151.103126] LustreError: 369216:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5151.109071] LustreError: 369214:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5152.158131] Lustre: Mounted lustre-client [ 5152.159691] Lustre: Skipped 1 previous similar message [ 5152.347559] ------------[ cut here ]------------ [ 5152.348869] list_del corruption. prev->next should be ffff9610ec7ab058, but was ffff9610c5693058 [ 5152.351326] WARNING: CPU: 2 PID: 369301 at lib/list_debug.c:53 __list_del_entry_valid+0xe9/0x140 [ 5152.353356] Modules linked in: lustre(O) osp(O) ofd(O) lod(O) mdt(O) mdd(O) mgs(O) lquota(O) lfsck(O) obdecho(O) mgc(O) mdc(O) lov(O) osc(O) lmv(O) fid(O) fld(O) ptlrpc_gss(O) ptlrpc(O) obdclass(O) ksocklnd(O) lnet(O) libcfs(O) rpcsec_gss_krb5 auth_rpcgss nfsv4 dns_resolver intel_rapl_msr intel_rapl_common sb_edac rapl i2c_piix4 pcspkr squashfs crct10dif_pclmul crc32_pclmul ata_generic crc32c_intel ata_piix ghash_clmulni_intel serio_raw libata dm_mirror dm_region_hash dm_log dm_mod sha512_ssse3 sha512_generic [last unloaded: libcfs] [ 5152.368566] CPU: 2 PID: 369301 Comm: lsof Kdump: loaded Tainted: G O -------- - - 4.18.0rh8.10-debug #2 [ 5152.371872] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.2-1.fc38 04/01/2014 [ 5152.375135] RIP: 0010:__list_del_entry_valid+0xe9/0x140 [ 5152.376281] Code: 83 05 02 7d 0c 03 01 c3 48 89 f2 48 89 fe 48 c7 c7 f0 e1 be 8a 48 83 05 f4 7c 0c 03 01 e8 fb 48 8f ff 48 83 05 ef 7c 0c 03 01 <0f> 0b 48 83 05 ed 7c 0c 03 01 48 83 05 ed 7c 0c 03 01 31 c0 48 83 [ 5152.381193] RSP: 0018:ffffa36647f0f868 EFLAGS: 00010202 [ 5152.383194] RAX: 0000000000000000 RBX: ffff9610ff6f9400 RCX: 0000000000000000 [ 5152.384769] RDX: ffff96110212e640 RSI: ffff96110211e5a8 RDI: ffff96110211e5a8 [ 5152.386731] RBP: ffff9610ec7ab000 R08: 0000000000000000 R09: c0000000ffff7fff [ 5152.389301] R10: 0000000000000001 R11: ffffa36647f0f658 R12: ffff9610ec7ab058 [ 5152.391328] R13: ffffa36648059058 R14: 0000000000000001 R15: 0000000000000001 [ 5152.393093] FS: 00007f95951ef840(0000) GS:ffff961102100000(0000) knlGS:0000000000000000 [ 5152.394870] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 5152.396480] CR2: 00005651bf124028 CR3: 000000011087c003 CR4: 0000000000170ee0 [ 5152.399315] Call Trace: [ 5152.400665] ? show_regs.cold.9+0x22/0x2f [ 5152.402169] ? __warn+0xc8/0x150 [ 5152.403260] ? __list_del_entry_valid+0xe9/0x140 [ 5152.404190] ? report_bug+0x113/0x140 [ 5152.405124] ? do_error_trap+0xb6/0x130 [ 5152.406683] ? do_invalid_op+0x46/0x60 [ 5152.408158] ? __list_del_entry_valid+0xe9/0x140 [ 5152.409585] ? invalid_op+0x14/0x20 [ 5152.410745] ? __list_del_entry_valid+0xe9/0x140 [ 5152.412563] ? __list_del_entry_valid+0xe1/0x140 [ 5152.413546] ldlm_resource_get+0x80a/0xcb0 [ptlrpc] [ 5152.415011] ldlm_lock_create+0x5e/0xa40 [ptlrpc] [ 5152.417184] ? lustre_swab_generic_32s+0x20/0x20 [ptlrpc] [ 5152.418797] ldlm_cli_enqueue+0x2c4/0xe40 [ptlrpc] [ 5152.420187] ? ldlm_cli_enqueue_local+0xc40/0xc40 [ptlrpc] [ 5152.422232] ? ll_lock_cancel_bits+0xd70/0xd70 [lustre] [ 5152.423631] ? mdc_changelog_cdev_finish+0x2c0/0x2c0 [mdc] [ 5152.425934] mdc_enqueue_base+0x2c7/0x1d80 [mdc] [ 5152.427470] mdc_intent_lock+0x353/0x7e0 [mdc] [ 5152.428767] ? ll_lock_cancel_bits+0xd70/0xd70 [lustre] [ 5152.430577] ? ldlm_cli_enqueue_local+0xc40/0xc40 [ptlrpc] [ 5152.431918] ? mdc_changelog_cdev_finish+0x2c0/0x2c0 [mdc] [ 5152.433993] lmv_intent_lookup+0x2e2/0x1000 [lmv] [ 5152.435782] ? ll_lock_cancel_bits+0xd70/0xd70 [lustre] [ 5152.437059] ? ll_lock_cancel_bits+0xd70/0xd70 [lustre] [ 5152.439176] lmv_intent_lock+0x407/0x4c0 [lmv] [ 5152.440139] ? lprocfs_stats_lock+0xae/0x100 [obdclass] [ 5152.442064] ll_intent_lock+0xc5/0xaa0 [lustre] [ 5152.444011] ? ll_lock_cancel_bits+0xd70/0xd70 [lustre] [ 5152.445427] ll_inode_revalidate.constprop.49+0x35f/0x980 [lustre] [ 5152.447746] ll_getattr_dentry+0x703/0x1800 [lustre] [ 5152.448802] ? getname_flags+0x6e/0x330 [ 5152.449569] ? __check_object_size+0xff/0x256 [ 5152.451083] ? strncpy_from_user+0x5c/0x2c0 [ 5152.451964] ll_getattr+0x19/0x30 [lustre] [ 5152.452869] vfs_getattr_nosec+0x8b/0xb0 [ 5152.454459] vfs_getattr+0x49/0x60 [ 5152.455834] vfs_statx+0xb2/0x150 [ 5152.456987] __do_sys_newstat+0x41/0x90 [ 5152.457865] __x64_sys_newstat+0x1a/0x30 [ 5152.458700] do_syscall_64+0xc1/0x440 [ 5152.459473] entry_SYSCALL_64_after_hwframe+0x49/0xae [ 5152.460494] RIP: 0033:0x7f95948c8be9 [ 5152.461215] Code: 00 00 00 75 05 48 83 c4 18 c3 e8 f2 12 02 00 66 90 f3 0f 1e fa 48 89 f0 83 ff 01 77 34 48 89 c7 48 89 d6 b8 04 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 07 c3 66 0f 1f 44 00 00 48 8b 15 69 32 2a 00 [ 5152.467502] RSP: 002b:00007ffc54f5fe78 EFLAGS: 00000246 ORIG_RAX: 0000000000000004 [ 5152.469298] RAX: ffffffffffffffda RBX: 00007ffc54f5fec0 RCX: 00007f95948c8be9 [ 5152.471585] RDX: 00007ffc54f60ed0 RSI: 00007ffc54f60ed0 RDI: 00007ffc54f5fec0 [ 5152.473726] RBP: 00007ffc54f5feb8 R08: 0000000000000000 R09: 00007f95939b32f0 [ 5152.476145] R10: 00007f95951ef840 R11: 0000000000000246 R12: 00007ffc54f5feac [ 5152.478493] R13: 00007ffc54f5feb0 R14: 00007ffc54f60ed0 R15: 00007ffc54f61f40 [ 5152.479981] ---[ end trace ed9959889b41dff3 ]--- [ 5152.776105] LustreError: 369317:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610c2f33800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5152.779196] LustreError: 369318:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5152.782336] LustreError: 369317:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5152.797747] Lustre: Unmounted lustre-client [ 5153.873577] Key type lgssc unregistered [ 5154.019749] LNet: 369558:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5155.045875] LNet: Removed LNI 192.168.204.8@tcp [ 5155.303058] Key type .llcrypt unregistered [ 5155.304896] Key type ._llcrypt unregistered [ 5155.591420] Key type ._llcrypt registered [ 5155.593291] Key type .llcrypt registered [ 5155.771460] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5155.776442] alg: No test for adler32 (adler32-zlib) [ 5156.642852] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5156.726252] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5156.728301] LNet: Accept secure, port 988 [ 5158.311142] Key type lgssc registered [ 5158.678870] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5162.107134] Lustre: DEBUG MARKER: Iteration 35 [ 5162.209763] LustreError: 370371:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5162.212725] LustreError: 370372:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5162.215039] LustreError: 370371:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5163.259807] Lustre: Mounted lustre-client [ 5163.260841] Lustre: Skipped 1 previous similar message [ 5163.739565] LustreError: 370474:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610d86fe000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5163.742692] LustreError: 370475:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5163.743701] LustreError: 370474:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5163.760096] Lustre: Unmounted lustre-client [ 5164.857983] Key type lgssc unregistered [ 5164.999768] LNet: 370716:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5166.053881] LNet: Removed LNI 192.168.204.8@tcp [ 5166.352096] Key type .llcrypt unregistered [ 5166.353310] Key type ._llcrypt unregistered [ 5166.621781] Key type ._llcrypt registered [ 5166.622712] Key type .llcrypt registered [ 5166.811022] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5166.819598] alg: No test for adler32 (adler32-zlib) [ 5167.678564] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5167.768024] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5167.770675] LNet: Accept secure, port 988 [ 5169.359136] Key type lgssc registered [ 5169.791877] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5173.330914] Lustre: DEBUG MARKER: Iteration 36 [ 5173.457440] LustreError: 371529:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5173.457925] LustreError: 371530:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5173.463942] LustreError: 371529:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5174.529978] Lustre: Mounted lustre-client [ 5175.105980] LustreError: 371638:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610f3949000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5175.109300] LustreError: 371637:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5175.111419] LustreError: 371638:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5175.125149] Lustre: Unmounted lustre-client [ 5176.210556] Key type lgssc unregistered [ 5176.354768] LNet: 371879:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5177.382508] LNet: Removed LNI 192.168.204.8@tcp [ 5177.643920] Key type .llcrypt unregistered [ 5177.644992] Key type ._llcrypt unregistered [ 5177.871715] Key type ._llcrypt registered [ 5177.873530] Key type .llcrypt registered [ 5178.175555] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5178.181655] alg: No test for adler32 (adler32-zlib) [ 5179.043581] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5179.125024] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5179.127124] LNet: Accept secure, port 988 [ 5180.703172] Key type lgssc registered [ 5181.075966] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5184.700332] Lustre: DEBUG MARKER: Iteration 37 [ 5184.810235] LustreError: 372693:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5184.810264] LustreError: 372694:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5184.816819] LustreError: 372693:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5185.867707] Lustre: Mounted lustre-client [ 5185.868949] Lustre: Skipped 1 previous similar message [ 5186.474305] LustreError: 372798:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610d190f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5186.476894] LustreError: 372799:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5186.479561] LustreError: 372798:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5186.511138] Lustre: Unmounted lustre-client [ 5187.686899] Key type lgssc unregistered [ 5187.815566] LNet: 373040:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5188.837696] LNet: Removed LNI 192.168.204.8@tcp [ 5189.074073] Key type .llcrypt unregistered [ 5189.075802] Key type ._llcrypt unregistered [ 5189.402928] Key type ._llcrypt registered [ 5189.403932] Key type .llcrypt registered [ 5189.574924] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5189.579836] alg: No test for adler32 (adler32-zlib) [ 5190.443172] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5190.535222] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5190.536968] LNet: Accept secure, port 988 [ 5192.119124] Key type lgssc registered [ 5192.491415] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5195.765667] Lustre: DEBUG MARKER: Iteration 38 [ 5195.894567] LustreError: 373855:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5195.894603] LustreError: 373854:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5195.900405] LustreError: 373855:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5196.945900] Lustre: Mounted lustre-client [ 5197.403792] LustreError: 373962:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610c8dca000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5197.407785] LustreError: 373963:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 5197.407945] LustreError: 373962:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5197.427050] Lustre: Unmounted lustre-client [ 5198.410315] Key type lgssc unregistered [ 5198.539531] LNet: 374203:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5199.589533] LNet: Removed LNI 192.168.204.8@tcp [ 5199.817763] Key type .llcrypt unregistered [ 5199.818805] Key type ._llcrypt unregistered [ 5200.019029] Key type ._llcrypt registered [ 5200.021062] Key type .llcrypt registered [ 5200.211806] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5200.221306] alg: No test for adler32 (adler32-zlib) [ 5201.088159] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5201.176726] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5201.178339] LNet: Accept secure, port 988 [ 5202.759158] Key type lgssc registered [ 5203.148610] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5206.750702] Lustre: DEBUG MARKER: Iteration 39 [ 5206.856715] LustreError: 375012:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5206.865940] LustreError: 375019:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5206.869351] LustreError: 375012:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [ 5207.936196] Lustre: Mounted lustre-client [ 5207.937307] Lustre: Skipped 1 previous similar message [ 5208.386097] LustreError: 375122:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610ca1be000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5208.389208] LustreError: 375121:0:(obd_class.h:479:obd_check_dev()) Device 7 not setup [ 5208.390288] LustreError: 375122:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5208.408647] Lustre: Unmounted lustre-client [ 5209.412110] Key type lgssc unregistered [ 5209.530555] LNet: 375364:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5210.596783] LNet: Removed LNI 192.168.204.8@tcp [ 5210.849986] Key type .llcrypt unregistered [ 5210.851729] Key type ._llcrypt unregistered [ 5211.135633] Key type ._llcrypt registered [ 5211.138149] Key type .llcrypt registered [ 5211.317764] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5211.322351] alg: No test for adler32 (adler32-zlib) [ 5212.176543] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5212.252580] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5212.254402] LNet: Accept secure, port 988 [ 5213.831114] Key type lgssc registered [ 5214.199321] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5217.505913] Lustre: DEBUG MARKER: Iteration 40 [ 5217.641676] LustreError: 376178:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5217.641686] LustreError: 376179:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5217.645787] LustreError: 376178:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 5218.703718] Lustre: Mounted lustre-client [ 5218.705848] Lustre: Skipped 1 previous similar message [ 5219.192812] LustreError: 376284:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610da411800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5219.197952] LustreError: 376284:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5219.203118] LustreError: 376283:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5219.206198] LustreError: 376283:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 5219.212246] Lustre: Unmounted lustre-client [ 5220.290292] Key type lgssc unregistered [ 5220.425561] LNet: 376526:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5221.477518] LNet: Removed LNI 192.168.204.8@tcp [ 5221.743080] Key type .llcrypt unregistered [ 5221.744922] Key type ._llcrypt unregistered [ 5222.004105] Key type ._llcrypt registered [ 5222.005187] Key type .llcrypt registered [ 5222.224217] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5222.231392] alg: No test for adler32 (adler32-zlib) [ 5223.100203] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5223.184660] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5223.186549] LNet: Accept secure, port 988 [ 5224.767204] Key type lgssc registered [ 5225.149769] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5228.415560] Lustre: DEBUG MARKER: Iteration 41 [ 5228.543328] LustreError: 377339:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5228.543419] LustreError: 377340:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5228.548982] LustreError: 377339:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5229.602468] Lustre: Mounted lustre-client [ 5229.603955] Lustre: Skipped 1 previous similar message [ 5230.132842] LustreError: 377447:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610d1db9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5230.139316] LustreError: 377447:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5230.141463] LustreError: 377447:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 5230.151119] Lustre: Unmounted lustre-client [ 5231.214450] Key type lgssc unregistered [ 5231.338513] LNet: 377689:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5232.358189] LNet: Removed LNI 192.168.204.8@tcp [ 5232.633925] Key type .llcrypt unregistered [ 5232.635024] Key type ._llcrypt unregistered [ 5232.979876] Key type ._llcrypt registered [ 5232.987214] Key type .llcrypt registered [ 5233.276024] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5233.281544] alg: No test for adler32 (adler32-zlib) [ 5234.149099] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5234.242431] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5234.244486] LNet: Accept secure, port 988 [ 5235.839165] Key type lgssc registered [ 5236.272565] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5239.970552] Lustre: DEBUG MARKER: Iteration 42 [ 5240.096798] LustreError: 378503:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5240.096919] LustreError: 378504:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5240.100669] LustreError: 378503:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5241.163762] Lustre: Mounted lustre-client [ 5241.166032] Lustre: Skipped 1 previous similar message [ 5241.782387] LustreError: 378611:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610d122e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5241.785203] LustreError: 378612:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5241.785317] LustreError: 378611:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5241.812087] Lustre: Unmounted lustre-client [ 5241.813064] Lustre: Skipped 1 previous similar message [ 5243.160786] Key type lgssc unregistered [ 5243.302779] LNet: 378855:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5244.326173] LNet: Removed LNI 192.168.204.8@tcp [ 5244.750529] Key type .llcrypt unregistered [ 5244.752358] Key type ._llcrypt unregistered [ 5245.047865] Key type ._llcrypt registered [ 5245.049292] Key type .llcrypt registered [ 5245.299742] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5245.304810] alg: No test for adler32 (adler32-zlib) [ 5246.175613] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5246.267355] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5246.268758] LNet: Accept secure, port 988 [ 5247.855157] Key type lgssc registered [ 5248.263777] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5251.982677] Lustre: DEBUG MARKER: Iteration 43 [ 5252.082937] LustreError: 379667:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5252.086457] LustreError: 379673:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5252.088274] LustreError: 379667:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5253.136745] Lustre: Mounted lustre-client [ 5253.137725] Lustre: Skipped 1 previous similar message [ 5253.709971] LustreError: 379776:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610ca1bd800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5253.715486] LustreError: 379776:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5253.717397] LustreError: 379776:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5253.739103] Lustre: Unmounted lustre-client [ 5255.174434] Key type lgssc unregistered [ 5255.312823] LNet: 380018:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5256.358521] LNet: Removed LNI 192.168.204.8@tcp [ 5256.747209] Key type .llcrypt unregistered [ 5256.748280] Key type ._llcrypt unregistered [ 5257.207939] Key type ._llcrypt registered [ 5257.208949] Key type .llcrypt registered [ 5257.396037] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5257.400632] alg: No test for adler32 (adler32-zlib) [ 5258.264991] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5258.350920] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5258.353159] LNet: Accept secure, port 988 [ 5259.935179] Key type lgssc registered [ 5260.578586] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5265.404727] Lustre: DEBUG MARKER: Iteration 44 [ 5265.534968] LustreError: 380832:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5265.535210] LustreError: 380833:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5265.540186] LustreError: 380832:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5266.590821] Lustre: Mounted lustre-client [ 5267.042525] LustreError: 380936:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610fea01800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5267.047743] LustreError: 380936:0:(obd_class.h:479:obd_check_dev()) Device 7 not setup [ 5267.050409] LustreError: 380936:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5267.062293] Lustre: Unmounted lustre-client [ 5268.267854] Key type lgssc unregistered [ 5268.426941] LNet: 381178:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5269.478239] LNet: Removed LNI 192.168.204.8@tcp [ 5269.750961] Key type .llcrypt unregistered [ 5269.752149] Key type ._llcrypt unregistered [ 5270.052155] Key type ._llcrypt registered [ 5270.058894] Key type .llcrypt registered [ 5270.318459] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5270.323903] alg: No test for adler32 (adler32-zlib) [ 5271.194572] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5271.287094] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5271.288634] LNet: Accept secure, port 988 [ 5272.879255] Key type lgssc registered [ 5273.317612] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5276.893349] Lustre: DEBUG MARKER: Iteration 45 [ 5277.030813] LustreError: 381994:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5277.030839] LustreError: 381993:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5277.036816] LustreError: 381994:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5278.095972] Lustre: Mounted lustre-client [ 5278.627598] LustreError: 382095:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610c561e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5278.631096] LustreError: 382095:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5278.631384] LustreError: 382096:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5278.635769] LustreError: 382096:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5278.644830] Lustre: Unmounted lustre-client [ 5279.826476] Key type lgssc unregistered [ 5279.950561] LNet: 382337:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5280.997445] LNet: Removed LNI 192.168.204.8@tcp [ 5281.243038] Key type .llcrypt unregistered [ 5281.243956] Key type ._llcrypt unregistered [ 5281.513816] Key type ._llcrypt registered [ 5281.536758] Key type .llcrypt registered [ 5281.843184] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5281.848469] alg: No test for adler32 (adler32-zlib) [ 5282.762558] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5282.866102] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5282.867961] LNet: Accept secure, port 988 [ 5284.463162] Key type lgssc registered [ 5284.917320] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5288.296865] Lustre: DEBUG MARKER: Iteration 46 [ 5288.426495] LustreError: 383151:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5288.426570] LustreError: 383152:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5288.432347] LustreError: 383151:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5289.483367] Lustre: Mounted lustre-client [ 5290.087924] LustreError: 383254:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610d2a04000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5290.091288] LustreError: 383253:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 5290.092116] LustreError: 383254:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5290.115966] Lustre: Unmounted lustre-client [ 5291.170678] Key type lgssc unregistered [ 5291.289574] LNet: 383494:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5292.325936] LNet: Removed LNI 192.168.204.8@tcp [ 5292.573905] Key type .llcrypt unregistered [ 5292.575063] Key type ._llcrypt unregistered [ 5292.817229] Key type ._llcrypt registered [ 5292.818250] Key type .llcrypt registered [ 5293.002510] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5293.007202] alg: No test for adler32 (adler32-zlib) [ 5293.868032] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5293.950109] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5293.951685] LNet: Accept secure, port 988 [ 5295.535136] Key type lgssc registered [ 5295.899070] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5299.371207] Lustre: DEBUG MARKER: Iteration 47 [ 5299.489524] LustreError: 384308:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5299.489560] LustreError: 384309:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5299.494927] LustreError: 384308:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5300.549941] Lustre: Mounted lustre-client [ 5301.125769] LustreError: 384411:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610c4fec000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5301.129150] LustreError: 384412:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 5301.129848] LustreError: 384411:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5301.144071] Lustre: Unmounted lustre-client [ 5302.221934] Key type lgssc unregistered [ 5302.346528] LNet: 384654:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5303.397649] LNet: Removed LNI 192.168.204.8@tcp [ 5303.654051] Key type .llcrypt unregistered [ 5303.655084] Key type ._llcrypt unregistered [ 5304.023796] Key type ._llcrypt registered [ 5304.024947] Key type .llcrypt registered [ 5304.299821] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5304.307087] alg: No test for adler32 (adler32-zlib) [ 5305.188568] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5305.284553] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5305.286398] LNet: Accept secure, port 988 [ 5306.871201] Key type lgssc registered [ 5307.239890] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5310.729862] Lustre: DEBUG MARKER: Iteration 48 [ 5310.842767] LustreError: 385467:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5310.843969] LustreError: 385468:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5310.849249] LustreError: 385467:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 5311.902885] Lustre: Mounted lustre-client [ 5311.903845] Lustre: Skipped 1 previous similar message [ 5312.428432] LustreError: 385572:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610fea05800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5312.431569] LustreError: 385572:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5312.432521] LustreError: 385573:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5312.469128] Lustre: Unmounted lustre-client [ 5313.539949] Key type lgssc unregistered [ 5313.663545] LNet: 385815:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5314.725891] LNet: Removed LNI 192.168.204.8@tcp [ 5315.009639] Key type .llcrypt unregistered [ 5315.010707] Key type ._llcrypt unregistered [ 5315.361822] Key type ._llcrypt registered [ 5315.363309] Key type .llcrypt registered [ 5315.641723] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5315.648320] alg: No test for adler32 (adler32-zlib) [ 5316.513154] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5316.601594] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5316.603073] LNet: Accept secure, port 988 [ 5318.191200] Key type lgssc registered [ 5318.624324] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5322.299692] Lustre: DEBUG MARKER: Iteration 49 [ 5322.404561] LustreError: 386628:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5322.404616] LustreError: 386629:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5322.408393] LustreError: 386628:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5323.455288] Lustre: Mounted lustre-client [ 5323.456489] Lustre: Skipped 1 previous similar message [ 5323.909086] LustreError: 386737:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610c8dca000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5323.914752] LustreError: 386737:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5323.916715] LustreError: 386737:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5323.932074] Lustre: Unmounted lustre-client [ 5324.896149] Key type lgssc unregistered [ 5325.024637] LNet: 386979:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5326.053778] LNet: Removed LNI 192.168.204.8@tcp [ 5326.281671] Key type .llcrypt unregistered [ 5326.283584] Key type ._llcrypt unregistered [ 5326.669897] Key type ._llcrypt registered [ 5326.680738] Key type .llcrypt registered [ 5326.869212] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5326.874243] alg: No test for adler32 (adler32-zlib) [ 5327.735718] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5327.811630] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5327.813375] LNet: Accept secure, port 988 [ 5329.391189] Key type lgssc registered [ 5329.854551] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5333.531300] Lustre: DEBUG MARKER: Iteration 50 [ 5333.635743] LustreError: 387795:0:(llite_lib.c:1369:ll_fill_super()) cfs_race id 1417 sleeping [ 5333.636087] LustreError: 387794:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 waking [ 5333.641223] LustreError: 387795:0:(llite_lib.c:1369:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5334.686669] Lustre: Mounted lustre-client [ 5335.259075] LustreError: 387900:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610f2e4a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5335.263943] LustreError: 387900:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5335.266991] LustreError: 387900:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5335.280838] Lustre: Unmounted lustre-client [ 5336.428792] Key type lgssc unregistered [ 5336.556461] LNet: 388142:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5337.573436] LNet: Removed LNI 192.168.204.8@tcp [ 5337.815134] Key type .llcrypt unregistered [ 5337.816212] Key type ._llcrypt unregistered [ 5338.053878] Key type ._llcrypt registered [ 5338.054947] Key type .llcrypt registered [ 5338.270611] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5338.290482] alg: No test for adler32 (adler32-zlib) [ 5339.157866] Lustre: Lustre: Build Version: 2.16.57_1_gf739d57 [ 5339.243711] LNet: Added LNI 192.168.204.8@tcp [8/256/0/180] [ 5339.245141] LNet: Accept secure, port 988 [ 5340.823191] Key type lgssc registered [ 5341.240314] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5345.259788] Lustre: Mounted lustre-client [ 5347.652591] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 15:10:53 (1752693053) [ 5364.191208] Lustre: 389466:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1752693054/real 1752693054] req@ffff9610f2db4e00 x1837831862362880/t0(0) o36->lustre-MDT0000-mdc-ffff9610da411800@192.168.204.108@tcp:12/10 lens 496/440 e 0 to 1 dl 1752693070 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 5364.202200] Lustre: lustre-MDT0000-mdc-ffff9610da411800: Connection to lustre-MDT0000 (at 192.168.204.108@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5364.219032] Lustre: lustre-MDT0000-mdc-ffff9610da411800: Connection restored to (at 192.168.204.108@tcp) [ 5380.575153] Lustre: 389466:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1752693070/real 1752693070] req@ffff9610f2db4e00 x1837831862362880/t0(0) o36->lustre-MDT0000-mdc-ffff9610da411800@192.168.204.108@tcp:12/10 lens 496/440 e 0 to 1 dl 1752693086 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 5380.583764] Lustre: lustre-MDT0000-mdc-ffff9610da411800: Connection to lustre-MDT0000 (at 192.168.204.108@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5380.594384] Lustre: lustre-MDT0000-mdc-ffff9610da411800: Connection restored to (at 192.168.204.108@tcp) [ 5395.935199] Lustre: 389466:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1752693086/real 1752693086] req@ffff9610f2db4e00 x1837831862362880/t0(0) o36->lustre-MDT0000-mdc-ffff9610da411800@192.168.204.108@tcp:12/10 lens 496/440 e 0 to 1 dl 1752693102 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 5395.943169] Lustre: lustre-MDT0000-mdc-ffff9610da411800: Connection to lustre-MDT0000 (at 192.168.204.108@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5395.950598] Lustre: lustre-MDT0000-mdc-ffff9610da411800: Connection restored to (at 192.168.204.108@tcp) [ 5412.319126] Lustre: 389466:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1752693102/real 1752693102] req@ffff9610f2db4e00 x1837831862362880/t0(0) o36->lustre-MDT0000-mdc-ffff9610da411800@192.168.204.108@tcp:12/10 lens 496/440 e 0 to 1 dl 1752693118 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 5412.331351] Lustre: lustre-MDT0000-mdc-ffff9610da411800: Connection to lustre-MDT0000 (at 192.168.204.108@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5412.344371] Lustre: lustre-MDT0000-mdc-ffff9610da411800: Connection restored to (at 192.168.204.108@tcp) [ 5412.939344] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 15:11:58 (1752693118) [ 5413.512291] Lustre: DEBUG MARKER: SKIP: sanityn test_111 needs >= 2 MDTs [ 5414.100249] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 15:11:59 (1752693119) [ 5414.630169] Lustre: DEBUG MARKER: SKIP: sanityn test_112 We need at least 2 MDTs for this test [ 5415.193398] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 15:12:00 (1752693120) [ 5417.604283] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 15:12:03 (1752693123) [ 5418.133884] Lustre: DEBUG MARKER: SKIP: sanityn test_114 We need at least 2 MDTs for this test [ 5418.753519] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 15:12:04 (1752693124) [ 5419.317319] Lustre: DEBUG MARKER: SKIP: sanityn test_115 ldiskfs only test [ 5419.957255] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 15:12:05 (1752693125) [ 5420.509456] Lustre: DEBUG MARKER: SKIP: sanityn test_116 needs >= 2 MDTs [ 5421.061448] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 15:12:06 (1752693126) [ 5438.943131] Lustre: 388334:0:(client.c:2453:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1752693129/real 1752693129] req@ffff9610e7451180 x1837831862389248/t0(0) o4->lustre-OST0000-osc-ffff9610da411800@192.168.204.108@tcp:6/4 lens 4584/448 e 0 to 1 dl 1752693145 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 5438.950134] Lustre: lustre-OST0000-osc-ffff9610da411800: Connection to lustre-OST0000 (at 192.168.204.108@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5526.687518] Lustre: DEBUG MARKER: oleg408-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9610d1dbd800.ost_server_uuid 50 [ 5527.219261] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9610d1dbd800.ost_server_uuid in IDLE state after 0 sec [ 5527.750484] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 5528.310833] Lustre: DEBUG MARKER: == sanityn test complete, duration 5406 sec ============== 15:13:53 (1752693233) [ 5528.921306] Lustre: DEBUG MARKER: === sanityn: start cleanup 15:13:54 (1752693234) === [ 5570.975893] LustreError: 393561:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610d1dbd800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5570.979375] LustreError: 393561:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5570.988083] Lustre: Unmounted lustre-client [ 5572.072680] Lustre: DEBUG MARKER: === sanityn: finish cleanup 15:14:37 (1752693277) === [ 5572.366983] LustreError: 393861:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffff9610da411800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5572.370717] LustreError: 393861:0:(lov_obd.c:784:lov_cleanup()) Skipped 1 previous similar message [ 5572.378407] LustreError: 393861:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5572.380108] LustreError: 393861:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 5572.400077] Lustre: Unmounted lustre-client