[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.2-1.fc38 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 457045222 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f5b50-0x000f5b5f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5970 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1BB7 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1A53 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001A13 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1AC7 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1B57 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1B8F 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1a53-0xbffe1ac6] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a52] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1ac7-0xbffe1b56] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1b57-0xbffe1b8e] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1b8f-0xbffe1bb6] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2528MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.002000] x2apic enabled [ 0.002007] Switched APIC routing to physical x2apic. [ 0.003012] kvm-guest: setup PV IPIs [ 0.005346] ..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.006021] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.007013] pid_max: default: 32768 minimum: 301 [ 0.008142] LSM: Security Framework initializing [ 0.009052] Yama: becoming mindful. [ 0.011031] SELinux: Initializing. [ 0.012067] *** VALIDATE selinux *** [ 0.021819] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026678] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.027136] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.028160] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029100] *** VALIDATE tmpfs *** [ 0.031026] *** VALIDATE proc *** [ 0.033018] *** VALIDATE cgroup *** [ 0.034009] *** VALIDATE cgroup2 *** [ 0.036245] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.037151] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.038012] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.039032] Spectre V2 : User space: Vulnerable [ 0.040006] Speculative Store Bypass: Vulnerable [ 0.043433] debug: unmapping init [mem 0xffffffffb9a59000-0xffffffffb9a60fff] [ 0.045904] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.046668] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.047019] ... version: 2 [ 0.048010] ... bit width: 48 [ 0.049007] ... generic registers: 4 [ 0.050011] ... value mask: 0000ffffffffffff [ 0.051014] ... max period: 00007fffffffffff [ 0.052014] ... fixed-purpose events: 3 [ 0.053010] ... event mask: 000000070000000f [ 0.054436] rcu: Hierarchical SRCU implementation. [ 0.056542] smp: Bringing up secondary CPUs ... [ 0.057620] x86: Booting SMP configuration: [ 0.058022] .... node #0, CPUs: #1 #2 #3 [ 0.072014] smp: Brought up 1 node, 4 CPUs [ 0.074023] smpboot: Max logical packages: 1 [ 0.075010] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.186798] node 0 deferred pages initialised in 108ms [ 0.190112] devtmpfs: initialized [ 0.191362] x86/mm: Memory block size: 128MB [ 0.195370] gcov: version magic: 0x41383552 [ 0.198567] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.199139] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.200381] pinctrl core: initialized pinctrl subsystem [ 0.201263] [ 0.201793] ************************************************************* [ 0.202045] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.203029] ** ** [ 0.204030] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.205018] ** ** [ 0.206029] ** This means that this kernel is built to expose internal ** [ 0.207029] ** IOMMU data structures, which may compromise security on ** [ 0.208028] ** your system. ** [ 0.209034] ** ** [ 0.210030] ** If you see this message and you are not debugging the ** [ 0.211015] ** kernel, report this immediately to your vendor! ** [ 0.212010] ** ** [ 0.213015] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.214015] ************************************************************* [ 0.216340] NET: Registered protocol family 16 [ 0.217382] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.218079] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.219038] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.221041] cpuidle: using governor menu [ 0.222729] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.225642] PCI: Using configuration type 1 for base access [ 0.228169] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.238182] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.241060] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.245051] cryptd: max_cpu_qlen set to 1000 [ 0.248035] ACPI: Added _OSI(Module Device) [ 0.249011] ACPI: Added _OSI(Processor Device) [ 0.250008] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.251009] ACPI: Added _OSI(Processor Aggregator Device) [ 0.254000] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.266337] ACPI: Interpreter enabled [ 0.267321] ACPI: PM: (supports S0 S3 S4 S5) [ 0.268033] ACPI: Using IOAPIC for interrupt routing [ 0.269132] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.271000] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.285671] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.286028] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.287017] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.288086] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.290365] acpiphp: Slot [2] registered [ 0.291191] acpiphp: Slot [3] registered [ 0.292105] acpiphp: Slot [4] registered [ 0.293103] acpiphp: Slot [5] registered [ 0.294221] acpiphp: Slot [6] registered [ 0.295142] acpiphp: Slot [7] registered [ 0.296120] acpiphp: Slot [8] registered [ 0.297089] acpiphp: Slot [9] registered [ 0.298122] acpiphp: Slot [10] registered [ 0.298792] acpiphp: Slot [11] registered [ 0.299065] acpiphp: Slot [12] registered [ 0.300092] acpiphp: Slot [13] registered [ 0.301071] acpiphp: Slot [14] registered [ 0.302057] acpiphp: Slot [15] registered [ 0.302898] acpiphp: Slot [16] registered [ 0.303061] acpiphp: Slot [17] registered [ 0.304072] acpiphp: Slot [18] registered [ 0.305087] acpiphp: Slot [19] registered [ 0.306079] acpiphp: Slot [20] registered [ 0.307089] acpiphp: Slot [21] registered [ 0.308155] acpiphp: Slot [22] registered [ 0.309076] acpiphp: Slot [23] registered [ 0.310076] acpiphp: Slot [24] registered [ 0.311059] acpiphp: Slot [25] registered [ 0.312049] acpiphp: Slot [26] registered [ 0.313084] acpiphp: Slot [27] registered [ 0.314054] acpiphp: Slot [28] registered [ 0.315107] acpiphp: Slot [29] registered [ 0.316077] acpiphp: Slot [30] registered [ 0.317141] acpiphp: Slot [31] registered [ 0.318058] PCI host bridge to bus 0000:00 [ 0.319016] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.320067] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.321026] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.322000] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.322000] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.323028] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.325230] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.329554] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.333474] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.338000] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.343059] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.345056] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.346013] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.348013] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.351727] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.355821] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.358036] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.360680] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.364014] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.375000] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.378014] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.384150] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.388033] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.393000] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.410040] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.427780] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.437292] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.443016] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.463017] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.483872] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.489189] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.491586] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.496427] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.506051] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.517042] iommu: Default domain type: Passthrough [ 0.520615] SCSI subsystem initialized [ 0.522135] ACPI: bus type USB registered [ 0.523130] usbcore: registered new interface driver usbfs [ 0.525080] usbcore: registered new interface driver hub [ 0.527094] usbcore: registered new device driver usb [ 0.528202] pps_core: LinuxPPS API ver. 1 registered [ 0.530014] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.533123] PTP clock support registered [ 0.534149] EDAC MC: Ver: 3.0.0 [ 0.535318] PCI: Using ACPI for IRQ routing [ 0.537795] NetLabel: Initializing [ 0.538013] NetLabel: domain hash size = 128 [ 0.540011] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.542100] NetLabel: unlabeled traffic allowed by default [ 0.544153] vgaarb: loaded [ 0.546254] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.547014] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.556202] clocksource: Switched to clocksource kvm-clock [ 0.702546] VFS: Disk quotas dquot_6.6.0 [ 0.703995] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.710246] *** VALIDATE ramfs *** [ 0.711428] *** VALIDATE hugetlbfs *** [ 0.715714] pnp: PnP ACPI init [ 0.730746] pnp: PnP ACPI: found 6 devices [ 0.766230] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.773235] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.778557] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.784066] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.792739] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.795967] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.804573] NET: Registered protocol family 2 [ 0.807105] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.819775] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.824713] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.832534] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.839571] TCP: Hash tables configured (established 65536 bind 65536) [ 0.842675] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.847806] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.851534] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.856559] NET: Registered protocol family 1 [ 0.861204] RPC: Registered named UNIX socket transport module. [ 0.863610] RPC: Registered udp transport module. [ 0.865437] RPC: Registered tcp transport module. [ 0.867082] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.870049] NET: Registered protocol family 44 [ 0.872188] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.874454] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.876430] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.878490] PCI: CLS 0 bytes, default 64 [ 0.880423] Unpacking initramfs... [ 3.568036] debug: unmapping init [mem 0xffffa01dbcc64000-0xffffa01dbffcffff] [ 3.575367] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 3.578024] software IO TLB: mapped [mem 0x00000000b8c64000-0x00000000bcc64000] (64MB) [ 3.582635] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 4.228413] Initialise system trusted keyrings [ 4.230442] Key type blacklist registered [ 4.232737] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 4.247049] zbud: loaded [ 4.251950] *** VALIDATE nfs *** [ 4.253706] *** VALIDATE nfs4 *** [ 4.261443] pstore: using deflate compression [ 4.268414] Platform Keyring initialized [ 4.435988] NET: Registered protocol family 38 [ 4.438970] Key type asymmetric registered [ 4.441240] Asymmetric key parser 'x509' registered [ 4.443420] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 4.447814] io scheduler mq-deadline registered [ 4.449982] io scheduler kyber registered [ 4.452439] io scheduler bfq registered [ 4.456797] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 4.462862] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 4.467611] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 4.479602] ACPI: Power Button [PWRF] [ 4.615651] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 4.857609] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 5.146562] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 5.207580] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 5.258451] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 5.266641] Non-volatile memory driver v1.3 [ 5.268340] Linux agpgart interface v0.103 [ 5.331493] virtio_blk virtio1: [vda] 133056 512-byte logical blocks (68.1 MB/65.0 MiB) [ 5.337289] vda: detected capacity change from 0 to 68124672 [ 5.371284] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 5.373896] vdb: detected capacity change from 0 to 1073741824 [ 5.384621] libphy: Fixed MDIO Bus: probed [ 5.406660] usbcore: registered new interface driver usbserial_generic [ 5.408969] usbserial: USB Serial support registered for generic [ 5.413593] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 5.419748] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 5.421632] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 5.424206] mousedev: PS/2 mouse device common for all mice [ 5.426956] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 5.429303] rtc_cmos 00:05: RTC can wake from S4 [ 5.436932] rtc_cmos 00:05: registered as rtc0 [ 5.439778] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 5.441972] intel_pstate: CPU model not supported [ 5.444046] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 5.451445] hid: raw HID events driver (C) Jiri Kosina [ 5.453169] usbcore: registered new interface driver usbhid [ 5.456932] usbhid: USB HID core driver [ 5.461073] drop_monitor: Initializing network drop monitor service [ 5.465756] Initializing XFRM netlink socket [ 5.466974] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 5.471825] NET: Registered protocol family 10 [ 5.472747] Segment Routing with IPv6 [ 5.472777] NET: Registered protocol family 17 [ 5.473668] mpls_gso: MPLS GSO support [ 5.509319] RAS: Correctable Errors collector initialized. [ 5.511905] AVX version of gcm_enc/dec engaged. [ 5.514774] AES CTR mode by8 optimization enabled [ 5.697647] sched_clock: Marking stable (5697627740, 0)->(6718857551, -1021229811) [ 5.702205] registered taskstats version 1 [ 5.705665] Loading compiled-in X.509 certificates [ 5.707766] zswap: loaded using pool lzo/zbud [ 5.756422] Key type big_key registered [ 5.794777] Key type encrypted registered [ 5.796731] ima: No TPM chip found, activating TPM-bypass! [ 5.800200] ima: Allocated hash algorithm: sha1 [ 5.802376] ima: No architecture policies found [ 5.813119] evm: Initialising EVM extended attributes: [ 5.819744] evm: security.selinux [ 5.821599] evm: security.ima [ 5.822794] evm: security.capability [ 5.824513] evm: HMAC attrs: 0x1 [ 5.827700] rtc_cmos 00:05: setting system clock to 2025-09-02 10:33:51 UTC (1756809231) [ 5.840788] debug: unmapping init [mem 0xffffffffbaa03000-0xffffffffbabfffff] [ 5.851776] debug: unmapping init [mem 0xffffffffb9782000-0xffffffffb9a58fff] [ 5.861154] Write protecting the kernel read-only data: 28672k [ 5.866172] debug: unmapping init [mem 0xffffffffb7e03000-0xffffffffb7ffffff] [ 5.868790] debug: unmapping init [mem 0xffffffffb8714000-0xffffffffb87fffff] [ 5.910906] 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) [ 5.920254] systemd[1]: Detected virtualization kvm. [ 5.923283] systemd[1]: Detected architecture x86-64. [ 5.924554] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 5.964075] systemd[1]: No hostname configured. [ 5.966056] systemd[1]: Set hostname to . [ 5.968197] random: systemd: uninitialized urandom read (16 bytes read) [ 5.969826] systemd[1]: Initializing machine ID from random generator. [ 6.076911] random: ln: uninitialized urandom read (6 bytes read) [ 6.330653] random: systemd: uninitialized urandom read (16 bytes read) [ 6.337056] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 6.361367] systemd[1]: Started Memstrack Anylazing Service. [ OK ] Started Memstrack Anylazing Service. [ 6.397587] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ OK ] Listening on udev Control Socket. Starting Setup Virtual Console... [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Slices. [ OK ] Listening on udev Kernel Socket. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local File Systems. [ OK ] Reached target Local Encrypted Volumes. Starting Create list of required st…ce nodes for the current kernel... Starting Create Volatile Files and Directories... [ OK ] Listening on Journal Socket (/dev/log). Starting Apply Kernel Variables... Starting Journal Service... [ OK ] Reached target Sockets. [ OK ] Reached target Timers. [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. 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.154313] device-mapper: uevent: version 1.0.3 [ 8.157518] 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 [ 9.437141] random: fast init done ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 9.560376] virtio_net virtio0 ens2: renamed from eth0 [ 9.998561] scsi host0: ata_piix [ 10.244385] scsi host1: ata_piix [ 10.245815] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 10.275831] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 14.352369] random: crng init done [ 14.355424] random: 7 urandom warning(s) missed due to ratelimiting [ 16.965386] dracut-initqueue[586]: 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... [ 19.207397] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped target Timers. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 22.323783] printk: systemd: 26 output lines suppressed due to ratelimiting [ 22.813872] SELinux: Disabled at runtime. [ 22.911852] 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) [ 22.923284] systemd[1]: Detected virtualization kvm. [ 22.924667] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 24.149354] systemd[1]: initrd-switch-root.service: Succeeded. [ 24.155071] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 24.165559] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 24.179190] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 24.191729] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 24.216641] systemd[1]: Starting Journal Service... Starting Journal Service... [ 24.294363] systemd[1]: Listening on Process Core Dump Socket. [ OK ] Listening on Process Core Dump Socket. Mounting Kernel Debug File System... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Created slice system-getty.slice. Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on udev Kernel Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Created slice system-sshd\x2dkeygen.slice. Mounting POSIX Message Queue File System... [ 24.618912] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Mounting Huge Pages File System... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Reached target RPC Port Mapper. Starting Apply Kernel Variables... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice User and Session Slice. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Slices. [ OK ] Created slice system-serial\x2dgetty.slice. Starting Remount Root and Kernel File Systems... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. Starting udev Coldplug all Devices... [ OK ] Stopped target Initrd Root File System. [ OK ] Started Journal Service. [ OK ] Mounted Kernel Debug File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Huge Pages File System. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 26.581996] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Coldplug all Devices. [ OK ] Started udev Kernel Device Manager. [ 28.134506] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 28.220690] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 30.245891] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 30.525716] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (8s / no limit) [** ] A start job is running for Configur…-only root support (9s / no limit) [*** ] A start job is running for Configur…only root support (10s / no limit)[ 34.501558] Key type dns_resolver registered [ *** ] A start job is running for Configur…only root support (10s / no limit)[ 35.266786] NFS: Registering the id_resolver key type [ 35.269756] Key type id_resolver registered [ 35.273259] Key type id_legacy registered [ *** ] A start job is running for Configur…only root support (11s / no limit) [ ***] 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 Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started D-Bus System Message Bus. Starting Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started irqbalance daemon. Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Started Login Service. Starting Hostname Service... [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg248-client login: [ 82.798704] libcfs: loading out-of-tree module taints kernel. [ 82.828269] Key type ._llcrypt registered [ 82.829575] Key type .llcrypt registered [ 83.136440] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 83.142423] alg: No test for adler32 (adler32-zlib) [ 84.124785] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 84.417735] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 86.032622] Key type lgssc registered [ 86.830046] Lustre: Echo OBD driver; http://www.lustre.org/ [ 146.572554] Lustre: Mounted lustre-client [ 149.437278] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 162.084527] Lustre: DEBUG MARKER: oleg248-client.virtnet: executing check_logdir /tmp/testlogs/ [ 164.316265] Lustre: DEBUG MARKER: oleg248-client.virtnet: executing yml_node [ 166.415508] Lustre: DEBUG MARKER: Client: 2.16.58.1 [ 167.392354] Lustre: lustre-OST0000-osc-ffffa01e18808800: disconnect after 20s idle [ 167.572084] Lustre: DEBUG MARKER: MDS: 2.16.58.1 [ 168.631835] Lustre: DEBUG MARKER: OSS: 2.16.58.1 [ 169.332255] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Tue Sep 2 06:36:34 EDT 2025 [ 176.861809] Lustre: DEBUG MARKER: excepting tests: 27 40a 102 [ 177.495509] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 178.268364] Lustre: DEBUG MARKER: === sanityn: start setup 06:36:43 (1756809403) === [ 178.530809] Lustre: Mounted lustre-client [ 180.220842] Lustre: DEBUG MARKER: oleg248-client.virtnet: executing check_config_client /mnt/lustre [ 189.732115] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 195.337854] Lustre: DEBUG MARKER: === sanityn: finish setup 06:37:00 (1756809420) === [ 196.742478] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 06:37:01 (1756809421) [ 199.137182] Lustre: lustre-OST0000-osc-ffffa01e03f5b000: disconnect after 20s idle [ 199.140715] Lustre: Skipped 1 previous similar message [ 201.636979] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 06:37:06 (1756809426) [ 205.409179] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 06:37:10 (1756809430) [ 208.958477] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 06:37:13 (1756809433) [ 212.641774] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 06:37:17 (1756809437) [ 216.430495] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 06:37:21 (1756809441) [ 220.409338] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 06:37:25 (1756809445) [ 224.195420] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 06:37:29 (1756809449) [ 224.934954] Lustre: DEBUG MARKER: SKIP: sanityn test_2f needs >= 2 MDTs [ 225.829400] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 06:37:30 (1756809450) [ 229.154215] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 06:37:34 (1756809454) [ 232.623669] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 06:37:37 (1756809457) [ 237.513630] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 06:37:42 (1756809462) [ 241.759455] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 06:37:46 (1756809466) [ 245.994827] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 06:37:50 (1756809470) [ 250.381267] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 06:37:55 (1756809475) [ 254.630917] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 06:37:59 (1756809479) [ 259.433688] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 06:38:04 (1756809484) [ 263.974548] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 06:38:08 (1756809488) [ 269.586361] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 06:38:14 (1756809494) [ 273.594138] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 06:38:18 (1756809498) [ 278.069435] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 06:38:22 (1756809502) [ 278.551417] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205272502304 file: /mnt/lustre/lockdir/lockfile=144115205272502302 [ 422.321254] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 06:40:47 (1756809647) [ 426.815910] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 06:40:51 (1756809651) [ 429.703984] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 06:40:54 (1756809654) [ 432.777417] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 06:40:57 (1756809657) [ 435.767894] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 06:41:00 (1756809660) [ 439.227854] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 06:41:04 (1756809664) [ 440.156887] Lustre: DEBUG MARKER: chmod [ 443.423201] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 06:41:08 (1756809668) [ 468.041415] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7524352KB free > 800000KB, increase MAXFREE (or reduce fs size) [ 477.714767] Lustre: DEBUG MARKER: == sanityn test 16a: 500 iterations of dual-mount fsx ==== 06:41:42 (1756809702) [ 504.480230] Lustre: DEBUG MARKER: == sanityn test 16b: 500 iterations of dual-mount fsx at small size ========================================================== 06:42:09 (1756809729) [ 517.995937] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 06:42:23 (1756809743) [ 519.026741] Lustre: DEBUG MARKER: SKIP: sanityn test_16c dio on ldiskfs only [ 519.772273] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 06:42:24 (1756809744) [ 538.054838] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 06:42:43 (1756809763) [ 541.693531] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 06:42:46 (1756809766) [ 542.208662] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 542.248388] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 542.283091] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 542.315658] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 542.351506] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 542.379982] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 542.415593] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 542.451067] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 542.483301] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 542.511797] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 542.548216] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 542.587870] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 542.626945] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 542.663135] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 542.693710] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 542.732234] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 542.767825] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 542.810221] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 542.853874] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 542.891615] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 542.927085] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 542.959717] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 542.992668] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.021783] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.049749] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.078976] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.110220] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.146537] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.182271] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.218539] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.246742] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.278784] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.312957] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.348187] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.380298] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.410320] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.438453] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.471060] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.498554] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.529492] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.559217] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.596508] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.625313] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.653702] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.688352] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.719284] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.745487] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.772192] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.802982] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.835118] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.862762] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.899566] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.929572] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 543.967288] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.005891] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.038239] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.072261] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.106356] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.139292] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.172747] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.212972] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.243356] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.276486] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.311042] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.339265] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.375426] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.416331] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.452378] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.483475] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.515530] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.546391] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.575766] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.600510] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.634857] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.669168] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.700302] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.737380] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.774900] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.802945] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.829766] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.858340] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.889228] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.918762] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.950216] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 544.985863] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.018918] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.051050] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.080319] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.107980] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.138637] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.178862] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.210264] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.240395] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.272493] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.305400] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.334744] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.364835] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.392538] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.431208] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.459945] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.490495] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.519191] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.551674] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.581697] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.610477] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.638542] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.670515] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.699557] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.728270] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.757335] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.783447] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.811386] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.839568] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.870027] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.901433] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.927440] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.957735] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 545.989618] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.016099] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.043744] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.072027] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.100986] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.131429] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.162722] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.193303] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.223867] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.254204] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.280163] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.307702] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.343064] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.374613] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.407678] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.443555] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.475531] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.511908] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.540766] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.567756] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.598662] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.627796] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.656503] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.682830] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.713630] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.748318] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.779812] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.812245] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.841566] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.874743] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.903746] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.930692] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 546.961762] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.003226] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.037069] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.068173] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.102680] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.136384] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.170804] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.201838] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.229248] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.264581] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.293050] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.327213] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.356029] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.392713] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.425893] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.459260] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.489024] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.528033] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.578905] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.626280] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.659611] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.692653] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.727638] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.764199] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.793672] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.823778] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.865814] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.904322] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.933486] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 547.971230] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.005728] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.048443] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.083664] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.115535] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.157500] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.199942] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.234505] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.266955] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.305957] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.345284] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.374932] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.404316] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.444232] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.497713] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.534571] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.568474] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.605151] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.639135] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.669073] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.701405] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.740701] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.769788] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.797711] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.831923] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.884775] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.912846] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.937475] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 548.974878] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.010944] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.044733] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.082362] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.116579] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.149809] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.180271] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.211788] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.246114] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.283651] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.319645] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.349101] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.378823] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.408726] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.440889] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.472174] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.508336] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.542970] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.574292] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.603231] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.641593] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.670243] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.702689] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.738086] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.770928] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.801753] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.836498] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.874803] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.904245] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.928895] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.952065] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 549.977400] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.011721] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.043321] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.074498] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.111114] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.148380] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.177207] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.209342] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.240072] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.277250] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.314273] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.344376] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.372188] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.402101] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.428287] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.457980] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.486730] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.518965] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.552463] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.582154] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.610861] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.639293] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.670175] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.697195] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.728648] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.766979] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.799806] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.847554] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.884965] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.923395] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 550.968511] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.003895] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.036655] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.064195] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.097116] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.124601] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.152717] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.179576] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.208088] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.237802] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.273510] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.305214] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.340976] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.385963] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.418458] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.446654] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.490521] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.536261] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.576595] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.611184] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.651363] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.682207] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.723583] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.771357] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.811935] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.847959] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.885297] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.933778] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 551.992257] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 552.031572] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 552.073043] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 552.109728] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 552.148712] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 552.182570] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 552.222681] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 552.264485] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 552.301244] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 552.342317] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 552.379427] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 552.421691] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 552.456832] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 552.498533] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 552.545848] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 552.578522] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 552.608086] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 552.653199] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 552.680148] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 552.712089] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 552.749138] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 552.797178] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 552.848790] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 552.880347] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 552.908591] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 552.938077] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 552.969260] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 552.999532] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 553.033059] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 553.064367] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 553.094710] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 553.125789] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 553.155445] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 553.187650] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 553.230267] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 553.267550] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 553.301374] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 553.335893] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 553.373740] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 553.410730] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 553.450249] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 553.481285] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 553.513577] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 553.540219] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 553.569171] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 553.607985] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 553.638689] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 553.675261] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 553.718470] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 553.761853] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 553.801395] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 553.849623] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 553.884776] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 553.926937] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 553.969591] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.004814] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.042565] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.077446] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.114853] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.150784] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.180222] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.217343] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.252554] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.284838] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.317529] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.349516] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.383785] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.423746] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.459511] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.509108] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.551489] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.586905] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.616497] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.650231] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.687147] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.732617] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.773103] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.802740] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.832590] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.871099] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.907455] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.941249] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 554.977483] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.017665] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.053703] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.085158] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.125429] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.171113] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.203753] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.232913] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.260980] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.299721] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.329893] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.357138] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.388414] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.416233] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.450468] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.485286] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.538654] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.577670] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.615871] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.647653] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.683226] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.716471] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.744762] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.779796] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.816891] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.852739] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.883152] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.912360] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.944724] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 555.980765] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 556.009223] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 556.038306] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 556.072212] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 556.105584] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 556.149639] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 556.184548] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 556.216817] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 556.253915] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 556.295978] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 556.331414] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 556.366048] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 556.398788] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 556.447230] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 556.488757] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 556.533695] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 556.566927] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 556.607722] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 556.649818] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 556.692529] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 556.737523] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 556.770917] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 556.808654] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 556.845969] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 556.889734] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 556.934056] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 556.973561] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.008597] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.047666] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.086603] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.123603] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.164597] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.223652] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.260100] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.291278] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.328454] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.363067] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.401589] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.439777] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.483699] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.519639] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.554760] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.583062] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.612243] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.641442] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.670760] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.703061] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.736029] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.763831] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.793569] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.817860] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.848791] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.884596] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.915674] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.948019] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 557.981484] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.018748] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.047595] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.094072] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.131464] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.168950] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.202036] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.234561] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.266093] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.299644] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.328097] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.364642] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.393346] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.424280] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.460405] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.498452] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.532372] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.562558] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.596342] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.637817] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.668504] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.706432] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.739619] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.783951] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.818783] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.847434] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.882808] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.914865] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.951529] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 558.986610] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.026862] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.060176] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.088096] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.117407] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.143620] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.174286] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.206620] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.242336] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.278156] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.308324] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.345352] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.376285] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.411477] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.444581] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.479538] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.517705] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.549055] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.576141] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.604258] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.635364] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.672346] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.702868] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.730180] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.763370] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.792612] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.819827] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.861731] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.896948] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.936363] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.967113] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 559.999842] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.033578] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.070997] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.111545] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.148933] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.178541] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.222278] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.272899] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.314550] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.345186] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.384595] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.416406] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.449423] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.484630] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.517275] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.546747] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.575252] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.609315] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.642536] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.679984] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.715436] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.751509] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.788518] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.816435] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.843262] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.873576] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.899251] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.924633] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.951575] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 560.978438] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 561.007467] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 561.036958] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 561.072975] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 561.102225] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 561.141348] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 561.186412] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 561.217308] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 561.245172] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 561.272635] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 561.299221] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 561.327429] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 561.359482] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 561.387792] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 561.416545] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 561.461076] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 561.493386] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 561.525516] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 561.559395] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 561.592412] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 561.621069] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 561.656312] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 561.688550] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 561.737324] rw_seq_cst_vs_d (29440): drop_caches: 3 [ 562.656188] Lustre: lustre-OST0001-osc-ffffa01e18808800: disconnect after 22s idle [ 562.660617] Lustre: Skipped 1 previous similar message [ 565.972808] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 06:43:10 (1756809790) [ 566.219280] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 566.503289] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 566.909109] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 566.937778] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 566.959368] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 567.110745] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 567.136798] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 567.156619] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 567.177966] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 567.198312] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 567.565155] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 567.599175] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 567.621460] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 567.641702] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 567.660398] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 567.684864] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 567.703411] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 567.733787] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 567.753391] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 567.773111] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 567.889107] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 568.015515] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 568.038935] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 568.059179] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 568.081253] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 568.102091] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 568.372951] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 568.400076] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 568.494070] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 568.735959] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 568.851961] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 569.001346] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 569.027159] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 569.048592] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 569.179101] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 569.447318] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 569.469450] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 569.559379] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 569.645143] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 569.748852] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 569.870132] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 570.223637] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 570.306499] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 570.334797] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 570.354726] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 570.611450] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 570.792269] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 571.086711] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 571.397370] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 571.416781] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 571.437452] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 571.473243] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 571.563138] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 571.583594] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 571.732721] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 571.775902] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 572.038931] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 572.058032] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 572.107206] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 572.127561] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 572.155729] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 572.217614] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 572.270404] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 572.453246] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 572.521647] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 572.557132] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 572.635758] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 572.655370] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 572.675963] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 572.696223] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 572.717650] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 572.835161] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 572.863599] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 572.943187] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 572.965272] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 572.993374] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 573.025763] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 573.059884] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 573.078231] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 573.108277] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 573.211226] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 573.231790] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 573.252349] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 573.271656] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 573.336612] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 573.354697] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 573.375719] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 573.395978] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 573.415922] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 573.435278] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 573.458763] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 573.481661] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 573.790868] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 573.810308] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 573.830863] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 573.850102] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 574.045176] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 574.076119] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 574.151089] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 574.170051] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 574.196122] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 574.219588] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 574.331182] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 574.360663] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 574.381208] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 574.401067] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 574.464090] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 574.485970] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 574.760032] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 574.784488] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 574.805527] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 574.827246] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 575.132170] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 575.382751] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 575.413211] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 575.431245] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 575.450463] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 575.473281] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 575.515555] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 575.535502] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 575.559744] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 575.578108] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 575.773954] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 575.796219] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 575.822918] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 575.928466] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 576.015321] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 576.037548] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 576.070727] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 576.236867] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 576.276636] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 576.455965] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 576.475265] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 576.505433] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 576.603430] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 576.650758] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 576.677270] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 576.698968] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 576.726744] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 576.957650] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 577.064492] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 577.344264] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 577.386747] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 577.414254] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 577.438057] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 577.540803] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 577.570136] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 577.596255] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 577.627870] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 577.675191] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 577.756545] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 578.111619] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 578.141646] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 578.167595] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 578.258733] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 578.360556] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 578.383552] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 578.407537] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 578.429837] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 578.450469] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 578.599020] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 578.735089] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 578.759306] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 578.837860] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 579.138490] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 579.174026] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 579.196995] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 579.216631] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 579.237528] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 579.260910] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 579.283934] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 579.828452] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 579.877325] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 579.895096] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 579.914802] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 579.935651] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 579.973280] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 579.997250] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 580.017796] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 580.181522] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 580.205407] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 580.237346] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 580.260800] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 580.285382] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 580.308395] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 580.335577] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 580.611207] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 580.634188] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 580.660077] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 580.689138] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 580.710385] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 580.731158] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 580.752877] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 580.770535] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 580.790958] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 580.814848] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 580.842722] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 580.862549] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 580.932455] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 580.997956] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 581.079428] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 581.372095] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 581.573809] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 581.993873] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 582.017334] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 582.040605] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 582.215723] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 582.241817] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 582.388282] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 582.602546] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 582.665183] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 582.692885] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 582.722832] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 582.837356] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 582.902185] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 582.921737] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 583.136173] Lustre: lustre-OST0000-osc-ffffa01e03f5b000: disconnect after 21s idle [ 583.139762] Lustre: Skipped 1 previous similar message [ 583.155499] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 583.180087] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 583.199777] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 583.336590] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 583.362658] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 583.382132] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 583.848511] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 583.871391] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 583.906967] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 583.927865] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 583.954720] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 583.977424] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 584.002849] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 584.023953] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 584.088359] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 584.106407] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 584.128796] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 584.147483] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 584.169556] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 584.190192] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 584.245824] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 584.354973] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 584.373854] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 584.395209] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 584.855618] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 584.888390] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 584.906794] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 584.949482] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 584.973131] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 585.048315] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 585.072967] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 585.095443] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 585.147222] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 585.169236] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 585.190416] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 585.212144] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 585.261626] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 585.281177] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 585.303306] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 585.376112] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 585.417243] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 585.454251] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 585.472760] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 585.492363] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 585.513541] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 585.533443] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 585.840985] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 585.860617] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 585.879613] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 585.899924] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 585.927631] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 585.972720] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 585.999973] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 586.017207] rw_seq_cst_vs_d (30017): drop_caches: 3 [ 588.256195] Lustre: lustre-OST0000-osc-ffffa01e18808800: disconnect after 21s idle [ 588.980615] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 06:43:34 (1756809814) [ 592.063494] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 06:43:37 (1756809817) [ 595.346111] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 06:43:40 (1756809820) [ 609.207524] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 06:43:54 (1756809834) [ 609.388653] bash (32463): drop_caches: 3 [ 612.590092] bash (32463): drop_caches: 3 [ 615.726695] bash (32463): drop_caches: 3 [ 618.790531] bash (32463): drop_caches: 3 [ 621.850908] bash (32463): drop_caches: 3 [ 624.907082] bash (32463): drop_caches: 3 [ 627.976873] bash (32463): drop_caches: 3 [ 631.855554] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 06:44:16 (1756809856) [ 637.574745] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 06:44:22 (1756809862) [ 654.557172] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 06:44:39 (1756809879) [ 655.531251] Lustre: DEBUG MARKER: SKIP: sanityn test_19 not cache-capable obdfilter [ 656.256811] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 06:44:41 (1756809881) [ 658.880913] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 06:44:44 (1756809884) [ 661.321494] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 06:44:46 (1756809886) [ 670.176425] Lustre: lustre-OST0001-osc-ffffa01e03f5b000: disconnect after 20s idle [ 725.558274] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 06:45:50 (1756809950) [ 728.265929] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 06:45:53 (1756809953) [ 730.841565] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 06:45:55 (1756809955) [ 733.888897] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 06:45:58 (1756809958) [ 734.499410] Lustre: DEBUG MARKER: SKIP: sanityn test_25b needs >= 2 MDTs [ 735.191429] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 06:46:00 (1756809960) [ 738.789316] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 06:46:03 (1756809963) [ 743.541390] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 06:46:08 (1756809968) [ 747.409344] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 748.127870] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 06:46:13 (1756809973) [ 752.798427] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 06:46:17 (1756809977) [ 752.947854] Lustre: *** cfs_fail_loc=314, val=0*** [ 754.016343] Lustre: *** cfs_fail_loc=314, val=0*** [ 754.020176] Lustre: Skipped 2 previous similar messages [ 756.592032] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 06:46:21 (1756809981) [ 766.382236] Lustre: *** cfs_fail_loc=314, val=0*** [ 766.427589] LustreError: lustre-OST0000-osc-ffffa01e03f5b000: operation ldlm_enqueue to node 192.168.202.148@tcp failed: rc = -107 [ 766.439186] Lustre: lustre-OST0000-osc-ffffa01e03f5b000: Connection to lustre-OST0000 (at 192.168.202.148@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 766.452062] LustreError: lustre-OST0000-osc-ffffa01e03f5b000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 766.459995] Lustre: 2365:0:(llite_lib.c:4239:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.202.148@tcp:/lustre/fid: [0x200000402:0x25:0x0]// may get corrupted (rc -108) [ 766.469256] LustreError: 41684:0:(ldlm_resource.c:981:ldlm_resource_complain()) lustre-OST0000-osc-ffffa01e03f5b000: namespace resource [0x280000400:0x35:0x0].0x0 (ffffa01e0a50cc00) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 766.479316] Lustre: lustre-OST0000-osc-ffffa01e03f5b000: Connection restored to 192.168.202.148@tcp (at 192.168.202.148@tcp) [ 769.470551] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 06:46:34 (1756809994) [ 769.573667] LustreError: 42265:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 772.593057] LustreError: 42265:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 775.172413] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 06:46:40 (1756810000) [ 778.502661] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 06:46:43 (1756810003) [ 782.121225] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 782.780030] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 06:46:47 (1756810007) [ 783.441897] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 784.142182] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 06:46:49 (1756810009) [ 784.722293] Lustre: DEBUG MARKER: SKIP: sanityn test_33c needs >= 2 MDTs [ 785.378272] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 06:46:50 (1756810010) [ 785.976217] Lustre: DEBUG MARKER: SKIP: sanityn test_33d needs >= 2 MDTs [ 786.632353] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 06:46:51 (1756810011) [ 787.214037] Lustre: DEBUG MARKER: SKIP: sanityn test_33e needs >= 2 MDTs [ 787.871964] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 06:46:53 (1756810013) [ 803.296480] Lustre: lustre-OST0000-osc-ffffa01e03f5b000: disconnect after 23s idle [ 803.301723] Lustre: Skipped 3 previous similar messages [ 843.243240] Lustre: lustre-OST0001-osc-ffffa01e03f5b000: Connection to lustre-OST0001 (at 192.168.202.148@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 843.288178] LustreError: lustre-OST0001-osc-ffffa01e03f5b000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 843.313920] LustreError: lustre-OST0001-osc-ffffa01e18808800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 843.324576] Lustre: lustre-OST0001-osc-ffffa01e03f5b000: Connection restored to 192.168.202.148@tcp (at 192.168.202.148@tcp) [ 859.618866] Lustre: lustre-OST0000-osc-ffffa01e18808800: Connection to lustre-OST0000 (at 192.168.202.148@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 859.624792] Lustre: Skipped 1 previous similar message [ 859.638789] LustreError: lustre-OST0000-osc-ffffa01e18808800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 859.653923] Lustre: lustre-OST0000-osc-ffffa01e18808800: Connection restored to 192.168.202.148@tcp (at 192.168.202.148@tcp) [ 859.659900] Lustre: Skipped 1 previous similar message [ 864.740770] Lustre: lustre-OST0001-osc-ffffa01e18808800: disconnect after 22s idle [ 877.643049] Lustre: DEBUG MARKER: oleg248-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffffa01e03f5b000.ost_server_uuid 50 [ 878.778392] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffffa01e03f5b000.ost_server_uuid in FULL state after 0 sec [ 881.405604] Lustre: DEBUG MARKER: oleg248-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffffa01e03f5b000.ost_server_uuid 50 [ 882.458611] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffffa01e03f5b000.ost_server_uuid in IDLE state after 0 sec [ 885.955504] Lustre: DEBUG MARKER: oleg248-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffffa01e03f5b000.ost_server_uuid 50 [ 887.082670] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffffa01e03f5b000.ost_server_uuid in FULL state after 0 sec [ 889.542876] Lustre: DEBUG MARKER: oleg248-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffffa01e03f5b000.ost_server_uuid 50 [ 890.714659] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffffa01e03f5b000.ost_server_uuid in IDLE state after 0 sec [ 896.818274] Lustre: DEBUG MARKER: oleg248-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffffa01e03f5b000.ost_server_uuid 50 [ 897.686514] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffffa01e03f5b000.ost_server_uuid in FULL state after 0 sec [ 899.980931] Lustre: DEBUG MARKER: oleg248-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffffa01e03f5b000.ost_server_uuid 50 [ 900.896209] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffffa01e03f5b000.ost_server_uuid in IDLE state after 0 sec [ 902.038909] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 06:48:46 (1756810126) [ 903.795351] Lustre: DEBUG MARKER: Race attempt 0 [ 905.632659] Lustre: DEBUG MARKER: Wait for 48963 49075 for 60 sec... [ 970.245464] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 06:49:55 (1756810195) [ 971.958011] hrtimer: interrupt took 2999903 ns [ 976.599861] Lustre: DEBUG MARKER: start test - cycle (0) [ 997.750987] Lustre: DEBUG MARKER: start test - cycle (1) [ 1018.526456] Lustre: DEBUG MARKER: start test - cycle (2) [ 1038.993349] Lustre: DEBUG MARKER: start test - cycle (3) [ 1059.703728] Lustre: DEBUG MARKER: start test - cycle (4) [ 1080.415570] Lustre: DEBUG MARKER: start test - cycle (5) [ 1101.075729] Lustre: DEBUG MARKER: start test - cycle (6) [ 1121.304801] Lustre: DEBUG MARKER: start test - cycle (7) [ 1143.109388] Lustre: DEBUG MARKER: start test - cycle (8) [ 1167.661931] Lustre: DEBUG MARKER: start test - cycle (9) [ 1171.940462] Lustre: lustre-OST0001-osc-ffffa01e03f5b000: disconnect after 21s idle [ 1171.948217] Lustre: Skipped 2 previous similar messages [ 1188.421099] Lustre: DEBUG MARKER: start test - cycle (10) [ 1212.955943] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 06:53:57 (1756810437) [ 1273.379635] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 06:54:58 (1756810498) [ 1277.398747] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 06:55:02 (1756810502) [ 1282.417665] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 06:55:07 (1756810507) [ 1287.504847] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 06:55:12 (1756810512) [ 1287.752344] Lustre: *** cfs_fail_loc=411, val=0*** [ 1291.379730] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 1292.386179] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 06:55:17 (1756810517) [ 1304.421211] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 06:55:29 (1756810529) [ 1305.058472] Lustre: lustre-OST0000-osc-ffffa01e18808800: disconnect after 21s idle [ 1305.061189] Lustre: Skipped 4 previous similar messages [ 1315.634455] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 06:55:40 (1756810540) [ 1326.095505] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 06:55:51 (1756810551) [ 1335.878560] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 06:56:00 (1756810560) [ 1343.124290] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 06:56:08 (1756810568) [ 1350.459918] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 06:56:15 (1756810575) [ 1357.584252] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 06:56:22 (1756810582) [ 1364.556972] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 06:56:29 (1756810589) [ 1370.916485] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 06:56:35 (1756810595) [ 1378.174224] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 06:56:43 (1756810603) [ 1386.049523] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 06:56:51 (1756810611) [ 1394.007718] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 06:56:58 (1756810618) [ 2016.736417] Lustre: lustre-OST0000-osc-ffffa01e03f5b000: disconnect after 24s idle [ 2016.739974] Lustre: Skipped 5 previous similar messages [ 2193.654991] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 07:10:18 (1756811418) [ 2200.207328] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 07:10:25 (1756811425) [ 2206.456810] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 07:10:31 (1756811431) [ 2212.840862] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 07:10:37 (1756811437) [ 2218.959411] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 07:10:44 (1756811444) [ 2225.190287] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 07:10:50 (1756811450) [ 2231.359843] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 07:10:56 (1756811456) [ 2237.493911] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 07:11:02 (1756811462) [ 2243.473926] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 07:11:08 (1756811468) [ 2267.103094] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 07:11:32 (1756811492) [ 2273.011833] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 07:11:38 (1756811498) [ 2279.072835] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 07:11:44 (1756811504) [ 2285.077622] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 07:11:50 (1756811510) [ 2290.810083] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 07:11:56 (1756811516) [ 2296.639843] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 07:12:01 (1756811521) [ 2302.622897] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 07:12:07 (1756811527) [ 2308.387742] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 07:12:13 (1756811533) [ 2308.937307] Lustre: DEBUG MARKER: SKIP: sanityn test_43i needs >= 2 MDTs [ 2309.613843] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 07:12:14 (1756811534) [ 2364.262463] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 07:13:09 (1756811589) [ 2846.876259] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 07:21:12 (1756812072) [ 2852.299743] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 07:21:17 (1756812077) [ 2857.739112] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 07:21:22 (1756812082) [ 2863.122096] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 07:21:28 (1756812088) [ 2868.554832] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 07:21:33 (1756812093) [ 2874.075144] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 07:21:39 (1756812099) [ 2879.463083] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 07:21:44 (1756812104) [ 2884.710377] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 07:21:49 (1756812109) [ 2890.339749] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 07:21:55 (1756812115) [ 2890.917959] Lustre: DEBUG MARKER: SKIP: sanityn test_44i needs >= 2 MDTs [ 2891.543657] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 07:21:56 (1756812116) [ 2907.616389] Lustre: lustre-OST0000-osc-ffffa01e18808800: disconnect after 21s idle [ 2907.619165] Lustre: Skipped 5 previous similar messages [ 2927.932224] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 07:22:33 (1756812153) [ 2933.323321] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 07:22:38 (1756812158) [ 2939.004529] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 07:22:44 (1756812164) [ 2944.756987] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 07:22:49 (1756812169) [ 2950.516416] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 07:22:55 (1756812175) [ 2956.202169] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 07:23:01 (1756812181) [ 2961.568882] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 07:23:06 (1756812186) [ 2966.592171] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 07:23:11 (1756812191) [ 2967.123843] Lustre: DEBUG MARKER: SKIP: sanityn test_45i needs >= 2 MDTs [ 2967.708773] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 07:23:12 (1756812192) [ 3436.139332] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 07:31:01 (1756812661) [ 3441.271266] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 07:31:06 (1756812666) [ 3446.306868] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 07:31:11 (1756812671) [ 3451.631530] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 07:31:16 (1756812676) [ 3456.760409] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 07:31:21 (1756812681) [ 3462.046685] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 07:31:27 (1756812687) [ 3467.358754] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 07:31:32 (1756812692) [ 3472.707071] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 07:31:37 (1756812697) [ 3478.033606] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 07:31:43 (1756812703) [ 3478.530474] Lustre: DEBUG MARKER: SKIP: sanityn test_46i needs >= 2 MDTs [ 3479.084882] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 07:31:44 (1756812704) [ 3479.638219] Lustre: DEBUG MARKER: SKIP: sanityn test_47a needs >= 2 MDTs [ 3480.193134] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 07:31:45 (1756812705) [ 3480.686964] Lustre: DEBUG MARKER: SKIP: sanityn test_47b needs >= 2 MDTs [ 3481.219754] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 07:31:46 (1756812706) [ 3481.682170] Lustre: DEBUG MARKER: SKIP: sanityn test_47c needs >= 2 MDTs [ 3482.221431] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 07:31:47 (1756812707) [ 3482.721627] Lustre: DEBUG MARKER: SKIP: sanityn test_47d needs >= 2 MDTs [ 3483.269531] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 07:31:48 (1756812708) [ 3483.773806] Lustre: DEBUG MARKER: SKIP: sanityn test_47e needs >= 2 MDTs [ 3484.292603] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 07:31:49 (1756812709) [ 3484.759048] Lustre: DEBUG MARKER: SKIP: sanityn test_47f needs >= 2 MDTs [ 3485.300704] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 07:31:50 (1756812710) [ 3485.780376] Lustre: DEBUG MARKER: SKIP: sanityn test_47g needs >= 2 MDTs [ 3486.316367] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 07:31:51 (1756812711) [ 3486.388595] LustreError: 21343:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 3488.472122] LustreError: 21343:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 3493.455996] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 07:31:58 (1756812718) [ 3497.592118] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 07:32:02 (1756812722) [ 3497.666878] LustreError: 260006:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3501.728133] LustreError: 260006:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 3501.734486] LustreError: 260006:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3505.792115] LustreError: 260006:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 3505.803805] LustreError: 260013:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 3509.864126] LustreError: 260013:0:(glimpse.c:207:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 3512.004562] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 07:32:17 (1756812737) [ 3518.487991] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 07:32:23 (1756812743) [ 3521.639805] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 07:32:26 (1756812746) [ 3525.728510] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 07:32:30 (1756812750) [ 3532.256188] Lustre: lustre-OST0001-osc-ffffa01e18808800: disconnect after 20s idle [ 3532.258337] Lustre: Skipped 3 previous similar messages [ 3549.512996] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 07:32:54 (1756812774) [ 3556.919262] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 07:33:02 (1756812782) [ 3564.359633] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 07:33:09 (1756812789) [ 3577.077564] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 07:33:22 (1756812802) [ 3586.541748] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 07:33:31 (1756812811) [ 3587.039919] Lustre: DEBUG MARKER: SKIP: sanityn test_55e needs >= 2 MDTs [ 3587.567371] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 07:33:32 (1756812812) [ 3600.561925] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 07:33:45 (1756812825) [ 3614.611198] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 07:33:59 (1756812839) [ 3638.397937] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 07:34:23 (1756812863) [ 3676.102435] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 07:35:01 (1756812901) [ 3678.285879] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 3680.375526] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 07:35:05 (1756812905) [ 3683.377243] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 07:35:08 (1756812908) [ 3683.979909] Lustre: DEBUG MARKER: SKIP: sanityn test_71a ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3684.615174] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 07:35:09 (1756812909) [ 3685.335153] Lustre: DEBUG MARKER: SKIP: sanityn test_71b ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3686.063427] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 07:35:11 (1756812911) [ 3686.667664] Lustre: DEBUG MARKER: SKIP: sanityn test_71c support only ldiskfs ost [ 3687.309565] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 07:35:12 (1756812912) [ 3687.881053] Lustre: DEBUG MARKER: SKIP: sanityn test_71d ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 3688.432434] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 07:35:13 (1756812913) [ 3690.642923] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 07:35:15 (1756812915) [ 3692.876612] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 07:35:18 (1756812918) [ 3695.936494] LustreError: lustre-MDT0000-mdc-ffffa01e03f5b000: operation ldlm_enqueue to node 192.168.202.148@tcp failed: rc = -35 [ 3695.938511] LustreError: Skipped 1 previous similar message [ 3699.049734] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 07:35:24 (1756812924) [ 3699.200346] LustreError: 2366:0:(osc_request.c:3108:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 3701.216335] LustreError: 2366:0:(osc_request.c:3108:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 3706.105408] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 07:35:31 (1756812931) [ 3724.255563] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 07:35:49 (1756812949) [ 3727.129910] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 07:35:52 (1756812952) [ 3730.910372] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 07:35:56 (1756812956) [ 3735.645859] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 07:36:00 (1756812960) [ 3740.303742] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 07:36:05 (1756812965) [ 3747.244318] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 07:36:12 (1756812972) [ 3754.407230] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 07:36:19 (1756812979) [ 3757.735291] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 07:36:22 (1756812982) [ 3761.373945] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 07:36:26 (1756812986) [ 3768.550913] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 07:36:33 (1756812993) [ 3814.055220] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 07:37:19 (1756813039) [ 3947.345847] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 07:39:32 (1756813172) [ 4086.184053] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 07:41:51 (1756813311) [ 4146.656120] Lustre: lustre-OST0001-osc-ffffa01e18808800: disconnect after 23s idle [ 4146.658042] Lustre: Skipped 11 previous similar messages [ 4444.781988] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 07:47:49 (1756813669) [ 4480.950641] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 07:48:26 (1756813706) [ 4517.728660] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 07:49:02 (1756813742) [ 4553.115965] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 07:49:38 (1756813778) [ 4636.017166] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 07:51:01 (1756813861) [ 4696.042343] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 07:52:01 (1756813921) [ 4755.936261] Lustre: lustre-OST0001-osc-ffffa01e18808800: disconnect after 21s idle [ 4755.939699] Lustre: Skipped 16 previous similar messages [ 4814.075522] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 07:53:59 (1756814039) [ 4817.517251] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 07:54:02 (1756814042) [ 4867.954289] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 07:54:53 (1756814093) [ 4936.598156] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 07:56:01 (1756814161) [ 4940.424518] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 07:56:05 (1756814165) [ 4979.775262] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 07:56:44 (1756814204) [ 4992.894904] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 07:56:58 (1756814218) [ 5032.335833] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 07:57:37 (1756814257) [ 5035.106676] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 07:57:40 (1756814260) [ 5047.792893] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 07:57:52 (1756814272) [ 5048.296621] Lustre: DEBUG MARKER: SKIP: sanityn test_80a needs >= 2 MDTs [ 5048.821908] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 07:57:54 (1756814274) [ 5049.272781] Lustre: DEBUG MARKER: SKIP: sanityn test_80b needs >= 2 MDTs [ 5049.753607] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 07:57:55 (1756814275) [ 5050.282839] Lustre: DEBUG MARKER: SKIP: sanityn test_81a needs >= 2 MDTs [ 5050.935088] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 07:57:56 (1756814276) [ 5051.479383] Lustre: DEBUG MARKER: SKIP: sanityn test_81b We need at least 2 MDTs for this test [ 5052.192674] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 07:57:57 (1756814277) [ 5052.728120] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 5053.325740] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 07:57:58 (1756814278) [ 5078.315965] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 07:58:23 (1756814303) [ 5080.757658] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 07:58:25 (1756814305) [ 5081.299187] Lustre: DEBUG MARKER: SKIP: sanityn test_83 needs >= 2 MDTs [ 5081.857436] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 07:58:27 (1756814307) [ 5089.932859] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 07:58:34 (1756814314) [ 5092.680672] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 07:58:37 (1756814317) [ 5093.151691] Lustre: DEBUG MARKER: SKIP: sanityn test_90 needs >= 2 MDTs [ 5093.693356] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 07:58:38 (1756814318) [ 5094.277598] Lustre: DEBUG MARKER: SKIP: sanityn test_91 needs >= 2 MDTs [ 5094.823645] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 07:58:40 (1756814320) [ 5095.298711] Lustre: DEBUG MARKER: SKIP: sanityn test_92 needs >= 2 MDTs [ 5095.981662] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 07:58:41 (1756814321) [ 5104.928292] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 07:58:50 (1756814330) [ 5105.020753] Lustre: DEBUG MARKER: write [ 5105.036698] LustreError: 5546:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 5107.043591] Lustre: DEBUG MARKER: kill 327515 [ 5107.045877] LustreError: 327515:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 5110.136103] LustreError: 5546:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 5113.080089] LustreError: 327515:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 5115.237037] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 07:59:00 (1756814340) [ 5117.403674] LustreError: 328121:0:(rw.c:1967:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 5127.496118] LustreError: 328121:0:(rw.c:1967:ll_readpage()) cfs_fail_timeout id 1422 awake [ 5130.176311] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 07:59:15 (1756814355) [ 5130.332822] LustreError: 328700:0:(rw.c:2201:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 5132.416115] LustreError: 328700:0:(rw.c:2201:ll_readpage()) cfs_fail_timeout interrupted [ 5138.548382] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 07:59:23 (1756814363) [ 5139.057365] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 5139.634079] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 07:59:24 (1756814364) [ 5141.859091] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 07:59:27 (1756814367) [ 5144.776105] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 07:59:29 (1756814369) [ 5147.488538] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 07:59:32 (1756814372) [ 5149.979543] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 07:59:35 (1756814375) [ 5152.326993] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 07:59:37 (1756814377) [ 5154.711204] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 07:59:39 (1756814379) [ 5158.226247] Lustre: DEBUG MARKER: SKIP: sanityn test_102 skipping ALWAYS excluded test 102 [ 5158.764597] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 07:59:43 (1756814383) [ 5159.520231] Lustre: *** cfs_fail_loc=415, val=0*** [ 5166.459250] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 07:59:51 (1756814391) [ 5166.974407] Lustre: DEBUG MARKER: SKIP: sanityn test_104 ldiskfs only test [ 5167.503515] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 07:59:52 (1756814392) [ 5167.567851] LustreError: 5546:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [ 5167.569954] LustreError: 5546:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 2 previous similar messages [ 5172.664075] LustreError: 32144:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 5172.666212] LustreError: 32144:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 2 previous similar messages [ 5182.856116] LustreError: 32145:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 5182.858352] LustreError: 32145:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 4 previous similar messages [ 5184.877926] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 08:00:10 (1756814410) [ 5185.338986] Lustre: DEBUG MARKER: SKIP: sanityn test_106a Test only for ldiskfs and statx() supported [ 5185.833499] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 08:00:11 (1756814411) [ 5187.985840] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 08:00:13 (1756814413) [ 5189.933050] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 08:00:15 (1756814415) [ 5194.025111] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 08:00:19 (1756814419) [ 5202.307739] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 08:00:27 (1756814427) [ 5202.427335] LustreError: 2367:0:(osc_request.c:2959:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 5202.429250] LustreError: 2367:0:(osc_request.c:2959:osc_build_rpc()) Skipped 6 previous similar messages [ 5206.488168] LustreError: 2367:0:(osc_request.c:2959:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 5206.491272] LustreError: 2367:0:(osc_request.c:2959:osc_build_rpc()) Skipped 1 previous similar message [ 5208.690860] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 08:00:33 (1756814433) [ 5209.333798] LustreError: 338635:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e18808800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5209.337635] LustreError: 338635:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5209.351097] Lustre: Unmounted lustre-client [ 5209.949631] LustreError: 338656:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e03f5b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5209.952380] LustreError: 338656:0:(lov_obd.c:784:lov_cleanup()) Skipped 1 previous similar message [ 5209.955933] LustreError: 338656:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5209.957436] LustreError: 338656:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 5209.967071] Lustre: Unmounted lustre-client [ 5210.414151] Lustre: DEBUG MARKER: Iteration 1 [ 5210.583907] LustreError: 338816:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5210.583960] LustreError: 338817:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5210.589922] LustreError: 338816:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5210.639934] Lustre: Mounted lustre-client [ 5211.186029] LustreError: 338919:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e18c54000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5211.188283] LustreError: 338920:0:(obd_class.h:479:obd_check_dev()) Device 7 not setup [ 5211.189082] LustreError: 338919:0:(lov_obd.c:784:lov_cleanup()) Skipped 3 previous similar messages [ 5211.190497] LustreError: 338920:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 5211.207086] Lustre: Unmounted lustre-client [ 5212.325818] Key type lgssc unregistered [ 5212.464460] LNet: 339160:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5212.466802] LNetError: 339160:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5212.474844] LNet: Removed LNI 192.168.202.48@tcp [ 5212.775093] Key type .llcrypt unregistered [ 5212.776081] Key type ._llcrypt unregistered [ 5213.005522] Key type ._llcrypt registered [ 5213.006519] Key type .llcrypt registered [ 5213.342601] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5213.347113] alg: No test for adler32 (adler32-zlib) [ 5214.299922] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5214.553414] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5216.160159] Key type lgssc registered [ 5216.672667] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5220.349183] Lustre: DEBUG MARKER: Iteration 2 [ 5220.443253] LustreError: 339979:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5220.447246] LustreError: 339980:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5220.449159] LustreError: 339979:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5221.501019] Lustre: Mounted lustre-client [ 5221.929455] LustreError: 340087:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e18a6e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5221.932328] LustreError: 340086:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5221.932555] LustreError: 340087:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5221.949051] Lustre: Unmounted lustre-client [ 5222.899800] Key type lgssc unregistered [ 5223.013791] LNet: 340329:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5223.016148] LNetError: 340329:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5223.026342] LNet: Removed LNI 192.168.202.48@tcp [ 5223.279098] Key type .llcrypt unregistered [ 5223.279996] Key type ._llcrypt unregistered [ 5223.555908] Key type ._llcrypt registered [ 5223.559090] Key type .llcrypt registered [ 5223.743278] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5223.748088] alg: No test for adler32 (adler32-zlib) [ 5224.602873] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5224.679598] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5226.256147] Key type lgssc registered [ 5226.597365] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5230.030210] Lustre: DEBUG MARKER: Iteration 3 [ 5230.150208] LustreError: 341147:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5230.150232] LustreError: 341148:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5230.153889] LustreError: 341147:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 5231.204208] Lustre: Mounted lustre-client [ 5231.626481] LustreError: 341255:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e120c4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5231.633247] LustreError: 341255:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5231.635257] LustreError: 341255:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5231.658387] Lustre: Unmounted lustre-client [ 5232.684873] Key type lgssc unregistered [ 5232.806596] LNet: 341496:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5232.809275] LNetError: 341496:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5232.818809] LNet: Removed LNI 192.168.202.48@tcp [ 5233.060113] Key type .llcrypt unregistered [ 5233.061429] Key type ._llcrypt unregistered [ 5233.329966] Key type ._llcrypt registered [ 5233.331344] Key type .llcrypt registered [ 5233.509802] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5233.514838] alg: No test for adler32 (adler32-zlib) [ 5234.373620] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5234.465627] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5236.048146] Key type lgssc registered [ 5236.438569] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5240.064235] Lustre: DEBUG MARKER: Iteration 4 [ 5240.181677] LustreError: 342324:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5240.181715] LustreError: 342325:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5240.185463] LustreError: 342324:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5241.234871] Lustre: Mounted lustre-client [ 5241.235836] Lustre: Skipped 1 previous similar message [ 5241.668529] LustreError: 342430:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e2d33f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5241.670670] LustreError: 342431:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 5241.671356] LustreError: 342430:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5241.687090] Lustre: Unmounted lustre-client [ 5242.691392] Key type lgssc unregistered [ 5242.814382] LNet: 342672:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5242.816795] LNetError: 342672:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5242.823753] LNet: Removed LNI 192.168.202.48@tcp [ 5243.082092] Key type .llcrypt unregistered [ 5243.082944] Key type ._llcrypt unregistered [ 5243.288456] Key type ._llcrypt registered [ 5243.289316] Key type .llcrypt registered [ 5243.500159] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5243.505268] alg: No test for adler32 (adler32-zlib) [ 5244.355569] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5244.427463] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5246.000180] Key type lgssc registered [ 5246.452215] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5249.628816] Lustre: DEBUG MARKER: Iteration 5 [ 5249.734028] LustreError: 343491:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5249.734097] LustreError: 343492:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5249.739203] LustreError: 343491:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5250.791851] Lustre: Mounted lustre-client [ 5250.792888] Lustre: Skipped 1 previous similar message [ 5251.312757] LustreError: 343593:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e06627800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5251.316346] LustreError: 343593:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5251.317486] LustreError: 343592:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5251.342804] Lustre: Unmounted lustre-client [ 5252.313964] Key type lgssc unregistered [ 5252.433521] LNet: 343834:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5252.435700] LNetError: 343834:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5252.445840] LNet: Removed LNI 192.168.202.48@tcp [ 5252.679111] Key type .llcrypt unregistered [ 5252.680270] Key type ._llcrypt unregistered [ 5252.926091] Key type ._llcrypt registered [ 5252.926974] Key type .llcrypt registered [ 5253.129192] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5253.134262] alg: No test for adler32 (adler32-zlib) [ 5253.989550] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5254.072611] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5255.656095] Key type lgssc registered [ 5256.026883] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5259.342379] Lustre: DEBUG MARKER: Iteration 6 [ 5259.442713] LustreError: 344651:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5259.442757] LustreError: 344652:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5259.446650] LustreError: 344651:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5260.490304] Lustre: Mounted lustre-client [ 5260.492138] Lustre: Skipped 1 previous similar message [ 5260.983930] LustreError: 344758:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e18a6b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5260.990081] LustreError: 344758:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5260.991771] LustreError: 344758:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5261.011116] Lustre: Unmounted lustre-client [ 5262.000819] Key type lgssc unregistered [ 5262.129366] LNet: 345000:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5262.131558] LNetError: 345000:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5262.138653] LNet: Removed LNI 192.168.202.48@tcp [ 5262.376095] Key type .llcrypt unregistered [ 5262.376976] Key type ._llcrypt unregistered [ 5262.621576] Key type ._llcrypt registered [ 5262.622563] Key type .llcrypt registered [ 5262.815966] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5262.820444] alg: No test for adler32 (adler32-zlib) [ 5263.677051] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5263.754842] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5265.344107] Key type lgssc registered [ 5265.714690] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5269.088248] Lustre: DEBUG MARKER: Iteration 7 [ 5269.203233] LustreError: 345818:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5269.203720] LustreError: 345819:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5269.208382] LustreError: 345818:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5270.254021] Lustre: Mounted lustre-client [ 5270.695859] LustreError: 345920:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e3ee80800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5270.699330] LustreError: 345921:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 5270.700548] LustreError: 345920:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5270.726782] Lustre: Unmounted lustre-client [ 5271.736035] Key type lgssc unregistered [ 5271.872476] LNet: 346161:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5271.874302] LNetError: 346161:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5271.884273] LNet: Removed LNI 192.168.202.48@tcp [ 5272.151092] Key type .llcrypt unregistered [ 5272.152067] Key type ._llcrypt unregistered [ 5272.439282] Key type ._llcrypt registered [ 5272.440465] Key type .llcrypt registered [ 5272.695531] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5272.711631] alg: No test for adler32 (adler32-zlib) [ 5273.572578] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5273.658832] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5275.248149] Key type lgssc registered [ 5275.601579] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5278.816497] Lustre: DEBUG MARKER: Iteration 8 [ 5278.916614] LustreError: 346979:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5278.916688] LustreError: 346980:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5278.921646] LustreError: 346979:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5279.964116] Lustre: Mounted lustre-client [ 5279.966190] Lustre: Skipped 1 previous similar message [ 5280.381443] LustreError: 347088:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e06622000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5280.383349] LustreError: 347087:0:(obd_class.h:479:obd_check_dev()) Device 7 not setup [ 5280.383972] LustreError: 347088:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5280.404116] Lustre: Unmounted lustre-client [ 5281.336253] Key type lgssc unregistered [ 5281.459493] LNet: 347329:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5281.461437] LNetError: 347329:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5281.472712] LNet: Removed LNI 192.168.202.48@tcp [ 5281.712094] Key type .llcrypt unregistered [ 5281.713080] Key type ._llcrypt unregistered [ 5281.944329] Key type ._llcrypt registered [ 5281.945194] Key type .llcrypt registered [ 5282.126631] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5282.130920] alg: No test for adler32 (adler32-zlib) [ 5282.979893] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5283.064225] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5284.640137] Key type lgssc registered [ 5285.000303] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5288.495874] Lustre: DEBUG MARKER: Iteration 9 [ 5288.593140] LustreError: 348147:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5288.593165] LustreError: 348148:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5288.597074] LustreError: 348147:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5289.638464] Lustre: Mounted lustre-client [ 5290.072374] LustreError: 348249:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e0375e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5290.074423] LustreError: 348250:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5290.075296] LustreError: 348249:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5290.100076] Lustre: Unmounted lustre-client [ 5291.020846] Key type lgssc unregistered [ 5291.136486] LNet: 348492:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5291.138520] LNetError: 348492:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5291.144709] LNet: Removed LNI 192.168.202.48@tcp [ 5291.388095] Key type .llcrypt unregistered [ 5291.389158] Key type ._llcrypt unregistered [ 5291.614598] Key type ._llcrypt registered [ 5291.615637] Key type .llcrypt registered [ 5291.807862] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5291.812383] alg: No test for adler32 (adler32-zlib) [ 5292.670042] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5292.756777] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5294.336264] Key type lgssc registered [ 5294.866912] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5298.722748] Lustre: DEBUG MARKER: Iteration 10 [ 5298.846400] LustreError: 349310:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5298.846423] LustreError: 349309:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5298.849915] LustreError: 349310:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 5299.896857] Lustre: Mounted lustre-client [ 5300.354803] LustreError: 349418:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e03ec5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5300.362265] LustreError: 349418:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5300.365650] LustreError: 349418:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5300.373361] Lustre: Unmounted lustre-client [ 5301.318724] Key type lgssc unregistered [ 5301.429497] LNet: 349659:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5301.431721] LNetError: 349659:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5301.440717] LNet: Removed LNI 192.168.202.48@tcp [ 5301.675133] Key type .llcrypt unregistered [ 5301.675941] Key type ._llcrypt unregistered [ 5301.894377] Key type ._llcrypt registered [ 5301.895292] Key type .llcrypt registered [ 5302.090317] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5302.095970] alg: No test for adler32 (adler32-zlib) [ 5302.949282] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5303.031772] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5304.616184] Key type lgssc registered [ 5304.984319] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5308.282864] Lustre: DEBUG MARKER: Iteration 11 [ 5308.379105] LustreError: 350477:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5308.379860] LustreError: 350478:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5308.384247] LustreError: 350477:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5309.433471] Lustre: Mounted lustre-client [ 5309.434548] Lustre: Skipped 1 previous similar message [ 5309.928865] LustreError: 350580:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e20927000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5309.931454] LustreError: 350581:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5309.932516] LustreError: 350580:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5309.946978] Lustre: Unmounted lustre-client [ 5310.854387] Key type lgssc unregistered [ 5310.975447] LNet: 350821:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5310.977762] LNetError: 350821:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5310.987691] LNet: Removed LNI 192.168.202.48@tcp [ 5311.223093] Key type .llcrypt unregistered [ 5311.224038] Key type ._llcrypt unregistered [ 5311.438798] Key type ._llcrypt registered [ 5311.439681] Key type .llcrypt registered [ 5311.648126] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5311.653114] alg: No test for adler32 (adler32-zlib) [ 5312.499658] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5312.577615] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5314.152118] Key type lgssc registered [ 5314.515260] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5317.906411] Lustre: DEBUG MARKER: Iteration 12 [ 5318.008225] LustreError: 351638:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5318.010987] LustreError: 351639:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5318.012790] LustreError: 351638:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5319.063194] Lustre: Mounted lustre-client [ 5319.519284] LustreError: 351741:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e37873000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5319.523020] LustreError: 351741:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5319.523731] LustreError: 351742:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5319.526529] LustreError: 351742:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5319.544664] Lustre: Unmounted lustre-client [ 5320.532140] Key type lgssc unregistered [ 5320.644521] LNet: 351983:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5320.648881] LNetError: 351983:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5320.658792] LNet: Removed LNI 192.168.202.48@tcp [ 5320.910085] Key type .llcrypt unregistered [ 5320.911078] Key type ._llcrypt unregistered [ 5321.196545] Key type ._llcrypt registered [ 5321.197439] Key type .llcrypt registered [ 5321.381738] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5321.387322] alg: No test for adler32 (adler32-zlib) [ 5322.250659] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5322.347580] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5323.928161] Key type lgssc registered [ 5324.283480] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5327.600223] Lustre: DEBUG MARKER: Iteration 13 [ 5327.701269] LustreError: 352800:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5327.701547] LustreError: 352801:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5327.705083] LustreError: 352800:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5328.756107] Lustre: Mounted lustre-client [ 5329.203918] LustreError: 352907:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e18c50800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5329.206824] LustreError: 352908:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5329.207201] LustreError: 352907:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5329.222074] Lustre: Unmounted lustre-client [ 5330.221026] Key type lgssc unregistered [ 5330.347904] LNet: 353149:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5330.350298] LNetError: 353149:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5330.357723] LNet: Removed LNI 192.168.202.48@tcp [ 5330.630116] Key type .llcrypt unregistered [ 5330.631564] Key type ._llcrypt unregistered [ 5330.982855] Key type ._llcrypt registered [ 5330.985616] Key type .llcrypt registered [ 5331.209884] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5331.215332] alg: No test for adler32 (adler32-zlib) [ 5332.084131] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5332.174976] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5333.760158] Key type lgssc registered [ 5334.154349] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5337.588546] Lustre: DEBUG MARKER: Iteration 14 [ 5337.695061] LustreError: 353966:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5337.695526] LustreError: 353967:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5337.699979] LustreError: 353966:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5338.747347] Lustre: Mounted lustre-client [ 5339.162120] LustreError: 354074:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e1034c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5339.167394] LustreError: 354074:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5339.169510] LustreError: 354074:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5339.179442] Lustre: Unmounted lustre-client [ 5340.159976] Key type lgssc unregistered [ 5340.278533] LNet: 354317:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5340.280512] LNetError: 354317:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5340.288595] LNet: Removed LNI 192.168.202.48@tcp [ 5340.546125] Key type .llcrypt unregistered [ 5340.547969] Key type ._llcrypt unregistered [ 5340.792996] Key type ._llcrypt registered [ 5340.793883] Key type .llcrypt registered [ 5340.994530] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5340.999979] alg: No test for adler32 (adler32-zlib) [ 5341.851887] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5341.932055] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5343.512195] Key type lgssc registered [ 5343.896694] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5347.184934] Lustre: DEBUG MARKER: Iteration 15 [ 5347.275307] LustreError: 355135:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5347.277149] LustreError: 355136:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5347.279170] LustreError: 355135:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5348.321824] Lustre: Mounted lustre-client [ 5348.759183] LustreError: 355239:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e2151c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5348.761774] LustreError: 355238:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 5348.762163] LustreError: 355239:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5348.776118] Lustre: Unmounted lustre-client [ 5349.806842] Key type lgssc unregistered [ 5349.935530] LNet: 355479:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5349.937688] LNetError: 355479:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5349.943909] LNet: Removed LNI 192.168.202.48@tcp [ 5350.194099] Key type .llcrypt unregistered [ 5350.195153] Key type ._llcrypt unregistered [ 5350.467427] Key type ._llcrypt registered [ 5350.468314] Key type .llcrypt registered [ 5350.643819] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5350.649184] alg: No test for adler32 (adler32-zlib) [ 5351.497825] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5351.574366] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5353.152114] Key type lgssc registered [ 5353.511908] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5356.900400] Lustre: DEBUG MARKER: Iteration 16 [ 5356.992263] LustreError: 356296:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5356.994111] LustreError: 356297:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5356.996201] LustreError: 356296:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5358.043109] Lustre: Mounted lustre-client [ 5358.044223] Lustre: Skipped 1 previous similar message [ 5358.500434] LustreError: 356402:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e2151d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5358.503921] LustreError: 356403:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5358.504859] LustreError: 356402:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5358.515938] Lustre: Unmounted lustre-client [ 5359.503048] Key type lgssc unregistered [ 5359.621444] LNet: 356644:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5359.624271] LNetError: 356644:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5359.631723] LNet: Removed LNI 192.168.202.48@tcp [ 5359.871115] Key type .llcrypt unregistered [ 5359.872226] Key type ._llcrypt unregistered [ 5360.176276] Key type ._llcrypt registered [ 5360.177151] Key type .llcrypt registered [ 5360.361753] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5360.367316] alg: No test for adler32 (adler32-zlib) [ 5361.214548] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5361.289342] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5362.872124] Key type lgssc registered [ 5363.242645] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5366.616922] Lustre: DEBUG MARKER: Iteration 17 [ 5366.725225] LustreError: 357463:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5366.725264] LustreError: 357464:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5366.729330] LustreError: 357463:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5367.784667] Lustre: Mounted lustre-client [ 5367.785919] Lustre: Skipped 1 previous similar message [ 5368.206546] LustreError: 357570:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e03354800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5368.211609] LustreError: 357570:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5368.213331] LustreError: 357570:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5368.231554] Lustre: Unmounted lustre-client [ 5369.245847] Key type lgssc unregistered [ 5369.365534] LNet: 357811:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5369.368658] LNetError: 357811:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5369.377724] LNet: Removed LNI 192.168.202.48@tcp [ 5369.616200] Key type .llcrypt unregistered [ 5369.617830] Key type ._llcrypt unregistered [ 5369.884258] Key type ._llcrypt registered [ 5369.893162] Key type .llcrypt registered [ 5370.074950] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5370.079642] alg: No test for adler32 (adler32-zlib) [ 5370.937872] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5371.023866] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5372.600192] Key type lgssc registered [ 5373.024455] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5376.337850] Lustre: DEBUG MARKER: Iteration 18 [ 5376.434839] LustreError: 358629:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5376.435287] LustreError: 358630:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5376.440067] LustreError: 358629:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5377.487116] Lustre: Mounted lustre-client [ 5377.488991] Lustre: Skipped 1 previous similar message [ 5377.918908] LustreError: 358737:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e07fa3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5377.921582] LustreError: 358736:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5377.922036] LustreError: 358737:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5377.925650] LustreError: 358736:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5377.941333] Lustre: Unmounted lustre-client [ 5379.014299] Key type lgssc unregistered [ 5379.131616] LNet: 358978:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5379.133638] LNetError: 358978:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5379.140253] LNet: Removed LNI 192.168.202.48@tcp [ 5379.410121] Key type .llcrypt unregistered [ 5379.411862] Key type ._llcrypt unregistered [ 5379.730429] Key type ._llcrypt registered [ 5379.736698] Key type .llcrypt registered [ 5379.967496] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5379.973480] alg: No test for adler32 (adler32-zlib) [ 5380.841303] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5380.927489] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5382.520117] Key type lgssc registered [ 5383.005371] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5386.804649] Lustre: DEBUG MARKER: Iteration 19 [ 5386.911152] LustreError: 359796:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5386.911181] LustreError: 359797:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5386.917639] LustreError: 359796:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5387.967973] Lustre: Mounted lustre-client [ 5388.412165] LustreError: 359904:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e03351800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5388.415946] LustreError: 359905:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5388.417571] LustreError: 359904:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5388.430099] Lustre: Unmounted lustre-client [ 5389.427123] Key type lgssc unregistered [ 5389.550475] LNet: 360146:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5389.554501] LNetError: 360146:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5389.562799] LNet: Removed LNI 192.168.202.48@tcp [ 5389.819084] Key type .llcrypt unregistered [ 5389.820550] Key type ._llcrypt unregistered [ 5390.090320] Key type ._llcrypt registered [ 5390.096610] Key type .llcrypt registered [ 5390.272188] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5390.276845] alg: No test for adler32 (adler32-zlib) [ 5391.128781] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5391.210411] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5392.792147] Key type lgssc registered [ 5393.181542] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5396.471886] Lustre: DEBUG MARKER: Iteration 20 [ 5396.563050] LustreError: 360964:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5396.564341] LustreError: 360965:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5396.566638] LustreError: 360964:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5397.608308] Lustre: Mounted lustre-client [ 5397.610256] Lustre: Skipped 1 previous similar message [ 5398.050114] LustreError: 361070:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e2086a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5398.052497] LustreError: 361069:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5398.053682] LustreError: 361070:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5398.085069] Lustre: Unmounted lustre-client [ 5399.079622] Key type lgssc unregistered [ 5399.202415] LNet: 361311:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5399.205807] LNetError: 361311:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5399.215361] LNet: Removed LNI 192.168.202.48@tcp [ 5399.483157] Key type .llcrypt unregistered [ 5399.484866] Key type ._llcrypt unregistered [ 5399.788291] Key type ._llcrypt registered [ 5399.789397] Key type .llcrypt registered [ 5399.947345] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5399.951977] alg: No test for adler32 (adler32-zlib) [ 5400.812469] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5400.891114] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5402.472140] Key type lgssc registered [ 5402.821425] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5406.242371] Lustre: DEBUG MARKER: Iteration 21 [ 5406.378197] LustreError: 362130:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5406.378254] LustreError: 362131:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5406.382232] LustreError: 362130:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5407.426268] Lustre: Mounted lustre-client [ 5407.955846] LustreError: 362234:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e1880b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5407.958542] LustreError: 362234:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5407.961794] LustreError: 362235:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5407.963635] LustreError: 362235:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5407.978142] Lustre: Unmounted lustre-client [ 5409.031689] Key type lgssc unregistered [ 5409.166768] LNet: 362475:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5409.168944] LNetError: 362475:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5409.175946] LNet: Removed LNI 192.168.202.48@tcp [ 5409.443125] Key type .llcrypt unregistered [ 5409.444307] Key type ._llcrypt unregistered [ 5409.777958] Key type ._llcrypt registered [ 5409.780912] Key type .llcrypt registered [ 5410.006186] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5410.011363] alg: No test for adler32 (adler32-zlib) [ 5410.874319] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5410.989351] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5412.568192] Key type lgssc registered [ 5413.009652] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5416.494432] Lustre: DEBUG MARKER: Iteration 22 [ 5416.607536] LustreError: 363293:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5416.607606] LustreError: 363294:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5416.610902] LustreError: 363293:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 5417.657322] Lustre: Mounted lustre-client [ 5417.658155] Lustre: Skipped 1 previous similar message [ 5418.197697] LustreError: 363396:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e18a6c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5418.200334] LustreError: 363396:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5418.200825] LustreError: 363395:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5418.251082] Lustre: Unmounted lustre-client [ 5419.530932] Key type lgssc unregistered [ 5419.686787] LNet: 363637:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5419.690870] LNetError: 363637:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5419.702488] LNet: Removed LNI 192.168.202.48@tcp [ 5420.054164] Key type .llcrypt unregistered [ 5420.055819] Key type ._llcrypt unregistered [ 5420.427755] Key type ._llcrypt registered [ 5420.428642] Key type .llcrypt registered [ 5420.619448] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5420.624270] alg: No test for adler32 (adler32-zlib) [ 5421.492812] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5421.592868] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5423.192203] Key type lgssc registered [ 5423.606664] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5426.903200] Lustre: DEBUG MARKER: Iteration 23 [ 5426.998091] LustreError: 364455:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5426.998146] LustreError: 364456:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5427.004252] LustreError: 364455:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5428.046534] Lustre: Mounted lustre-client [ 5428.048590] Lustre: Skipped 1 previous similar message [ 5428.484788] LustreError: 364557:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e03ec5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5428.489523] LustreError: 364559:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5428.515535] Lustre: Unmounted lustre-client [ 5429.486450] Key type lgssc unregistered [ 5429.611374] LNet: 364800:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5429.613975] LNetError: 364800:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5429.622740] LNet: Removed LNI 192.168.202.48@tcp [ 5429.858110] Key type .llcrypt unregistered [ 5429.859083] Key type ._llcrypt unregistered [ 5430.193387] Key type ._llcrypt registered [ 5430.194452] Key type .llcrypt registered [ 5430.466803] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5430.471585] alg: No test for adler32 (adler32-zlib) [ 5431.338528] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5431.427647] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5433.024159] Key type lgssc registered [ 5433.450232] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5436.896331] Lustre: DEBUG MARKER: Iteration 24 [ 5437.010389] LustreError: 365617:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5437.010442] LustreError: 365618:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5437.016057] LustreError: 365617:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5438.062473] Lustre: Mounted lustre-client [ 5438.588862] LustreError: 365720:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e18a6c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5438.593157] LustreError: 365720:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5438.611604] Lustre: Unmounted lustre-client [ 5439.630144] Key type lgssc unregistered [ 5439.738543] LNet: 365962:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5439.742595] LNetError: 365962:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5439.750878] LNet: Removed LNI 192.168.202.48@tcp [ 5439.970096] Key type .llcrypt unregistered [ 5439.971708] Key type ._llcrypt unregistered [ 5440.233661] Key type ._llcrypt registered [ 5440.234557] Key type .llcrypt registered [ 5440.400893] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5440.405221] alg: No test for adler32 (adler32-zlib) [ 5441.255581] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5441.345515] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5442.936110] Key type lgssc registered [ 5443.288561] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5446.700386] Lustre: DEBUG MARKER: Iteration 25 [ 5446.808180] LustreError: 366781:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5446.808256] LustreError: 366782:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5446.814505] LustreError: 366781:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5447.865736] Lustre: Mounted lustre-client [ 5448.434185] LustreError: 366886:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e18a6d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5448.438697] LustreError: 366886:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5448.443994] LustreError: 366887:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5448.445448] LustreError: 366887:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5448.468714] Lustre: Unmounted lustre-client [ 5449.420675] Key type lgssc unregistered [ 5449.545537] LNet: 367128:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5449.547925] LNetError: 367128:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5449.557432] LNet: Removed LNI 192.168.202.48@tcp [ 5449.802126] Key type .llcrypt unregistered [ 5449.803936] Key type ._llcrypt unregistered [ 5450.062184] Key type ._llcrypt registered [ 5450.063354] Key type .llcrypt registered [ 5450.249826] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5450.256212] alg: No test for adler32 (adler32-zlib) [ 5451.111140] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5451.203557] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5452.792148] Key type lgssc registered [ 5453.235074] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5456.748834] Lustre: DEBUG MARKER: Iteration 26 [ 5456.864681] LustreError: 367944:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5456.868438] LustreError: 367951:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5456.870974] LustreError: 367944:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5457.927527] Lustre: Mounted lustre-client [ 5457.929147] Lustre: Skipped 1 previous similar message [ 5458.506780] LustreError: 368053:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e06627000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5458.510363] LustreError: 368053:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5458.510797] LustreError: 368054:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5458.514652] LustreError: 368054:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5458.526129] Lustre: Unmounted lustre-client [ 5459.569317] Key type lgssc unregistered [ 5459.698805] LNet: 368295:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5459.701598] LNetError: 368295:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5459.711933] LNet: Removed LNI 192.168.202.48@tcp [ 5459.964134] Key type .llcrypt unregistered [ 5459.965904] Key type ._llcrypt unregistered [ 5460.239669] Key type ._llcrypt registered [ 5460.241603] Key type .llcrypt registered [ 5460.530188] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5460.535504] alg: No test for adler32 (adler32-zlib) [ 5461.397460] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5461.486765] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5463.072213] Key type lgssc registered [ 5463.506232] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5466.769763] Lustre: DEBUG MARKER: Iteration 27 [ 5466.890353] LustreError: 369114:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5466.890404] LustreError: 369113:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5466.894496] LustreError: 369114:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5467.945638] Lustre: Mounted lustre-client [ 5467.947470] Lustre: Skipped 1 previous similar message [ 5468.368492] LustreError: 369217:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e189eb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5468.371368] LustreError: 369217:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5468.374238] LustreError: 369218:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5468.401639] Lustre: Unmounted lustre-client [ 5469.273612] Key type lgssc unregistered [ 5469.389546] LNet: 369459:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5469.391499] LNetError: 369459:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5469.399725] LNet: Removed LNI 192.168.202.48@tcp [ 5469.631081] Key type .llcrypt unregistered [ 5469.631909] Key type ._llcrypt unregistered [ 5469.887685] Key type ._llcrypt registered [ 5469.888582] Key type .llcrypt registered [ 5470.064288] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5470.068853] alg: No test for adler32 (adler32-zlib) [ 5470.914741] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5470.991713] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5472.568146] Key type lgssc registered [ 5472.923638] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5476.233463] Lustre: DEBUG MARKER: Iteration 28 [ 5476.334755] LustreError: 370276:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5476.335217] LustreError: 370277:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5476.338777] LustreError: 370276:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 5477.380628] Lustre: Mounted lustre-client [ 5477.382350] Lustre: Skipped 1 previous similar message [ 5477.824391] LustreError: 370380:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e0a432000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5477.829029] LustreError: 370380:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5477.830801] LustreError: 370380:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5477.850421] Lustre: Unmounted lustre-client [ 5479.060523] Key type lgssc unregistered [ 5479.193672] LNet: 370622:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5479.197811] LNetError: 370622:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5479.209981] LNet: Removed LNI 192.168.202.48@tcp [ 5479.593157] Key type .llcrypt unregistered [ 5479.595167] Key type ._llcrypt unregistered [ 5479.881260] Key type ._llcrypt registered [ 5479.885415] Key type .llcrypt registered [ 5480.060609] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5480.064746] alg: No test for adler32 (adler32-zlib) [ 5480.920815] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5481.007380] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5482.584137] Key type lgssc registered [ 5482.940798] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5486.371680] Lustre: DEBUG MARKER: Iteration 29 [ 5486.468540] LustreError: 371440:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5486.468887] LustreError: 371441:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5486.474825] LustreError: 371440:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5487.516902] Lustre: Mounted lustre-client [ 5487.938453] LustreError: 371549:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e18c56000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5487.945668] LustreError: 371549:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5487.947745] LustreError: 371549:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5487.962467] Lustre: Unmounted lustre-client [ 5489.065184] Key type lgssc unregistered [ 5489.181530] LNet: 371790:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5489.185072] LNetError: 371790:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5489.196910] LNet: Removed LNI 192.168.202.48@tcp [ 5489.487132] Key type .llcrypt unregistered [ 5489.489434] Key type ._llcrypt unregistered [ 5489.764353] Key type ._llcrypt registered [ 5489.770754] Key type .llcrypt registered [ 5489.956494] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5489.960975] alg: No test for adler32 (adler32-zlib) [ 5490.815294] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5490.902056] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5492.488184] Key type lgssc registered [ 5492.830905] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5496.343899] Lustre: DEBUG MARKER: Iteration 30 [ 5496.461913] LustreError: 372607:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5496.461947] LustreError: 372608:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5496.466208] LustreError: 372607:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5497.519100] Lustre: Mounted lustre-client [ 5497.520614] Lustre: Skipped 1 previous similar message [ 5498.031144] LustreError: 372709:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e120c6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5498.034630] LustreError: 372709:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5498.035445] LustreError: 372710:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5498.072887] Lustre: Unmounted lustre-client [ 5499.232747] Key type lgssc unregistered [ 5499.372413] LNet: 372951:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5499.375395] LNetError: 372951:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5499.384932] LNet: Removed LNI 192.168.202.48@tcp [ 5499.686184] Key type .llcrypt unregistered [ 5499.688055] Key type ._llcrypt unregistered [ 5499.969203] Key type ._llcrypt registered [ 5499.973062] Key type .llcrypt registered [ 5500.237464] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5500.244443] alg: No test for adler32 (adler32-zlib) [ 5501.129021] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5501.229076] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5502.824230] Key type lgssc registered [ 5503.284964] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5507.139685] Lustre: DEBUG MARKER: Iteration 31 [ 5507.240248] LustreError: 373769:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5507.240282] LustreError: 373770:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5507.243825] LustreError: 373769:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 5508.291275] Lustre: Mounted lustre-client [ 5508.292947] Lustre: Skipped 1 previous similar message [ 5508.811845] LustreError: 373877:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e08277000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5508.814982] LustreError: 373876:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5508.816077] LustreError: 373877:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5508.828573] Lustre: Unmounted lustre-client [ 5509.864994] Key type lgssc unregistered [ 5509.992567] LNet: 374118:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5509.997473] LNetError: 374118:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5510.006782] LNet: Removed LNI 192.168.202.48@tcp [ 5510.251118] Key type .llcrypt unregistered [ 5510.252433] Key type ._llcrypt unregistered [ 5510.518513] Key type ._llcrypt registered [ 5510.519919] Key type .llcrypt registered [ 5510.703697] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5510.708873] alg: No test for adler32 (adler32-zlib) [ 5511.600062] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5511.688585] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5513.272155] Key type lgssc registered [ 5513.707033] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5517.290495] Lustre: DEBUG MARKER: Iteration 32 [ 5517.405288] LustreError: 374936:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5517.406242] LustreError: 374937:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5517.408954] LustreError: 374936:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 5518.460298] Lustre: Mounted lustre-client [ 5518.462040] Lustre: Skipped 1 previous similar message [ 5518.874758] LustreError: 375036:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e2f1ab800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5518.879571] LustreError: 375036:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5518.909596] Lustre: Unmounted lustre-client [ 5520.234413] Key type lgssc unregistered [ 5520.351547] LNet: 375279:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5520.353540] LNetError: 375279:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5520.361978] LNet: Removed LNI 192.168.202.48@tcp [ 5520.616153] Key type .llcrypt unregistered [ 5520.617305] Key type ._llcrypt unregistered [ 5520.924203] Key type ._llcrypt registered [ 5520.925169] Key type .llcrypt registered [ 5521.098062] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5521.104049] alg: No test for adler32 (adler32-zlib) [ 5521.995754] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5522.103915] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5523.688150] Key type lgssc registered [ 5524.148226] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5527.705176] Lustre: DEBUG MARKER: Iteration 33 [ 5527.797766] LustreError: 376096:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5527.798819] LustreError: 376097:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5527.802320] LustreError: 376096:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5528.855093] Lustre: Mounted lustre-client [ 5529.312790] LustreError: 376204:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e03352800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5529.318189] LustreError: 376204:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5529.320381] LustreError: 376204:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5529.342106] Lustre: Unmounted lustre-client [ 5529.343135] Lustre: Skipped 1 previous similar message [ 5530.415561] Key type lgssc unregistered [ 5530.553501] LNet: 376445:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5530.557164] LNetError: 376445:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5530.567037] LNet: Removed LNI 192.168.202.48@tcp [ 5530.836152] Key type .llcrypt unregistered [ 5530.838279] Key type ._llcrypt unregistered [ 5531.080221] Key type ._llcrypt registered [ 5531.081334] Key type .llcrypt registered [ 5531.270580] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5531.279360] alg: No test for adler32 (adler32-zlib) [ 5532.137978] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5532.225871] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5533.808213] Key type lgssc registered [ 5534.224594] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5537.574424] Lustre: DEBUG MARKER: Iteration 34 [ 5537.692271] LustreError: 377263:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5537.692508] LustreError: 377264:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5537.697451] LustreError: 377263:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5538.741451] Lustre: Mounted lustre-client [ 5539.188397] LustreError: 377368:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e0a2fe800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5539.192810] LustreError: 377368:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5539.212082] Lustre: Unmounted lustre-client [ 5540.257815] Key type lgssc unregistered [ 5540.384454] LNet: 377611:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5540.388424] LNetError: 377611:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5540.396744] LNet: Removed LNI 192.168.202.48@tcp [ 5540.656138] Key type .llcrypt unregistered [ 5540.657636] Key type ._llcrypt unregistered [ 5540.963289] Key type ._llcrypt registered [ 5540.964296] Key type .llcrypt registered [ 5541.119508] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5541.124136] alg: No test for adler32 (adler32-zlib) [ 5541.978694] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5542.059585] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5543.640176] Key type lgssc registered [ 5544.006612] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5547.981408] Lustre: DEBUG MARKER: Iteration 35 [ 5548.130497] LustreError: 378428:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5548.130528] LustreError: 378429:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5548.137203] LustreError: 378428:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5549.196253] Lustre: Mounted lustre-client [ 5549.196253] Lustre: Mounted lustre-client [ 5549.612080] LustreError: 378534:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e1880b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5549.616165] LustreError: 378534:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5549.616716] LustreError: 378535:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5549.654919] Lustre: Unmounted lustre-client [ 5549.656518] Lustre: Skipped 1 previous similar message [ 5550.786109] Key type lgssc unregistered [ 5550.904562] LNet: 378776:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5550.906870] LNetError: 378776:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5550.913829] LNet: Removed LNI 192.168.202.48@tcp [ 5551.171102] Key type .llcrypt unregistered [ 5551.172499] Key type ._llcrypt unregistered [ 5551.453219] Key type ._llcrypt registered [ 5551.455261] Key type .llcrypt registered [ 5551.635439] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5551.640244] alg: No test for adler32 (adler32-zlib) [ 5552.505062] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5552.596613] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5554.184181] Key type lgssc registered [ 5554.693772] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5558.281375] Lustre: DEBUG MARKER: Iteration 36 [ 5558.401115] LustreError: 379601:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5558.401402] LustreError: 379602:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5558.405419] LustreError: 379601:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5559.460412] Lustre: Mounted lustre-client [ 5559.461369] Lustre: Skipped 1 previous similar message [ 5559.884932] LustreError: 379710:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e18a6d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5559.887035] LustreError: 379709:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5559.889481] LustreError: 379710:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5559.905500] Lustre: Unmounted lustre-client [ 5560.956563] Key type lgssc unregistered [ 5561.097501] LNet: 379953:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5561.099953] LNetError: 379953:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5561.107411] LNet: Removed LNI 192.168.202.48@tcp [ 5561.368116] Key type .llcrypt unregistered [ 5561.369098] Key type ._llcrypt unregistered [ 5561.681322] Key type ._llcrypt registered [ 5561.684194] Key type .llcrypt registered [ 5561.903465] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5561.908545] alg: No test for adler32 (adler32-zlib) [ 5562.772676] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5562.851613] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5564.432139] Key type lgssc registered [ 5564.821241] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5568.185164] Lustre: DEBUG MARKER: Iteration 37 [ 5568.286567] LustreError: 380771:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5568.287750] LustreError: 380772:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5568.290802] LustreError: 380771:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5569.332626] Lustre: Mounted lustre-client [ 5569.334096] Lustre: Skipped 1 previous similar message [ 5569.782033] LustreError: 380875:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e0a47f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5569.785204] LustreError: 380876:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5569.785659] LustreError: 380875:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5569.796507] Lustre: Unmounted lustre-client [ 5570.760273] Key type lgssc unregistered [ 5570.886496] LNet: 381117:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5570.891232] LNetError: 381117:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5570.899631] LNet: Removed LNI 192.168.202.48@tcp [ 5571.141103] Key type .llcrypt unregistered [ 5571.141967] Key type ._llcrypt unregistered [ 5571.388252] Key type ._llcrypt registered [ 5571.389546] Key type .llcrypt registered [ 5571.582540] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5571.587257] alg: No test for adler32 (adler32-zlib) [ 5572.447318] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5572.532875] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5574.121086] Key type lgssc registered [ 5574.458434] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5577.714929] Lustre: DEBUG MARKER: Iteration 38 [ 5577.821478] LustreError: 381935:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5577.821514] LustreError: 381934:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5577.827180] LustreError: 381935:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5578.874902] Lustre: Mounted lustre-client [ 5579.319382] LustreError: 382039:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e18a6f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5579.325914] LustreError: 382039:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5579.329916] LustreError: 382039:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 5579.338090] Lustre: Unmounted lustre-client [ 5580.310257] Key type lgssc unregistered [ 5580.437451] LNet: 382281:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5580.439878] LNetError: 382281:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5580.449622] LNet: Removed LNI 192.168.202.48@tcp [ 5580.708112] Key type .llcrypt unregistered [ 5580.708989] Key type ._llcrypt unregistered [ 5580.996054] Key type ._llcrypt registered [ 5581.002053] Key type .llcrypt registered [ 5581.158410] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5581.162988] alg: No test for adler32 (adler32-zlib) [ 5582.029355] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5582.111210] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5583.696192] Key type lgssc registered [ 5584.094613] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5587.059782] Lustre: DEBUG MARKER: Iteration 39 [ 5587.155468] LustreError: 383099:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5587.155498] LustreError: 383100:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5587.158868] LustreError: 383099:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 5588.217035] Lustre: Mounted lustre-client [ 5588.708120] LustreError: 383208:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e2f287800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5588.710543] LustreError: 383208:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5588.714693] LustreError: 383208:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5588.716386] LustreError: 383208:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5588.729097] Lustre: Unmounted lustre-client [ 5589.802646] Key type lgssc unregistered [ 5589.939765] LNet: 383448:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5589.942139] LNetError: 383448:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5589.952394] LNet: Removed LNI 192.168.202.48@tcp [ 5590.214146] Key type .llcrypt unregistered [ 5590.215879] Key type ._llcrypt unregistered [ 5590.521348] Key type ._llcrypt registered [ 5590.550392] Key type .llcrypt registered [ 5590.742757] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5590.748156] alg: No test for adler32 (adler32-zlib) [ 5591.603511] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5591.683603] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5593.264124] Key type lgssc registered [ 5593.611393] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5596.839249] Lustre: DEBUG MARKER: Iteration 40 [ 5596.947388] LustreError: 384265:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5596.947440] LustreError: 384266:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5596.951382] LustreError: 384265:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5598.005722] Lustre: Mounted lustre-client [ 5598.006890] Lustre: Skipped 1 previous similar message [ 5598.545112] LustreError: 384367:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e03356800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5598.548033] LustreError: 384367:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5598.548850] LustreError: 384368:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5598.551850] LustreError: 384368:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5598.567751] Lustre: Unmounted lustre-client [ 5599.648540] Key type lgssc unregistered [ 5599.773898] LNet: 384608:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5599.777062] LNetError: 384608:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5599.783960] LNet: Removed LNI 192.168.202.48@tcp [ 5600.058109] Key type .llcrypt unregistered [ 5600.059735] Key type ._llcrypt unregistered [ 5600.345956] Key type ._llcrypt registered [ 5600.348666] Key type .llcrypt registered [ 5600.623765] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5600.631205] alg: No test for adler32 (adler32-zlib) [ 5601.497230] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5601.584705] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5603.176151] Key type lgssc registered [ 5603.540282] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5606.654183] Lustre: DEBUG MARKER: Iteration 41 [ 5606.750733] LustreError: 385428:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5606.750765] LustreError: 385427:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5606.756648] LustreError: 385428:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5607.805594] Lustre: Mounted lustre-client [ 5607.807550] Lustre: Skipped 1 previous similar message [ 5608.230351] LustreError: 385536:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e3edd4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5608.233371] LustreError: 385536:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5608.233813] LustreError: 385537:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5608.237597] LustreError: 385537:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5608.264632] Lustre: Unmounted lustre-client [ 5608.265504] Lustre: Skipped 1 previous similar message [ 5609.254220] Key type lgssc unregistered [ 5609.380731] LNet: 385778:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5609.384209] LNetError: 385778:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5609.392919] LNet: Removed LNI 192.168.202.48@tcp [ 5609.708148] Key type .llcrypt unregistered [ 5609.710029] Key type ._llcrypt unregistered [ 5609.967325] Key type ._llcrypt registered [ 5609.981337] Key type .llcrypt registered [ 5610.222883] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5610.234253] alg: No test for adler32 (adler32-zlib) [ 5611.094995] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5611.179497] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5612.760198] Key type lgssc registered [ 5613.184963] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5616.714505] Lustre: DEBUG MARKER: Iteration 42 [ 5616.830698] LustreError: 386594:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5616.832317] LustreError: 386595:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5616.834104] LustreError: 386594:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5617.887418] Lustre: Mounted lustre-client [ 5617.888327] Lustre: Skipped 1 previous similar message [ 5618.385828] LustreError: 386694:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e189ec000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5618.389856] LustreError: 386694:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 5618.392165] LustreError: 386694:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5618.408096] Lustre: Unmounted lustre-client [ 5619.428685] Key type lgssc unregistered [ 5619.542507] LNet: 386936:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5619.544758] LNetError: 386936:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5619.551815] LNet: Removed LNI 192.168.202.48@tcp [ 5619.821089] Key type .llcrypt unregistered [ 5619.821948] Key type ._llcrypt unregistered [ 5620.094240] Key type ._llcrypt registered [ 5620.095607] Key type .llcrypt registered [ 5620.290059] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5620.297472] alg: No test for adler32 (adler32-zlib) [ 5621.147609] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5621.224678] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5622.800116] Key type lgssc registered [ 5623.161318] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5626.307239] Lustre: DEBUG MARKER: Iteration 43 [ 5626.401944] LustreError: 387754:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5626.401982] LustreError: 387755:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5626.409017] LustreError: 387754:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5627.449874] Lustre: Mounted lustre-client [ 5627.885027] LustreError: 387863:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e2f282000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5627.888986] LustreError: 387862:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5627.889338] LustreError: 387863:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5627.902637] Lustre: Unmounted lustre-client [ 5628.916938] Key type lgssc unregistered [ 5629.027432] LNet: 388104:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5629.029283] LNetError: 388104:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5629.037635] LNet: Removed LNI 192.168.202.48@tcp [ 5629.272101] Key type .llcrypt unregistered [ 5629.273340] Key type ._llcrypt unregistered [ 5629.559428] Key type ._llcrypt registered [ 5629.560430] Key type .llcrypt registered [ 5629.746222] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5629.750789] alg: No test for adler32 (adler32-zlib) [ 5630.613034] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5630.688824] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5632.264109] Key type lgssc registered [ 5632.615601] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5635.759985] Lustre: DEBUG MARKER: Iteration 44 [ 5635.856180] LustreError: 388923:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5635.856221] LustreError: 388924:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5635.859909] LustreError: 388923:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 5636.915544] Lustre: Mounted lustre-client [ 5636.916358] Lustre: Skipped 1 previous similar message [ 5637.392128] LustreError: 389034:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e3edd7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5637.395310] LustreError: 389034:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5637.396924] LustreError: 389033:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5637.400247] LustreError: 389033:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5637.411123] Lustre: Unmounted lustre-client [ 5638.522660] Key type lgssc unregistered [ 5638.655881] LNet: 389275:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5638.658400] LNetError: 389275:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5638.669457] LNet: Removed LNI 192.168.202.48@tcp [ 5638.907097] Key type .llcrypt unregistered [ 5638.908124] Key type ._llcrypt unregistered [ 5639.115420] Key type ._llcrypt registered [ 5639.116317] Key type .llcrypt registered [ 5639.333438] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5639.345818] alg: No test for adler32 (adler32-zlib) [ 5640.194277] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5640.271139] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5641.856140] Key type lgssc registered [ 5642.252111] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5645.577576] Lustre: DEBUG MARKER: Iteration 45 [ 5645.678244] LustreError: 390091:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5645.678275] LustreError: 390092:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5645.681773] LustreError: 390091:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 5646.725648] Lustre: Mounted lustre-client [ 5647.163371] LustreError: 390197:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e2f283800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5647.165977] LustreError: 390196:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5647.168719] LustreError: 390197:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5647.182527] Lustre: Unmounted lustre-client [ 5648.164814] Key type lgssc unregistered [ 5648.286469] LNet: 390439:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5648.288516] LNetError: 390439:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5648.298656] LNet: Removed LNI 192.168.202.48@tcp [ 5648.520114] Key type .llcrypt unregistered [ 5648.522083] Key type ._llcrypt unregistered [ 5648.754971] Key type ._llcrypt registered [ 5648.765313] Key type .llcrypt registered [ 5648.966143] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5648.971643] alg: No test for adler32 (adler32-zlib) [ 5649.830152] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5649.906521] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5651.488172] Key type lgssc registered [ 5651.838467] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5655.211798] Lustre: DEBUG MARKER: Iteration 46 [ 5655.329838] LustreError: 391257:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5655.329898] LustreError: 391258:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5655.336359] LustreError: 391257:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5656.386560] Lustre: Mounted lustre-client [ 5656.388728] Lustre: Skipped 1 previous similar message [ 5656.957571] LustreError: 391357:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e2151a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5656.960834] LustreError: 391359:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5656.961194] LustreError: 391357:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5656.977086] Lustre: Unmounted lustre-client [ 5658.017888] Key type lgssc unregistered [ 5658.132769] LNet: 391600:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5658.135621] LNetError: 391600:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5658.144734] LNet: Removed LNI 192.168.202.48@tcp [ 5658.404112] Key type .llcrypt unregistered [ 5658.405176] Key type ._llcrypt unregistered [ 5658.784992] Key type ._llcrypt registered [ 5658.786375] Key type .llcrypt registered [ 5658.973976] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5658.979162] alg: No test for adler32 (adler32-zlib) [ 5659.825462] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5659.902859] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5661.480137] Key type lgssc registered [ 5661.846792] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5665.026787] Lustre: DEBUG MARKER: Iteration 47 [ 5665.133302] LustreError: 392417:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5665.133352] LustreError: 392418:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5665.138607] LustreError: 392417:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 5666.195352] Lustre: Mounted lustre-client [ 5666.196595] Lustre: Skipped 1 previous similar message [ 5666.711603] LustreError: 392520:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e03352000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5666.715809] LustreError: 392520:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5666.739927] Lustre: Unmounted lustre-client [ 5667.829894] Key type lgssc unregistered [ 5667.965345] LNet: 392764:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5667.967854] LNetError: 392764:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5667.975925] LNet: Removed LNI 192.168.202.48@tcp [ 5668.268147] Key type .llcrypt unregistered [ 5668.269144] Key type ._llcrypt unregistered [ 5668.568367] Key type ._llcrypt registered [ 5668.569609] Key type .llcrypt registered [ 5668.752457] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5668.757603] alg: No test for adler32 (adler32-zlib) [ 5669.633871] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5669.733894] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5671.320169] Key type lgssc registered [ 5671.722744] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5675.341948] Lustre: DEBUG MARKER: Iteration 48 [ 5675.454136] LustreError: 393581:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5675.454157] LustreError: 393582:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5675.458669] LustreError: 393581:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5676.507711] Lustre: Mounted lustre-client [ 5676.969962] LustreError: 393688:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e18a6a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5676.972663] LustreError: 393688:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5676.974919] LustreError: 393687:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5676.979363] LustreError: 393687:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 5676.986079] Lustre: Unmounted lustre-client [ 5677.970405] Key type lgssc unregistered [ 5678.098786] LNet: 393928:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5678.101101] LNetError: 393928:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5678.108413] LNet: Removed LNI 192.168.202.48@tcp [ 5678.359122] Key type .llcrypt unregistered [ 5678.360973] Key type ._llcrypt unregistered [ 5678.639598] Key type ._llcrypt registered [ 5678.640410] Key type .llcrypt registered [ 5678.930525] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5678.965530] alg: No test for adler32 (adler32-zlib) [ 5679.846793] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5679.951559] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5681.536164] Key type lgssc registered [ 5681.963086] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5685.485894] Lustre: DEBUG MARKER: Iteration 49 [ 5685.591209] LustreError: 394744:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5685.595717] LustreError: 394745:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5685.597392] LustreError: 394744:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 5686.645733] Lustre: Mounted lustre-client [ 5686.647772] Lustre: Skipped 1 previous similar message [ 5687.076312] LustreError: 394854:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e050b5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5687.081077] LustreError: 394854:0:(lov_obd.c:784:lov_cleanup()) Skipped 2 previous similar messages [ 5687.081272] LustreError: 394853:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5687.084876] LustreError: 394853:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5687.112274] Lustre: Unmounted lustre-client [ 5688.026825] Key type lgssc unregistered [ 5688.144436] LNet: 395096:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5688.146322] LNetError: 395096:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5688.154620] LNet: Removed LNI 192.168.202.48@tcp [ 5688.394138] Key type .llcrypt unregistered [ 5688.395082] Key type ._llcrypt unregistered [ 5688.659896] Key type ._llcrypt registered [ 5688.660876] Key type .llcrypt registered [ 5688.834834] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5688.839378] alg: No test for adler32 (adler32-zlib) [ 5689.694402] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5689.771763] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5691.352107] Key type lgssc registered [ 5691.750061] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5694.966544] Lustre: DEBUG MARKER: Iteration 50 [ 5695.068035] LustreError: 395912:0:(llite_lib.c:1394:ll_fill_super()) cfs_race id 1417 sleeping [ 5695.069312] LustreError: 395914:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 waking [ 5695.073673] LustreError: 395912:0:(llite_lib.c:1394:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 5696.120676] Lustre: Mounted lustre-client [ 5696.122470] Lustre: Skipped 1 previous similar message [ 5696.565450] LustreError: 396018:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e1f0aa800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5696.571908] LustreError: 396018:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 5696.574816] LustreError: 396018:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 5696.588071] Lustre: Unmounted lustre-client [ 5697.520819] Key type lgssc unregistered [ 5697.646531] LNet: 396261:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5697.650418] LNetError: 396261:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5697.658777] LNet: Removed LNI 192.168.202.48@tcp [ 5697.904125] Key type .llcrypt unregistered [ 5697.905304] Key type ._llcrypt unregistered [ 5698.143901] Key type ._llcrypt registered [ 5698.145028] Key type .llcrypt registered [ 5698.294446] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 5698.303291] alg: No test for adler32 (adler32-zlib) [ 5699.170927] Lustre: Lustre: Build Version: 2.16.58_1_g7af8a00 [ 5699.257616] LNet: Added LNI 192.168.202.48@tcp [8/256/0/180] [ 5700.840144] Key type lgssc registered [ 5701.211052] Lustre: Echo OBD driver; http://www.lustre.org/ [ 5705.108329] Lustre: Mounted lustre-client [ 5707.284821] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 08:08:52 (1756814932) [ 5724.128102] Lustre: 397588:0:(client.c:2470:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1756814933/real 1756814933] req@ffffa01e3ab54e00 x1842153965757184/t0(0) o36->lustre-MDT0000-mdc-ffffa01e04db8000@192.168.202.148@tcp:12/10 lens 496/440 e 0 to 1 dl 1756814949 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 5724.134413] Lustre: lustre-MDT0000-mdc-ffffa01e04db8000: Connection to lustre-MDT0000 (at 192.168.202.148@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5724.142576] Lustre: lustre-MDT0000-mdc-ffffa01e04db8000: Connection restored to 192.168.202.148@tcp (at 192.168.202.148@tcp) [ 5739.488196] Lustre: 397588:0:(client.c:2470:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1756814949/real 1756814949] req@ffffa01e3ab54e00 x1842153965757184/t0(0) o36->lustre-MDT0000-mdc-ffffa01e04db8000@192.168.202.148@tcp:12/10 lens 496/440 e 0 to 1 dl 1756814965 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 5739.503709] Lustre: lustre-MDT0000-mdc-ffffa01e04db8000: Connection to lustre-MDT0000 (at 192.168.202.148@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5739.518504] Lustre: lustre-MDT0000-mdc-ffffa01e04db8000: Connection restored to 192.168.202.148@tcp (at 192.168.202.148@tcp) [ 5755.872116] Lustre: 397588:0:(client.c:2470:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1756814965/real 1756814965] req@ffffa01e3ab54e00 x1842153965757184/t0(0) o36->lustre-MDT0000-mdc-ffffa01e04db8000@192.168.202.148@tcp:12/10 lens 496/440 e 0 to 1 dl 1756814981 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 5755.881144] Lustre: lustre-MDT0000-mdc-ffffa01e04db8000: Connection to lustre-MDT0000 (at 192.168.202.148@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5755.890225] Lustre: lustre-MDT0000-mdc-ffffa01e04db8000: Connection restored to 192.168.202.148@tcp (at 192.168.202.148@tcp) [ 5772.256109] Lustre: 397588:0:(client.c:2470:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1756814981/real 1756814981] req@ffffa01e3ab54e00 x1842153965757184/t0(0) o36->lustre-MDT0000-mdc-ffffa01e04db8000@192.168.202.148@tcp:12/10 lens 496/440 e 0 to 1 dl 1756814997 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 5772.264784] Lustre: lustre-MDT0000-mdc-ffffa01e04db8000: Connection to lustre-MDT0000 (at 192.168.202.148@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5772.273489] Lustre: lustre-MDT0000-mdc-ffffa01e04db8000: Connection restored to 192.168.202.148@tcp (at 192.168.202.148@tcp) [ 5772.789667] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 08:09:57 (1756814997) [ 5773.292996] Lustre: DEBUG MARKER: SKIP: sanityn test_111 needs >= 2 MDTs [ 5773.850432] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 08:09:59 (1756814999) [ 5774.314994] Lustre: DEBUG MARKER: SKIP: sanityn test_112 We need at least 2 MDTs for this test [ 5774.827592] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 08:10:00 (1756815000) [ 5776.858234] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 08:10:02 (1756815002) [ 5777.307749] Lustre: DEBUG MARKER: SKIP: sanityn test_114 We need at least 2 MDTs for this test [ 5777.809725] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 08:10:03 (1756815003) [ 5778.260647] Lustre: DEBUG MARKER: SKIP: sanityn test_115 ldiskfs only test [ 5778.789259] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 08:10:04 (1756815004) [ 5779.234231] Lustre: DEBUG MARKER: SKIP: sanityn test_116 needs >= 2 MDTs [ 5779.757293] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 08:10:04 (1756815004) [ 5797.856152] Lustre: 396450:0:(client.c:2470:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1756815007/real 1756815007] req@ffffa01e40768000 x1842153965783296/t0(0) o4->lustre-OST0000-osc-ffffa01e04db8000@192.168.202.148@tcp:6/4 lens 4584/448 e 0 to 1 dl 1756815023 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 5797.856183] Lustre: lustre-OST0000-osc-ffffa01e04db8000: Connection to lustre-OST0000 (at 192.168.202.148@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 5797.862714] Lustre: 396450:0:(client.c:2470:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [ 5885.200981] Lustre: DEBUG MARKER: oleg248-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffffa01e04db8000.ost_server_uuid 50 [ 5885.684861] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffffa01e04db8000.ost_server_uuid in FULL state after 0 sec [ 5886.228947] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 5886.772135] Lustre: DEBUG MARKER: == sanityn test complete, duration 5716 sec ============== 08:11:51 (1756815111) [ 5887.335855] Lustre: DEBUG MARKER: === sanityn: start cleanup 08:11:52 (1756815112) === [ 5930.083182] LustreError: 401686:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e07fa2000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5930.086958] LustreError: 401686:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5930.097067] Lustre: Unmounted lustre-client [ 5931.232123] Lustre: DEBUG MARKER: === sanityn: finish cleanup 08:12:36 (1756815156) === [ 5931.532316] LustreError: 401987:0:(lov_obd.c:784:lov_cleanup()) lustre-clilov-ffffa01e04db8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5931.537638] LustreError: 401987:0:(lov_obd.c:784:lov_cleanup()) Skipped 1 previous similar message [ 5931.545661] LustreError: 401987:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5931.549318] LustreError: 401987:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 5931.566082] Lustre: Unmounted lustre-client [ 5943.683734] Key type lgssc unregistered [ 5943.805542] LNet: 402468:0:(lib-ptl.c:966:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 5943.807735] LNetError: 402468:0:(acceptor.c:263:lnet_acceptor_remove_socket()) Interface ens2 not found [ 5943.817845] LNet: Removed LNI 192.168.202.48@tcp [ 5944.074110] Key type .llcrypt unregistered [ 5944.075042] Key type ._llcrypt unregistered