[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-8.fc42 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 412996356 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2895288K/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.001015] APIC: Switch to symmetric I/O mode setup [ 0.002330] x2apic enabled [ 0.003006] Switched APIC routing to physical x2apic. [ 0.004000] kvm-guest: setup PV IPIs [ 0.006000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.006000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.006030] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.007014] pid_max: default: 32768 minimum: 301 [ 0.009167] LSM: Security Framework initializing [ 0.010050] Yama: becoming mindful. [ 0.011033] SELinux: Initializing. [ 0.012064] *** VALIDATE selinux *** [ 0.020757] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.025452] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.026169] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.027108] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028124] *** VALIDATE tmpfs *** [ 0.029464] *** VALIDATE proc *** [ 0.031240] *** VALIDATE cgroup *** [ 0.032009] *** VALIDATE cgroup2 *** [ 0.033267] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.035157] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.036009] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.037035] Spectre V2 : User space: Vulnerable [ 0.039005] Speculative Store Bypass: Vulnerable [ 0.042254] debug: unmapping init [mem 0xffffffffa1859000-0xffffffffa1860fff] [ 0.045233] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.046770] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.047028] ... version: 2 [ 0.048013] ... bit width: 48 [ 0.049009] ... generic registers: 4 [ 0.050011] ... value mask: 0000ffffffffffff [ 0.051014] ... max period: 00007fffffffffff [ 0.052014] ... fixed-purpose events: 3 [ 0.053009] ... event mask: 000000070000000f [ 0.054364] rcu: Hierarchical SRCU implementation. [ 0.056608] smp: Bringing up secondary CPUs ... [ 0.057542] x86: Booting SMP configuration: [ 0.058022] .... node #0, CPUs: #1 #2 #3 [ 0.068021] smp: Brought up 1 node, 4 CPUs [ 0.070011] smpboot: Max logical packages: 1 [ 0.071008] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.112092] node 0 deferred pages initialised in 39ms [ 0.118110] devtmpfs: initialized [ 0.119433] x86/mm: Memory block size: 128MB [ 0.123776] gcov: version magic: 0x41383552 [ 0.126308] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.127078] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.130430] pinctrl core: initialized pinctrl subsystem [ 0.132226] [ 0.132748] ************************************************************* [ 0.135011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.137013] ** ** [ 0.139013] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.142015] ** ** [ 0.144010] ** This means that this kernel is built to expose internal ** [ 0.146011] ** IOMMU data structures, which may compromise security on ** [ 0.148013] ** your system. ** [ 0.151012] ** ** [ 0.153011] ** If you see this message and you are not debugging the ** [ 0.155014] ** kernel, report this immediately to your vendor! ** [ 0.158016] ** ** [ 0.160010] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.161010] ************************************************************* [ 0.163684] NET: Registered protocol family 16 [ 0.165404] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.167046] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.169038] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.171388] cpuidle: using governor menu [ 0.172449] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.177740] PCI: Using configuration type 1 for base access [ 0.179142] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.189142] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.191015] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.194155] cryptd: max_cpu_qlen set to 1000 [ 0.195216] ACPI: Added _OSI(Module Device) [ 0.196000] ACPI: Added _OSI(Processor Device) [ 0.198013] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.200009] ACPI: Added _OSI(Processor Aggregator Device) [ 0.203546] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.208504] ACPI: Interpreter enabled [ 0.209000] ACPI: PM: (supports S0 S3 S4 S5) [ 0.211026] ACPI: Using IOAPIC for interrupt routing [ 0.212131] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.215415] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.224447] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.225048] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.227019] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.230109] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.234842] acpiphp: Slot [2] registered [ 0.235000] acpiphp: Slot [5] registered [ 0.238186] acpiphp: Slot [6] registered [ 0.239133] acpiphp: Slot [3] registered [ 0.240093] acpiphp: Slot [4] registered [ 0.242115] acpiphp: Slot [7] registered [ 0.243129] acpiphp: Slot [8] registered [ 0.245111] acpiphp: Slot [9] registered [ 0.247105] acpiphp: Slot [10] registered [ 0.248148] acpiphp: Slot [11] registered [ 0.250114] acpiphp: Slot [12] registered [ 0.251088] acpiphp: Slot [13] registered [ 0.252097] acpiphp: Slot [14] registered [ 0.254086] acpiphp: Slot [15] registered [ 0.255066] acpiphp: Slot [16] registered [ 0.256104] acpiphp: Slot [17] registered [ 0.257079] acpiphp: Slot [18] registered [ 0.258062] acpiphp: Slot [19] registered [ 0.259059] acpiphp: Slot [20] registered [ 0.260058] acpiphp: Slot [21] registered [ 0.261092] acpiphp: Slot [22] registered [ 0.262061] acpiphp: Slot [23] registered [ 0.263088] acpiphp: Slot [24] registered [ 0.264071] acpiphp: Slot [25] registered [ 0.265060] acpiphp: Slot [26] registered [ 0.266061] acpiphp: Slot [27] registered [ 0.267076] acpiphp: Slot [28] registered [ 0.268060] acpiphp: Slot [29] registered [ 0.269120] acpiphp: Slot [30] registered [ 0.270063] acpiphp: Slot [31] registered [ 0.271066] PCI host bridge to bus 0000:00 [ 0.272014] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.274013] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.275011] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.277013] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.279013] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.281019] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.282179] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.285112] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.287080] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.292875] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.295470] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.297014] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.299011] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.301016] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.303571] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.305533] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.307019] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.309863] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.314013] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.320878] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.324013] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.329297] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.335015] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.341017] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.356018] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.363684] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.370032] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.378025] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.393028] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.402292] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.405735] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.407323] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.409255] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.411153] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.415076] iommu: Default domain type: Passthrough [ 0.417191] SCSI subsystem initialized [ 0.418108] ACPI: bus type USB registered [ 0.420131] usbcore: registered new interface driver usbfs [ 0.421049] usbcore: registered new interface driver hub [ 0.423059] usbcore: registered new device driver usb [ 0.424174] pps_core: LinuxPPS API ver. 1 registered [ 0.425011] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.428067] PTP clock support registered [ 0.430112] EDAC MC: Ver: 3.0.0 [ 0.432176] PCI: Using ACPI for IRQ routing [ 0.434242] NetLabel: Initializing [ 0.435007] NetLabel: domain hash size = 128 [ 0.436000] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.436000] NetLabel: unlabeled traffic allowed by default [ 0.438100] vgaarb: loaded [ 0.440315] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.442008] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.450000] clocksource: Switched to clocksource kvm-clock [ 0.554421] VFS: Disk quotas dquot_6.6.0 [ 0.559565] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.561834] *** VALIDATE ramfs *** [ 0.587440] *** VALIDATE hugetlbfs *** [ 0.589042] pnp: PnP ACPI init [ 0.593515] pnp: PnP ACPI: found 6 devices [ 0.615332] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.617924] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.619531] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.621214] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.623427] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.625823] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.629073] NET: Registered protocol family 2 [ 0.631641] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.635813] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.639957] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.649851] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.652750] TCP: Hash tables configured (established 65536 bind 65536) [ 0.655289] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.658561] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.660954] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.663010] NET: Registered protocol family 1 [ 0.665608] RPC: Registered named UNIX socket transport module. [ 0.668886] RPC: Registered udp transport module. [ 0.670881] RPC: Registered tcp transport module. [ 0.672713] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.675263] NET: Registered protocol family 44 [ 0.676960] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.679422] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.681641] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.683265] PCI: CLS 0 bytes, default 64 [ 0.684888] Unpacking initramfs... [ 2.350451] debug: unmapping init [mem 0xffff9d0a3cc64000-0xffff9d0a3ffcffff] [ 2.413051] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.419940] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.425771] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 3.086273] Initialise system trusted keyrings [ 3.087934] Key type blacklist registered [ 3.090065] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.107000] zbud: loaded [ 3.112052] *** VALIDATE nfs *** [ 3.113120] *** VALIDATE nfs4 *** [ 3.116584] pstore: using deflate compression [ 3.121445] Platform Keyring initialized [ 3.259800] NET: Registered protocol family 38 [ 3.264396] Key type asymmetric registered [ 3.268242] Asymmetric key parser 'x509' registered [ 3.270210] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.273446] io scheduler mq-deadline registered [ 3.274904] io scheduler kyber registered [ 3.276946] io scheduler bfq registered [ 3.279117] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.281558] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.287726] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.293839] ACPI: Power Button [PWRF] [ 3.304908] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.316541] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.325992] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.355756] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.388718] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.394765] Non-volatile memory driver v1.3 [ 3.397501] Linux agpgart interface v0.103 [ 3.456698] virtio_blk virtio1: [vda] 134768 512-byte logical blocks (69.0 MB/65.8 MiB) [ 3.459645] vda: detected capacity change from 0 to 69001216 [ 3.476390] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.478722] vdb: detected capacity change from 0 to 1073741824 [ 3.593735] libphy: Fixed MDIO Bus: probed [ 3.665790] usbcore: registered new interface driver usbserial_generic [ 3.704426] usbserial: USB Serial support registered for generic [ 3.713383] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.726675] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.731710] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.735249] mousedev: PS/2 mouse device common for all mice [ 3.738691] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.755593] rtc_cmos 00:05: RTC can wake from S4 [ 3.760666] rtc_cmos 00:05: registered as rtc0 [ 3.760722] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.762284] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.768305] intel_pstate: CPU model not supported [ 3.770662] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.778747] hid: raw HID events driver (C) Jiri Kosina [ 3.785477] usbcore: registered new interface driver usbhid [ 3.791491] usbhid: USB HID core driver [ 3.793353] drop_monitor: Initializing network drop monitor service [ 3.807734] Initializing XFRM netlink socket [ 3.810611] NET: Registered protocol family 10 [ 3.815155] Segment Routing with IPv6 [ 3.817220] NET: Registered protocol family 17 [ 3.819900] mpls_gso: MPLS GSO support [ 3.828911] RAS: Correctable Errors collector initialized. [ 3.831723] AVX version of gcm_enc/dec engaged. [ 3.834822] AES CTR mode by8 optimization enabled [ 4.031946] sched_clock: Marking stable (4031919371, 0)->(4939710950, -907791579) [ 4.044308] registered taskstats version 1 [ 4.052621] Loading compiled-in X.509 certificates [ 4.064736] zswap: loaded using pool lzo/zbud [ 4.120874] Key type big_key registered [ 4.137602] Key type encrypted registered [ 4.139936] ima: No TPM chip found, activating TPM-bypass! [ 4.142512] ima: Allocated hash algorithm: sha1 [ 4.144716] ima: No architecture policies found [ 4.146701] evm: Initialising EVM extended attributes: [ 4.149197] evm: security.selinux [ 4.157579] evm: security.ima [ 4.160800] evm: security.capability [ 4.167831] evm: HMAC attrs: 0x1 [ 4.183577] rtc_cmos 00:05: setting system clock to 2026-03-16 18:20:15 UTC (1773685215) [ 4.193194] debug: unmapping init [mem 0xffffffffa2803000-0xffffffffa29fffff] [ 4.197470] debug: unmapping init [mem 0xffffffffa1582000-0xffffffffa1858fff] [ 4.206442] Write protecting the kernel read-only data: 28672k [ 4.213800] debug: unmapping init [mem 0xffffffff9fc03000-0xffffffff9fdfffff] [ 4.235924] debug: unmapping init [mem 0xffffffffa0514000-0xffffffffa05fffff] [ 4.362855] 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.382591] systemd[1]: Detected virtualization kvm. [ 4.385996] systemd[1]: Detected architecture x86-64. [ 4.393569] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 4.431975] systemd[1]: No hostname configured. [ 4.434362] systemd[1]: Set hostname to . [ 4.436689] random: systemd: uninitialized urandom read (16 bytes read) [ 4.439776] systemd[1]: Initializing machine ID from random generator. [ 4.597895] random: ln: uninitialized urandom read (6 bytes read) [ 4.760447] random: systemd: uninitialized urandom read (16 bytes read) [ 4.763800] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 4.791532] systemd[1]: Starting Setup Virtual Console... Starting Setup Virtual Console... [ 4.841832] systemd[1]: Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Slices. Starting Journal Service... [ OK ] Reached target Timers. [ OK ] Reached target Local File Systems. Starting Create Volatile Files and Directories... [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. [ OK ] Started Memstrack Anylazing Service. Starting Apply Kernel Variables... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Swap. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Initrd Root Device. [ OK ] Started Setup Virtual Console. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 8.613687] device-mapper: uevent: version 1.0.3 [ 8.619565] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 10.455545] virtio_net virtio0 ens2: renamed from eth0 [ 10.663573] scsi host0: ata_piix [ 10.738181] scsi host1: ata_piix [ 10.739466] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 10.741682] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 15.785968] random: crng init done [ 15.792092] random: 7 urandom warning(s) missed due to ratelimiting [ 19.016995] dracut-initqueue[589]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 21.720477] 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. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped dracut initqueue hook. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped target Local Encrypted Volumes. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Paths. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped udev Kernel Device Manager. [ OK ] 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 Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 26.690636] printk: systemd: 26 output lines suppressed due to ratelimiting [ 27.934373] SELinux: Disabled at runtime. [ 28.041775] 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) [ 28.050336] systemd[1]: Detected virtualization kvm. [ 28.056731] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 29.416029] hrtimer: interrupt took 5208240 ns [ 31.491921] systemd[1]: initrd-switch-root.service: Succeeded. [ 31.510210] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 31.660407] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 31.671264] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 31.680573] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 31.694473] systemd[1]: Starting Journal Service... Starting Journal Service... [ 31.712314] systemd[1]: Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target rpc_pipefs.target. Starting Remount Root and Kernel File Systems... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Created slice system-getty.slice. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on udev Kernel Socket. Mounting Kernel Debug File System... [ OK ] Stopped target Switch Root. [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting Apply Kernel Variables... [ OK ] Stopped target Initrd Root 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... [ 32.431815] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Listening on Process Core Dump Socket. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Local Encrypted Volumes. Mounting Huge Pages File System... [ OK ] Stopped target Initrd File Systems. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Reached target Paths. [ OK ] Reached target RPC Port Mapper. Mounting POSIX Message Queue File System... [ OK ] Started Journal Service. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Apply Kernel Variables. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started udev Coldplug all Devices. [ 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 ] Mounted /mnt. [ 34.740433] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 36.089062] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 36.427083] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 36.929100] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 37.060754] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (9s / no limit) [** ] A start job is running for Configur…-only root support (9s / no limit)[ 41.411888] Key type dns_resolver registered [*** ] A start job is running for Configur…only root support (10s / no limit) [ *** ] A start job is running for Configur…only root support (10s / no limit)[ 42.260472] NFS: Registering the id_resolver key type [ 42.271310] Key type id_resolver registered [ 42.276268] Key type id_legacy registered [ *** ] A start job is running for Configur…only root support (11s / no limit) [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... Starting Mark the need to relabel after reboot... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting Network Manager Wait Online... [ OK ] Started Login Service. 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 Permit User Sessions. [ OK ] Started Serial Getty on ttyS0. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Reached target Login Prompts. [ OK ] Started Command Scheduler. [ OK ] Started OpenSSH server daemon. [ 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. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg445-client login: [ 97.180932] libcfs: loading out-of-tree module taints kernel. [ 97.263386] Key type ._llcrypt registered [ 97.264866] Key type .llcrypt registered [ 97.609564] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 97.619149] alg: No test for adler32 (adler32-zlib) [ 98.791789] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 99.255091] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 100.903734] Key type lgssc registered [ 101.810741] Lustre: Echo OBD driver; http://www.lustre.org/ [ 193.362148] Lustre: Mounted lustre-client [ 197.625258] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 211.800675] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing check_logdir /tmp/testlogs/ [ 215.200658] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing yml_node [ 218.994433] Lustre: DEBUG MARKER: Client: 2.17.50.152 [ 219.103600] Lustre: lustre-OST0000-osc-ffff9d0a8aa8f800: disconnect after 23s idle [ 221.094907] Lustre: DEBUG MARKER: MDS: 2.17.50.152 [ 223.082588] Lustre: DEBUG MARKER: OSS: 2.17.50.152 [ 224.089137] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Mon Mar 16 14:23:54 EDT 2026 [ 236.919026] Lustre: DEBUG MARKER: excepting tests: 27 40a 102 [ 237.790231] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 238.794930] Lustre: DEBUG MARKER: === sanityn: start setup 14:24:09 (1773685449) === [ 239.320697] Lustre: Mounted lustre-client [ 241.680293] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing check_config_client /mnt/lustre [ 255.383921] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 260.066166] Lustre: lustre-OST0000-osc-ffff9d0a840c8000: disconnect after 21s idle [ 260.077557] Lustre: Skipped 1 previous similar message [ 262.681318] Lustre: DEBUG MARKER: === sanityn: finish setup 14:24:33 (1773685473) === [ 264.051155] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 14:24:34 (1773685474) [ 268.111701] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 14:24:38 (1773685478) [ 271.612664] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 14:24:42 (1773685482) [ 275.209760] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 14:24:45 (1773685485) [ 279.338722] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 14:24:49 (1773685489) [ 283.357025] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 14:24:53 (1773685493) [ 286.949359] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 14:24:57 (1773685497) [ 290.628904] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 14:25:01 (1773685501) [ 294.715254] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 14:25:05 (1773685505) [ 298.094194] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 14:25:08 (1773685508) [ 301.483633] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 14:25:11 (1773685511) [ 305.879436] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 14:25:16 (1773685516) [ 309.217550] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 14:25:19 (1773685519) [ 312.712364] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 14:25:23 (1773685523) [ 316.510353] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 14:25:26 (1773685526) [ 320.159833] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 14:25:30 (1773685530) [ 323.833981] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 14:25:34 (1773685534) [ 327.629974] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 14:25:38 (1773685538) [ 331.285888] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 14:25:41 (1773685541) [ 334.135237] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 14:25:44 (1773685544) [ 337.228739] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 14:25:47 (1773685547) [ 337.673686] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279518 file: /mnt/lustre/lockdir/lockfile=144115205289279517 [ 485.289592] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 14:28:15 (1773685695) [ 489.599903] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 14:28:20 (1773685700) [ 493.644140] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 14:28:24 (1773685704) [ 497.374529] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 14:28:27 (1773685707) [ 501.113746] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 14:28:31 (1773685711) [ 505.087932] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 14:28:35 (1773685715) [ 506.194343] Lustre: DEBUG MARKER: chmod [ 509.779983] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 14:28:40 (1773685720) [ 534.206649] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7523328KB free > 800000KB, increase MAXFREE (or reduce fs size) [ 544.550105] Lustre: DEBUG MARKER: == sanityn test 16a: 500 iterations of dual-mount fsx ==== 14:29:15 (1773685755) [ 572.484442] Lustre: DEBUG MARKER: == sanityn test 16b: 500 iterations of dual-mount fsx at small size ========================================================== 14:29:43 (1773685783) [ 588.275260] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 14:29:58 (1773685798) [ 589.415256] Lustre: DEBUG MARKER: SKIP: sanityn test_16c dio on ldiskfs only [ 590.208451] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 14:30:00 (1773685800) [ 613.765085] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 14:30:24 (1773685824) [ 617.613204] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 14:30:28 (1773685828) [ 618.199687] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 618.239376] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 618.279958] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 618.319503] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 618.355446] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 618.380976] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 618.422995] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 618.454355] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 618.490579] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 618.529075] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 618.565895] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 618.604478] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 618.649652] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 618.691698] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 618.722199] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 618.765390] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 618.798862] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 618.826470] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 618.858305] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 618.885515] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 618.922035] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 618.976253] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 619.009443] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 619.062128] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 619.108678] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 619.148240] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 619.198793] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 619.244099] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 619.290427] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 619.329826] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 619.375090] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 619.408173] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 619.457967] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 619.492837] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 619.538089] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 619.581746] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 619.618259] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 619.668538] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 619.710088] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 619.761057] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 619.797977] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 619.848930] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 619.885584] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 619.921964] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 619.957051] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 619.990157] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.025495] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.058359] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.097472] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.131594] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.171278] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.205454] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.243962] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.282758] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.317886] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.359199] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.390933] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.431363] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.469595] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.512375] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.543220] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.570972] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.606327] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.643415] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.680335] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.709551] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.736222] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.765701] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.804271] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.844861] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.880932] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.913129] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.951883] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 620.990620] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 621.030299] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 621.066634] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 621.096071] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 621.129593] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 621.175085] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 621.218533] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 621.261478] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 621.294747] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 621.331169] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 621.365577] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 621.399321] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 621.436570] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 621.475818] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 621.531406] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 621.573381] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 621.624814] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 621.674422] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 621.720731] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 621.764595] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 621.799457] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 621.840183] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 621.870849] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 621.912908] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 621.951813] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 621.982872] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 622.024982] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 622.065402] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 622.103570] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 622.145415] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 622.190122] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 622.228144] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 622.274777] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 622.313831] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 622.356382] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 622.386691] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 622.412508] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 622.438182] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 622.464235] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 622.494624] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 622.524109] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 622.560513] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 622.597358] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 622.624800] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 622.663679] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 622.697109] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 622.748551] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 622.795827] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 622.836873] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 622.869282] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 622.905534] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 622.938523] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 622.969803] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.003314] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.042242] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.075133] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.116821] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.150718] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.190879] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.224754] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.255691] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.297944] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.327189] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.367143] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.405607] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.446216] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.475322] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.504677] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.533704] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.564758] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.598617] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.639025] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.675207] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.716833] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.754795] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.791481] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.824429] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.864878] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.892508] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.927063] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.957323] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 623.990961] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.017979] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.057468] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.096987] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.125710] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.162311] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.190857] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.219061] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.250395] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.277819] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.306376] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.335432] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.368112] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.398527] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.429419] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.470514] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.501675] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.543201] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.590107] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.625933] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.651435] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.679586] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.706164] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.736198] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.761071] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.791734] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.828350] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.857350] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.898204] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.939207] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 624.975226] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.019176] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.052023] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.089310] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.120842] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.151772] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.192469] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.229590] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.264655] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.300582] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.343364] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.378081] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.411654] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.454359] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.493298] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.521770] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.553235] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.588582] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.621575] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.655566] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.696443] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.727527] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.754387] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.778475] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.807444] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.836762] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.870298] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.911385] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.946630] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 625.983132] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.011918] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.047479] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.081036] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.109985] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.151479] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.174416] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.198112] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.230795] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.261326] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.287863] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.312815] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.347032] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.383779] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.413860] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.444259] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.468530] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.496455] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.526107] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.563917] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.596445] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.640780] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.669417] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.695109] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.727252] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.755484] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.779920] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.803223] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.826751] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.850091] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.878595] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.907341] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.932940] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 626.967521] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.009913] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.047908] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.089101] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.123185] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.148815] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.176267] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.199494] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.226352] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.256195] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.287124] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.317751] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.341997] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.375649] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.415411] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.448403] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.497533] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.532171] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.561430] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.596506] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.623559] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.654253] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.680896] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.706384] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.734318] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.765495] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.787622] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.825910] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.865788] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.899894] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.925855] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.953816] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 627.994459] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.032902] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.087250] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.119339] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.161907] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.200479] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.232335] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.266472] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.291035] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.323737] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.351541] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.380715] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.405757] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.434250] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.462548] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.493158] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.518571] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.549049] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.585398] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.607785] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.632595] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.664038] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.695158] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.721245] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.748938] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.775280] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.814089] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.838758] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.870657] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.905093] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.936783] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 628.979147] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.014906] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.048634] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.083389] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.143759] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.169336] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.195038] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.220672] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.248527] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.277859] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.310821] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.343504] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.376390] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.403880] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.447424] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.485369] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.522763] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.561925] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.602441] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.639356] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.672910] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.700572] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.749891] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.783817] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.825155] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.857231] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.894785] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.946912] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 629.984312] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.017373] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.046512] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.074586] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.112923] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.147549] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.192478] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.224410] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.259587] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.290960] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.330356] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.368993] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.405919] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.442277] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.479679] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.506869] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.532516] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.572544] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.605338] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.640527] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.668636] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.701725] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.744160] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.779346] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.809468] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.842598] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.872576] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.901110] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.933701] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.969358] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 630.998823] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.033564] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.066303] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.100467] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.129676] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.163311] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.191136] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.215912] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.243785] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.279367] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.313694] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.349148] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.388643] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.419533] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.451427] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.480537] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.514332] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.548764] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.581587] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.611259] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.639138] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.671659] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.719436] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.750484] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.787827] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.824742] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.873166] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.907664] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.939922] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 631.981866] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 632.024994] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 632.075159] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 632.120749] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 632.154542] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 632.190278] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 632.230350] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 632.267680] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 632.301615] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 632.332655] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 632.370817] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 632.407597] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 632.458227] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 632.510200] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 632.563831] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 632.596651] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 632.644255] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 632.701639] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 632.739674] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 632.777232] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 632.826656] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 632.890844] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 632.959920] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 633.005381] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 633.051664] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 633.096249] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 633.148468] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 633.193151] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 633.235188] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 633.289494] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 633.336205] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 633.386229] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 633.435687] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 633.482307] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 633.559841] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 633.610020] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 633.653311] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 633.687021] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 633.721443] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 633.760584] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 633.804325] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 633.823280] Lustre: lustre-OST0001-osc-ffff9d0a840c8000: disconnect after 20s idle [ 633.825791] Lustre: Skipped 1 previous similar message [ 633.850323] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 633.890260] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 633.927314] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 633.971063] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 634.014368] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 634.073638] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 634.119500] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 634.155234] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 634.198977] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 634.246122] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 634.279785] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 634.313352] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 634.357373] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 634.419926] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 634.473153] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 634.518508] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 634.568056] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 634.618703] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 634.661274] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 634.700065] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 634.752800] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 634.781839] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 634.817993] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 634.849190] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 634.879338] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 634.907889] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 634.952487] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 634.998372] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.033660] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.069536] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.101867] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.129227] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.162257] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.191937] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.233983] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.264296] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.288966] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.319128] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.357627] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.390305] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.422543] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.447898] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.488496] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.525737] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.553381] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.590401] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.616530] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.649649] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.677912] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.706738] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.747799] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.791094] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.827409] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.869884] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.922666] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.959487] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 635.994614] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 636.023925] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 636.063336] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 636.095659] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 636.128449] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 636.155317] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 636.186782] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 636.236261] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 636.279061] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 636.319995] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 636.356723] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 636.402345] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 636.454367] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 636.497420] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 636.539536] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 636.575249] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 636.600302] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 636.628909] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 636.660249] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 636.695536] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 636.733316] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 636.767043] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 636.797314] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 636.835924] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 636.879397] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 636.914339] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 636.947257] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 636.984757] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 637.012223] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 637.051804] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 637.097187] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 637.133271] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 637.175895] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 637.219220] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 637.258525] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 637.292949] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 637.325694] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 637.357450] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 637.392928] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 637.440101] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 637.502209] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 637.554312] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 637.601055] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 637.662606] rw_seq_cst_vs_d (30946): drop_caches: 3 [ 638.944267] Lustre: lustre-OST0001-osc-ffff9d0a8aa8f800: disconnect after 20s idle [ 641.353578] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 14:30:52 (1773685852) [ 641.631620] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 641.674608] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 641.701732] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 641.727335] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 641.749766] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 641.781525] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 641.798494] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 641.827212] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 642.011816] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 642.029859] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 642.117170] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 642.542057] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 642.648450] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 642.673230] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 642.707572] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 642.884559] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 642.959434] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 642.983514] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 643.008336] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 643.033484] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 643.061028] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 643.090163] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 643.137067] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 643.252733] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 643.307166] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 643.332227] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 643.473875] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 643.498471] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 643.519368] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 643.540431] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 643.588085] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 643.675774] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 643.724843] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 643.756095] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 643.812615] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 643.841675] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 643.860170] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 643.939750] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 643.959800] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 644.002873] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 644.146093] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 644.164349] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 644.575455] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 644.593568] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 644.623344] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 644.651615] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 644.673651] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 644.701083] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 644.730395] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 644.760580] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 644.812571] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 644.833372] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 644.926922] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 644.950989] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 644.980597] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 645.002677] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 645.092568] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 645.132833] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 645.150700] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 645.179442] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 645.217664] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 645.255485] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 645.467234] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 645.498588] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 645.515551] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 645.548344] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 645.564714] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 645.589324] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 645.620468] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 645.634969] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 645.655732] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 645.675652] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 645.698655] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 645.722046] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 645.749900] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 645.791225] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 645.822235] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 645.856083] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 645.874848] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 645.970482] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 646.013755] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 646.133839] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 646.154944] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 646.177707] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 646.219084] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 646.255878] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 646.547682] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 646.573031] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 646.597167] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 646.650890] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 646.700703] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 646.731181] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 646.750695] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 646.772704] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 646.944298] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 647.106741] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 647.140864] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 647.166909] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 647.187717] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 647.210623] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 647.281739] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 647.305527] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 647.328607] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 647.350340] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 647.438598] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 647.474651] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 647.489852] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 647.670032] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 647.934233] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 647.963516] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 648.050919] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 648.187342] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 648.228175] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 648.324623] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 648.345689] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 648.386099] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 648.469926] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 648.621829] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 648.716502] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 648.776709] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 648.807967] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 648.835882] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 648.868441] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 648.885905] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 648.960038] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 649.028975] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 649.144176] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 649.207212] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 649.302826] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 649.329172] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 649.355765] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 649.377296] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 649.506152] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 649.534040] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 649.569312] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 649.591417] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 649.610442] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 649.640925] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 649.688298] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 649.709762] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 649.735872] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 649.761660] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 649.783608] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 649.914090] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 650.003625] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 650.035670] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 650.230289] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 650.306962] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 650.349719] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 650.403346] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 650.433181] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 650.540518] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 650.564205] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 650.585593] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 650.631785] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 650.727360] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 650.755796] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 650.799530] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 650.887127] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 650.917444] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 651.047992] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 651.181151] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 651.211797] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 651.247290] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 651.449954] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 651.560961] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 651.585341] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 651.604308] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 651.632052] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 651.729135] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 651.798286] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 651.821550] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 651.942815] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 652.097469] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 652.116929] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 652.154871] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 652.225431] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 652.248442] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 652.269545] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 652.417426] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 652.593440] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 652.800388] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 652.863071] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 652.889833] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 652.910073] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 652.931019] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 652.977388] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 652.997615] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 653.065608] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 653.159491] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 653.343885] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 653.396440] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 653.434911] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 653.520991] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 653.627662] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 653.655078] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 653.748971] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 653.769793] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 653.921658] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 653.968801] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 654.120618] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 654.411567] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 654.446290] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 654.580242] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 654.661110] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 654.681914] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 654.712623] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 654.751612] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 654.813737] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 654.863148] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 654.968735] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 655.171692] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 655.204936] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 655.238113] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 655.278595] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 655.315870] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 655.339179] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 655.384329] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 655.403657] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 655.423652] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 655.450865] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 655.521832] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 655.633598] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 655.651389] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 655.732323] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 655.866080] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 655.889106] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 655.913107] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 656.035174] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 656.088407] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 656.124537] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 656.245156] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 656.272776] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 656.304633] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 656.375488] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 656.413032] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 656.448498] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 656.475762] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 656.554405] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 656.582250] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 656.788373] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 656.822267] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 656.852049] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 656.932785] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 656.982517] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 657.195565] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 657.224876] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 657.256536] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 657.390213] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 657.999514] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 658.035131] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 658.075814] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 658.106217] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 658.138174] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 658.302028] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 658.485164] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 658.660446] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 658.755617] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 658.859683] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 658.886823] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 658.968667] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 658.990677] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 659.079116] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 659.131318] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 659.159145] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 659.235562] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 659.274848] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 659.423835] Lustre: lustre-OST0000-osc-ffff9d0a8aa8f800: disconnect after 20s idle [ 659.452470] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 659.489783] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 659.525419] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 659.650865] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 659.684537] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 659.708433] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 659.864942] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 659.932798] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 659.951932] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 659.976311] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 660.008053] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 660.027422] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 660.162211] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 660.191730] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 660.242494] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 660.338733] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 660.370788] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 660.670736] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 660.707167] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 660.820363] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 660.840239] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 660.858258] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 660.876643] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 660.894176] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 660.934522] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 660.957487] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 660.980745] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 661.088498] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 661.116485] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 661.255338] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 661.294093] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 661.389153] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 661.407049] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 661.442416] rw_seq_cst_vs_d (31537): drop_caches: 3 [ 665.194206] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 14:31:15 (1773685875) [ 668.775409] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 14:31:19 (1773685879) [ 672.744123] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 14:31:23 (1773685883) [ 691.282536] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 14:31:41 (1773685901) [ 691.447560] bash (34011): drop_caches: 3 [ 694.633053] bash (34011): drop_caches: 3 [ 697.917740] bash (34011): drop_caches: 3 [ 701.001440] bash (34011): drop_caches: 3 [ 704.075698] bash (34011): drop_caches: 3 [ 707.153099] bash (34011): drop_caches: 3 [ 710.239138] bash (34011): drop_caches: 3 [ 714.073315] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 14:32:04 (1773685924) [ 720.366467] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 14:32:11 (1773685931) [ 739.224648] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 14:32:29 (1773685949) [ 740.425572] Lustre: DEBUG MARKER: SKIP: sanityn test_19 not cache-capable obdfilter [ 741.192127] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 14:32:31 (1773685951) [ 744.284186] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 14:32:34 (1773685954) [ 747.941144] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 14:32:38 (1773685958) [ 756.703339] Lustre: lustre-OST0000-osc-ffff9d0a840c8000: disconnect after 25s idle [ 756.707443] Lustre: Skipped 1 previous similar message [ 813.028327] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 14:33:43 (1773686023) [ 816.369205] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 14:33:46 (1773686026) [ 819.255797] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 14:33:49 (1773686029) [ 823.405457] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 14:33:53 (1773686033) [ 827.444456] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 14:33:57 (1773686037) [ 831.701479] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 14:34:02 (1773686042) [ 837.009509] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 14:34:07 (1773686047) [ 841.831237] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 842.846779] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 14:34:13 (1773686053) [ 848.261981] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 14:34:18 (1773686058) [ 848.610495] Lustre: *** cfs_fail_loc=314, val=0*** [ 849.632135] Lustre: *** cfs_fail_loc=314, val=0*** [ 849.637214] Lustre: Skipped 2 previous similar messages [ 852.768210] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 14:34:23 (1773686063) [ 863.236916] Lustre: *** cfs_fail_loc=314, val=0*** [ 863.264642] LustreError: lustre-OST0000-osc-ffff9d0a840c8000: operation ldlm_enqueue to node 192.168.204.145@tcp failed: rc = -107 [ 863.272627] Lustre: lustre-OST0000-osc-ffff9d0a840c8000: Connection to lustre-OST0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 863.287702] LustreError: lustre-OST0000-osc-ffff9d0a840c8000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 863.298702] LustreError: 43680:0:(ldlm_resource.c:1170:ldlm_resource_complain()) lustre-OST0000-osc-ffff9d0a840c8000: namespace resource [0x280000401:0x31:0x0].0x0 (ffff9d0a82b1f600) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 863.309911] Lustre: lustre-OST0000-osc-ffff9d0a840c8000: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 866.611302] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 14:34:37 (1773686077) [ 866.722439] LustreError: 44269:0:(file.c:785:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 869.743344] LustreError: 44269:0:(file.c:785:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 872.648740] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 14:34:43 (1773686083) [ 876.513307] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 14:34:47 (1773686087) [ 880.828616] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 881.716425] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 14:34:52 (1773686092) [ 882.609413] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 883.572591] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 14:34:54 (1773686094) [ 884.716377] Lustre: lustre-MDT0000-mdc-ffff9d0a8aa8f800: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 889.830643] LustreError: MGC192.168.204.145@tcp: Connection to MGS (at 192.168.204.145@tcp) was lost; in progress operations using this service will fail [ 889.840522] Lustre: Evicted from MGS (at 192.168.204.145@tcp) after server handle changed from 0xa31fd4299f16cd20 to 0xa31fd4299f2481a1 [ 889.853830] Lustre: MGC192.168.204.145@tcp: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 894.405161] Lustre: lustre-MDT0000-mdc-ffff9d0a8aa8f800: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 908.833249] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 14:35:19 (1773686119) [ 925.664156] Lustre: lustre-OST0000-osc-ffff9d0a840c8000: disconnect after 20s idle [ 925.669564] Lustre: Skipped 3 previous similar messages [ 933.462040] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 14:35:44 (1773686144) [ 945.349174] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 14:35:55 (1773686155) [ 961.503600] Lustre: lustre-OST0000-osc-ffff9d0a840c8000: disconnect after 22s idle [ 991.163206] Lustre: lustre-OST0001-osc-ffff9d0a8aa8f800: Connection to lustre-OST0001 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 991.168291] Lustre: Skipped 1 previous similar message [ 991.172423] LustreError: lustre-OST0001-osc-ffff9d0a840c8000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 991.177568] LustreError: lustre-OST0001-osc-ffff9d0a8aa8f800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 991.179500] Lustre: lustre-OST0001-osc-ffff9d0a840c8000: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 991.185350] Lustre: Skipped 2 previous similar messages [ 1011.642101] Lustre: lustre-OST0000-osc-ffff9d0a8aa8f800: Connection to lustre-OST0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1011.649695] Lustre: Skipped 1 previous similar message [ 1011.653673] LustreError: lustre-OST0000-osc-ffff9d0a8aa8f800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1011.661072] Lustre: lustre-OST0000-osc-ffff9d0a8aa8f800: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 1023.076740] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9d0a840c8000.ost_server_uuid 50 [ 1023.777820] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9d0a840c8000.ost_server_uuid in FULL state after 0 sec [ 1025.563485] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9d0a840c8000.ost_server_uuid 50 [ 1026.529765] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9d0a840c8000.ost_server_uuid in IDLE state after 0 sec [ 1029.056935] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9d0a840c8000.ost_server_uuid 50 [ 1029.767256] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9d0a840c8000.ost_server_uuid in FULL state after 0 sec [ 1031.741942] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9d0a840c8000.ost_server_uuid 50 [ 1032.497813] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9d0a840c8000.ost_server_uuid in IDLE state after 0 sec [ 1033.183265] Lustre: lustre-OST0000-osc-ffff9d0a8aa8f800: disconnect after 22s idle [ 1033.189187] Lustre: Skipped 2 previous similar messages [ 1037.561250] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9d0a840c8000.ost_server_uuid 50 [ 1038.286451] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9d0a840c8000.ost_server_uuid in FULL state after 0 sec [ 1040.015275] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9d0a840c8000.ost_server_uuid 50 [ 1040.751675] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9d0a840c8000.ost_server_uuid in IDLE state after 0 sec [ 1041.650844] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 14:37:32 (1773686252) [ 1042.764184] Lustre: DEBUG MARKER: Race attempt 0 [ 1044.508858] Lustre: DEBUG MARKER: Wait for 54739 54867 for 60 sec... [ 1107.896144] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 14:38:38 (1773686318) [ 1113.565868] Lustre: DEBUG MARKER: start test - cycle (0) [ 1136.167941] Lustre: DEBUG MARKER: start test - cycle (1) [ 1156.322735] Lustre: DEBUG MARKER: start test - cycle (2) [ 1176.858631] Lustre: DEBUG MARKER: start test - cycle (3) [ 1197.098431] Lustre: DEBUG MARKER: start test - cycle (4) [ 1218.324552] Lustre: DEBUG MARKER: start test - cycle (5) [ 1222.623817] Lustre: lustre-OST0001-osc-ffff9d0a840c8000: disconnect after 21s idle [ 1238.883614] Lustre: DEBUG MARKER: start test - cycle (6) [ 1259.067923] Lustre: DEBUG MARKER: start test - cycle (7) [ 1279.681524] Lustre: DEBUG MARKER: start test - cycle (8) [ 1299.998691] Lustre: DEBUG MARKER: start test - cycle (9) [ 1321.226214] Lustre: DEBUG MARKER: start test - cycle (10) [ 1343.183417] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 14:42:33 (1773686553) [ 1375.116183] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 14:43:05 (1773686585) [ 1377.931328] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 14:43:08 (1773686588) [ 1381.560361] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 14:43:12 (1773686592) [ 1385.229244] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 14:43:15 (1773686595) [ 1385.343459] Lustre: *** cfs_fail_loc=411, val=0*** [ 1388.155538] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 1388.926321] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 14:43:19 (1773686599) [ 1399.325670] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 14:43:29 (1773686609) [ 1408.815234] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 14:43:39 (1773686619) [ 1417.893342] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 14:43:48 (1773686628) [ 1426.559363] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 14:43:57 (1773686637) [ 1432.291175] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 14:44:03 (1773686643) [ 1438.847973] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 14:44:09 (1773686649) [ 1444.931503] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 14:44:15 (1773686655) [ 1450.560730] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 14:44:21 (1773686661) [ 1456.105314] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 14:44:26 (1773686666) [ 1461.742170] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 14:44:32 (1773686672) [ 1467.478598] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 14:44:38 (1773686678) [ 1473.444567] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 14:44:44 (1773686684) [ 1493.983339] Lustre: lustre-OST0000-osc-ffff9d0a8aa8f800: disconnect after 24s idle [ 1493.986771] Lustre: Skipped 4 previous similar messages [ 2093.023247] Lustre: lustre-OST0000-osc-ffff9d0a840c8000: disconnect after 20s idle [ 2093.025833] Lustre: Skipped 1 previous similar message [ 2205.082558] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 14:56:55 (1773687415) [ 2210.575753] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 14:57:01 (1773687421) [ 2216.377246] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 14:57:07 (1773687427) [ 2222.849857] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 14:57:13 (1773687433) [ 2229.320793] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 14:57:20 (1773687440) [ 2235.280885] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 14:57:26 (1773687446) [ 2240.797222] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 14:57:31 (1773687451) [ 2246.264812] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 14:57:37 (1773687457) [ 2251.711196] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 14:57:42 (1773687462) [ 2308.061394] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 14:58:38 (1773687518) [ 2313.975525] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 14:58:44 (1773687524) [ 2320.016112] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 14:58:50 (1773687530) [ 2325.918832] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 14:58:56 (1773687536) [ 2331.553479] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 14:59:02 (1773687542) [ 2336.913136] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 14:59:07 (1773687547) [ 2342.356490] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 14:59:13 (1773687553) [ 2348.185577] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 14:59:18 (1773687558) [ 2354.292435] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 14:59:25 (1773687565) [ 2397.816945] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 15:00:08 (1773687608) [ 2844.751787] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 15:07:35 (1773688055) [ 2850.218406] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 15:07:41 (1773688061) [ 2855.496860] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 15:07:46 (1773688066) [ 2860.769105] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 15:07:51 (1773688071) [ 2866.191268] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 15:07:57 (1773688077) [ 2871.797430] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 15:08:02 (1773688082) [ 2877.614660] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 15:08:08 (1773688088) [ 2883.392697] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 15:08:14 (1773688094) [ 2888.923496] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 15:08:19 (1773688099) [ 2894.512883] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 15:08:25 (1773688105) [ 2912.223204] Lustre: lustre-OST0001-osc-ffff9d0a8aa8f800: disconnect after 21s idle [ 2912.225783] Lustre: Skipped 6 previous similar messages [ 2956.353043] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 15:09:27 (1773688167) [ 2961.660828] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 15:09:32 (1773688172) [ 2966.943148] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 15:09:37 (1773688177) [ 2972.052400] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 15:09:42 (1773688182) [ 2977.221872] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 15:09:48 (1773688188) [ 2982.223465] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 15:09:53 (1773688193) [ 2987.225311] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 15:09:58 (1773688198) [ 2991.825759] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 15:10:02 (1773688202) [ 2996.731872] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 15:10:07 (1773688207) [ 3433.695230] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 15:17:24 (1773688644) [ 3438.703568] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 15:17:29 (1773688649) [ 3443.775258] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 15:17:34 (1773688654) [ 3448.912676] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 15:17:39 (1773688659) [ 3454.152123] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 15:17:44 (1773688664) [ 3459.210706] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 15:17:50 (1773688670) [ 3464.329448] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 15:17:55 (1773688675) [ 3469.350579] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 15:18:00 (1773688680) [ 3474.389412] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 15:18:05 (1773688685) [ 3479.279737] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 15:18:10 (1773688690) [ 3484.193610] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 15:18:15 (1773688695) [ 3490.359484] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 15:18:21 (1773688701) [ 3495.457224] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 15:18:26 (1773688706) [ 3500.527913] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 15:18:31 (1773688711) [ 3505.602766] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 15:18:36 (1773688716) [ 3510.806219] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 15:18:41 (1773688721) [ 3516.784758] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 15:18:47 (1773688727) [ 3516.855663] LustreError: 6310:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 3518.935111] LustreError: 6310:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 3523.915190] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 15:18:54 (1773688734) [ 3528.215311] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 15:18:59 (1773688739) [ 3528.318226] LustreError: 233785:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3532.375123] LustreError: 233785:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 3532.390374] LustreError: 233785:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3536.447120] LustreError: 233785:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 3536.463296] LustreError: 233792:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3540.519100] LustreError: 233792:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 3542.794622] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 15:19:13 (1773688753) [ 3549.397690] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 15:19:20 (1773688760) [ 3552.585680] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 15:19:23 (1773688763) [ 3556.719870] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 15:19:27 (1773688767) [ 3567.583204] Lustre: lustre-OST0001-osc-ffff9d0a8aa8f800: disconnect after 22s idle [ 3567.586497] Lustre: Skipped 1 previous similar message [ 3581.217483] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 15:19:52 (1773688792) [ 3588.621727] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 15:19:59 (1773688799) [ 3596.204814] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 15:20:07 (1773688807) [ 3608.865299] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 15:20:19 (1773688819) [ 3618.470102] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 15:20:29 (1773688829) [ 3631.315421] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 15:20:42 (1773688842) [ 3644.069358] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 15:20:54 (1773688854) [ 3658.183427] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 15:21:09 (1773688869) [ 3685.598477] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 15:21:36 (1773688896) [ 3724.230661] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 15:22:15 (1773688935) [ 3726.450210] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 3729.364848] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 15:22:20 (1773688940) [ 3732.147800] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 15:22:22 (1773688942) [ 3732.795480] Lustre: DEBUG MARKER: SKIP: sanityn test_71a ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3733.342183] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 15:22:24 (1773688944) [ 3733.860989] Lustre: DEBUG MARKER: SKIP: sanityn test_71b ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3734.344532] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 15:22:25 (1773688945) [ 3734.816654] Lustre: DEBUG MARKER: SKIP: sanityn test_71c support only ldiskfs ost [ 3735.319932] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 15:22:26 (1773688946) [ 3735.856329] Lustre: DEBUG MARKER: SKIP: sanityn test_71d ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3736.377708] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 15:22:27 (1773688947) [ 3738.488439] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 15:22:29 (1773688949) [ 3740.648505] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 15:22:31 (1773688951) [ 3743.716474] LustreError: lustre-MDT0000-mdc-ffff9d0a8aa8f800: operation ldlm_enqueue to node 192.168.204.145@tcp failed: rc = -35 [ 3746.664366] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 15:22:37 (1773688957) [ 3746.799457] LustreError: 2410:0:(osc_request.c:3117:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 3748.879089] LustreError: 2410:0:(osc_request.c:3117:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 3753.782275] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 15:22:44 (1773688964) [ 3803.806352] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 15:23:34 (1773689014) [ 3806.660172] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 15:23:37 (1773689017) [ 3810.588238] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 15:23:41 (1773689021) [ 3815.391731] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 15:23:46 (1773689026) [ 3820.207843] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 15:23:51 (1773689031) [ 3827.413854] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 15:23:58 (1773689038) [ 3834.818110] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 15:24:05 (1773689045) [ 3838.282038] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 15:24:09 (1773689049) [ 3842.201662] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 15:24:13 (1773689053) [ 3849.792070] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 15:24:20 (1773689060) [ 3895.448838] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 15:25:06 (1773689106) [ 4028.683137] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 15:27:19 (1773689239) [ 4161.783543] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 15:29:32 (1773689372) [ 4187.103215] Lustre: lustre-OST0001-osc-ffff9d0a8aa8f800: disconnect after 24s idle [ 4187.105723] Lustre: Skipped 11 previous similar messages [ 4515.826221] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 15:35:26 (1773689726) [ 4552.732802] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 15:36:03 (1773689763) [ 4594.003188] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 15:36:44 (1773689804) [ 4625.078687] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 15:37:15 (1773689835) [ 4698.596322] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 15:38:29 (1773689909) [ 4762.876502] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 15:39:33 (1773689973) [ 4821.983224] Lustre: lustre-OST0001-osc-ffff9d0a8aa8f800: disconnect after 24s idle [ 4821.985805] Lustre: Skipped 15 previous similar messages [ 4880.646347] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 15:41:31 (1773690091) [ 4881.708257] LustreError: 277376:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a8aa8f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4881.732093] Lustre: Unmounted lustre-client [ 4882.615959] LustreError: 277389:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a840c8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 4882.618989] LustreError: 277389:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 4882.642091] Lustre: Unmounted lustre-client [ 4930.560580] Lustre: Mounted lustre-client [ 4932.095896] Lustre: Mounted lustre-client [ 4933.168216] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 5018.962895] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 15:43:49 (1773690229) [ 5026.767798] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 15:43:57 (1773690237) [ 5030.122518] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 15:44:00 (1773690240) [ 5080.899441] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 15:44:51 (1773690291) [ 5145.763629] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 15:45:56 (1773690356) [ 5149.718232] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 15:46:00 (1773690360) [ 5187.551500] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 15:46:38 (1773690398) [ 5199.195025] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 15:46:50 (1773690410) [ 5240.066905] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 15:47:30 (1773690450) [ 5243.058395] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 15:47:33 (1773690453) [ 5255.931920] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 15:47:46 (1773690466) [ 5259.559258] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 15:47:50 (1773690470) [ 5260.037653] LustreError: 298040:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x22:0x0]: rc = -5 [ 5260.042195] LustreError: 298040:0:(llite_lib.c:3739:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5260.608142] LustreError: 298070:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x3a8:0x0]: rc = -5 [ 5260.612564] LustreError: 298070:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 5 previous similar messages [ 5260.616026] LustreError: 298070:0:(llite_lib.c:3739:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5260.619493] LustreError: 298070:0:(llite_lib.c:3739:ll_prep_inode()) Skipped 5 previous similar messages [ 5261.656948] LustreError: 298131:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x3bc:0x0]: rc = -5 [ 5261.660303] LustreError: 298131:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 11 previous similar messages [ 5261.662534] LustreError: 298131:0:(llite_lib.c:3739:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5261.664675] LustreError: 298131:0:(llite_lib.c:3739:ll_prep_inode()) Skipped 11 previous similar messages [ 5263.719766] LustreError: 298265:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x3d8:0x0]: rc = -5 [ 5263.723751] LustreError: 298265:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 25 previous similar messages [ 5263.725911] LustreError: 298265:0:(llite_lib.c:3739:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5263.728441] LustreError: 298265:0:(llite_lib.c:3739:ll_prep_inode()) Skipped 25 previous similar messages [ 5267.839698] LustreError: 298525:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0xdb:0x0]: rc = -5 [ 5267.842608] LustreError: 298525:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 71 previous similar messages [ 5267.844632] LustreError: 298525:0:(llite_lib.c:3739:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5267.846920] LustreError: 298525:0:(llite_lib.c:3739:ll_prep_inode()) Skipped 71 previous similar messages [ 5275.877375] LustreError: 299057:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x19b:0x0]: rc = -5 [ 5275.883223] LustreError: 299057:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 155 previous similar messages [ 5275.887599] LustreError: 299057:0:(llite_lib.c:3739:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5275.892101] LustreError: 299057:0:(llite_lib.c:3739:ll_prep_inode()) Skipped 155 previous similar messages [ 5276.694603] LustreError: 299107:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff9d0a8328c800: inode [0x2000013a1:0x4c5:0x0] mdc close failed: rc = -2 [ 5291.898056] LustreError: 300153:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x625:0x0]: rc = -5 [ 5291.904018] LustreError: 300153:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 320 previous similar messages [ 5291.908166] LustreError: 300153:0:(llite_lib.c:3739:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 5291.912197] LustreError: 300153:0:(llite_lib.c:3739:ll_prep_inode()) Skipped 320 previous similar messages [ 5320.993432] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 15:48:51 (1773690531) [ 5323.329371] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 15:48:54 (1773690534) [ 5404.848836] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 15:50:15 (1773690615) [ 5405.347455] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 5405.858961] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 15:50:16 (1773690616) [ 5449.267438] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 15:51:00 (1773690660) [ 5451.628738] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 15:51:02 (1773690662) [ 5474.783105] Lustre: lustre-OST0001-osc-ffff9d0a8328c800: disconnect after 21s idle [ 5474.785868] Lustre: Skipped 11 previous similar messages [ 5573.888517] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 15:53:04 (1773690784) [ 5581.281802] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 15:53:12 (1773690792) [ 5584.004767] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 15:53:14 (1773690794) [ 5766.154526] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 15:56:17 (1773690977) [ 5948.300027] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 15:59:19 (1773691159) [ 5950.412334] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 15:59:21 (1773691161) [ 5959.341528] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 15:59:30 (1773691170) [ 5959.413885] Lustre: DEBUG MARKER: write [ 5959.432581] LustreError: 292055:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 5961.436494] Lustre: DEBUG MARKER: kill 351981 [ 5961.438848] LustreError: 351981:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 5964.535114] LustreError: 292055:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 5967.471087] LustreError: 351981:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 5969.565261] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 15:59:40 (1773691180) [ 5971.735301] LustreError: 352594:0:(rw.c:1960:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 5981.823072] LustreError: 352594:0:(rw.c:1960:ll_readpage()) cfs_fail_timeout id 1422 awake [ 5983.923995] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 15:59:54 (1773691194) [ 5984.014244] LustreError: 353181:0:(rw.c:2211:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 5986.095098] LustreError: 353181:0:(rw.c:2211:ll_readpage()) cfs_fail_timeout interrupted [ 5992.018492] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 16:00:02 (1773691202) [ 5992.495490] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 5993.033480] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 16:00:03 (1773691203) [ 5995.569518] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 16:00:06 (1773691206) [ 5997.884340] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 16:00:08 (1773691208) [ 6000.323803] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 16:00:11 (1773691211) [ 6002.726402] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 16:00:13 (1773691213) [ 6004.951727] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 16:00:15 (1773691215) [ 6007.162034] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 16:00:18 (1773691218) [ 6010.362483] Lustre: DEBUG MARKER: SKIP: sanityn test_102 skipping ALWAYS excluded test 102 [ 6010.904554] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 16:00:21 (1773691221) [ 6011.551833] Lustre: *** cfs_fail_loc=415, val=0*** [ 6018.298792] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 16:00:29 (1773691229) [ 6018.828042] Lustre: DEBUG MARKER: SKIP: sanityn test_104 ldiskfs only test [ 6019.472022] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 16:00:30 (1773691230) [ 6019.584168] LustreError: 292055:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [ 6019.586711] LustreError: 292055:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [ 6024.679084] LustreError: 279071:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 6024.682602] LustreError: 279071:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 2 previous similar messages [ 6034.879102] LustreError: 292055:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 6034.881178] LustreError: 292055:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 5 previous similar messages [ 6037.271124] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 16:00:48 (1773691248) [ 6037.817508] Lustre: DEBUG MARKER: SKIP: sanityn test_106a Test only for ldiskfs and statx() supported [ 6038.363687] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 16:00:49 (1773691249) [ 6040.938716] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 16:00:51 (1773691251) [ 6043.297689] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 16:00:54 (1773691254) [ 6047.698810] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 16:00:58 (1773691258) [ 6056.164254] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 16:01:06 (1773691266) [ 6056.307389] LustreError: 350477:0:(osc_request.c:2968:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 6056.309589] LustreError: 350477:0:(osc_request.c:2968:osc_build_rpc()) Skipped 5 previous similar messages [ 6060.367120] LustreError: 350477:0:(osc_request.c:2968:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 6062.649588] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 16:01:13 (1773691273) [ 6064.174779] LustreError: 363284:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a86090800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6064.177507] LustreError: 363284:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 6064.202090] Lustre: Unmounted lustre-client [ 6064.712075] LustreError: 363304:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a8328c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6064.715018] LustreError: 363304:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 6064.781994] Lustre: Unmounted lustre-client [ 6065.300337] Lustre: DEBUG MARKER: Iteration 1 [ 6065.419876] LustreError: 363467:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6065.420087] LustreError: 363468:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6065.424194] LustreError: 363467:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6065.479692] Lustre: Mounted lustre-client [ 6066.098935] LustreError: 363577:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a83ffe000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6066.104623] LustreError: 363577:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 6066.189802] Lustre: Unmounted lustre-client [ 6067.205880] Key type lgssc unregistered [ 6067.337451] LNet: 363821:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6067.339674] LNetError: 363821:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6067.348704] LNet: Removed LNI 192.168.204.45@tcp [ 6067.634101] Key type .llcrypt unregistered [ 6067.635178] Key type ._llcrypt unregistered [ 6067.923590] Key type ._llcrypt registered [ 6067.924542] Key type .llcrypt registered [ 6068.170492] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6068.175227] alg: No test for adler32 (adler32-zlib) [ 6069.141740] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6069.384440] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6070.991129] Key type lgssc registered [ 6071.492838] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6075.544826] Lustre: DEBUG MARKER: Iteration 2 [ 6075.641208] LustreError: 364654:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6075.641640] LustreError: 364655:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6075.648131] LustreError: 364654:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6076.717771] Lustre: Mounted lustre-client [ 6076.718817] Lustre: Skipped 1 previous similar message [ 6077.305475] LustreError: 364767:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0aa1d65000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6077.308246] LustreError: 364767:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6077.375175] Lustre: Unmounted lustre-client [ 6078.391803] Key type lgssc unregistered [ 6078.517716] LNet: 365011:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6078.520447] LNetError: 365011:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6078.527988] LNet: Removed LNI 192.168.204.45@tcp [ 6078.801138] Key type .llcrypt unregistered [ 6078.802081] Key type ._llcrypt unregistered [ 6079.106922] Key type ._llcrypt registered [ 6079.108032] Key type .llcrypt registered [ 6079.274067] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6079.280260] alg: No test for adler32 (adler32-zlib) [ 6080.141173] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6080.224804] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6081.807188] Key type lgssc registered [ 6082.178804] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6085.546383] Lustre: DEBUG MARKER: Iteration 3 [ 6085.650985] LustreError: 365842:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6085.651054] LustreError: 365843:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6085.655914] LustreError: 365842:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6086.714424] Lustre: Mounted lustre-client [ 6087.157615] LustreError: 365957:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a84c5b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6087.160552] LustreError: 365957:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6087.200074] Lustre: Unmounted lustre-client [ 6088.065572] Key type lgssc unregistered [ 6088.174391] LNet: 366200:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6088.176432] LNetError: 366200:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6088.185705] LNet: Removed LNI 192.168.204.45@tcp [ 6088.445102] Key type .llcrypt unregistered [ 6088.446137] Key type ._llcrypt unregistered [ 6088.660107] Key type ._llcrypt registered [ 6088.661171] Key type .llcrypt registered [ 6088.871598] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6088.877472] alg: No test for adler32 (adler32-zlib) [ 6089.726540] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6089.800357] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6091.383137] Key type lgssc registered [ 6091.746996] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6094.975343] Lustre: DEBUG MARKER: Iteration 4 [ 6095.070809] LustreError: 367032:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6095.071100] LustreError: 367033:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6095.075080] LustreError: 367032:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6096.132850] Lustre: Mounted lustre-client [ 6096.134650] Lustre: Skipped 1 previous similar message [ 6096.607490] LustreError: 367148:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a909a8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6096.610941] LustreError: 367148:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6096.668684] Lustre: Unmounted lustre-client [ 6096.669647] Lustre: Skipped 1 previous similar message [ 6097.669965] Key type lgssc unregistered [ 6097.777440] LNet: 367392:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6097.779622] LNetError: 367392:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6097.787608] LNet: Removed LNI 192.168.204.45@tcp [ 6098.023116] Key type .llcrypt unregistered [ 6098.024700] Key type ._llcrypt unregistered [ 6098.311285] Key type ._llcrypt registered [ 6098.312775] Key type .llcrypt registered [ 6098.525821] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6098.530502] alg: No test for adler32 (adler32-zlib) [ 6099.391065] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6099.474938] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6101.063120] Key type lgssc registered [ 6101.461621] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6104.845451] Lustre: DEBUG MARKER: Iteration 5 [ 6104.967236] LustreError: 368225:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6104.968433] LustreError: 368224:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6104.971882] LustreError: 368225:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6106.036885] Lustre: Mounted lustre-client [ 6106.037960] Lustre: Skipped 1 previous similar message [ 6106.601330] LustreError: 368337:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a8614f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6106.605850] LustreError: 368337:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6106.679646] Lustre: Unmounted lustre-client [ 6107.679531] Key type lgssc unregistered [ 6107.797671] LNet: 368580:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6107.800864] LNetError: 368580:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6107.811580] LNet: Removed LNI 192.168.204.45@tcp [ 6108.043112] Key type .llcrypt unregistered [ 6108.044148] Key type ._llcrypt unregistered [ 6108.289819] Key type ._llcrypt registered [ 6108.290885] Key type .llcrypt registered [ 6108.577668] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6108.582165] alg: No test for adler32 (adler32-zlib) [ 6109.434441] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6109.527257] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6111.119157] Key type lgssc registered [ 6111.496540] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6114.863035] Lustre: DEBUG MARKER: Iteration 6 [ 6114.990813] LustreError: 369412:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6114.990851] LustreError: 369413:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6114.997451] LustreError: 369412:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6116.063660] Lustre: Mounted lustre-client [ 6116.644264] LustreError: 369525:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a89545800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6116.735246] Lustre: Unmounted lustre-client [ 6117.730470] Key type lgssc unregistered [ 6117.859793] LNet: 369769:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6117.862284] LNetError: 369769:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6117.871526] LNet: Removed LNI 192.168.204.45@tcp [ 6118.123103] Key type .llcrypt unregistered [ 6118.124311] Key type ._llcrypt unregistered [ 6118.463643] Key type ._llcrypt registered [ 6118.464740] Key type .llcrypt registered [ 6118.702112] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6118.707882] alg: No test for adler32 (adler32-zlib) [ 6119.576552] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6119.665796] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6121.255119] Key type lgssc registered [ 6121.630520] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6124.911182] Lustre: DEBUG MARKER: Iteration 7 [ 6125.018613] LustreError: 370601:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6125.018669] LustreError: 370600:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6125.023818] LustreError: 370601:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6126.082608] Lustre: Mounted lustre-client [ 6126.537058] LustreError: 370717:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a83ffe000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6126.540746] LustreError: 370717:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6126.577686] Lustre: Unmounted lustre-client [ 6127.539317] Key type lgssc unregistered [ 6127.663865] LNet: 370961:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6127.665985] LNetError: 370961:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6127.673229] LNet: Removed LNI 192.168.204.45@tcp [ 6127.934140] Key type .llcrypt unregistered [ 6127.935820] Key type ._llcrypt unregistered [ 6128.271806] Key type ._llcrypt registered [ 6128.273300] Key type .llcrypt registered [ 6128.504915] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6128.510305] alg: No test for adler32 (adler32-zlib) [ 6129.383284] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6129.481828] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6131.063158] Key type lgssc registered [ 6131.470120] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6134.909299] Lustre: DEBUG MARKER: Iteration 8 [ 6135.021181] LustreError: 371792:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6135.021227] LustreError: 371793:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6135.026411] LustreError: 371792:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6136.090068] Lustre: Mounted lustre-client [ 6136.090960] Lustre: Skipped 1 previous similar message [ 6136.534216] LustreError: 371909:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a828f6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6136.536987] LustreError: 371909:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6136.640093] Lustre: Unmounted lustre-client [ 6137.534224] Key type lgssc unregistered [ 6137.639422] LNet: 372152:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6137.641383] LNetError: 372152:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6137.647686] LNet: Removed LNI 192.168.204.45@tcp [ 6137.873095] Key type .llcrypt unregistered [ 6137.874186] Key type ._llcrypt unregistered [ 6138.148872] Key type ._llcrypt registered [ 6138.156642] Key type .llcrypt registered [ 6138.316400] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6138.321131] alg: No test for adler32 (adler32-zlib) [ 6139.174238] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6139.252405] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6140.831114] Key type lgssc registered [ 6141.201047] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6144.472209] Lustre: DEBUG MARKER: Iteration 9 [ 6144.576291] LustreError: 372983:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6144.576322] LustreError: 372984:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6144.581363] LustreError: 372983:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6145.643692] Lustre: Mounted lustre-client [ 6145.645063] Lustre: Skipped 1 previous similar message [ 6146.104192] LustreError: 373095:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a997d9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6146.110122] LustreError: 373095:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6146.150128] Lustre: Unmounted lustre-client [ 6147.099590] Key type lgssc unregistered [ 6147.210480] LNet: 373339:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6147.212353] LNetError: 373339:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6147.222621] LNet: Removed LNI 192.168.204.45@tcp [ 6147.446086] Key type .llcrypt unregistered [ 6147.447694] Key type ._llcrypt unregistered [ 6147.664833] Key type ._llcrypt registered [ 6147.666403] Key type .llcrypt registered [ 6147.868595] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6147.884593] alg: No test for adler32 (adler32-zlib) [ 6148.734760] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6148.811030] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6150.391186] Key type lgssc registered [ 6150.751417] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6154.071919] Lustre: DEBUG MARKER: Iteration 10 [ 6154.166924] LustreError: 374169:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6154.168553] LustreError: 374171:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6154.172965] LustreError: 374169:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6155.232841] Lustre: Mounted lustre-client [ 6155.234241] Lustre: Skipped 1 previous similar message [ 6155.699320] LustreError: 374287:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0ab191e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6155.705177] LustreError: 374287:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6155.763972] Lustre: Unmounted lustre-client [ 6156.699027] Key type lgssc unregistered [ 6156.800475] LNet: 374532:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6156.802641] LNetError: 374532:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6156.811747] LNet: Removed LNI 192.168.204.45@tcp [ 6157.051110] Key type .llcrypt unregistered [ 6157.052657] Key type ._llcrypt unregistered [ 6157.372203] Key type ._llcrypt registered [ 6157.373289] Key type .llcrypt registered [ 6157.532181] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6157.537358] alg: No test for adler32 (adler32-zlib) [ 6158.403565] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6158.495761] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6160.087228] Key type lgssc registered [ 6160.567622] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6164.166938] Lustre: DEBUG MARKER: Iteration 11 [ 6164.294544] LustreError: 375363:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6164.295087] LustreError: 375364:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6164.300458] LustreError: 375363:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6165.363953] Lustre: Mounted lustre-client [ 6165.365062] Lustre: Skipped 1 previous similar message [ 6165.949428] LustreError: 375478:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a98c29800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6165.953138] LustreError: 375478:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6166.021188] Lustre: Unmounted lustre-client [ 6167.093653] Key type lgssc unregistered [ 6167.214755] LNet: 375723:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6167.217463] LNetError: 375723:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6167.227256] LNet: Removed LNI 192.168.204.45@tcp [ 6167.487128] Key type .llcrypt unregistered [ 6167.488965] Key type ._llcrypt unregistered [ 6167.850187] Key type ._llcrypt registered [ 6167.856194] Key type .llcrypt registered [ 6168.114050] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6168.119347] alg: No test for adler32 (adler32-zlib) [ 6168.989070] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6169.083698] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6170.671163] Key type lgssc registered [ 6171.106314] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6174.563763] Lustre: DEBUG MARKER: Iteration 12 [ 6174.699430] LustreError: 376554:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6174.702381] LustreError: 376555:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6174.704623] LustreError: 376554:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6175.774648] Lustre: Mounted lustre-client [ 6175.776193] Lustre: Skipped 1 previous similar message [ 6176.260377] LustreError: 376668:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0ac16f2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6176.323572] Lustre: Unmounted lustre-client [ 6177.400959] Key type lgssc unregistered [ 6177.507513] LNet: 376911:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6177.509967] LNetError: 376911:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6177.517706] LNet: Removed LNI 192.168.204.45@tcp [ 6177.754110] Key type .llcrypt unregistered [ 6177.755657] Key type ._llcrypt unregistered [ 6178.083177] Key type ._llcrypt registered [ 6178.084073] Key type .llcrypt registered [ 6178.244777] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6178.249555] alg: No test for adler32 (adler32-zlib) [ 6179.103583] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6179.182918] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6180.767160] Key type lgssc registered [ 6181.136970] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6184.478097] Lustre: DEBUG MARKER: Iteration 13 [ 6184.585570] LustreError: 377743:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6184.587140] LustreError: 377742:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6184.591081] LustreError: 377743:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6185.646283] Lustre: Mounted lustre-client [ 6186.112809] LustreError: 377851:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a98da2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6186.169642] Lustre: Unmounted lustre-client [ 6187.057738] Key type lgssc unregistered [ 6187.176571] LNet: 378094:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6187.179255] LNetError: 378094:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6187.186624] LNet: Removed LNI 192.168.204.45@tcp [ 6187.395113] Key type .llcrypt unregistered [ 6187.396048] Key type ._llcrypt unregistered [ 6187.686082] Key type ._llcrypt registered [ 6187.686979] Key type .llcrypt registered [ 6187.877958] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6187.883764] alg: No test for adler32 (adler32-zlib) [ 6188.744995] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6188.836093] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6190.431145] Key type lgssc registered [ 6190.813916] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6194.008082] Lustre: DEBUG MARKER: Iteration 14 [ 6194.118463] LustreError: 378925:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6194.118504] LustreError: 378926:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6194.122585] LustreError: 378925:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6195.169944] Lustre: Mounted lustre-client [ 6195.624278] LustreError: 379036:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0ab191e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6195.721098] Lustre: Unmounted lustre-client [ 6196.678298] Key type lgssc unregistered [ 6196.790517] LNet: 379280:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6196.794653] LNetError: 379280:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6196.800782] LNet: Removed LNI 192.168.204.45@tcp [ 6197.039091] Key type .llcrypt unregistered [ 6197.040108] Key type ._llcrypt unregistered [ 6197.396769] Key type ._llcrypt registered [ 6197.397841] Key type .llcrypt registered [ 6197.524246] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6197.530303] alg: No test for adler32 (adler32-zlib) [ 6198.383389] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6198.467106] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6200.055180] Key type lgssc registered [ 6200.444161] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6203.964294] Lustre: DEBUG MARKER: Iteration 15 [ 6204.071835] LustreError: 380111:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6204.072166] LustreError: 380112:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6204.076139] LustreError: 380111:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6205.141626] Lustre: Mounted lustre-client [ 6205.633694] LustreError: 380227:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a82d4b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6205.638745] LustreError: 380227:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6205.670930] Lustre: Unmounted lustre-client [ 6206.736811] Key type lgssc unregistered [ 6206.850409] LNet: 380471:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6206.852446] LNetError: 380471:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6206.859463] LNet: Removed LNI 192.168.204.45@tcp [ 6207.106103] Key type .llcrypt unregistered [ 6207.108047] Key type ._llcrypt unregistered [ 6207.409402] Key type ._llcrypt registered [ 6207.411602] Key type .llcrypt registered [ 6207.574858] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6207.580390] alg: No test for adler32 (adler32-zlib) [ 6208.450126] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6208.546298] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6210.135103] Key type lgssc registered [ 6210.497308] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6213.842500] Lustre: DEBUG MARKER: Iteration 16 [ 6213.938671] LustreError: 381302:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6213.940666] LustreError: 381303:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6213.942467] LustreError: 381302:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 6214.995043] Lustre: Mounted lustre-client [ 6215.458213] LustreError: 381409:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a909ac800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6215.535496] Lustre: Unmounted lustre-client [ 6216.453784] Key type lgssc unregistered [ 6216.566563] LNet: 381653:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6216.568719] LNetError: 381653:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6216.577669] LNet: Removed LNI 192.168.204.45@tcp [ 6216.798106] Key type .llcrypt unregistered [ 6216.799806] Key type ._llcrypt unregistered [ 6217.120325] Key type ._llcrypt registered [ 6217.122699] Key type .llcrypt registered [ 6217.294321] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6217.299427] alg: No test for adler32 (adler32-zlib) [ 6218.170554] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6218.256541] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6219.839135] Key type lgssc registered [ 6220.224826] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6223.679398] Lustre: DEBUG MARKER: Iteration 17 [ 6223.782233] LustreError: 382486:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6223.782278] LustreError: 382487:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6223.789193] LustreError: 382486:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6224.840802] Lustre: Mounted lustre-client [ 6225.289490] LustreError: 382598:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a98c2a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6225.292948] LustreError: 382598:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6225.344126] Lustre: Unmounted lustre-client [ 6226.253481] Key type lgssc unregistered [ 6226.369475] LNet: 382842:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6226.371512] LNetError: 382842:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6226.381676] LNet: Removed LNI 192.168.204.45@tcp [ 6226.603120] Key type .llcrypt unregistered [ 6226.604158] Key type ._llcrypt unregistered [ 6226.900356] Key type ._llcrypt registered [ 6226.914631] Key type .llcrypt registered [ 6227.078684] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6227.083256] alg: No test for adler32 (adler32-zlib) [ 6227.947483] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6228.030803] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6229.615139] Key type lgssc registered [ 6229.987296] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6233.438418] Lustre: DEBUG MARKER: Iteration 18 [ 6233.551656] LustreError: 383677:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6233.554170] LustreError: 383676:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6233.557756] LustreError: 383677:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6234.601278] Lustre: Mounted lustre-client [ 6235.055138] LustreError: 383786:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a85063800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6235.060535] LustreError: 383786:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6235.123224] Lustre: Unmounted lustre-client [ 6236.143750] Key type lgssc unregistered [ 6236.280402] LNet: 384030:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6236.284413] LNetError: 384030:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6236.298728] LNet: Removed LNI 192.168.204.45@tcp [ 6236.561106] Key type .llcrypt unregistered [ 6236.562677] Key type ._llcrypt unregistered [ 6237.054544] Key type ._llcrypt registered [ 6237.055633] Key type .llcrypt registered [ 6237.141912] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6237.147318] alg: No test for adler32 (adler32-zlib) [ 6238.028348] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6238.131377] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6239.727240] Key type lgssc registered [ 6240.133657] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6244.020109] Lustre: DEBUG MARKER: Iteration 19 [ 6244.139686] LustreError: 384862:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6244.139772] LustreError: 384863:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6244.143495] LustreError: 384862:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6245.214642] Lustre: Mounted lustre-client [ 6245.739655] LustreError: 384979:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a95620800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6245.742955] LustreError: 384979:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6245.839761] Lustre: Unmounted lustre-client [ 6245.840818] Lustre: Skipped 1 previous similar message [ 6246.833402] Key type lgssc unregistered [ 6246.947894] LNet: 385222:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6246.950288] LNetError: 385222:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6246.960504] LNet: Removed LNI 192.168.204.45@tcp [ 6247.210146] Key type .llcrypt unregistered [ 6247.211712] Key type ._llcrypt unregistered [ 6247.503230] Key type ._llcrypt registered [ 6247.504374] Key type .llcrypt registered [ 6247.722941] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6247.727729] alg: No test for adler32 (adler32-zlib) [ 6248.586861] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6248.676793] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6250.271151] Key type lgssc registered [ 6250.694848] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6254.599106] Lustre: DEBUG MARKER: Iteration 20 [ 6254.723257] LustreError: 386055:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6254.723307] LustreError: 386056:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6254.729960] LustreError: 386055:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6255.790434] Lustre: Mounted lustre-client [ 6256.336066] LustreError: 386167:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a82e85800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6256.392110] Lustre: Unmounted lustre-client [ 6257.379242] Key type lgssc unregistered [ 6257.503673] LNet: 386411:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6257.506039] LNetError: 386411:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6257.516226] LNet: Removed LNI 192.168.204.45@tcp [ 6257.774104] Key type .llcrypt unregistered [ 6257.775199] Key type ._llcrypt unregistered [ 6258.101760] Key type ._llcrypt registered [ 6258.103286] Key type .llcrypt registered [ 6258.347323] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6258.352795] alg: No test for adler32 (adler32-zlib) [ 6259.205211] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6259.284312] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6260.863122] Key type lgssc registered [ 6261.221933] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6264.839259] Lustre: DEBUG MARKER: Iteration 21 [ 6264.958564] LustreError: 387243:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6264.964805] LustreError: 387245:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6264.966948] LustreError: 387243:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6266.030034] Lustre: Mounted lustre-client [ 6266.476557] LustreError: 387358:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a8aa8d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6266.481063] LustreError: 387358:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6266.575269] Lustre: Unmounted lustre-client [ 6267.567770] Key type lgssc unregistered [ 6267.694466] LNet: 387601:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6267.696669] LNetError: 387601:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6267.705603] LNet: Removed LNI 192.168.204.45@tcp [ 6267.965158] Key type .llcrypt unregistered [ 6267.967055] Key type ._llcrypt unregistered [ 6268.302995] Key type ._llcrypt registered [ 6268.304715] Key type .llcrypt registered [ 6268.583503] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6268.588410] alg: No test for adler32 (adler32-zlib) [ 6269.455149] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6269.553652] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6271.143137] Key type lgssc registered [ 6271.519638] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6275.003186] Lustre: DEBUG MARKER: Iteration 22 [ 6275.121990] LustreError: 388433:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6275.122212] LustreError: 388434:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6275.127561] LustreError: 388433:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6276.196940] Lustre: Mounted lustre-client [ 6276.198061] Lustre: Skipped 1 previous similar message [ 6276.728350] LustreError: 388546:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a909ac000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6276.732110] LustreError: 388546:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6276.817190] Lustre: Unmounted lustre-client [ 6277.797477] Key type lgssc unregistered [ 6277.912579] LNet: 388790:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6277.914914] LNetError: 388790:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6277.923469] LNet: Removed LNI 192.168.204.45@tcp [ 6278.149127] Key type .llcrypt unregistered [ 6278.150227] Key type ._llcrypt unregistered [ 6278.601092] Key type ._llcrypt registered [ 6278.602426] Key type .llcrypt registered [ 6278.676952] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6278.682209] alg: No test for adler32 (adler32-zlib) [ 6279.543929] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6279.635101] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6281.223116] Key type lgssc registered [ 6281.649759] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6285.588488] Lustre: DEBUG MARKER: Iteration 23 [ 6285.704372] LustreError: 389622:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6285.709332] LustreError: 389623:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6285.712464] LustreError: 389622:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 6286.781063] Lustre: Mounted lustre-client [ 6286.782683] Lustre: Skipped 1 previous similar message [ 6287.241446] LustreError: 389735:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a860d5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6287.245419] LustreError: 389735:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6287.277105] Lustre: Unmounted lustre-client [ 6288.216067] Key type lgssc unregistered [ 6288.334467] LNet: 389979:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6288.336849] LNetError: 389979:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6288.346752] LNet: Removed LNI 192.168.204.45@tcp [ 6288.576103] Key type .llcrypt unregistered [ 6288.577608] Key type ._llcrypt unregistered [ 6288.879354] Key type ._llcrypt registered [ 6288.880405] Key type .llcrypt registered [ 6289.047062] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6289.053587] alg: No test for adler32 (adler32-zlib) [ 6289.918809] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6290.019285] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6291.599229] Key type lgssc registered [ 6292.004299] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6295.572937] Lustre: DEBUG MARKER: Iteration 24 [ 6295.679317] LustreError: 390812:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6295.679364] LustreError: 390813:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6295.684220] LustreError: 390812:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6296.744444] Lustre: Mounted lustre-client [ 6297.284993] LustreError: 390924:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0ab4aa2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6297.353095] Lustre: Unmounted lustre-client [ 6298.377023] Key type lgssc unregistered [ 6298.500487] LNet: 391167:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6298.502641] LNetError: 391167:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6298.512700] LNet: Removed LNI 192.168.204.45@tcp [ 6298.744082] Key type .llcrypt unregistered [ 6298.745106] Key type ._llcrypt unregistered [ 6299.060091] Key type ._llcrypt registered [ 6299.060944] Key type .llcrypt registered [ 6299.234494] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6299.239794] alg: No test for adler32 (adler32-zlib) [ 6300.116679] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6300.205250] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6301.791246] Key type lgssc registered [ 6302.250508] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6305.601431] Lustre: DEBUG MARKER: Iteration 25 [ 6305.725443] LustreError: 391999:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6305.725523] LustreError: 392000:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6305.729789] LustreError: 391999:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6306.791487] Lustre: Mounted lustre-client [ 6307.317483] LustreError: 392110:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a95622800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6307.398126] Lustre: Unmounted lustre-client [ 6308.450982] Key type lgssc unregistered [ 6308.576662] LNet: 392354:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6308.579904] LNetError: 392354:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6308.589829] LNet: Removed LNI 192.168.204.45@tcp [ 6308.869104] Key type .llcrypt unregistered [ 6308.870093] Key type ._llcrypt unregistered [ 6309.189251] Key type ._llcrypt registered [ 6309.190306] Key type .llcrypt registered [ 6309.424670] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6309.429893] alg: No test for adler32 (adler32-zlib) [ 6310.313305] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6310.420099] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6312.015109] Key type lgssc registered [ 6312.446459] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6315.935744] Lustre: DEBUG MARKER: Iteration 26 [ 6316.051818] LustreError: 393186:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6316.056976] LustreError: 393192:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6316.060731] LustreError: 393186:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 6317.138671] Lustre: Mounted lustre-client [ 6317.140078] Lustre: Skipped 1 previous similar message [ 6317.691508] LustreError: 393300:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a98c2d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6317.696117] LustreError: 393300:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6317.763822] Lustre: Unmounted lustre-client [ 6318.776769] Key type lgssc unregistered [ 6318.904842] LNet: 393543:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6318.907282] LNetError: 393543:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6318.916406] LNet: Removed LNI 192.168.204.45@tcp [ 6319.165105] Key type .llcrypt unregistered [ 6319.166170] Key type ._llcrypt unregistered [ 6319.544775] Key type ._llcrypt registered [ 6319.545771] Key type .llcrypt registered [ 6319.764887] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6319.771189] alg: No test for adler32 (adler32-zlib) [ 6320.638048] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6320.728112] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6322.319146] Key type lgssc registered [ 6322.726750] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6326.480118] Lustre: DEBUG MARKER: Iteration 27 [ 6326.590297] LustreError: 394376:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6326.590339] LustreError: 394377:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6326.595749] LustreError: 394376:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6327.652818] Lustre: Mounted lustre-client [ 6328.226363] LustreError: 394490:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a83a7e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6328.230175] LustreError: 394490:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6328.308311] Lustre: Unmounted lustre-client [ 6329.306790] Key type lgssc unregistered [ 6329.426571] LNet: 394733:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6329.428719] LNetError: 394733:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6329.436798] LNet: Removed LNI 192.168.204.45@tcp [ 6329.692097] Key type .llcrypt unregistered [ 6329.693147] Key type ._llcrypt unregistered [ 6330.044056] Key type ._llcrypt registered [ 6330.045424] Key type .llcrypt registered [ 6330.196667] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6330.201661] alg: No test for adler32 (adler32-zlib) [ 6331.055510] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6331.145996] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6332.735133] Key type lgssc registered [ 6333.184285] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6336.796157] Lustre: DEBUG MARKER: Iteration 28 [ 6336.929373] LustreError: 395565:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6336.929457] LustreError: 395566:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6336.933372] LustreError: 395565:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6337.999846] Lustre: Mounted lustre-client [ 6338.001830] Lustre: Skipped 1 previous similar message [ 6338.550644] LustreError: 395678:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a8610a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6338.555471] LustreError: 395678:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6338.596096] Lustre: Unmounted lustre-client [ 6339.621381] Key type lgssc unregistered [ 6339.741848] LNet: 395923:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6339.744261] LNetError: 395923:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6339.753451] LNet: Removed LNI 192.168.204.45@tcp [ 6340.010152] Key type .llcrypt unregistered [ 6340.011325] Key type ._llcrypt unregistered [ 6340.342225] Key type ._llcrypt registered [ 6340.343163] Key type .llcrypt registered [ 6340.629967] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6340.635247] alg: No test for adler32 (adler32-zlib) [ 6341.505826] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6341.595580] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6343.191146] Key type lgssc registered [ 6343.577226] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6347.339308] Lustre: DEBUG MARKER: Iteration 29 [ 6347.460658] LustreError: 396755:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6347.460694] LustreError: 396756:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6347.465174] LustreError: 396755:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6348.526257] Lustre: Mounted lustre-client [ 6348.527219] Lustre: Skipped 1 previous similar message [ 6348.991238] LustreError: 396863:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a909ab800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6349.083120] Lustre: Unmounted lustre-client [ 6350.013682] Key type lgssc unregistered [ 6350.123423] LNet: 397107:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6350.125647] LNetError: 397107:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6350.132919] LNet: Removed LNI 192.168.204.45@tcp [ 6350.386123] Key type .llcrypt unregistered [ 6350.388087] Key type ._llcrypt unregistered [ 6350.671949] Key type ._llcrypt registered [ 6350.673710] Key type .llcrypt registered [ 6350.938866] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6350.943208] alg: No test for adler32 (adler32-zlib) [ 6351.792885] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6351.886306] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6353.471113] Key type lgssc registered [ 6353.852599] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6357.594459] Lustre: DEBUG MARKER: Iteration 30 [ 6357.720385] LustreError: 397941:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6357.723588] LustreError: 397939:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6357.726676] LustreError: 397941:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6358.792314] Lustre: Mounted lustre-client [ 6358.793329] Lustre: Skipped 1 previous similar message [ 6359.348118] LustreError: 398052:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a838b5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6359.351470] LustreError: 398052:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6359.401284] Lustre: Unmounted lustre-client [ 6360.267565] Key type lgssc unregistered [ 6360.368499] LNet: 398295:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6360.370583] LNetError: 398295:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6360.380684] LNet: Removed LNI 192.168.204.45@tcp [ 6360.594083] Key type .llcrypt unregistered [ 6360.594979] Key type ._llcrypt unregistered [ 6360.948287] Key type ._llcrypt registered [ 6360.951773] Key type .llcrypt registered [ 6361.056039] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6361.060499] alg: No test for adler32 (adler32-zlib) [ 6361.913194] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6361.993499] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6363.575111] Key type lgssc registered [ 6363.920418] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6367.236549] Lustre: DEBUG MARKER: Iteration 31 [ 6367.340546] LustreError: 399127:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6367.340612] LustreError: 399128:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6367.344242] LustreError: 399127:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6368.398186] Lustre: Mounted lustre-client [ 6368.399851] Lustre: Skipped 1 previous similar message [ 6368.868418] LustreError: 399240:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a8610a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6368.872083] LustreError: 399240:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6368.939293] Lustre: Unmounted lustre-client [ 6368.940766] Lustre: Skipped 1 previous similar message [ 6369.875247] Key type lgssc unregistered [ 6369.987380] LNet: 399484:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6369.990441] LNetError: 399484:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6369.998690] LNet: Removed LNI 192.168.204.45@tcp [ 6370.209088] Key type .llcrypt unregistered [ 6370.210074] Key type ._llcrypt unregistered [ 6370.494711] Key type ._llcrypt registered [ 6370.495732] Key type .llcrypt registered [ 6370.700795] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6370.705831] alg: No test for adler32 (adler32-zlib) [ 6371.573303] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6371.664950] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6373.247113] Key type lgssc registered [ 6373.612484] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6376.811488] Lustre: DEBUG MARKER: Iteration 32 [ 6376.912991] LustreError: 400317:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6376.914140] LustreError: 400318:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6376.916655] LustreError: 400317:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6377.973953] Lustre: Mounted lustre-client [ 6377.976629] Lustre: Skipped 1 previous similar message [ 6378.446493] LustreError: 400430:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a98da6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6378.450796] LustreError: 400430:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6378.525734] Lustre: Unmounted lustre-client [ 6379.542825] Key type lgssc unregistered [ 6379.657764] LNet: 400674:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6379.660427] LNetError: 400674:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6379.667844] LNet: Removed LNI 192.168.204.45@tcp [ 6379.910115] Key type .llcrypt unregistered [ 6379.911678] Key type ._llcrypt unregistered [ 6380.289605] Key type ._llcrypt registered [ 6380.290600] Key type .llcrypt registered [ 6380.472046] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6380.476757] alg: No test for adler32 (adler32-zlib) [ 6381.348618] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6381.450331] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6383.039174] Key type lgssc registered [ 6383.449778] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6386.749937] Lustre: DEBUG MARKER: Iteration 33 [ 6386.861206] LustreError: 401505:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6386.861255] LustreError: 401506:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6386.865386] LustreError: 401505:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6387.922107] Lustre: Mounted lustre-client [ 6387.923842] Lustre: Skipped 1 previous similar message [ 6388.405214] LustreError: 401617:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0ab20ef800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6388.408584] LustreError: 401617:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6388.474109] Lustre: Unmounted lustre-client [ 6389.395135] Key type lgssc unregistered [ 6389.512384] LNet: 401860:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6389.514850] LNetError: 401860:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6389.524764] LNet: Removed LNI 192.168.204.45@tcp [ 6389.770101] Key type .llcrypt unregistered [ 6389.771120] Key type ._llcrypt unregistered [ 6390.061762] Key type ._llcrypt registered [ 6390.062705] Key type .llcrypt registered [ 6390.259770] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6390.264836] alg: No test for adler32 (adler32-zlib) [ 6391.136434] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6391.221469] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6392.799164] Key type lgssc registered [ 6393.142914] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6396.769480] Lustre: DEBUG MARKER: Iteration 34 [ 6396.894730] LustreError: 402692:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6396.894782] LustreError: 402693:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6396.901324] LustreError: 402692:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6397.977367] Lustre: Mounted lustre-client [ 6398.565395] LustreError: 402809:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a99ff0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6398.570117] LustreError: 402809:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6398.663573] Lustre: Unmounted lustre-client [ 6399.718226] Key type lgssc unregistered [ 6399.839524] LNet: 403052:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6399.841632] LNetError: 403052:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6399.848631] LNet: Removed LNI 192.168.204.45@tcp [ 6400.065138] Key type .llcrypt unregistered [ 6400.066059] Key type ._llcrypt unregistered [ 6400.426187] Key type ._llcrypt registered [ 6400.432617] Key type .llcrypt registered [ 6400.536930] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6400.541482] alg: No test for adler32 (adler32-zlib) [ 6401.400775] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6401.489591] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6403.079104] Key type lgssc registered [ 6403.472557] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6406.938257] Lustre: DEBUG MARKER: Iteration 35 [ 6407.047944] LustreError: 403884:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6407.049869] LustreError: 403885:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6407.054036] LustreError: 403884:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6408.107613] Lustre: Mounted lustre-client [ 6408.108563] Lustre: Skipped 1 previous similar message [ 6408.540280] LustreError: 403999:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a914e5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6408.637720] Lustre: Unmounted lustre-client [ 6409.531648] Key type lgssc unregistered [ 6409.660347] LNet: 404242:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6409.663733] LNetError: 404242:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6409.672666] LNet: Removed LNI 192.168.204.45@tcp [ 6409.904094] Key type .llcrypt unregistered [ 6409.905081] Key type ._llcrypt unregistered [ 6410.159895] Key type ._llcrypt registered [ 6410.160811] Key type .llcrypt registered [ 6410.340172] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6410.344547] alg: No test for adler32 (adler32-zlib) [ 6411.193421] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6411.272330] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6412.855167] Key type lgssc registered [ 6413.226942] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6416.531777] Lustre: DEBUG MARKER: Iteration 36 [ 6416.632139] LustreError: 405075:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6416.632187] LustreError: 405076:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6416.637128] LustreError: 405075:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6417.705217] Lustre: Mounted lustre-client [ 6417.706277] Lustre: Skipped 1 previous similar message [ 6418.161351] LustreError: 405188:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0ab5831800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6418.241094] Lustre: Unmounted lustre-client [ 6419.134989] Key type lgssc unregistered [ 6419.248298] LNet: 405432:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6419.250370] LNetError: 405432:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6419.258621] LNet: Removed LNI 192.168.204.45@tcp [ 6419.465105] Key type .llcrypt unregistered [ 6419.466055] Key type ._llcrypt unregistered [ 6419.753927] Key type ._llcrypt registered [ 6419.758806] Key type .llcrypt registered [ 6419.912246] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6419.916853] alg: No test for adler32 (adler32-zlib) [ 6420.764497] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6420.841534] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6422.423143] Key type lgssc registered [ 6422.784466] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6426.071938] Lustre: DEBUG MARKER: Iteration 37 [ 6426.190126] LustreError: 406264:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6426.190142] LustreError: 406265:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6426.194522] LustreError: 406264:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6427.254749] Lustre: Mounted lustre-client [ 6427.712380] LustreError: 406382:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a84c59800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6427.715589] LustreError: 406382:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6427.757082] Lustre: Unmounted lustre-client [ 6428.619386] Key type lgssc unregistered [ 6428.725396] LNet: 406624:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6428.727621] LNetError: 406624:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6428.735673] LNet: Removed LNI 192.168.204.45@tcp [ 6428.946087] Key type .llcrypt unregistered [ 6428.947034] Key type ._llcrypt unregistered [ 6429.284624] Key type ._llcrypt registered [ 6429.285535] Key type .llcrypt registered [ 6429.430872] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6429.435423] alg: No test for adler32 (adler32-zlib) [ 6430.287991] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6430.363096] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6431.943113] Key type lgssc registered [ 6432.335778] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6435.857464] Lustre: DEBUG MARKER: Iteration 38 [ 6435.960723] LustreError: 407456:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6435.960767] LustreError: 407455:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6435.967085] LustreError: 407456:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6437.020059] Lustre: Mounted lustre-client [ 6437.021710] Lustre: Skipped 1 previous similar message [ 6437.478923] LustreError: 407566:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a8aa89800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6437.483138] LustreError: 407566:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6437.581088] Lustre: Unmounted lustre-client [ 6438.521924] Key type lgssc unregistered [ 6438.637491] LNet: 407810:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6438.639594] LNetError: 407810:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6438.649714] LNet: Removed LNI 192.168.204.45@tcp [ 6438.891095] Key type .llcrypt unregistered [ 6438.892065] Key type ._llcrypt unregistered [ 6439.232486] Key type ._llcrypt registered [ 6439.233530] Key type .llcrypt registered [ 6439.376748] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6439.381124] alg: No test for adler32 (adler32-zlib) [ 6440.230289] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6440.306901] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6441.887110] Key type lgssc registered [ 6442.219628] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6445.618224] Lustre: DEBUG MARKER: Iteration 39 [ 6445.740853] LustreError: 408641:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6445.740919] LustreError: 408642:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6445.744821] LustreError: 408641:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6446.799141] Lustre: Mounted lustre-client [ 6446.800689] Lustre: Skipped 1 previous similar message [ 6447.243253] LustreError: 408757:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a90b09800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6447.342153] Lustre: Unmounted lustre-client [ 6447.343150] Lustre: Skipped 1 previous similar message [ 6448.276239] Key type lgssc unregistered [ 6448.391445] LNet: 409002:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6448.393456] LNetError: 409002:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6448.400593] LNet: Removed LNI 192.168.204.45@tcp [ 6448.613125] Key type .llcrypt unregistered [ 6448.614257] Key type ._llcrypt unregistered [ 6448.894506] Key type ._llcrypt registered [ 6448.895487] Key type .llcrypt registered [ 6449.058855] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6449.063924] alg: No test for adler32 (adler32-zlib) [ 6449.928445] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6450.032764] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6451.631168] Key type lgssc registered [ 6451.990757] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6455.419827] Lustre: DEBUG MARKER: Iteration 40 [ 6455.536138] LustreError: 409833:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6455.537127] LustreError: 409834:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6455.542814] LustreError: 409833:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6456.605292] Lustre: Mounted lustre-client [ 6456.606693] Lustre: Skipped 1 previous similar message [ 6457.147144] LustreError: 409947:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a85479800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6457.150681] LustreError: 409947:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6457.197895] Lustre: Unmounted lustre-client [ 6458.238748] Key type lgssc unregistered [ 6458.350419] LNet: 410191:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6458.352437] LNetError: 410191:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6458.360437] LNet: Removed LNI 192.168.204.45@tcp [ 6458.584098] Key type .llcrypt unregistered [ 6458.585776] Key type ._llcrypt unregistered [ 6458.915226] Key type ._llcrypt registered [ 6458.916237] Key type .llcrypt registered [ 6459.054073] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6459.060153] alg: No test for adler32 (adler32-zlib) [ 6459.913658] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6459.994810] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6461.583125] Key type lgssc registered [ 6461.978463] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6465.230396] Lustre: DEBUG MARKER: Iteration 41 [ 6465.326432] LustreError: 411022:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6465.327831] LustreError: 411023:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6465.331285] LustreError: 411022:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6466.389177] Lustre: Mounted lustre-client [ 6466.825915] LustreError: 411134:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a9996e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6466.885114] Lustre: Unmounted lustre-client [ 6467.762353] Key type lgssc unregistered [ 6467.885383] LNet: 411378:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6467.887289] LNetError: 411378:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6467.895730] LNet: Removed LNI 192.168.204.45@tcp [ 6468.132113] Key type .llcrypt unregistered [ 6468.133665] Key type ._llcrypt unregistered [ 6468.414212] Key type ._llcrypt registered [ 6468.415701] Key type .llcrypt registered [ 6468.610620] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6468.615360] alg: No test for adler32 (adler32-zlib) [ 6469.479400] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6469.560845] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6471.143154] Key type lgssc registered [ 6471.540329] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6474.940214] Lustre: DEBUG MARKER: Iteration 42 [ 6475.044549] LustreError: 412209:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6475.044578] LustreError: 412210:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6475.050241] LustreError: 412209:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6476.116347] Lustre: Mounted lustre-client [ 6476.117943] Lustre: Skipped 1 previous similar message [ 6476.566343] LustreError: 412321:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a910d6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6476.572720] LustreError: 412321:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6476.598057] Lustre: Unmounted lustre-client [ 6477.484628] Key type lgssc unregistered [ 6477.588479] LNet: 412565:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6477.590705] LNetError: 412565:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6477.600707] LNet: Removed LNI 192.168.204.45@tcp [ 6477.807098] Key type .llcrypt unregistered [ 6477.808051] Key type ._llcrypt unregistered [ 6478.048290] Key type ._llcrypt registered [ 6478.049315] Key type .llcrypt registered [ 6478.258849] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6478.263838] alg: No test for adler32 (adler32-zlib) [ 6479.117629] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6479.199893] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6480.783166] Key type lgssc registered [ 6481.132757] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6484.469870] Lustre: DEBUG MARKER: Iteration 43 [ 6484.563079] LustreError: 413397:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6484.564110] LustreError: 413398:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6484.566893] LustreError: 413397:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6485.613759] Lustre: Mounted lustre-client [ 6486.093930] LustreError: 413507:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a901a5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6486.097550] LustreError: 413507:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6486.147123] Lustre: Unmounted lustre-client [ 6487.059759] Key type lgssc unregistered [ 6487.165694] LNet: 413752:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6487.167898] LNetError: 413752:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6487.178299] LNet: Removed LNI 192.168.204.45@tcp [ 6487.403097] Key type .llcrypt unregistered [ 6487.403952] Key type ._llcrypt unregistered [ 6487.755115] Key type ._llcrypt registered [ 6487.756706] Key type .llcrypt registered [ 6487.950291] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6487.954800] alg: No test for adler32 (adler32-zlib) [ 6488.811590] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6488.895107] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6490.487111] Key type lgssc registered [ 6490.882694] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6494.084785] Lustre: DEBUG MARKER: Iteration 44 [ 6494.178128] LustreError: 414583:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6494.178228] LustreError: 414584:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6494.182048] LustreError: 414583:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6495.227526] Lustre: Mounted lustre-client [ 6495.230088] Lustre: Skipped 1 previous similar message [ 6495.651378] LustreError: 414692:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a9996d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6495.705294] Lustre: Unmounted lustre-client [ 6496.631077] Key type lgssc unregistered [ 6496.738353] LNet: 414936:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6496.741415] LNetError: 414936:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6496.750661] LNet: Removed LNI 192.168.204.45@tcp [ 6496.967106] Key type .llcrypt unregistered [ 6496.968725] Key type ._llcrypt unregistered [ 6497.232538] Key type ._llcrypt registered [ 6497.233551] Key type .llcrypt registered [ 6497.408418] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6497.413454] alg: No test for adler32 (adler32-zlib) [ 6498.265444] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6498.350764] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6499.927151] Key type lgssc registered [ 6500.290165] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6503.374553] Lustre: DEBUG MARKER: Iteration 45 [ 6503.476946] LustreError: 415767:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6503.476971] LustreError: 415768:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6503.480733] LustreError: 415767:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6504.536887] Lustre: Mounted lustre-client [ 6504.979064] LustreError: 415883:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0aa1ead800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6505.102165] Lustre: Unmounted lustre-client [ 6506.006756] Key type lgssc unregistered [ 6506.110630] LNet: 416126:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6506.114550] LNetError: 416126:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6506.121570] LNet: Removed LNI 192.168.204.45@tcp [ 6506.344093] Key type .llcrypt unregistered [ 6506.345056] Key type ._llcrypt unregistered [ 6506.611759] Key type ._llcrypt registered [ 6506.614352] Key type .llcrypt registered [ 6506.787489] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6506.793284] alg: No test for adler32 (adler32-zlib) [ 6507.651144] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6507.735755] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6509.319129] Key type lgssc registered [ 6509.659490] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6512.787657] Lustre: DEBUG MARKER: Iteration 46 [ 6512.903597] LustreError: 416957:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6512.903627] LustreError: 416958:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6512.907816] LustreError: 416957:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6513.969676] Lustre: Mounted lustre-client [ 6513.970682] Lustre: Skipped 1 previous similar message [ 6514.523034] LustreError: 417070:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a85448800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6514.525751] LustreError: 417070:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6514.623610] Lustre: Unmounted lustre-client [ 6515.607913] Key type lgssc unregistered [ 6515.725416] LNet: 417315:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6515.727376] LNetError: 417315:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6515.734638] LNet: Removed LNI 192.168.204.45@tcp [ 6515.985091] Key type .llcrypt unregistered [ 6515.986073] Key type ._llcrypt unregistered [ 6516.341224] Key type ._llcrypt registered [ 6516.342171] Key type .llcrypt registered [ 6516.534029] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6516.538383] alg: No test for adler32 (adler32-zlib) [ 6517.403767] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6517.517194] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6519.103134] Key type lgssc registered [ 6519.497634] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6522.917351] Lustre: DEBUG MARKER: Iteration 47 [ 6523.039117] LustreError: 418146:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6523.040164] LustreError: 418148:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6523.043422] LustreError: 418146:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6524.105361] Lustre: Mounted lustre-client [ 6524.540978] LustreError: 418264:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a862e8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6524.545314] LustreError: 418264:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6524.590866] Lustre: Unmounted lustre-client [ 6525.466436] Key type lgssc unregistered [ 6525.576513] LNet: 418508:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6525.578951] LNetError: 418508:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6525.587781] LNet: Removed LNI 192.168.204.45@tcp [ 6525.820105] Key type .llcrypt unregistered [ 6525.821105] Key type ._llcrypt unregistered [ 6526.046162] Key type ._llcrypt registered [ 6526.047231] Key type .llcrypt registered [ 6526.226628] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6526.241875] alg: No test for adler32 (adler32-zlib) [ 6527.100189] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6527.185724] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6528.767145] Key type lgssc registered [ 6529.147921] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6532.400297] Lustre: DEBUG MARKER: Iteration 48 [ 6532.498073] LustreError: 419339:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6532.498466] LustreError: 419340:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6532.502522] LustreError: 419339:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6533.553263] Lustre: Mounted lustre-client [ 6533.986891] LustreError: 419455:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0ab20ed000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6533.989651] LustreError: 419455:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6534.067084] Lustre: Unmounted lustre-client [ 6534.914143] Key type lgssc unregistered [ 6535.010399] LNet: 419699:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6535.012842] LNetError: 419699:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6535.020716] LNet: Removed LNI 192.168.204.45@tcp [ 6535.241107] Key type .llcrypt unregistered [ 6535.241963] Key type ._llcrypt unregistered [ 6535.553208] Key type ._llcrypt registered [ 6535.554809] Key type .llcrypt registered [ 6535.702716] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6535.707171] alg: No test for adler32 (adler32-zlib) [ 6536.552036] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6536.637033] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6538.223119] Key type lgssc registered [ 6538.611419] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6541.904613] Lustre: DEBUG MARKER: Iteration 49 [ 6542.007129] LustreError: 420531:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6542.007178] LustreError: 420532:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6542.011702] LustreError: 420531:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6543.077784] Lustre: Mounted lustre-client [ 6543.079225] Lustre: Skipped 1 previous similar message [ 6543.603961] LustreError: 420643:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0a862ee800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6543.609468] LustreError: 420643:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6543.670624] Lustre: Unmounted lustre-client [ 6544.599071] Key type lgssc unregistered [ 6544.704502] LNet: 420888:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6544.707675] LNetError: 420888:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6544.718449] LNet: Removed LNI 192.168.204.45@tcp [ 6544.925086] Key type .llcrypt unregistered [ 6544.926149] Key type ._llcrypt unregistered [ 6545.151228] Key type ._llcrypt registered [ 6545.152610] Key type .llcrypt registered [ 6545.359461] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6545.366413] alg: No test for adler32 (adler32-zlib) [ 6546.211959] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6546.289681] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6547.863157] Key type lgssc registered [ 6548.210561] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6551.329656] Lustre: DEBUG MARKER: Iteration 50 [ 6551.430116] LustreError: 421729:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 6551.430163] LustreError: 421730:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 6551.435295] LustreError: 421729:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6552.486989] Lustre: Mounted lustre-client [ 6552.928963] LustreError: 421841:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0ab20ef800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6552.981483] Lustre: Unmounted lustre-client [ 6553.827254] Key type lgssc unregistered [ 6553.934362] LNet: 422085:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6553.938310] LNetError: 422085:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6553.949737] LNet: Removed LNI 192.168.204.45@tcp [ 6554.166090] Key type .llcrypt unregistered [ 6554.167885] Key type ._llcrypt unregistered [ 6554.404569] Key type ._llcrypt registered [ 6554.406108] Key type .llcrypt registered [ 6554.603385] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6554.610331] alg: No test for adler32 (adler32-zlib) [ 6555.465895] Lustre: Lustre: Build Version: 2.17.50_152_gca7da69 [ 6555.542968] LNet: Added LNI 192.168.204.45@tcp [8/256/0/180] [ 6557.119176] Key type lgssc registered [ 6557.464766] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6561.200402] Lustre: Mounted lustre-client [ 6563.295697] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 16:09:34 (1773691774) [ 6579.167133] Lustre: 423438:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773691774/real 1773691774] req@ffff9d0aba281880 x1859850617235328/t0(0) o36->lustre-MDT0000-mdc-ffff9d0ab5d8c000@192.168.204.145@tcp:12/10 lens 496/440 e 0 to 1 dl 1773691790 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6579.177374] Lustre: lustre-MDT0000-mdc-ffff9d0ab5d8c000: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6579.189642] Lustre: lustre-MDT0000-mdc-ffff9d0ab5d8c000: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 6595.551144] Lustre: 423438:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773691790/real 1773691790] req@ffff9d0aba281880 x1859850617235328/t0(0) o36->lustre-MDT0000-mdc-ffff9d0ab5d8c000@192.168.204.145@tcp:12/10 lens 496/440 e 0 to 1 dl 1773691806 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6595.561292] Lustre: lustre-MDT0000-mdc-ffff9d0ab5d8c000: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6595.573246] Lustre: lustre-MDT0000-mdc-ffff9d0ab5d8c000: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 6610.911188] Lustre: 423438:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773691806/real 1773691806] req@ffff9d0aba281880 x1859850617235328/t0(0) o36->lustre-MDT0000-mdc-ffff9d0ab5d8c000@192.168.204.145@tcp:12/10 lens 496/440 e 0 to 1 dl 1773691822 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6610.922588] Lustre: lustre-MDT0000-mdc-ffff9d0ab5d8c000: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6610.933664] Lustre: lustre-MDT0000-mdc-ffff9d0ab5d8c000: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 6627.295139] Lustre: 423438:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773691822/real 1773691822] req@ffff9d0aba281880 x1859850617235328/t0(0) o36->lustre-MDT0000-mdc-ffff9d0ab5d8c000@192.168.204.145@tcp:12/10 lens 496/440 e 0 to 1 dl 1773691838 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6627.302130] Lustre: lustre-MDT0000-mdc-ffff9d0ab5d8c000: Connection to lustre-MDT0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6627.310211] Lustre: lustre-MDT0000-mdc-ffff9d0ab5d8c000: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 6627.800651] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 16:10:38 (1773691838) [ 6633.351277] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 16:10:44 (1773691844) [ 6636.775282] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 16:10:47 (1773691847) [ 6638.759085] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 16:10:49 (1773691849) [ 6645.641625] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 16:10:56 (1773691856) [ 6646.124275] Lustre: DEBUG MARKER: SKIP: sanityn test_115 ldiskfs only test [ 6646.641968] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 16:10:57 (1773691857) [ 6648.839388] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 16:10:59 (1773691859) [ 6648.896979] LustreError: 427702:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [ 6650.983077] LustreError: 427702:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [ 6653.138995] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 16:11:03 (1773691863) [ 6671.311091] Lustre: 422280:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773691866/real 1773691866] req@ffff9d0a8a9d0000 x1859850617498496/t0(0) o4->lustre-OST0000-osc-ffff9d0ab5d8c000@192.168.204.145@tcp:6/4 lens 4584/448 e 0 to 1 dl 1773691882 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 6671.322440] Lustre: lustre-OST0000-osc-ffff9d0ab5d8c000: Connection to lustre-OST0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6671.333268] Lustre: lustre-OST0000-osc-ffff9d0ab5d8c000: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 6686.687122] Lustre: 422281:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773691882/real 1773691882] req@ffff9d0ab21e4700 x1859850617498112/t0(0) o4->lustre-OST0000-osc-ffff9d0ab5d8c000@192.168.204.145@tcp:6/4 lens 4584/448 e 0 to 1 dl 1773691898 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 6686.687152] Lustre: lustre-OST0000-osc-ffff9d0ab5d8c000: Connection to lustre-OST0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6686.693773] Lustre: 422281:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 8 previous similar messages [ 6686.701457] Lustre: lustre-OST0000-osc-ffff9d0ab5d8c000: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 6703.071116] Lustre: 422279:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773691898/real 1773691898] req@ffff9d0a9eb12a00 x1859850617498880/t0(0) o4->lustre-OST0000-osc-ffff9d0ab5d8c000@192.168.204.145@tcp:6/4 lens 4584/448 e 0 to 1 dl 1773691914 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 6703.071164] Lustre: lustre-OST0000-osc-ffff9d0ab5d8c000: Connection to lustre-OST0000 (at 192.168.204.145@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6703.077927] Lustre: 422279:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 6703.086955] Lustre: lustre-OST0000-osc-ffff9d0ab5d8c000: Connection restored to 192.168.204.145@tcp (at 192.168.204.145@tcp) [ 6718.321026] Lustre: DEBUG MARKER: oleg445-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9d0ab02d6000.ost_server_uuid 50 [ 6718.811681] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9d0ab02d6000.ost_server_uuid in IDLE state after 0 sec [ 6719.370896] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 6719.916374] Lustre: DEBUG MARKER: == sanityn test complete, duration 6495 sec ============== 16:12:10 (1773691930) [ 6720.456455] Lustre: DEBUG MARKER: === sanityn: start cleanup 16:12:11 (1773691931) === [ 6785.096994] LustreError: 429748:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0ab02d6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6785.125207] Lustre: Unmounted lustre-client [ 6786.547352] Lustre: DEBUG MARKER: === sanityn: finish cleanup 16:13:17 (1773691997) === [ 6786.907222] LustreError: 430052:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9d0ab5d8c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6786.910731] LustreError: 430052:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 6786.940154] Lustre: Unmounted lustre-client [ 6817.825862] Key type lgssc unregistered [ 6817.933590] LNet: 430592:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6817.937261] LNetError: 430592:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6817.944771] LNet: Removed LNI 192.168.204.45@tcp [ 6818.171083] Key type .llcrypt unregistered [ 6818.171960] Key type ._llcrypt unregistered