[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.17.0-8.fc42 06/10/2025 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 413815865 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524588K 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.001013] APIC: Switch to symmetric I/O mode setup [ 0.003119] x2apic enabled [ 0.004006] Switched APIC routing to physical x2apic. [ 0.005011] kvm-guest: setup PV IPIs [ 0.008331] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009025] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010014] pid_max: default: 32768 minimum: 301 [ 0.011140] LSM: Security Framework initializing [ 0.012056] Yama: becoming mindful. [ 0.013000] SELinux: Initializing. [ 0.013000] *** VALIDATE selinux *** [ 0.021061] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.026416] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.028016] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.029111] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031088] *** VALIDATE tmpfs *** [ 0.032517] *** VALIDATE proc *** [ 0.033259] *** VALIDATE cgroup *** [ 0.034008] *** VALIDATE cgroup2 *** [ 0.035273] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.036152] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.037007] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.038031] Spectre V2 : User space: Vulnerable [ 0.039009] Speculative Store Bypass: Vulnerable [ 0.042020] debug: unmapping init [mem 0xffffffffa3859000-0xffffffffa3860fff] [ 0.044335] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.045807] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.046030] ... version: 2 [ 0.047012] ... bit width: 48 [ 0.048011] ... generic registers: 4 [ 0.049010] ... value mask: 0000ffffffffffff [ 0.050012] ... max period: 00007fffffffffff [ 0.051014] ... fixed-purpose events: 3 [ 0.052012] ... event mask: 000000070000000f [ 0.053356] rcu: Hierarchical SRCU implementation. [ 0.055651] smp: Bringing up secondary CPUs ... [ 0.056701] x86: Booting SMP configuration: [ 0.057024] .... node #0, CPUs: #1 #2 #3 [ 0.064292] smp: Brought up 1 node, 4 CPUs [ 0.065968] smpboot: Max logical packages: 1 [ 0.066013] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.123650] node 0 deferred pages initialised in 55ms [ 0.128191] devtmpfs: initialized [ 0.130263] x86/mm: Memory block size: 128MB [ 0.134966] gcov: version magic: 0x41383552 [ 0.138337] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.144154] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.148419] pinctrl core: initialized pinctrl subsystem [ 0.152198] [ 0.153007] ************************************************************* [ 0.157028] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.160061] ** ** [ 0.164061] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.168051] ** ** [ 0.171015] ** This means that this kernel is built to expose internal ** [ 0.175015] ** IOMMU data structures, which may compromise security on ** [ 0.178018] ** your system. ** [ 0.180045] ** ** [ 0.183013] ** If you see this message and you are not debugging the ** [ 0.187014] ** kernel, report this immediately to your vendor! ** [ 0.190024] ** ** [ 0.194014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.197014] ************************************************************* [ 0.201062] NET: Registered protocol family 16 [ 0.203668] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.206089] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.209067] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.213036] cpuidle: using governor menu [ 0.214815] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.215409] PCI: Using configuration type 1 for base access [ 0.217127] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.225066] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.227061] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.231131] cryptd: max_cpu_qlen set to 1000 [ 0.233180] ACPI: Added _OSI(Module Device) [ 0.234013] ACPI: Added _OSI(Processor Device) [ 0.235009] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.237010] ACPI: Added _OSI(Processor Aggregator Device) [ 0.241309] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.247579] ACPI: Interpreter enabled [ 0.249064] ACPI: PM: (supports S0 S3 S4 S5) [ 0.250010] ACPI: Using IOAPIC for interrupt routing [ 0.251105] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.255400] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.264913] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.267046] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.269019] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.273083] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.277824] acpiphp: Slot [2] registered [ 0.279104] acpiphp: Slot [5] registered [ 0.281167] acpiphp: Slot [6] registered [ 0.283094] acpiphp: Slot [3] registered [ 0.284075] acpiphp: Slot [4] registered [ 0.285138] acpiphp: Slot [7] registered [ 0.287085] acpiphp: Slot [8] registered [ 0.289083] acpiphp: Slot [9] registered [ 0.290083] acpiphp: Slot [10] registered [ 0.291091] acpiphp: Slot [11] registered [ 0.293092] acpiphp: Slot [12] registered [ 0.294092] acpiphp: Slot [13] registered [ 0.296108] acpiphp: Slot [14] registered [ 0.297105] acpiphp: Slot [15] registered [ 0.299083] acpiphp: Slot [16] registered [ 0.300102] acpiphp: Slot [17] registered [ 0.301086] acpiphp: Slot [18] registered [ 0.303068] acpiphp: Slot [19] registered [ 0.304060] acpiphp: Slot [20] registered [ 0.305064] acpiphp: Slot [21] registered [ 0.306086] acpiphp: Slot [22] registered [ 0.307081] acpiphp: Slot [23] registered [ 0.309101] acpiphp: Slot [24] registered [ 0.310078] acpiphp: Slot [25] registered [ 0.312085] acpiphp: Slot [26] registered [ 0.313078] acpiphp: Slot [27] registered [ 0.314072] acpiphp: Slot [28] registered [ 0.315061] acpiphp: Slot [29] registered [ 0.317095] acpiphp: Slot [30] registered [ 0.318122] acpiphp: Slot [31] registered [ 0.319069] PCI host bridge to bus 0000:00 [ 0.320018] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.322024] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.324028] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.326020] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.328022] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.331026] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.332158] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.334741] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.337960] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.342453] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.346054] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.348016] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.350014] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.352015] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.354546] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.356663] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.358039] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.360622] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.364016] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.371019] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.374010] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.378965] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.383033] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.388015] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.400018] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.409169] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.414014] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.419016] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.430018] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.439580] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.442297] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.444454] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.447287] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.449179] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.455030] iommu: Default domain type: Passthrough [ 0.457403] SCSI subsystem initialized [ 0.458174] ACPI: bus type USB registered [ 0.460104] usbcore: registered new interface driver usbfs [ 0.462087] usbcore: registered new interface driver hub [ 0.465131] usbcore: registered new device driver usb [ 0.467179] pps_core: LinuxPPS API ver. 1 registered [ 0.469016] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.471068] PTP clock support registered [ 0.474132] EDAC MC: Ver: 3.0.0 [ 0.476175] PCI: Using ACPI for IRQ routing [ 0.478856] NetLabel: Initializing [ 0.479009] NetLabel: domain hash size = 128 [ 0.481010] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.483101] NetLabel: unlabeled traffic allowed by default [ 0.486078] vgaarb: loaded [ 0.487322] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.489013] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.497000] clocksource: Switched to clocksource kvm-clock [ 0.592565] VFS: Disk quotas dquot_6.6.0 [ 0.594256] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.596502] *** VALIDATE ramfs *** [ 0.597472] *** VALIDATE hugetlbfs *** [ 0.598548] pnp: PnP ACPI init [ 0.600187] pnp: PnP ACPI: found 6 devices [ 0.613464] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.615997] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.617882] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.619442] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.621303] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.623204] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.625496] NET: Registered protocol family 2 [ 0.627324] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.631518] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.635139] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.640466] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.644220] TCP: Hash tables configured (established 65536 bind 65536) [ 0.646564] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.649157] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.651556] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.653907] NET: Registered protocol family 1 [ 0.655814] RPC: Registered named UNIX socket transport module. [ 0.657457] RPC: Registered udp transport module. [ 0.658634] RPC: Registered tcp transport module. [ 0.660000] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.661994] NET: Registered protocol family 44 [ 0.663676] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.665201] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.667102] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.669372] PCI: CLS 0 bytes, default 64 [ 0.671732] Unpacking initramfs... [ 2.015768] debug: unmapping init [mem 0xffff8a96fcc64000-0xffff8a96fffcffff] [ 2.019686] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.022179] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.025198] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.481948] Initialise system trusted keyrings [ 2.483753] Key type blacklist registered [ 2.485717] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.495405] zbud: loaded [ 2.498406] *** VALIDATE nfs *** [ 2.499737] *** VALIDATE nfs4 *** [ 2.501408] pstore: using deflate compression [ 2.504827] Platform Keyring initialized [ 2.583562] NET: Registered protocol family 38 [ 2.584911] Key type asymmetric registered [ 2.586024] Asymmetric key parser 'x509' registered [ 2.587553] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.589925] io scheduler mq-deadline registered [ 2.591256] io scheduler kyber registered [ 2.593054] io scheduler bfq registered [ 2.594440] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.596658] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.598842] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.601075] ACPI: Power Button [PWRF] [ 2.604783] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.609370] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.623091] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.649265] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.676241] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.679784] Non-volatile memory driver v1.3 [ 2.681275] Linux agpgart interface v0.103 [ 2.704845] virtio_blk virtio1: [vda] 134024 512-byte logical blocks (68.6 MB/65.4 MiB) [ 2.707068] vda: detected capacity change from 0 to 68620288 [ 2.728013] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.730731] vdb: detected capacity change from 0 to 1073741824 [ 2.737939] libphy: Fixed MDIO Bus: probed [ 2.744285] usbcore: registered new interface driver usbserial_generic [ 2.746345] usbserial: USB Serial support registered for generic [ 2.748227] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.752487] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.753994] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.756033] mousedev: PS/2 mouse device common for all mice [ 2.758646] rtc_cmos 00:05: RTC can wake from S4 [ 2.760945] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.765978] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.766473] rtc_cmos 00:05: registered as rtc0 [ 2.771384] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.771489] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.777138] intel_pstate: CPU model not supported [ 2.779110] hid: raw HID events driver (C) Jiri Kosina [ 2.780921] usbcore: registered new interface driver usbhid [ 2.782675] usbhid: USB HID core driver [ 2.783942] drop_monitor: Initializing network drop monitor service [ 2.785889] Initializing XFRM netlink socket [ 2.789870] NET: Registered protocol family 10 [ 2.793563] Segment Routing with IPv6 [ 2.795769] NET: Registered protocol family 17 [ 2.797777] mpls_gso: MPLS GSO support [ 2.803255] RAS: Correctable Errors collector initialized. [ 2.805721] AVX version of gcm_enc/dec engaged. [ 2.807667] AES CTR mode by8 optimization enabled [ 2.871237] sched_clock: Marking stable (2871215320, 0)->(3715761753, -844546433) [ 2.874734] registered taskstats version 1 [ 2.876739] Loading compiled-in X.509 certificates [ 2.878744] zswap: loaded using pool lzo/zbud [ 2.897357] Key type big_key registered [ 2.906727] Key type encrypted registered [ 2.908247] ima: No TPM chip found, activating TPM-bypass! [ 2.909903] ima: Allocated hash algorithm: sha1 [ 2.911356] ima: No architecture policies found [ 2.913087] evm: Initialising EVM extended attributes: [ 2.914545] evm: security.selinux [ 2.915522] evm: security.ima [ 2.916465] evm: security.capability [ 2.917612] evm: HMAC attrs: 0x1 [ 2.920131] rtc_cmos 00:05: setting system clock to 2026-01-02 02:45:25 UTC (1767321925) [ 2.927124] debug: unmapping init [mem 0xffffffffa4803000-0xffffffffa49fffff] [ 2.930691] debug: unmapping init [mem 0xffffffffa3582000-0xffffffffa3858fff] [ 2.937078] Write protecting the kernel read-only data: 28672k [ 2.939523] debug: unmapping init [mem 0xffffffffa1c03000-0xffffffffa1dfffff] [ 2.941409] debug: unmapping init [mem 0xffffffffa2514000-0xffffffffa25fffff] [ 2.967656] 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) [ 2.976399] systemd[1]: Detected virtualization kvm. [ 2.978321] systemd[1]: Detected architecture x86-64. [ 2.979993] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.006863] systemd[1]: No hostname configured. [ 3.009569] systemd[1]: Set hostname to . [ 3.012646] random: systemd: uninitialized urandom read (16 bytes read) [ 3.016673] systemd[1]: Initializing machine ID from random generator. [ 3.134324] random: systemd: uninitialized urandom read (16 bytes read) [ 3.136952] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 3.141377] random: systemd: uninitialized urandom read (16 bytes read) [ 3.143251] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 3.147786] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Timers. [ OK ] Reached target Slices. [ OK ] Listening on Journal Socket. Starting Create Volatile Files and Directories... [ OK ] Started Memstrack Anylazing Service. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Paths. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Local Encrypted Volumes. Starting Setup Virtual Console... Starting Apply Kernel Variables... [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. 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... [ 3.674330] device-mapper: uevent: version 1.0.3 [ 3.676132] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 4.358741] virtio_net virtio0 ens2: renamed from eth0 [ 4.384075] random: fast init done [ 4.414300] scsi host0: ata_piix [ 4.438235] scsi host1: ata_piix [ 4.439952] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.442502] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.784161] dracut-initqueue[587]: RTNETLINK answers: File exists [ 9.424880] random: crng init done [ 9.426933] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 9.725695] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Timers. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Swap. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Apply Kernel Variables. [ 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... [ 10.872087] printk: systemd: 26 output lines suppressed due to ratelimiting [ 11.117666] SELinux: Disabled at runtime. [ 11.177820] 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) [ 11.183464] systemd[1]: Detected virtualization kvm. [ 11.184693] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 11.647635] systemd[1]: initrd-switch-root.service: Succeeded. [ 11.652356] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 11.657747] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 11.662281] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 11.664910] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 11.670562] systemd[1]: Starting Journal Service... Starting Journal Service... [ 11.674350] systemd[1]: proc-sys-fs-binfmt_misc.automount: Refusing to start, unit to trigger not loaded. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on RPCbind Server Activation Socket. Starting Apply Kernel Variables... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Reached target rpc_pipefs.target. Mounting Kernel Debug File System... [ OK ] Started Dispatch Password Requests to Console Directory Watch. Starting Remount Root and Kernel File Systems... Activating swap /dev/disk/by-label/SWAP... [ OK ] Listening on initctl Compatibility Named Pipe. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Listening on udev Control Socket. [ OK ] Stopped target Initrd Root File System. [ OK ] Created slice system-getty.slice. Mounting Huge Pages File System... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ 11.783257] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Mounting POSIX Message Queue File System... [ OK ] Listening on udev Kernel Socket. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. Starting udev Coldplug all Devices... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Reached target RPC Port Mapper. [ OK ] Started Journal Service. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted Kernel Debug File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... Starting Configure read-only root support... 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. [ 12.021611] 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. [ 12.364383] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.403516] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.474179] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 12.483425] EDAC sbridge: Ver: 1.1.2 [ 13.530717] Key type dns_resolver registered [ 13.834882] NFS: Registering the id_resolver key type [ 13.836866] Key type id_resolver registered [ 13.838477] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Mark the need to relabel after reboot... Starting Rebuild Dynamic Linker Cache... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Reached target sshd-keygen.target. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started D-Bus System Message Bus. Starting Login Service... Starting Network Manager... [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started irqbalance daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting Network Manager Wait Online... [ OK ] Started Login Service. [ OK ] Started OpenSSH server daemon. Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ 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. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. [ OK ] Started Crash recovery kernel arming. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg426-client login: [ 64.642102] libcfs: loading out-of-tree module taints kernel. [ 64.946029] Key type ._llcrypt registered [ 64.950614] Key type .llcrypt registered [ 65.666629] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 65.700876] alg: No test for adler32 (adler32-zlib) [ 67.476638] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 68.650831] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 70.535320] Key type lgssc registered [ 72.402950] Lustre: Echo OBD driver; http://www.lustre.org/ [ 305.902016] hrtimer: interrupt took 14737884 ns [ 391.671884] Lustre: Mounted lustre-client [ 396.597035] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 417.250305] Lustre: lustre-OST0000-osc-ffff8a97518ba000: disconnect after 23s idle [ 420.364576] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing check_logdir /tmp/testlogs/ [ 425.689386] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing yml_node [ 434.119272] Lustre: DEBUG MARKER: Client: 2.17.0.RC4 [ 436.942729] Lustre: DEBUG MARKER: MDS: 2.17.0.RC4 [ 440.100876] Lustre: DEBUG MARKER: OSS: 2.17.0.RC4 [ 441.751687] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Thu Jan 1 21:52:43 EST 2026 [ 463.268915] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 464.884335] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 466.893746] Lustre: DEBUG MARKER: === sanityn: start setup 21:53:07 (1767322387) === [ 468.043542] Lustre: Mounted lustre-client [ 472.837809] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing check_config_client /mnt/lustre [ 513.304591] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 526.824444] Lustre: DEBUG MARKER: === sanityn: finish setup 21:54:07 (1767322447) === [ 529.681889] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 21:54:10 (1767322450) [ 537.809440] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 21:54:18 (1767322458) [ 545.504364] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 21:54:26 (1767322466) [ 554.022220] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 21:54:34 (1767322474) [ 561.216743] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 21:54:42 (1767322482) [ 567.594223] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 21:54:48 (1767322488) [ 574.530708] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 21:54:55 (1767322495) [ 581.224070] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 21:55:02 (1767322502) [ 587.767945] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 21:55:09 (1767322509) [ 593.590415] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 21:55:14 (1767322514) [ 600.483432] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 21:55:21 (1767322521) [ 607.979462] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 21:55:29 (1767322529) [ 611.297225] Lustre: lustre-OST0000-osc-ffff8a97518ba000: disconnect after 23s idle [ 611.306063] Lustre: Skipped 1 previous similar message [ 613.987885] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 21:55:35 (1767322535) [ 621.420300] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 21:55:42 (1767322542) [ 621.535231] Lustre: lustre-OST0001-osc-ffff8a9746823800: disconnect after 20s idle [ 628.740237] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 21:55:49 (1767322549) [ 635.928922] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 21:55:57 (1767322557) [ 642.020092] Lustre: lustre-OST0000-osc-ffff8a97518ba000: disconnect after 22s idle [ 643.723232] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 21:56:04 (1767322564) [ 651.982960] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 21:56:12 (1767322572) [ 660.211541] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 21:56:21 (1767322581) [ 666.943680] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 21:56:28 (1767322588) [ 673.251668] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 21:56:34 (1767322594) [ 673.933336] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279517 file: /mnt/lustre/lockdir/lockfile=144115205289279516 [ 811.074536] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 21:58:52 (1767322732) [ 818.022616] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 21:58:59 (1767322739) [ 823.914044] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 21:59:05 (1767322745) [ 829.672149] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 21:59:11 (1767322751) [ 835.988656] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 21:59:17 (1767322757) [ 842.206871] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 21:59:23 (1767322763) [ 843.801733] Lustre: DEBUG MARKER: chmod [ 849.339975] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 21:59:30 (1767322770) [ 1578.975322] Lustre: lustre-OST0000-osc-ffff8a97518ba000: disconnect after 21s idle [ 1580.365888] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 22:11:41 (1767323501) [ 1701.855411] Lustre: lustre-OST0001-osc-ffff8a9746823800: disconnect after 20s idle [ 1701.864965] Lustre: Skipped 3 previous similar messages [ 1755.160905] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 22:14:36 (1767323676) [ 1842.350018] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 22:16:04 (1767323764) [ 1946.706783] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 22:17:48 (1767323868) [ 1970.496542] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 22:18:12 (1767323892) [ 1975.152512] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 22:18:16 (1767323896) [ 1975.917462] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1975.979898] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1976.032936] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1976.092358] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1976.146587] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1976.186061] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1976.244708] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1976.285758] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1976.329423] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1976.402110] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1976.478277] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1976.545364] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1976.592349] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1976.641242] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1976.694401] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1976.763841] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1976.812375] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1976.867767] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1976.938109] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1977.007166] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1977.076398] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1977.122513] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1977.193484] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1977.277240] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1977.334420] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1977.388145] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1977.452838] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1977.526297] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1977.577327] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1977.642976] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1977.717328] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1977.757741] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1977.837490] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1977.888340] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1977.945265] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1977.995523] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1978.040112] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1978.096530] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1978.141915] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1978.191673] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1978.251919] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1978.297482] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1978.366151] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1978.433321] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1978.495910] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1978.543413] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1978.583843] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1978.627056] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1978.679732] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1978.712616] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1978.768972] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1978.851660] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1978.928261] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1979.005474] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1979.048496] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1979.119266] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1979.204598] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1979.268538] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1979.326673] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1979.385766] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1979.431866] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1979.480232] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1979.535049] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1979.578028] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1979.616189] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1979.653868] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1979.702697] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1979.755274] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1979.815652] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1979.869892] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1979.941428] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1979.983640] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1980.037209] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1980.117139] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1980.174474] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1980.236163] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1980.310269] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1980.366230] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1980.427754] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1980.477594] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1980.539119] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1980.592768] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1980.675460] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1980.742598] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1980.827541] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1980.900067] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1980.971149] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1981.072156] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1981.144838] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1981.229335] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1981.302805] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1981.389082] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1981.454524] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1981.501301] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1981.572126] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1981.643620] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1981.724864] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1981.788238] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1981.836032] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1981.884543] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1981.925776] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1981.964664] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1982.014997] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1982.061540] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1982.101572] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1982.153433] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1982.220256] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1982.302513] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1982.351410] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1982.405668] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1982.458201] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1982.505529] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1982.572929] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1982.637968] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1982.697569] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1982.744314] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1982.792775] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1982.866202] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1982.938318] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1982.995981] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1983.051166] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1983.117434] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1983.174395] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1983.255497] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1983.329516] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1983.384075] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1983.435341] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1983.494430] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1983.549181] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1983.601065] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1983.653222] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1983.719684] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1983.791658] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1983.863770] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1983.972705] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1984.025450] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1984.089067] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1984.153855] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1984.216981] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1984.296266] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1984.362770] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1984.460611] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1984.551881] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1984.620345] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1984.692461] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1984.744068] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1984.794538] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1984.842897] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1984.899533] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1984.954428] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1985.003621] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1985.054593] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1985.104314] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1985.150483] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1985.205597] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1985.293351] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1985.367697] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1985.405867] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1985.468552] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1985.528567] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1985.588403] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1985.660155] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1985.711363] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1985.759477] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1985.852151] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1985.914700] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1985.969136] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1986.011584] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1986.070347] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1986.125789] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1986.186252] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1986.235480] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1986.300231] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1986.355941] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1986.431196] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1986.505829] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1986.551757] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1986.590067] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1986.632585] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1986.689437] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1986.742336] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1986.789176] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1986.853745] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1986.906757] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1986.946476] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1986.989173] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1987.036681] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1987.090595] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1987.160288] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1987.223488] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1987.275080] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1987.324257] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1987.408218] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1987.452639] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1987.496436] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1987.540649] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1987.602360] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1987.662321] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1987.700922] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1987.739566] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1987.796502] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1987.843926] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1987.882976] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1987.916098] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1987.961862] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1987.998096] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1988.050750] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1988.114872] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1988.167656] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1988.246437] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1988.302681] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1988.357329] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1988.411151] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1988.466126] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1988.522276] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1988.589314] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1988.672496] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1988.757658] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1988.798190] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1988.838818] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1988.875622] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1988.923694] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1988.979113] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1989.022700] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1989.075169] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1989.133399] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1989.191104] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1989.270935] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1989.324060] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1989.386122] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1989.451128] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1989.520453] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1989.573472] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1989.624340] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1989.663338] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1989.708721] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1989.768121] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1989.850706] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1989.920024] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1989.999317] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1990.064608] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1990.167201] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1990.218336] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1990.265249] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1990.316761] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1990.400910] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1990.457167] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1990.497229] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1990.542070] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1990.604348] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1990.664105] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1990.713915] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1990.753314] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1990.815386] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1990.879080] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1990.947493] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1991.029027] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1991.095310] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1991.194837] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1991.253906] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1991.323520] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1991.378738] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1991.433773] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1991.495378] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1991.559093] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1991.616660] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1991.667134] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1991.735595] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1991.794460] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1991.870606] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1991.936448] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1991.997426] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1992.044938] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1992.093410] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1992.142959] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1992.189314] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1992.236966] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1992.288495] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1992.338572] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1992.416134] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1992.460840] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1992.509586] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1992.556638] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1992.604497] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1992.663545] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1992.700364] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1992.745075] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1992.819563] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1992.861410] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1992.915497] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1992.971888] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1993.044771] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1993.093316] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1993.158833] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1993.212707] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1993.275640] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1993.328205] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1993.381406] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1993.439182] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1993.489410] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1993.541375] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1993.591396] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1993.653739] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1993.696218] Lustre: lustre-OST0000-osc-ffff8a9746823800: disconnect after 23s idle [ 1993.716882] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1993.783987] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1993.847345] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1993.926320] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1993.994512] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1994.047798] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1994.122544] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1994.184883] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1994.249233] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1994.337871] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1994.404509] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1994.481980] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1994.546768] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1994.604063] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1994.644142] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1994.687133] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1994.720546] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1994.759507] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1994.799554] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1994.833262] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1994.877779] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1994.940942] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1994.976505] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1995.008449] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1995.035991] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1995.074352] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1995.114656] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1995.155328] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1995.187650] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1995.241229] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1995.283784] rw_seq_cst_vs_d (34889): drop_caches: 3 [ 1998.818102] Lustre: lustre-OST0000-osc-ffff8a97518ba000: disconnect after 22s idle [ 1999.828307] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 22:18:41 (1767323921) [ 2000.142520] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2000.180196] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2000.276688] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2000.314835] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2000.337701] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2000.425150] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2000.469184] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2000.501905] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2000.580893] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2000.603438] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2000.817498] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2000.853714] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2000.899056] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2000.991573] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2001.267215] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2001.314116] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2001.511425] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2001.634798] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2001.926795] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2001.952179] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2001.984699] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2002.086645] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2002.125101] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2002.181441] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2002.212573] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2002.248528] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2002.279177] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2002.307099] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2002.339867] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2002.366266] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2002.398289] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2002.522234] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2002.554284] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2003.017795] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2003.239704] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2003.277417] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2003.374501] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2003.454715] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2003.537334] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2003.605825] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2003.655662] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2003.692753] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2003.766025] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2003.890075] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2003.918100] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2004.034854] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2004.292774] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2004.329641] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2004.448829] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2004.477622] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2004.516673] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2004.621574] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2004.662107] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2004.687435] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2004.706265] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2004.921950] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2004.938809] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2005.060629] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2005.090142] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2005.203580] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2005.338037] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2005.391403] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2005.503841] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2005.604067] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2005.688471] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2005.851444] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2005.892508] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2005.935216] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2005.967609] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2006.097663] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2006.200679] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2006.321744] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2006.362357] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2006.790000] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2006.995297] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2007.109683] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2007.208302] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2007.252118] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2007.312850] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2007.550721] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2007.648645] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2007.685190] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2007.716464] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2007.795477] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2007.834250] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2007.943850] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2008.029585] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2008.072920] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2008.112132] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2008.305233] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2008.442284] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2008.606201] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2008.656909] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2008.792872] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2008.831841] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2008.952464] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2009.088980] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2009.221328] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2009.285624] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2009.312110] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2009.347215] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2009.377654] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2009.422443] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2009.536806] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2009.575189] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2009.750330] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2009.804890] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2009.851875] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2010.272926] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2010.427439] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2010.482817] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2010.606514] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2010.787244] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2011.111271] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2011.165607] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2011.281704] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2011.500739] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2011.599858] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2011.667358] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2011.855658] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2011.920755] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2012.072097] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2012.096329] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2012.500678] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2012.574903] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2012.628201] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2012.839218] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2012.918694] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2013.027148] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2013.135852] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2013.169571] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2013.319116] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2013.354609] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2013.470533] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2013.699626] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2013.819591] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2013.869380] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2013.976579] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2014.022464] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2014.064618] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2014.128709] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2014.175761] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2014.357488] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2014.467904] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2014.664350] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2014.698708] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2014.737724] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2014.770145] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2014.801341] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2014.916984] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2014.968606] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2015.014807] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2015.058732] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2015.087546] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2015.190196] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2015.324513] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2015.392814] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2015.619129] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2015.685984] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2015.741502] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2015.831611] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2015.925741] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2016.130716] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2016.367820] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2016.485939] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2016.557057] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2016.601996] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2016.739679] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2016.778547] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2016.882273] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2016.994958] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2017.033547] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2017.170767] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2017.291596] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2017.420700] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2017.458910] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2017.543463] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2017.617740] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2017.763477] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2017.840358] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2018.194880] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2018.351849] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2018.587940] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2018.693864] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2018.738878] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2018.821664] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2018.950235] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2019.127422] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2019.236145] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2019.280453] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2019.296047] Lustre: lustre-OST0001-osc-ffff8a9746823800: disconnect after 24s idle [ 2019.394720] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2019.533820] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2019.608459] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2019.650329] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2019.755754] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2019.788348] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2019.823586] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2019.862746] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2019.928521] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2019.972945] rw_seq_cst_vs_d (35479): drop_caches: 3 [ 2025.385179] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 22:19:07 (1767323947) [ 2030.090822] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 22:19:11 (1767323951) [ 2034.887478] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 22:19:16 (1767323956) [ 2053.960511] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 22:19:35 (1767323975) [ 2054.289143] bash (37960): drop_caches: 3 [ 2057.481541] bash (37960): drop_caches: 3 [ 2060.562710] bash (37960): drop_caches: 3 [ 2063.702702] bash (37960): drop_caches: 3 [ 2066.793951] bash (37960): drop_caches: 3 [ 2069.862231] bash (37960): drop_caches: 3 [ 2073.115613] bash (37960): drop_caches: 3 [ 2076.439325] bash (37960): drop_caches: 3 [ 2079.517255] bash (37960): drop_caches: 3 [ 2080.736392] Lustre: lustre-OST0000-osc-ffff8a9746823800: disconnect after 23s idle [ 2080.743276] Lustre: Skipped 1 previous similar message [ 2082.640812] bash (37960): drop_caches: 3 [ 2086.739777] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 22:20:08 (1767324008) [ 2095.227406] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 22:20:16 (1767324016) [ 2118.860912] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 22:20:40 (1767324040) [ 2124.540196] Lustre: DEBUG MARKER: loop 5 [ 2127.628707] Lustre: DEBUG MARKER: loop 10 [ 2130.829499] Lustre: DEBUG MARKER: loop 15 [ 2131.935289] Lustre: lustre-OST0001-osc-ffff8a97518ba000: disconnect after 20s idle [ 2133.766919] Lustre: DEBUG MARKER: loop 20 [ 2138.765423] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 22:21:00 (1767324060) [ 2143.760518] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 22:21:05 (1767324065) [ 2149.517396] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 22:21:11 (1767324071) [ 2172.896242] Lustre: lustre-OST0000-osc-ffff8a97518ba000: disconnect after 22s idle [ 2172.903396] Lustre: Skipped 4 previous similar messages [ 2215.735562] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 22:22:17 (1767324137) [ 2219.605425] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 22:22:21 (1767324141) [ 2223.430653] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 22:22:25 (1767324145) [ 2227.820946] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 22:22:29 (1767324149) [ 2232.303802] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 22:22:33 (1767324153) [ 2237.774619] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 22:22:39 (1767324159) [ 2243.804117] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 22:22:45 (1767324165) [ 2249.213529] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 2250.218581] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 22:22:51 (1767324171) [ 2256.925897] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 22:22:58 (1767324178) [ 2257.386862] Lustre: *** cfs_fail_loc=314, val=0*** [ 2258.463220] Lustre: *** cfs_fail_loc=314, val=0*** [ 2258.466574] Lustre: Skipped 2 previous similar messages [ 2262.917161] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 22:23:04 (1767324184) [ 2269.714954] Lustre: *** cfs_fail_loc=314, val=0*** [ 2269.784243] LustreError: lustre-OST0000-osc-ffff8a9746823800: operation ldlm_enqueue to node 192.168.204.126@tcp failed: rc = -107 [ 2269.788053] Lustre: lustre-OST0000-osc-ffff8a9746823800: Connection to lustre-OST0000 (at 192.168.204.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2269.800634] LustreError: lustre-OST0000-osc-ffff8a9746823800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2269.820900] Lustre: 2462:0:(llite_lib.c:4226:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.126@tcp:/lustre/fid: [0x200000403:0x27:0x0]// may get corrupted (rc -108) [ 2269.832923] LustreError: 48862:0:(ldlm_resource.c:981:ldlm_resource_complain()) lustre-OST0000-osc-ffff8a9746823800: namespace resource [0x280000401:0x38:0x0].0x0 (ffff8a9742cca900) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 2269.839796] Lustre: lustre-OST0000-osc-ffff8a9746823800: Connection restored to 192.168.204.126@tcp (at 192.168.204.126@tcp) [ 2274.232782] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 22:23:15 (1767324195) [ 2274.404248] LustreError: 49451:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 2277.423180] LustreError: 49451:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 2281.130682] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 22:23:22 (1767324202) [ 2286.220636] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 22:23:27 (1767324207) [ 2291.655488] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 2292.863676] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 22:23:34 (1767324214) [ 2293.835916] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 2294.772326] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 22:23:36 (1767324216) [ 2300.905926] Lustre: lustre-MDT0000-mdc-ffff8a97518ba000: Connection to lustre-MDT0000 (at 192.168.204.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2306.020386] LustreError: MGC192.168.204.126@tcp: Connection to MGS (at 192.168.204.126@tcp) was lost; in progress operations using this service will fail [ 2306.026592] Lustre: Evicted from MGS (at 192.168.204.126@tcp) after server handle changed from 0x4df732c5a58a9af3 to 0x4df732c5a5959d30 [ 2306.036403] Lustre: MGC192.168.204.126@tcp: Connection restored to 192.168.204.126@tcp (at 192.168.204.126@tcp) [ 2309.282399] Lustre: lustre-MDT0000-mdc-ffff8a97518ba000: Connection restored to 192.168.204.126@tcp (at 192.168.204.126@tcp) [ 2326.651726] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 22:24:08 (1767324248) [ 2357.793331] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 22:24:39 (1767324279) [ 2372.901360] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 22:24:54 (1767324294) [ 2427.853172] Lustre: lustre-OST0001-osc-ffff8a97518ba000: Connection to lustre-OST0001 (at 192.168.204.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2427.864270] Lustre: Skipped 1 previous similar message [ 2427.872364] LustreError: lustre-OST0001-osc-ffff8a97518ba000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 2427.884958] LustreError: lustre-OST0001-osc-ffff8a9746823800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 2427.885918] Lustre: lustre-OST0001-osc-ffff8a97518ba000: Connection restored to 192.168.204.126@tcp (at 192.168.204.126@tcp) [ 2427.899390] Lustre: Skipped 2 previous similar messages [ 2438.083835] Lustre: lustre-OST0000-osc-ffff8a97518ba000: Connection to lustre-OST0000 (at 192.168.204.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2438.098761] Lustre: Skipped 1 previous similar message [ 2438.106119] LustreError: lustre-OST0000-osc-ffff8a97518ba000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2438.124127] Lustre: lustre-OST0000-osc-ffff8a97518ba000: Connection restored to 192.168.204.126@tcp (at 192.168.204.126@tcp) [ 2449.376325] Lustre: lustre-OST0001-osc-ffff8a97518ba000: disconnect after 21s idle [ 2453.300674] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8a9746823800.ost_server_uuid 50 [ 2454.071717] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8a9746823800.ost_server_uuid in FULL state after 0 sec [ 2456.307968] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8a9746823800.ost_server_uuid 50 [ 2457.288033] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8a9746823800.ost_server_uuid in IDLE state after 0 sec [ 2460.035341] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8a9746823800.ost_server_uuid 50 [ 2460.933884] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8a9746823800.ost_server_uuid in FULL state after 0 sec [ 2462.930873] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8a9746823800.ost_server_uuid 50 [ 2463.938670] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8a9746823800.ost_server_uuid in IDLE state after 0 sec [ 2469.706856] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8a9746823800.ost_server_uuid 50 [ 2470.810365] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8a9746823800.ost_server_uuid in FULL state after 0 sec [ 2472.957758] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8a9746823800.ost_server_uuid 50 [ 2473.841921] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8a9746823800.ost_server_uuid in IDLE state after 0 sec [ 2474.796229] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 22:26:36 (1767324396) [ 2476.279100] Lustre: DEBUG MARKER: Race attempt 0 [ 2478.303544] Lustre: DEBUG MARKER: Wait for 59951 60050 for 60 sec... [ 2541.617116] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 22:27:43 (1767324463) [ 2547.141250] Lustre: DEBUG MARKER: start test - cycle (0) [ 2565.518400] Lustre: DEBUG MARKER: start test - cycle (1) [ 2583.464252] Lustre: DEBUG MARKER: start test - cycle (2) [ 2600.402833] Lustre: DEBUG MARKER: start test - cycle (3) [ 2618.112903] Lustre: DEBUG MARKER: start test - cycle (4) [ 2633.443703] Lustre: DEBUG MARKER: start test - cycle (5) [ 2648.629037] Lustre: DEBUG MARKER: start test - cycle (6) [ 2664.731205] Lustre: DEBUG MARKER: start test - cycle (7) [ 2682.795779] Lustre: DEBUG MARKER: start test - cycle (8) [ 2700.366920] Lustre: DEBUG MARKER: start test - cycle (9) [ 2715.225087] Lustre: DEBUG MARKER: start test - cycle (10) [ 2733.689296] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 22:30:55 (1767324655) [ 2741.215188] Lustre: lustre-OST0001-osc-ffff8a9746823800: disconnect after 21s idle [ 2741.218084] Lustre: Skipped 2 previous similar messages [ 2765.611820] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 22:31:27 (1767324687) [ 2769.242511] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 22:31:31 (1767324691) [ 2773.459825] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 22:31:35 (1767324695) [ 2777.765229] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 22:31:39 (1767324699) [ 2777.906841] Lustre: *** cfs_fail_loc=411, val=0*** [ 2780.699296] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 2781.355649] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 22:31:43 (1767324703) [ 2791.496821] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 22:31:53 (1767324713) [ 2801.276210] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 22:32:03 (1767324723) [ 2811.113287] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 22:32:13 (1767324733) [ 2820.445712] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 22:32:22 (1767324742) [ 2826.852679] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 22:32:28 (1767324748) [ 2833.062413] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 22:32:35 (1767324755) [ 2839.612739] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 22:32:41 (1767324761) [ 2846.355801] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 22:32:48 (1767324768) [ 2852.416945] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 22:32:54 (1767324774) [ 2859.380902] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 22:33:01 (1767324781) [ 2865.811891] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 22:33:07 (1767324787) [ 2872.752898] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 22:33:14 (1767324794) [ 3504.095272] Lustre: lustre-OST0000-osc-ffff8a9746823800: disconnect after 24s idle [ 3504.098380] Lustre: Skipped 6 previous similar messages [ 3648.936088] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 22:46:10 (1767325570) [ 3655.690283] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 22:46:17 (1767325577) [ 3661.855661] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 22:46:23 (1767325583) [ 3667.881342] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 22:46:29 (1767325589) [ 3673.889735] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 22:46:35 (1767325595) [ 3680.491692] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 22:46:42 (1767325602) [ 3686.908438] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 22:46:48 (1767325608) [ 3693.274099] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 22:46:55 (1767325615) [ 3699.615216] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 22:47:01 (1767325621) [ 3736.965351] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 22:47:38 (1767325658) [ 3743.529522] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 22:47:45 (1767325665) [ 3751.127393] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 22:47:53 (1767325673) [ 3757.928388] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 22:47:59 (1767325679) [ 3764.181107] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 22:48:06 (1767325686) [ 3769.617733] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 22:48:11 (1767325691) [ 3775.445369] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 22:48:17 (1767325697) [ 3781.425425] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 22:48:23 (1767325703) [ 3787.486360] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 22:48:29 (1767325709) [ 3839.366334] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 22:49:21 (1767325761) [ 4332.895728] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 22:57:34 (1767326254) [ 4338.055203] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 22:57:40 (1767326260) [ 4343.219556] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 22:57:45 (1767326265) [ 4348.396105] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 22:57:50 (1767326270) [ 4353.499496] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 22:57:55 (1767326275) [ 4358.484237] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 22:58:00 (1767326280) [ 4363.607958] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 22:58:05 (1767326285) [ 4368.951185] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 22:58:10 (1767326290) [ 4374.319335] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 22:58:16 (1767326296) [ 4379.801536] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 22:58:21 (1767326301) [ 4394.975119] Lustre: lustre-OST0000-osc-ffff8a97518ba000: disconnect after 20s idle [ 4394.977430] Lustre: Skipped 6 previous similar messages [ 4417.861890] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 22:58:59 (1767326339) [ 4423.175611] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 22:59:05 (1767326345) [ 4428.392836] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 22:59:10 (1767326350) [ 4433.598272] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 22:59:15 (1767326355) [ 4438.861782] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 22:59:20 (1767326360) [ 4444.032599] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 22:59:26 (1767326366) [ 4449.101432] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 22:59:31 (1767326371) [ 4453.801575] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 22:59:35 (1767326375) [ 4458.942657] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 22:59:41 (1767326381) [ 4887.222833] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 23:06:49 (1767326809) [ 4892.055497] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 23:06:54 (1767326814) [ 4897.156208] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 23:06:59 (1767326819) [ 4902.374200] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 23:07:04 (1767326824) [ 4907.367629] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 23:07:09 (1767326829) [ 4912.274239] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 23:07:14 (1767326834) [ 4917.342338] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 23:07:19 (1767326839) [ 4922.428214] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 23:07:24 (1767326844) [ 4927.463301] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 23:07:29 (1767326849) [ 4932.639080] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 23:07:34 (1767326854) [ 4938.012158] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 23:07:40 (1767326860) [ 4944.104273] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 23:07:46 (1767326866) [ 4949.160553] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 23:07:51 (1767326871) [ 4954.310767] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 23:07:56 (1767326876) [ 4959.511759] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 23:08:01 (1767326881) [ 4964.753734] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 23:08:06 (1767326886) [ 4970.613622] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 23:08:12 (1767326892) [ 4970.697725] LustreError: 25025:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 4972.783092] LustreError: 25025:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 4977.941482] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 23:08:20 (1767326900) [ 4982.042264] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 23:08:24 (1767326904) [ 4982.130743] LustreError: 240743:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4986.191069] LustreError: 240743:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 4986.196608] LustreError: 240743:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4990.255113] LustreError: 240743:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 4990.268361] LustreError: 240750:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4994.327111] LustreError: 240750:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 4996.462161] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 23:08:38 (1767326918) [ 5002.958662] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 23:08:45 (1767326925) [ 5006.100375] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 23:08:48 (1767326928) [ 5010.218420] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 23:08:52 (1767326932) [ 5019.615232] Lustre: lustre-OST0000-osc-ffff8a97518ba000: disconnect after 23s idle [ 5019.618940] Lustre: Skipped 1 previous similar message [ 5034.320703] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 23:09:16 (1767326956) [ 5041.608644] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 23:09:23 (1767326963) [ 5048.985963] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 23:09:31 (1767326971) [ 5061.656601] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 23:09:43 (1767326983) [ 5071.045316] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 23:09:53 (1767326993) [ 5083.895581] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 23:10:06 (1767327006) [ 5096.506353] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 23:10:18 (1767327018) [ 5110.724598] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 23:10:32 (1767327032) [ 5118.494174] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 23:10:40 (1767327040) [ 5147.007162] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 23:11:09 (1767327069) [ 5149.216885] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 5151.797226] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 23:11:13 (1767327073) [ 5154.232897] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 23:11:16 (1767327076) [ 5156.388815] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 23:11:18 (1767327078) [ 5158.392500] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 23:11:20 (1767327080) [ 5168.371705] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 23:11:30 (1767327090) [ 5181.694666] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 23:11:43 (1767327103) [ 5183.911749] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 23:11:46 (1767327106) [ 5186.145387] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 23:11:48 (1767327108) [ 5189.232840] LustreError: lustre-MDT0000-mdc-ffff8a97518ba000: operation ldlm_enqueue to node 192.168.204.126@tcp failed: rc = -35 [ 5189.235455] LustreError: Skipped 1 previous similar message [ 5192.211692] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 23:11:54 (1767327114) [ 5192.344137] LustreError: 2464:0:(osc_request.c:3093:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 5194.423094] LustreError: 2464:0:(osc_request.c:3093:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 5199.329239] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 23:12:01 (1767327121) [ 5243.429431] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 23:12:45 (1767327165) [ 5246.137976] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 23:12:48 (1767327168) [ 5249.937328] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 23:12:52 (1767327172) [ 5254.582125] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 23:12:56 (1767327176) [ 5259.197554] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 23:13:01 (1767327181) [ 5266.344781] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 23:13:08 (1767327188) [ 5273.584424] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 23:13:15 (1767327195) [ 5276.943790] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 23:13:19 (1767327199) [ 5280.500922] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 23:13:22 (1767327202) [ 5287.220563] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 23:13:29 (1767327209) [ 5331.786035] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 23:14:13 (1767327253) [ 5444.500384] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 23:16:06 (1767327366) [ 5557.377234] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 23:17:59 (1767327479) [ 5644.255154] Lustre: lustre-OST0001-osc-ffff8a97518ba000: disconnect after 20s idle [ 5644.258625] Lustre: Skipped 10 previous similar messages [ 5824.347525] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 23:22:26 (1767327746) [ 5855.414675] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 23:22:57 (1767327777) [ 5886.261385] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 23:23:28 (1767327808) [ 5912.829982] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 23:23:54 (1767327834) [ 5971.933813] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 23:24:54 (1767327894) [ 6025.370639] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 23:25:47 (1767327947) [ 6117.185613] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 23:27:19 (1767328039) [ 6118.230232] LustreError: 288086:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a97518ba000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6118.233389] LustreError: 288086:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6118.250093] Lustre: Unmounted lustre-client [ 6118.873837] LustreError: 288099:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9746823800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6118.877304] LustreError: 288099:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 6118.880342] LustreError: 288099:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 6118.883049] LustreError: 288099:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6118.901078] Lustre: Unmounted lustre-client [ 6194.158882] Lustre: Mounted lustre-client [ 6195.715525] Lustre: Mounted lustre-client [ 6196.799342] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 6256.243391] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 23:29:38 (1767328178) [ 6262.239153] Lustre: lustre-OST0000-osc-ffff8a9778830800: disconnect after 24s idle [ 6262.242147] Lustre: Skipped 9 previous similar messages [ 6264.004157] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 23:29:46 (1767328186) [ 6267.522411] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 23:29:49 (1767328189) [ 6318.713680] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 23:30:40 (1767328240) [ 6363.035213] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 23:31:25 (1767328285) [ 6366.840734] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 23:31:28 (1767328288) [ 6405.762527] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 23:32:07 (1767328327) [ 6417.583321] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 23:32:19 (1767328339) [ 6458.213986] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 23:33:00 (1767328380) [ 6461.121527] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 23:33:03 (1767328383) [ 6473.788809] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 23:33:15 (1767328395) [ 6477.416938] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 23:33:19 (1767328399) [ 6477.863162] LustreError: 308566:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x342:0x0]: rc = -5 [ 6477.866647] LustreError: 308566:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 6478.364781] LustreError: 308608:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x34c:0x0]: rc = -5 [ 6478.369362] LustreError: 308608:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 9 previous similar messages [ 6478.372188] LustreError: 308608:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 6478.376439] LustreError: 308608:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 9 previous similar messages [ 6479.389839] LustreError: 308702:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x368:0x0]: rc = -5 [ 6479.395577] LustreError: 308702:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 26 previous similar messages [ 6479.400053] LustreError: 308702:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 6479.404009] LustreError: 308702:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 26 previous similar messages [ 6481.392704] LustreError: 308394:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0xa4:0x0]: rc = -5 [ 6481.397261] LustreError: 308394:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 64 previous similar messages [ 6481.401509] LustreError: 308394:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 6481.404072] LustreError: 308394:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 64 previous similar messages [ 6485.485077] LustreError: 309253:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x407:0x0]: rc = -5 [ 6485.489640] LustreError: 309253:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 137 previous similar messages [ 6485.492384] LustreError: 309253:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 6485.494755] LustreError: 309253:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 137 previous similar messages [ 6587.537092] LustreError: 308394:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x123:0x0]: rc = -5 [ 6587.543336] LustreError: 308394:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 17 previous similar messages [ 6587.547972] LustreError: 308394:0:(llite_lib.c:3781:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [ 6587.552331] LustreError: 308394:0:(llite_lib.c:3781:ll_prep_inode()) Skipped 17 previous similar messages [ 6587.559931] LustreError: lustre-MDT0001-mdc-ffff8a9778830800: operation mds_getattr_lock to node 192.168.204.126@tcp failed: rc = -107 [ 6587.562808] Lustre: lustre-MDT0001-mdc-ffff8a9778830800: Connection to lustre-MDT0001 (at 192.168.204.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6587.567670] LustreError: lustre-MDT0001-mdc-ffff8a9778830800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [ 6587.573334] LustreError: 309290:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff8a9778830800: inode [0x240000bd0:0x11f:0x0] mdc close failed: rc = -108 [ 6587.577972] Lustre: lustre-MDT0001-mdc-ffff8a9778830800: Connection restored to 192.168.204.126@tcp (at 192.168.204.126@tcp) [ 6589.841101] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 23:35:11 (1767328511) [ 6592.028267] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 23:35:14 (1767328514) [ 6637.037859] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 23:35:59 (1767328559) [ 6637.557371] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 6638.165663] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 23:36:00 (1767328560) [ 6680.923096] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 23:36:43 (1767328603) [ 6683.021550] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 23:36:45 (1767328605) [ 6805.348285] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 23:38:47 (1767328727) [ 6812.943431] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 23:38:54 (1767328734) [ 6815.980282] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 23:38:57 (1767328737) [ 6998.465975] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 23:42:00 (1767328920) [ 7101.919209] Lustre: lustre-OST0000-osc-ffff8a9778830800: disconnect after 21s idle [ 7101.921187] Lustre: Skipped 5 previous similar messages [ 7180.748764] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 23:45:02 (1767329102) [ 7183.258690] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 23:45:05 (1767329105) [ 7192.233532] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 23:45:14 (1767329114) [ 7192.288988] Lustre: DEBUG MARKER: write [ 7192.303577] LustreError: 290732:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 7194.307460] Lustre: DEBUG MARKER: kill 372519 [ 7194.309021] LustreError: 372519:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 7197.407051] LustreError: 290732:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 7200.343101] LustreError: 372519:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 7202.294096] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 23:45:24 (1767329124) [ 7204.447983] LustreError: 373133:0:(rw.c:1957:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 7214.535075] LustreError: 373133:0:(rw.c:1957:ll_readpage()) cfs_fail_timeout id 1422 awake [ 7216.550618] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 23:45:38 (1767329138) [ 7216.630714] LustreError: 373721:0:(rw.c:2202:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 7218.711071] LustreError: 373721:0:(rw.c:2202:ll_readpage()) cfs_fail_timeout interrupted [ 7224.736251] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 23:45:46 (1767329146) [ 7225.268207] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 7225.844099] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 23:45:47 (1767329147) [ 7228.751801] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 23:45:50 (1767329150) [ 7231.283345] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 23:45:53 (1767329153) [ 7233.817922] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 23:45:55 (1767329155) [ 7236.020663] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 23:45:58 (1767329158) [ 7238.549754] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 23:46:00 (1767329160) [ 7241.124855] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 23:46:03 (1767329163) [ 7244.808902] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 23:46:06 (1767329166) [ 7247.822639] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 23:46:09 (1767329169) [ 7248.501166] Lustre: *** cfs_fail_loc=415, val=0*** [ 7255.491678] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 23:46:17 (1767329177) [ 7274.689729] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 23:46:36 (1767329196) [ 7274.774265] LustreError: 290198:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [ 7274.777620] LustreError: 290198:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [ 7279.775100] LustreError: 290198:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 7289.975100] LustreError: 290198:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 7289.976820] LustreError: 290198:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 7 previous similar messages [ 7292.267735] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 23:46:54 (1767329214) [ 7294.838579] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 23:46:56 (1767329216) [ 7297.206877] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 23:46:59 (1767329219) [ 7299.339743] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 23:47:01 (1767329221) [ 7303.595192] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 23:47:05 (1767329225) [ 7311.780857] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 23:47:13 (1767329233) [ 7311.907548] LustreError: 384477:0:(osc_request.c:2944:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 7311.909661] LustreError: 384477:0:(osc_request.c:2944:osc_build_rpc()) Skipped 6 previous similar messages [ 7315.967077] LustreError: 384477:0:(osc_request.c:2944:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 7315.968957] LustreError: 384477:0:(osc_request.c:2944:osc_build_rpc()) Skipped 1 previous similar message [ 7317.881262] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 23:47:20 (1767329240) [ 7318.983192] LustreError: 385188:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9778830800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7318.985811] LustreError: 385188:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 7318.989409] LustreError: 385188:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 7318.991741] LustreError: 385188:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 7319.012637] Lustre: Unmounted lustre-client [ 7319.512944] LustreError: 385208:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7319.514694] LustreError: 385208:0:(obd_class.h:479:obd_check_dev()) Skipped 11 previous similar messages [ 7319.522690] Lustre: Unmounted lustre-client [ 7320.022845] Lustre: DEBUG MARKER: Iteration 1 [ 7320.142789] LustreError: 385372:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7320.142808] LustreError: 385371:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7320.147651] LustreError: 385372:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7320.224782] Lustre: Mounted lustre-client [ 7320.792708] LustreError: 385484:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9750019800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7320.798483] LustreError: 385484:0:(lov_obd.c:783:lov_cleanup()) Skipped 5 previous similar messages [ 7320.803473] LustreError: 385485:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7320.805025] LustreError: 385485:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 7320.859086] Lustre: Unmounted lustre-client [ 7321.816050] Key type lgssc unregistered [ 7321.930589] LNet: 385726:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7321.933627] LNetError: 385726:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7321.942074] LNet: Removed LNI 192.168.204.26@tcp [ 7322.240112] Key type .llcrypt unregistered [ 7322.241150] Key type ._llcrypt unregistered [ 7322.645374] Key type ._llcrypt registered [ 7322.647294] Key type .llcrypt registered [ 7322.913690] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7322.919246] alg: No test for adler32 (adler32-zlib) [ 7323.894327] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7324.156698] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7325.767154] Key type lgssc registered [ 7326.298547] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7330.101805] Lustre: DEBUG MARKER: Iteration 2 [ 7330.199251] LustreError: 386555:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7330.199342] LustreError: 386556:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7330.203144] LustreError: 386555:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7331.255240] Lustre: Mounted lustre-client [ 7331.256438] Lustre: Skipped 1 previous similar message [ 7331.697709] LustreError: 386669:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9780416800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7331.700818] LustreError: 386668:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7331.700965] LustreError: 386669:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7331.741158] Lustre: Unmounted lustre-client [ 7332.611771] Key type lgssc unregistered [ 7332.713487] LNet: 386912:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7332.715521] LNetError: 386912:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7332.723731] LNet: Removed LNI 192.168.204.26@tcp [ 7332.945081] Key type .llcrypt unregistered [ 7332.945971] Key type ._llcrypt unregistered [ 7333.235539] Key type ._llcrypt registered [ 7333.236452] Key type .llcrypt registered [ 7333.409196] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7333.413979] alg: No test for adler32 (adler32-zlib) [ 7334.262994] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7334.336744] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7335.911108] Key type lgssc registered [ 7336.270353] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7339.499634] Lustre: DEBUG MARKER: Iteration 3 [ 7339.597393] LustreError: 387740:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7339.597423] LustreError: 387741:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7339.601042] LustreError: 387740:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7340.649761] Lustre: Mounted lustre-client [ 7341.089258] LustreError: 387853:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9745207800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7341.092474] LustreError: 387853:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7341.094539] LustreError: 387852:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7341.096606] LustreError: 387852:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7341.139390] Lustre: Unmounted lustre-client [ 7342.005341] Key type lgssc unregistered [ 7342.114515] LNet: 388095:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7342.116746] LNetError: 388095:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7342.123426] LNet: Removed LNI 192.168.204.26@tcp [ 7342.367130] Key type .llcrypt unregistered [ 7342.368225] Key type ._llcrypt unregistered [ 7342.691267] Key type ._llcrypt registered [ 7342.692328] Key type .llcrypt registered [ 7342.827222] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7342.831894] alg: No test for adler32 (adler32-zlib) [ 7343.685996] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7343.761870] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7345.343088] Key type lgssc registered [ 7345.672272] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7348.869152] Lustre: DEBUG MARKER: Iteration 4 [ 7348.987242] LustreError: 388925:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7348.987547] LustreError: 388926:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7348.990913] LustreError: 388925:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 7350.049684] Lustre: Mounted lustre-client [ 7350.051301] Lustre: Skipped 1 previous similar message [ 7350.482815] LustreError: 389036:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a976039b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7350.485630] LustreError: 389035:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7350.486912] LustreError: 389036:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7350.542255] Lustre: Unmounted lustre-client [ 7351.365167] Key type lgssc unregistered [ 7351.471347] LNet: 389278:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7351.473681] LNetError: 389278:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7351.482741] LNet: Removed LNI 192.168.204.26@tcp [ 7351.680097] Key type .llcrypt unregistered [ 7351.680961] Key type ._llcrypt unregistered [ 7351.928609] Key type ._llcrypt registered [ 7351.929606] Key type .llcrypt registered [ 7352.103349] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7352.107857] alg: No test for adler32 (adler32-zlib) [ 7352.950765] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7353.025769] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7354.599134] Key type lgssc registered [ 7354.937981] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7358.121179] Lustre: DEBUG MARKER: Iteration 5 [ 7358.211301] LustreError: 390106:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7358.211347] LustreError: 390107:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7358.215153] LustreError: 390106:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7359.261547] Lustre: Mounted lustre-client [ 7359.262435] Lustre: Skipped 1 previous similar message [ 7359.700284] LustreError: 390221:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9759598000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7359.702113] LustreError: 390220:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7359.703161] LustreError: 390221:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7359.771188] Lustre: Unmounted lustre-client [ 7360.615456] Key type lgssc unregistered [ 7360.724309] LNet: 390464:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7360.727642] LNetError: 390464:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7360.738778] LNet: Removed LNI 192.168.204.26@tcp [ 7360.971083] Key type .llcrypt unregistered [ 7360.971953] Key type ._llcrypt unregistered [ 7361.204439] Key type ._llcrypt registered [ 7361.205889] Key type .llcrypt registered [ 7361.400922] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7361.406536] alg: No test for adler32 (adler32-zlib) [ 7362.248870] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7362.326121] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7363.903120] Key type lgssc registered [ 7364.225689] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7367.606340] Lustre: DEBUG MARKER: Iteration 6 [ 7367.726378] LustreError: 391292:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7367.726419] LustreError: 391293:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7367.729893] LustreError: 391292:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 7368.779625] Lustre: Mounted lustre-client [ 7369.201780] LustreError: 391403:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9745050000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7369.207023] LustreError: 391403:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7369.246140] Lustre: Unmounted lustre-client [ 7370.087750] Key type lgssc unregistered [ 7370.192472] LNet: 391647:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7370.194462] LNetError: 391647:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7370.203748] LNet: Removed LNI 192.168.204.26@tcp [ 7370.413109] Key type .llcrypt unregistered [ 7370.414475] Key type ._llcrypt unregistered [ 7370.685869] Key type ._llcrypt registered [ 7370.691883] Key type .llcrypt registered [ 7370.869348] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7370.874842] alg: No test for adler32 (adler32-zlib) [ 7371.721845] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7371.798631] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7373.375082] Key type lgssc registered [ 7373.725890] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7376.988227] Lustre: DEBUG MARKER: Iteration 7 [ 7377.111314] LustreError: 392476:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7377.111362] LustreError: 392477:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7377.117271] LustreError: 392476:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7378.173975] Lustre: Mounted lustre-client [ 7378.681644] LustreError: 392590:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a975208b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7378.686640] LustreError: 392590:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7378.689367] LustreError: 392590:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7378.712945] Lustre: Unmounted lustre-client [ 7379.684384] Key type lgssc unregistered [ 7379.799512] LNet: 392833:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7379.801488] LNetError: 392833:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7379.810669] LNet: Removed LNI 192.168.204.26@tcp [ 7380.008165] Key type .llcrypt unregistered [ 7380.009207] Key type ._llcrypt unregistered [ 7380.334665] Key type ._llcrypt registered [ 7380.359482] Key type .llcrypt registered [ 7380.466077] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7380.472906] alg: No test for adler32 (adler32-zlib) [ 7381.323323] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7381.406035] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7382.991113] Key type lgssc registered [ 7383.339586] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7386.534614] Lustre: DEBUG MARKER: Iteration 8 [ 7386.624175] LustreError: 393661:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7386.626111] LustreError: 393662:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7386.628968] LustreError: 393661:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7387.679550] Lustre: Mounted lustre-client [ 7387.680477] Lustre: Skipped 1 previous similar message [ 7388.107360] LustreError: 393773:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9744dcf800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7388.109155] LustreError: 393774:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7388.110301] LustreError: 393773:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7388.174110] Lustre: Unmounted lustre-client [ 7388.175252] Lustre: Skipped 1 previous similar message [ 7389.043695] Key type lgssc unregistered [ 7389.149401] LNet: 394015:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7389.151384] LNetError: 394015:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7389.160676] LNet: Removed LNI 192.168.204.26@tcp [ 7389.367106] Key type .llcrypt unregistered [ 7389.368654] Key type ._llcrypt unregistered [ 7389.672334] Key type ._llcrypt registered [ 7389.673260] Key type .llcrypt registered [ 7389.802858] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7389.807346] alg: No test for adler32 (adler32-zlib) [ 7390.647933] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7390.720371] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7392.295167] Key type lgssc registered [ 7392.626979] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7395.746252] Lustre: DEBUG MARKER: Iteration 9 [ 7395.843773] LustreError: 394845:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7395.843821] LustreError: 394844:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7395.847701] LustreError: 394845:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7396.914384] Lustre: Mounted lustre-client [ 7396.915313] Lustre: Skipped 1 previous similar message [ 7397.334792] LustreError: 394959:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9742caf800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7397.336419] LustreError: 394960:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7397.337726] LustreError: 394959:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7397.378384] Lustre: Unmounted lustre-client [ 7398.211341] Key type lgssc unregistered [ 7398.319492] LNet: 395203:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7398.321428] LNetError: 395203:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7398.327725] LNet: Removed LNI 192.168.204.26@tcp [ 7398.524107] Key type .llcrypt unregistered [ 7398.524934] Key type ._llcrypt unregistered [ 7398.771879] Key type ._llcrypt registered [ 7398.773830] Key type .llcrypt registered [ 7398.945486] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7398.949892] alg: No test for adler32 (adler32-zlib) [ 7399.791430] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7399.863958] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7401.439104] Key type lgssc registered [ 7401.804088] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7405.136421] Lustre: DEBUG MARKER: Iteration 10 [ 7405.234423] LustreError: 396031:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7405.234727] LustreError: 396032:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7405.240479] LustreError: 396031:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7406.301384] Lustre: Mounted lustre-client [ 7406.302328] Lustre: Skipped 1 previous similar message [ 7406.728722] LustreError: 396143:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9747fdd000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7406.732222] LustreError: 396143:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7406.735349] LustreError: 396144:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7406.737885] LustreError: 396144:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 7406.756141] Lustre: Unmounted lustre-client [ 7407.724504] Key type lgssc unregistered [ 7407.845680] LNet: 396386:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7407.847703] LNetError: 396386:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7407.860353] LNet: Removed LNI 192.168.204.26@tcp [ 7408.098097] Key type .llcrypt unregistered [ 7408.099151] Key type ._llcrypt unregistered [ 7408.415139] Key type ._llcrypt registered [ 7408.420630] Key type .llcrypt registered [ 7408.641798] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7408.646413] alg: No test for adler32 (adler32-zlib) [ 7409.509891] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7409.587313] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7411.167246] Key type lgssc registered [ 7411.533186] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7414.779213] Lustre: DEBUG MARKER: Iteration 11 [ 7414.874666] LustreError: 397216:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7414.874733] LustreError: 397214:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7414.880151] LustreError: 397216:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7415.937814] Lustre: Mounted lustre-client [ 7416.382309] LustreError: 397330:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9780414000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7416.388604] LustreError: 397330:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7416.390358] LustreError: 397330:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7416.419670] Lustre: Unmounted lustre-client [ 7417.267339] Key type lgssc unregistered [ 7417.375448] LNet: 397571:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7417.377637] LNetError: 397571:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7417.389580] LNet: Removed LNI 192.168.204.26@tcp [ 7417.592103] Key type .llcrypt unregistered [ 7417.592987] Key type ._llcrypt unregistered [ 7417.867484] Key type ._llcrypt registered [ 7417.868480] Key type .llcrypt registered [ 7418.052613] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7418.056974] alg: No test for adler32 (adler32-zlib) [ 7418.909354] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7418.984995] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7420.567102] Key type lgssc registered [ 7420.906060] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7424.122887] Lustre: DEBUG MARKER: Iteration 12 [ 7424.219094] LustreError: 398399:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7424.219124] LustreError: 398400:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7424.225217] LustreError: 398399:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7425.282288] Lustre: Mounted lustre-client [ 7425.284153] Lustre: Skipped 1 previous similar message [ 7425.728771] LustreError: 398510:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9747fdd000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7425.732493] LustreError: 398510:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7425.733529] LustreError: 398511:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7425.789381] Lustre: Unmounted lustre-client [ 7426.660162] Key type lgssc unregistered [ 7426.768513] LNet: 398751:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7426.770513] LNetError: 398751:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7426.778786] LNet: Removed LNI 192.168.204.26@tcp [ 7426.979094] Key type .llcrypt unregistered [ 7426.980085] Key type ._llcrypt unregistered [ 7427.189926] Key type ._llcrypt registered [ 7427.190911] Key type .llcrypt registered [ 7427.358099] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7427.373568] alg: No test for adler32 (adler32-zlib) [ 7428.214859] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7428.288766] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7429.863136] Key type lgssc registered [ 7430.197990] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7433.400114] Lustre: DEBUG MARKER: Iteration 13 [ 7433.495106] LustreError: 399589:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7433.495228] LustreError: 399590:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7433.499280] LustreError: 399589:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7434.549686] Lustre: Mounted lustre-client [ 7434.550599] Lustre: Skipped 1 previous similar message [ 7434.976376] LustreError: 399703:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9747f43000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7434.978322] LustreError: 399702:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7434.979139] LustreError: 399703:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7435.053750] Lustre: Unmounted lustre-client [ 7435.054782] Lustre: Skipped 1 previous similar message [ 7435.905560] Key type lgssc unregistered [ 7436.020468] LNet: 399945:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7436.022422] LNetError: 399945:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7436.028748] LNet: Removed LNI 192.168.204.26@tcp [ 7436.234093] Key type .llcrypt unregistered [ 7436.235035] Key type ._llcrypt unregistered [ 7436.456481] Key type ._llcrypt registered [ 7436.457303] Key type .llcrypt registered [ 7436.666831] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7436.671222] alg: No test for adler32 (adler32-zlib) [ 7437.514995] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7437.587182] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7439.167106] Key type lgssc registered [ 7439.487062] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7442.527816] Lustre: DEBUG MARKER: Iteration 14 [ 7442.620359] LustreError: 400782:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7442.620389] LustreError: 400783:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7442.624157] LustreError: 400782:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7443.667824] Lustre: Mounted lustre-client [ 7444.118149] LustreError: 400895:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9746747800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7444.121520] LustreError: 400895:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7444.124130] LustreError: 400894:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7444.126068] LustreError: 400894:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7444.159060] Lustre: Unmounted lustre-client [ 7444.971233] Key type lgssc unregistered [ 7445.076564] LNet: 401136:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7445.078363] LNetError: 401136:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7445.089767] LNet: Removed LNI 192.168.204.26@tcp [ 7445.294159] Key type .llcrypt unregistered [ 7445.295576] Key type ._llcrypt unregistered [ 7445.522131] Key type ._llcrypt registered [ 7445.523060] Key type .llcrypt registered [ 7445.716936] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7445.721679] alg: No test for adler32 (adler32-zlib) [ 7446.564409] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7446.639650] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7448.215131] Key type lgssc registered [ 7448.540356] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7451.633512] Lustre: DEBUG MARKER: Iteration 15 [ 7451.727305] LustreError: 401965:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7451.727358] LustreError: 401964:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7451.730854] LustreError: 401965:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 7452.777517] Lustre: Mounted lustre-client [ 7453.217704] LustreError: 402079:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9771e63000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7453.219426] LustreError: 402078:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7453.220646] LustreError: 402079:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7453.277540] Lustre: Unmounted lustre-client [ 7454.092935] Key type lgssc unregistered [ 7454.192508] LNet: 402321:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7454.194901] LNetError: 402321:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7454.204756] LNet: Removed LNI 192.168.204.26@tcp [ 7454.405105] Key type .llcrypt unregistered [ 7454.406371] Key type ._llcrypt unregistered [ 7454.599458] Key type ._llcrypt registered [ 7454.600844] Key type .llcrypt registered [ 7454.812093] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7454.816659] alg: No test for adler32 (adler32-zlib) [ 7455.671215] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7455.748460] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7457.327100] Key type lgssc registered [ 7457.664902] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7461.135606] Lustre: DEBUG MARKER: Iteration 16 [ 7461.241278] LustreError: 403150:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7461.241310] LustreError: 403151:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7461.246823] LustreError: 403150:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 7462.315536] Lustre: Mounted lustre-client [ 7462.317212] Lustre: Skipped 1 previous similar message [ 7462.727053] LustreError: 403263:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9743cd1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7462.732234] LustreError: 403264:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7462.733813] LustreError: 403264:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7462.769979] Lustre: Unmounted lustre-client [ 7463.597503] Key type lgssc unregistered [ 7463.702380] LNet: 403505:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7463.704893] LNetError: 403505:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7463.713597] LNet: Removed LNI 192.168.204.26@tcp [ 7463.935084] Key type .llcrypt unregistered [ 7463.936066] Key type ._llcrypt unregistered [ 7464.180212] Key type ._llcrypt registered [ 7464.181307] Key type .llcrypt registered [ 7464.358601] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7464.363341] alg: No test for adler32 (adler32-zlib) [ 7465.210578] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7465.295074] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7466.871123] Key type lgssc registered [ 7467.237783] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7470.789510] Lustre: DEBUG MARKER: Iteration 17 [ 7470.901894] LustreError: 404334:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7470.902195] LustreError: 404333:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7470.905520] LustreError: 404334:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7471.948372] Lustre: Mounted lustre-client [ 7471.950059] Lustre: Skipped 1 previous similar message [ 7472.380783] LustreError: 404447:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9743cd3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7472.383873] LustreError: 404446:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7472.385152] LustreError: 404447:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7472.410411] Lustre: Unmounted lustre-client [ 7473.264474] Key type lgssc unregistered [ 7473.377457] LNet: 404689:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7473.379487] LNetError: 404689:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7473.389583] LNet: Removed LNI 192.168.204.26@tcp [ 7473.599090] Key type .llcrypt unregistered [ 7473.600184] Key type ._llcrypt unregistered [ 7473.869469] Key type ._llcrypt registered [ 7473.870414] Key type .llcrypt registered [ 7474.019857] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7474.024463] alg: No test for adler32 (adler32-zlib) [ 7474.880788] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7474.961395] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7476.543119] Key type lgssc registered [ 7476.873432] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7480.047862] Lustre: DEBUG MARKER: Iteration 18 [ 7480.142172] LustreError: 405518:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7480.142391] LustreError: 405519:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7480.146928] LustreError: 405518:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 7481.197144] Lustre: Mounted lustre-client [ 7481.624389] LustreError: 405629:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9747fde800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7481.627742] LustreError: 405628:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7481.629081] LustreError: 405629:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7481.660087] Lustre: Unmounted lustre-client [ 7482.491222] Key type lgssc unregistered [ 7482.597483] LNet: 405869:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7482.599422] LNetError: 405869:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7482.605596] LNet: Removed LNI 192.168.204.26@tcp [ 7482.795123] Key type .llcrypt unregistered [ 7482.796050] Key type ._llcrypt unregistered [ 7483.102229] Key type ._llcrypt registered [ 7483.103697] Key type .llcrypt registered [ 7483.225666] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7483.229874] alg: No test for adler32 (adler32-zlib) [ 7484.071900] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7484.147405] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7485.727135] Key type lgssc registered [ 7486.064764] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7489.231353] Lustre: DEBUG MARKER: Iteration 19 [ 7489.363746] LustreError: 406697:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7489.363837] LustreError: 406698:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7489.367809] LustreError: 406697:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7490.432154] Lustre: Mounted lustre-client [ 7490.858751] LustreError: 406811:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a974611f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7490.862090] LustreError: 406812:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7490.862609] LustreError: 406811:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7490.908326] Lustre: Unmounted lustre-client [ 7491.819196] Key type lgssc unregistered [ 7491.924419] LNet: 407053:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7491.927278] LNetError: 407053:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7491.937741] LNet: Removed LNI 192.168.204.26@tcp [ 7492.143101] Key type .llcrypt unregistered [ 7492.144129] Key type ._llcrypt unregistered [ 7492.373569] Key type ._llcrypt registered [ 7492.376655] Key type .llcrypt registered [ 7492.569809] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7492.576164] alg: No test for adler32 (adler32-zlib) [ 7493.422619] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7493.503475] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7495.079150] Key type lgssc registered [ 7495.420992] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7498.574116] Lustre: DEBUG MARKER: Iteration 20 [ 7498.677729] LustreError: 407881:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7498.677781] LustreError: 407882:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7498.682451] LustreError: 407881:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7499.740775] Lustre: Mounted lustre-client [ 7499.741958] Lustre: Skipped 1 previous similar message [ 7500.180666] LustreError: 407997:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9758abc800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7500.182550] LustreError: 407998:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7500.184269] LustreError: 407997:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7500.239488] Lustre: Unmounted lustre-client [ 7501.076520] Key type lgssc unregistered [ 7501.183454] LNet: 408240:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7501.185402] LNetError: 408240:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7501.193766] LNet: Removed LNI 192.168.204.26@tcp [ 7501.399084] Key type .llcrypt unregistered [ 7501.400096] Key type ._llcrypt unregistered [ 7501.651216] Key type ._llcrypt registered [ 7501.652465] Key type .llcrypt registered [ 7501.822402] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7501.826959] alg: No test for adler32 (adler32-zlib) [ 7502.669937] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7502.742902] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7504.319151] Key type lgssc registered [ 7504.655421] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7508.084102] Lustre: DEBUG MARKER: Iteration 21 [ 7508.174305] LustreError: 409069:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7508.174342] LustreError: 409070:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7508.178963] LustreError: 409069:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 7509.229402] Lustre: Mounted lustre-client [ 7509.230932] Lustre: Skipped 1 previous similar message [ 7509.673645] LustreError: 409184:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9775367000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7509.676455] LustreError: 409184:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7509.676841] LustreError: 409183:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7509.680202] LustreError: 409183:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7509.709533] Lustre: Unmounted lustre-client [ 7510.523984] Key type lgssc unregistered [ 7510.628509] LNet: 409427:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7510.630511] LNetError: 409427:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7510.636670] LNet: Removed LNI 192.168.204.26@tcp [ 7510.845087] Key type .llcrypt unregistered [ 7510.845907] Key type ._llcrypt unregistered [ 7511.086492] Key type ._llcrypt registered [ 7511.087403] Key type .llcrypt registered [ 7511.261791] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7511.267537] alg: No test for adler32 (adler32-zlib) [ 7512.115737] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7512.188986] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7513.767138] Key type lgssc registered [ 7514.148596] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7517.586101] Lustre: DEBUG MARKER: Iteration 22 [ 7517.707290] LustreError: 410257:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7517.707314] LustreError: 410258:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7517.711422] LustreError: 410257:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7518.759443] Lustre: Mounted lustre-client [ 7519.183690] LustreError: 410370:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a975160a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7519.186818] LustreError: 410370:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7519.186918] LustreError: 410371:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7519.190980] LustreError: 410371:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7519.254086] Lustre: Unmounted lustre-client [ 7520.262093] Key type lgssc unregistered [ 7520.361404] LNet: 410612:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7520.364411] LNetError: 410612:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7520.372723] LNet: Removed LNI 192.168.204.26@tcp [ 7520.575084] Key type .llcrypt unregistered [ 7520.575980] Key type ._llcrypt unregistered [ 7520.886206] Key type ._llcrypt registered [ 7520.887103] Key type .llcrypt registered [ 7521.007941] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7521.012549] alg: No test for adler32 (adler32-zlib) [ 7521.885428] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7521.974720] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7523.567147] Key type lgssc registered [ 7523.962226] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7528.060284] Lustre: DEBUG MARKER: Iteration 23 [ 7528.190122] LustreError: 411441:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7528.190155] LustreError: 411440:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7528.194329] LustreError: 411441:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7529.259302] Lustre: Mounted lustre-client [ 7529.261253] Lustre: Skipped 1 previous similar message [ 7529.765403] LustreError: 411554:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a976039d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7529.769486] LustreError: 411554:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7529.769982] LustreError: 411553:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7529.774155] LustreError: 411553:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7529.852319] Lustre: Unmounted lustre-client [ 7531.040117] Key type lgssc unregistered [ 7531.199667] LNet: 411797:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7531.204756] LNetError: 411797:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7531.218171] LNet: Removed LNI 192.168.204.26@tcp [ 7531.605156] Key type .llcrypt unregistered [ 7531.607210] Key type ._llcrypt unregistered [ 7531.971661] Key type ._llcrypt registered [ 7531.976477] Key type .llcrypt registered [ 7532.205578] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7532.211458] alg: No test for adler32 (adler32-zlib) [ 7533.128372] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7533.253477] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7534.871226] Key type lgssc registered [ 7535.546182] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7540.124222] Lustre: DEBUG MARKER: Iteration 24 [ 7540.274675] LustreError: 412626:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7540.274942] LustreError: 412627:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7540.278342] LustreError: 412626:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7541.350053] Lustre: Mounted lustre-client [ 7541.351054] Lustre: Skipped 1 previous similar message [ 7541.885108] LustreError: 412740:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a976039c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7541.888265] LustreError: 412740:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7541.888950] LustreError: 412741:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7541.935839] Lustre: Unmounted lustre-client [ 7543.312135] Key type lgssc unregistered [ 7543.473976] LNet: 412984:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7543.478547] LNetError: 412984:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7543.490144] LNet: Removed LNI 192.168.204.26@tcp [ 7543.897195] Key type .llcrypt unregistered [ 7543.899260] Key type ._llcrypt unregistered [ 7544.431044] Key type ._llcrypt registered [ 7544.431929] Key type .llcrypt registered [ 7544.498191] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7544.503174] alg: No test for adler32 (adler32-zlib) [ 7545.388849] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7545.478419] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7547.071112] Key type lgssc registered [ 7547.464178] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7551.171819] Lustre: DEBUG MARKER: Iteration 25 [ 7551.293123] LustreError: 413814:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7551.293164] LustreError: 413813:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7551.297498] LustreError: 413814:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7552.351974] Lustre: Mounted lustre-client [ 7552.779947] LustreError: 413925:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9746a52000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7552.782392] LustreError: 413924:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7552.783542] LustreError: 413925:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7552.822647] Lustre: Unmounted lustre-client [ 7553.971386] Key type lgssc unregistered [ 7554.125545] LNet: 414167:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7554.129430] LNetError: 414167:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7554.141703] LNet: Removed LNI 192.168.204.26@tcp [ 7554.540204] Key type .llcrypt unregistered [ 7554.542733] Key type ._llcrypt unregistered [ 7554.875907] Key type ._llcrypt registered [ 7554.877037] Key type .llcrypt registered [ 7555.173360] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7555.178128] alg: No test for adler32 (adler32-zlib) [ 7556.136756] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7556.281925] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7557.895264] Key type lgssc registered [ 7558.539177] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7562.405609] Lustre: DEBUG MARKER: Iteration 26 [ 7562.549347] LustreError: 414996:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7562.549378] LustreError: 414995:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7562.554562] LustreError: 414996:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7563.628116] Lustre: Mounted lustre-client [ 7563.629412] Lustre: Skipped 1 previous similar message [ 7564.055443] LustreError: 415109:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9775364000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7564.061665] LustreError: 415109:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7564.064442] LustreError: 415109:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7564.100060] Lustre: Unmounted lustre-client [ 7565.370973] Key type lgssc unregistered [ 7565.524559] LNet: 415352:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7565.529383] LNetError: 415352:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7565.541507] LNet: Removed LNI 192.168.204.26@tcp [ 7565.871150] Key type .llcrypt unregistered [ 7565.873786] Key type ._llcrypt unregistered [ 7566.167929] Key type ._llcrypt registered [ 7566.169378] Key type .llcrypt registered [ 7566.340975] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7566.346860] alg: No test for adler32 (adler32-zlib) [ 7567.226928] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7567.338494] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7568.951225] Key type lgssc registered [ 7569.604687] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7574.863916] Lustre: DEBUG MARKER: Iteration 27 [ 7575.040272] LustreError: 416181:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7575.040351] LustreError: 416180:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7575.045678] LustreError: 416181:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7576.111716] Lustre: Mounted lustre-client [ 7576.113748] Lustre: Skipped 1 previous similar message [ 7576.705920] LustreError: 416294:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a974518f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7576.710500] LustreError: 416293:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7576.710555] LustreError: 416294:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7576.755996] Lustre: Unmounted lustre-client [ 7577.818719] Key type lgssc unregistered [ 7577.933772] LNet: 416537:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7577.936718] LNetError: 416537:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7577.945681] LNet: Removed LNI 192.168.204.26@tcp [ 7578.186108] Key type .llcrypt unregistered [ 7578.187127] Key type ._llcrypt unregistered [ 7578.510794] Key type ._llcrypt registered [ 7578.512023] Key type .llcrypt registered [ 7578.760065] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7578.765122] alg: No test for adler32 (adler32-zlib) [ 7579.685954] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7579.830889] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7581.455299] Key type lgssc registered [ 7582.178087] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7587.265568] Lustre: DEBUG MARKER: Iteration 28 [ 7587.459104] LustreError: 417366:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7587.460611] LustreError: 417365:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7587.466666] LustreError: 417366:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 7588.533648] Lustre: Mounted lustre-client [ 7588.536288] Lustre: Skipped 1 previous similar message [ 7589.131199] LustreError: 417481:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a977ed77000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7589.133160] LustreError: 417480:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7589.133899] LustreError: 417481:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7589.185210] Lustre: Unmounted lustre-client [ 7589.186148] Lustre: Skipped 1 previous similar message [ 7590.522814] Key type lgssc unregistered [ 7590.678141] LNet: 417723:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7590.682574] LNetError: 417723:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7590.695589] LNet: Removed LNI 192.168.204.26@tcp [ 7591.074185] Key type .llcrypt unregistered [ 7591.076513] Key type ._llcrypt unregistered [ 7591.365074] Key type ._llcrypt registered [ 7591.366267] Key type .llcrypt registered [ 7591.689239] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7591.694279] alg: No test for adler32 (adler32-zlib) [ 7592.610901] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7592.720076] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7594.303189] Key type lgssc registered [ 7594.979995] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7600.464253] Lustre: DEBUG MARKER: Iteration 29 [ 7600.614688] LustreError: 418551:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7600.614782] LustreError: 418552:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7600.618896] LustreError: 418551:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7601.679486] Lustre: Mounted lustre-client [ 7602.186573] LustreError: 418667:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9771619000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7602.188483] LustreError: 418666:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7602.189572] LustreError: 418667:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7602.244747] Lustre: Unmounted lustre-client [ 7603.611818] Key type lgssc unregistered [ 7603.762735] LNet: 418911:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7603.766518] LNetError: 418911:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7603.777300] LNet: Removed LNI 192.168.204.26@tcp [ 7604.147165] Key type .llcrypt unregistered [ 7604.149258] Key type ._llcrypt unregistered [ 7604.462215] Key type ._llcrypt registered [ 7604.463193] Key type .llcrypt registered [ 7604.602453] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7604.606895] alg: No test for adler32 (adler32-zlib) [ 7605.484938] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7605.601662] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7607.207177] Key type lgssc registered [ 7607.638782] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7611.953318] Lustre: DEBUG MARKER: Iteration 30 [ 7612.111318] LustreError: 419739:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7612.111361] LustreError: 419740:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7612.116160] LustreError: 419739:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7613.187358] Lustre: Mounted lustre-client [ 7613.693905] LustreError: 419857:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a974cb47000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7613.697081] LustreError: 419857:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7613.699515] LustreError: 419856:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7613.702107] LustreError: 419856:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7613.747665] Lustre: Unmounted lustre-client [ 7615.072953] Key type lgssc unregistered [ 7615.228598] LNet: 420098:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7615.232304] LNetError: 420098:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7615.243776] LNet: Removed LNI 192.168.204.26@tcp [ 7615.638165] Key type .llcrypt unregistered [ 7615.640425] Key type ._llcrypt unregistered [ 7615.978204] Key type ._llcrypt registered [ 7615.981113] Key type .llcrypt registered [ 7616.220677] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7616.225299] alg: No test for adler32 (adler32-zlib) [ 7617.130390] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7617.218673] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7618.799135] Key type lgssc registered [ 7619.526803] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7624.191964] Lustre: DEBUG MARKER: Iteration 31 [ 7624.364384] LustreError: 420928:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7624.364453] LustreError: 420927:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7624.368898] LustreError: 420928:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7625.435372] Lustre: Mounted lustre-client [ 7625.436545] Lustre: Skipped 1 previous similar message [ 7626.020953] LustreError: 421036:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9746a50000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7626.025858] LustreError: 421036:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7626.027351] LustreError: 421036:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7626.065489] Lustre: Unmounted lustre-client [ 7627.441181] Key type lgssc unregistered [ 7627.576591] LNet: 421280:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7627.579575] LNetError: 421280:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7627.595328] LNet: Removed LNI 192.168.204.26@tcp [ 7627.980160] Key type .llcrypt unregistered [ 7627.982229] Key type ._llcrypt unregistered [ 7628.357106] Key type ._llcrypt registered [ 7628.358043] Key type .llcrypt registered [ 7628.661426] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7628.665997] alg: No test for adler32 (adler32-zlib) [ 7629.548423] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7629.666375] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7631.279224] Key type lgssc registered [ 7631.985138] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7637.270767] Lustre: DEBUG MARKER: Iteration 32 [ 7637.427234] LustreError: 422109:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7637.427580] LustreError: 422110:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7637.433234] LustreError: 422109:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7638.490633] Lustre: Mounted lustre-client [ 7638.493076] Lustre: Skipped 1 previous similar message [ 7639.101589] LustreError: 422221:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9747ed6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7639.106435] LustreError: 422221:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7639.173791] Lustre: Unmounted lustre-client [ 7640.621104] Key type lgssc unregistered [ 7640.764980] LNet: 422465:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7640.770398] LNetError: 422465:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7640.782381] LNet: Removed LNI 192.168.204.26@tcp [ 7641.187175] Key type .llcrypt unregistered [ 7641.189461] Key type ._llcrypt unregistered [ 7641.584572] Key type ._llcrypt registered [ 7641.585572] Key type .llcrypt registered [ 7641.820641] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7641.825855] alg: No test for adler32 (adler32-zlib) [ 7642.786253] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7642.926672] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7644.543227] Key type lgssc registered [ 7645.248586] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7651.003696] Lustre: DEBUG MARKER: Iteration 33 [ 7651.167357] LustreError: 423294:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7651.167401] LustreError: 423295:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7651.171763] LustreError: 423294:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7652.237578] Lustre: Mounted lustre-client [ 7652.878236] LustreError: 423407:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9775363800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7652.880407] LustreError: 423406:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7652.881281] LustreError: 423407:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7652.929597] Lustre: Unmounted lustre-client [ 7654.390932] Key type lgssc unregistered [ 7654.545126] LNet: 423649:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7654.550392] LNetError: 423649:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7654.562429] LNet: Removed LNI 192.168.204.26@tcp [ 7654.936233] Key type .llcrypt unregistered [ 7654.938806] Key type ._llcrypt unregistered [ 7655.239949] Key type ._llcrypt registered [ 7655.258206] Key type .llcrypt registered [ 7655.531092] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7655.536058] alg: No test for adler32 (adler32-zlib) [ 7656.470363] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7656.598678] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7658.215238] Key type lgssc registered [ 7658.933167] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7664.306865] Lustre: DEBUG MARKER: Iteration 34 [ 7664.449478] LustreError: 424477:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7664.449518] LustreError: 424478:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7664.453895] LustreError: 424477:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7665.520826] Lustre: Mounted lustre-client [ 7666.087907] LustreError: 424592:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9747ed3800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7666.091437] LustreError: 424592:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7666.091481] LustreError: 424593:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 7666.095816] LustreError: 424593:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7666.148111] Lustre: Unmounted lustre-client [ 7667.133732] Key type lgssc unregistered [ 7667.251529] LNet: 424834:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7667.256821] LNetError: 424834:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7667.265530] LNet: Removed LNI 192.168.204.26@tcp [ 7667.521108] Key type .llcrypt unregistered [ 7667.522492] Key type ._llcrypt unregistered [ 7667.898454] Key type ._llcrypt registered [ 7667.899856] Key type .llcrypt registered [ 7668.145405] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7668.150508] alg: No test for adler32 (adler32-zlib) [ 7669.029082] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7669.113056] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7670.695112] Key type lgssc registered [ 7671.212674] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7675.777561] Lustre: DEBUG MARKER: Iteration 35 [ 7675.922692] LustreError: 425661:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7675.923689] LustreError: 425663:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7675.926285] LustreError: 425661:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7676.990136] Lustre: Mounted lustre-client [ 7677.605448] LustreError: 425774:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9751d28000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7677.607943] LustreError: 425774:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7677.608423] LustreError: 425775:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7677.611380] LustreError: 425775:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7677.678270] Lustre: Unmounted lustre-client [ 7679.016604] Key type lgssc unregistered [ 7679.173922] LNet: 426018:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7679.178590] LNetError: 426018:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7679.189944] LNet: Removed LNI 192.168.204.26@tcp [ 7679.550121] Key type .llcrypt unregistered [ 7679.551428] Key type ._llcrypt unregistered [ 7679.958321] Key type ._llcrypt registered [ 7679.959265] Key type .llcrypt registered [ 7680.178059] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7680.183409] alg: No test for adler32 (adler32-zlib) [ 7681.130713] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7681.265084] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7682.879268] Key type lgssc registered [ 7683.632930] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7689.120526] Lustre: DEBUG MARKER: Iteration 36 [ 7689.263725] LustreError: 426847:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7689.263776] LustreError: 426848:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7689.267659] LustreError: 426847:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7690.337957] Lustre: Mounted lustre-client [ 7690.340105] Lustre: Skipped 1 previous similar message [ 7690.929660] LustreError: 426964:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a975937c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7690.934566] LustreError: 426964:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7690.936582] LustreError: 426964:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7690.980105] Lustre: Unmounted lustre-client [ 7692.466807] Key type lgssc unregistered [ 7692.638186] LNet: 427209:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7692.643402] LNetError: 427209:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7692.654687] LNet: Removed LNI 192.168.204.26@tcp [ 7693.076160] Key type .llcrypt unregistered [ 7693.079130] Key type ._llcrypt unregistered [ 7693.432301] Key type ._llcrypt registered [ 7693.443631] Key type .llcrypt registered [ 7693.668383] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7693.672898] alg: No test for adler32 (adler32-zlib) [ 7694.575862] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7694.689751] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7696.311174] Key type lgssc registered [ 7696.985863] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7701.651855] Lustre: DEBUG MARKER: Iteration 37 [ 7701.779520] LustreError: 428037:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7701.779574] LustreError: 428038:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7701.783342] LustreError: 428037:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7702.841518] Lustre: Mounted lustre-client [ 7702.841519] Lustre: Mounted lustre-client [ 7703.360405] LustreError: 428150:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a975829b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7703.363485] LustreError: 428150:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7703.364523] LustreError: 428151:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7703.427061] Lustre: Unmounted lustre-client [ 7704.494980] Key type lgssc unregistered [ 7704.608882] LNet: 428393:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7704.611668] LNetError: 428393:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7704.619479] LNet: Removed LNI 192.168.204.26@tcp [ 7704.933144] Key type .llcrypt unregistered [ 7704.935275] Key type ._llcrypt unregistered [ 7705.351227] Key type ._llcrypt registered [ 7705.352193] Key type .llcrypt registered [ 7705.531553] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7705.538939] alg: No test for adler32 (adler32-zlib) [ 7706.391073] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7706.471785] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7708.055247] Key type lgssc registered [ 7708.472732] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7712.447415] Lustre: DEBUG MARKER: Iteration 38 [ 7712.568956] LustreError: 429223:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7712.569438] LustreError: 429224:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7712.572667] LustreError: 429223:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7713.635483] Lustre: Mounted lustre-client [ 7713.636534] Lustre: Skipped 1 previous similar message [ 7714.136743] LustreError: 429336:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9758299000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7714.139754] LustreError: 429335:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7714.141437] LustreError: 429336:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7714.183158] Lustre: Unmounted lustre-client [ 7715.105644] Key type lgssc unregistered [ 7715.224760] LNet: 429579:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7715.227186] LNetError: 429579:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7715.234721] LNet: Removed LNI 192.168.204.26@tcp [ 7715.466115] Key type .llcrypt unregistered [ 7715.467530] Key type ._llcrypt unregistered [ 7715.709081] Key type ._llcrypt registered [ 7715.709982] Key type .llcrypt registered [ 7715.976124] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7715.981335] alg: No test for adler32 (adler32-zlib) [ 7716.860229] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7716.942358] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7718.527185] Key type lgssc registered [ 7719.030732] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7722.751682] Lustre: DEBUG MARKER: Iteration 39 [ 7722.866174] LustreError: 430408:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7722.867579] LustreError: 430409:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7722.870091] LustreError: 430408:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7723.936953] Lustre: Mounted lustre-client [ 7723.937900] Lustre: Skipped 1 previous similar message [ 7724.441491] LustreError: 430525:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9744dcb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7724.446612] LustreError: 430525:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7724.448383] LustreError: 430525:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7724.490132] Lustre: Unmounted lustre-client [ 7725.548118] Key type lgssc unregistered [ 7725.660858] LNet: 430768:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7725.663233] LNetError: 430768:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7725.671377] LNet: Removed LNI 192.168.204.26@tcp [ 7725.908114] Key type .llcrypt unregistered [ 7725.909197] Key type ._llcrypt unregistered [ 7726.217520] Key type ._llcrypt registered [ 7726.218525] Key type .llcrypt registered [ 7726.452279] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7726.456785] alg: No test for adler32 (adler32-zlib) [ 7727.322167] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7727.418131] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7729.007185] Key type lgssc registered [ 7729.424607] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7733.200425] Lustre: DEBUG MARKER: Iteration 40 [ 7733.308361] LustreError: 431598:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7733.310380] LustreError: 431597:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7733.312328] LustreError: 431598:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 7734.370241] Lustre: Mounted lustre-client [ 7734.899718] LustreError: 431712:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a97595de000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7734.901661] LustreError: 431713:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7734.902849] LustreError: 431712:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7734.934935] Lustre: Unmounted lustre-client [ 7736.268392] Key type lgssc unregistered [ 7736.416206] LNet: 431955:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7736.420875] LNetError: 431955:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7736.437169] LNet: Removed LNI 192.168.204.26@tcp [ 7736.758104] Key type .llcrypt unregistered [ 7736.759079] Key type ._llcrypt unregistered [ 7736.991113] Key type ._llcrypt registered [ 7736.992056] Key type .llcrypt registered [ 7737.255473] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7737.267105] alg: No test for adler32 (adler32-zlib) [ 7738.178065] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7738.305950] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7739.911134] Key type lgssc registered [ 7740.505505] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7744.911252] Lustre: DEBUG MARKER: Iteration 41 [ 7745.042118] LustreError: 432797:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7745.042143] LustreError: 432798:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7745.045916] LustreError: 432797:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 7746.110368] Lustre: Mounted lustre-client [ 7746.111366] Lustre: Skipped 1 previous similar message [ 7746.675920] LustreError: 432911:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9751d2e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7746.679290] LustreError: 432911:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7746.679777] LustreError: 432912:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7746.682976] LustreError: 432912:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7746.721138] Lustre: Unmounted lustre-client [ 7747.797181] Key type lgssc unregistered [ 7747.942242] LNet: 433156:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7747.946751] LNetError: 433156:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7747.958702] LNet: Removed LNI 192.168.204.26@tcp [ 7748.293171] Key type .llcrypt unregistered [ 7748.295756] Key type ._llcrypt unregistered [ 7748.664898] Key type ._llcrypt registered [ 7748.666405] Key type .llcrypt registered [ 7748.918385] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7748.923566] alg: No test for adler32 (adler32-zlib) [ 7749.806597] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7749.899924] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7751.495212] Key type lgssc registered [ 7751.873199] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7755.561046] Lustre: DEBUG MARKER: Iteration 42 [ 7755.677183] LustreError: 433986:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7755.677499] LustreError: 433987:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7755.682498] LustreError: 433986:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7756.745089] Lustre: Mounted lustre-client [ 7756.746899] Lustre: Skipped 1 previous similar message [ 7757.212923] LustreError: 434098:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9746740000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7757.216203] LustreError: 434097:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7757.216338] LustreError: 434098:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7757.261159] Lustre: Unmounted lustre-client [ 7758.702487] Key type lgssc unregistered [ 7758.863167] LNet: 434342:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7758.868201] LNetError: 434342:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7758.879168] LNet: Removed LNI 192.168.204.26@tcp [ 7759.286177] Key type .llcrypt unregistered [ 7759.288173] Key type ._llcrypt unregistered [ 7759.593301] Key type ._llcrypt registered [ 7759.594185] Key type .llcrypt registered [ 7759.832335] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7759.858902] alg: No test for adler32 (adler32-zlib) [ 7760.717334] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7760.800348] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7762.383195] Key type lgssc registered [ 7762.910356] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7767.111723] Lustre: DEBUG MARKER: Iteration 43 [ 7767.211688] LustreError: 435168:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7767.212372] LustreError: 435170:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7767.215427] LustreError: 435168:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7768.254993] Lustre: Mounted lustre-client [ 7768.674718] LustreError: 435281:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9780411800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7768.677396] LustreError: 435280:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7768.678375] LustreError: 435281:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7768.737435] Lustre: Unmounted lustre-client [ 7769.811119] Key type lgssc unregistered [ 7769.971569] LNet: 435521:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7769.976524] LNetError: 435521:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7769.986570] LNet: Removed LNI 192.168.204.26@tcp [ 7770.316234] Key type .llcrypt unregistered [ 7770.317331] Key type ._llcrypt unregistered [ 7770.550607] Key type ._llcrypt registered [ 7770.551630] Key type .llcrypt registered [ 7770.741927] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7770.747082] alg: No test for adler32 (adler32-zlib) [ 7771.630436] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7771.735664] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7773.319199] Key type lgssc registered [ 7773.932102] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7777.576706] Lustre: DEBUG MARKER: Iteration 44 [ 7777.699296] LustreError: 436350:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7777.699345] LustreError: 436351:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7777.703801] LustreError: 436350:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7778.753535] Lustre: Mounted lustre-client [ 7779.180731] LustreError: 436466:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9760399000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7779.182789] LustreError: 436465:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7779.183375] LustreError: 436466:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7779.217236] Lustre: Unmounted lustre-client [ 7780.443396] Key type lgssc unregistered [ 7780.578996] LNet: 436709:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7780.583472] LNetError: 436709:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7780.595242] LNet: Removed LNI 192.168.204.26@tcp [ 7780.911161] Key type .llcrypt unregistered [ 7780.913305] Key type ._llcrypt unregistered [ 7781.188526] Key type ._llcrypt registered [ 7781.189753] Key type .llcrypt registered [ 7781.372430] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7781.376809] alg: No test for adler32 (adler32-zlib) [ 7782.244157] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7782.354047] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7783.959163] Key type lgssc registered [ 7784.565495] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7789.405844] Lustre: DEBUG MARKER: Iteration 45 [ 7789.542390] LustreError: 437538:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7789.542826] LustreError: 437539:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7789.546344] LustreError: 437538:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7790.597470] Lustre: Mounted lustre-client [ 7791.040898] LustreError: 437651:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a974fd2a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7791.045930] LustreError: 437651:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7791.047937] LustreError: 437651:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7791.103525] Lustre: Unmounted lustre-client [ 7792.256888] Key type lgssc unregistered [ 7792.414965] LNet: 437893:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7792.419500] LNetError: 437893:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7792.430669] LNet: Removed LNI 192.168.204.26@tcp [ 7792.798153] Key type .llcrypt unregistered [ 7792.799554] Key type ._llcrypt unregistered [ 7793.085865] Key type ._llcrypt registered [ 7793.087031] Key type .llcrypt registered [ 7793.275394] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7793.280052] alg: No test for adler32 (adler32-zlib) [ 7794.134747] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7794.236601] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7795.823195] Key type lgssc registered [ 7796.381193] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7800.744364] Lustre: DEBUG MARKER: Iteration 46 [ 7800.843520] LustreError: 438722:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7800.846803] LustreError: 438723:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7800.848812] LustreError: 438722:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7801.899727] Lustre: Mounted lustre-client [ 7802.335063] LustreError: 438836:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9750363800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7802.338402] LustreError: 438836:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7802.338699] LustreError: 438837:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7802.387483] Lustre: Unmounted lustre-client [ 7803.549851] Key type lgssc unregistered [ 7803.675618] LNet: 439078:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7803.677645] LNetError: 439078:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7803.687792] LNet: Removed LNI 192.168.204.26@tcp [ 7803.982146] Key type .llcrypt unregistered [ 7803.983964] Key type ._llcrypt unregistered [ 7804.269209] Key type ._llcrypt registered [ 7804.274165] Key type .llcrypt registered [ 7804.451858] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7804.456551] alg: No test for adler32 (adler32-zlib) [ 7805.326221] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7805.432760] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7807.015116] Key type lgssc registered [ 7807.348791] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7811.316672] Lustre: DEBUG MARKER: Iteration 47 [ 7811.421205] LustreError: 439906:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7811.424758] LustreError: 439907:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7811.426638] LustreError: 439906:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7812.478750] Lustre: Mounted lustre-client [ 7812.902252] LustreError: 440019:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9744dc8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7812.905857] LustreError: 440018:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 7812.906231] LustreError: 440019:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7812.964068] Lustre: Unmounted lustre-client [ 7814.191631] Key type lgssc unregistered [ 7814.366549] LNet: 440260:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7814.371059] LNetError: 440260:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7814.381268] LNet: Removed LNI 192.168.204.26@tcp [ 7814.757190] Key type .llcrypt unregistered [ 7814.759327] Key type ._llcrypt unregistered [ 7815.045414] Key type ._llcrypt registered [ 7815.046579] Key type .llcrypt registered [ 7815.235990] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7815.240333] alg: No test for adler32 (adler32-zlib) [ 7816.127773] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7816.247550] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7817.855206] Key type lgssc registered [ 7818.424418] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7822.531692] Lustre: DEBUG MARKER: Iteration 48 [ 7822.665308] LustreError: 441088:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7822.666396] LustreError: 441089:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7822.669179] LustreError: 441088:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7823.723079] Lustre: Mounted lustre-client [ 7824.141932] LustreError: 441197:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9746741000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7824.144624] LustreError: 441198:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7824.145356] LustreError: 441197:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7824.179061] Lustre: Unmounted lustre-client [ 7825.410984] Key type lgssc unregistered [ 7825.565536] LNet: 441440:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7825.570166] LNetError: 441440:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7825.581449] LNet: Removed LNI 192.168.204.26@tcp [ 7825.895226] Key type .llcrypt unregistered [ 7825.897172] Key type ._llcrypt unregistered [ 7826.216242] Key type ._llcrypt registered [ 7826.217151] Key type .llcrypt registered [ 7826.345822] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7826.350110] alg: No test for adler32 (adler32-zlib) [ 7827.225838] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7827.321256] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7828.911330] Key type lgssc registered [ 7829.382593] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7833.790369] Lustre: DEBUG MARKER: Iteration 49 [ 7833.933686] LustreError: 442268:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7833.933956] LustreError: 442269:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7833.938324] LustreError: 442268:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7834.998576] Lustre: Mounted lustre-client [ 7835.000879] Lustre: Skipped 1 previous similar message [ 7835.560411] LustreError: 442383:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9742cae000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7835.564664] LustreError: 442383:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7835.568307] LustreError: 442384:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 7835.570099] LustreError: 442384:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 7835.600548] Lustre: Unmounted lustre-client [ 7837.065473] Key type lgssc unregistered [ 7837.237982] LNet: 442626:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7837.242479] LNetError: 442626:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7837.256204] LNet: Removed LNI 192.168.204.26@tcp [ 7837.669164] Key type .llcrypt unregistered [ 7837.671055] Key type ._llcrypt unregistered [ 7838.059372] Key type ._llcrypt registered [ 7838.060700] Key type .llcrypt registered [ 7838.243816] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7838.248848] alg: No test for adler32 (adler32-zlib) [ 7839.147056] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7839.257173] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7840.879130] Key type lgssc registered [ 7841.540369] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7846.407975] Lustre: DEBUG MARKER: Iteration 50 [ 7846.549730] LustreError: 443454:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 7846.550783] LustreError: 443455:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 7846.553500] LustreError: 443454:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7847.612426] Lustre: Mounted lustre-client [ 7847.613570] Lustre: Skipped 1 previous similar message [ 7848.035525] LustreError: 443569:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9744184800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7848.037408] LustreError: 443568:0:(obd_class.h:479:obd_check_dev()) Device 11 not setup [ 7848.038526] LustreError: 443569:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7848.102248] Lustre: Unmounted lustre-client [ 7849.447793] Key type lgssc unregistered [ 7849.614641] LNet: 443812:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7849.619507] LNetError: 443812:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7849.630537] LNet: Removed LNI 192.168.204.26@tcp [ 7849.996159] Key type .llcrypt unregistered [ 7849.998478] Key type ._llcrypt unregistered [ 7850.265524] Key type ._llcrypt registered [ 7850.266474] Key type .llcrypt registered [ 7850.452729] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7850.457370] alg: No test for adler32 (adler32-zlib) [ 7851.323192] Lustre: Lustre: Build Version: 2.17.0_RC4_2_g50ac801 [ 7851.423635] LNet: Added LNI 192.168.204.26@tcp [8/256/0/180] [ 7853.031197] Key type lgssc registered [ 7853.674733] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7858.711935] Lustre: Mounted lustre-client [ 7861.567914] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 23:56:23 (1767329783) [ 7877.599216] Lustre: 445164:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767329784/real 1767329784] req@ffff8a977467b800 x1853179584063360/t0(0) o36->lustre-MDT0000-mdc-ffff8a9743cd6800@192.168.204.126@tcp:12/10 lens 496/440 e 0 to 1 dl 1767329800 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7877.610814] Lustre: lustre-MDT0000-mdc-ffff8a9743cd6800: Connection to lustre-MDT0000 (at 192.168.204.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7877.622353] Lustre: lustre-MDT0000-mdc-ffff8a9743cd6800: Connection restored to 192.168.204.126@tcp (at 192.168.204.126@tcp) [ 7893.983219] Lustre: 445164:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767329800/real 1767329800] req@ffff8a977467b800 x1853179584063360/t0(0) o36->lustre-MDT0000-mdc-ffff8a9743cd6800@192.168.204.126@tcp:12/10 lens 496/440 e 0 to 1 dl 1767329816 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7893.997242] Lustre: lustre-MDT0000-mdc-ffff8a9743cd6800: Connection to lustre-MDT0000 (at 192.168.204.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7894.010910] Lustre: lustre-MDT0000-mdc-ffff8a9743cd6800: Connection restored to 192.168.204.126@tcp (at 192.168.204.126@tcp) [ 7910.367235] Lustre: 445164:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767329816/real 1767329816] req@ffff8a977467b800 x1853179584063360/t0(0) o36->lustre-MDT0000-mdc-ffff8a9743cd6800@192.168.204.126@tcp:12/10 lens 496/440 e 0 to 1 dl 1767329832 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7910.383762] Lustre: lustre-MDT0000-mdc-ffff8a9743cd6800: Connection to lustre-MDT0000 (at 192.168.204.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7910.404217] Lustre: lustre-MDT0000-mdc-ffff8a9743cd6800: Connection restored to 192.168.204.126@tcp (at 192.168.204.126@tcp) [ 7925.727180] Lustre: 445164:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767329832/real 1767329832] req@ffff8a977467b800 x1853179584063360/t0(0) o36->lustre-MDT0000-mdc-ffff8a9743cd6800@192.168.204.126@tcp:12/10 lens 496/440 e 0 to 1 dl 1767329848 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7925.743966] Lustre: lustre-MDT0000-mdc-ffff8a9743cd6800: Connection to lustre-MDT0000 (at 192.168.204.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7925.760160] Lustre: lustre-MDT0000-mdc-ffff8a9743cd6800: Connection restored to 192.168.204.126@tcp (at 192.168.204.126@tcp) [ 7942.111167] Lustre: 445164:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767329848/real 1767329848] req@ffff8a977467b800 x1853179584063360/t0(0) o36->lustre-MDT0000-mdc-ffff8a9743cd6800@192.168.204.126@tcp:12/10 lens 496/440 e 0 to 1 dl 1767329864 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 7942.124749] Lustre: lustre-MDT0000-mdc-ffff8a9743cd6800: Connection to lustre-MDT0000 (at 192.168.204.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 7942.142207] Lustre: lustre-MDT0000-mdc-ffff8a9743cd6800: Connection restored to 192.168.204.126@tcp (at 192.168.204.126@tcp) [ 7942.954283] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 23:57:44 (1767329864) [ 7950.010249] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 23:57:52 (1767329872) [ 7954.762456] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 23:57:56 (1767329876) [ 7957.955888] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 23:57:59 (1767329879) [ 7967.934368] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 23:58:09 (1767329889) [ 7985.553064] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 23:58:27 (1767329907) [ 7988.074888] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 23:58:30 (1767329910) [ 7988.149272] LustreError: 449950:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [ 7990.231144] LustreError: 449950:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [ 7993.113184] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 23:58:35 (1767329915) [ 8011.743220] Lustre: 444004:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767329918/real 1767329918] req@ffff8a9766421880 x1853179585106688/t0(0) o4->lustre-OST0000-osc-ffff8a9743cd6800@192.168.204.126@tcp:6/4 lens 4584/448 e 0 to 1 dl 1767329934 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 8011.743289] Lustre: lustre-OST0000-osc-ffff8a9743cd6800: Connection to lustre-OST0000 (at 192.168.204.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8011.756893] Lustre: 444004:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [ 8011.775116] Lustre: lustre-OST0000-osc-ffff8a9743cd6800: Connection restored to 192.168.204.126@tcp (at 192.168.204.126@tcp) [ 8028.127210] Lustre: 444002:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767329934/real 1767329934] req@ffff8a9774589880 x1853179585104896/t0(0) o4->lustre-OST0000-osc-ffff8a9743cd6800@192.168.204.126@tcp:6/4 lens 4584/448 e 0 to 1 dl 1767329950 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 8028.127294] Lustre: lustre-OST0000-osc-ffff8a9743cd6800: Connection to lustre-OST0000 (at 192.168.204.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8028.139795] Lustre: 444002:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 4 previous similar messages [ 8028.157469] Lustre: lustre-OST0000-osc-ffff8a9743cd6800: Connection restored to 192.168.204.126@tcp (at 192.168.204.126@tcp) [ 8076.255141] Lustre: 444002:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767329982/real 1767329982] req@ffff8a9774589880 x1853179585104896/t0(0) o4->lustre-OST0000-osc-ffff8a9743cd6800@192.168.204.126@tcp:6/4 lens 4584/448 e 0 to 1 dl 1767329998 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 8076.255175] Lustre: lustre-OST0000-osc-ffff8a9743cd6800: Connection to lustre-OST0000 (at 192.168.204.126@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8076.261019] Lustre: 444002:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 9 previous similar messages [ 8076.269685] Lustre: Skipped 2 previous similar messages [ 8076.276620] Lustre: lustre-OST0000-osc-ffff8a9743cd6800: Connection restored to 192.168.204.126@tcp (at 192.168.204.126@tcp) [ 8076.278669] Lustre: Skipped 2 previous similar messages [ 8083.008025] Lustre: DEBUG MARKER: oleg426-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8a9743cd6800.ost_server_uuid 50 [ 8083.447710] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8a9743cd6800.ost_server_uuid in FULL state after 0 sec [ 8083.974464] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 8084.497409] Lustre: DEBUG MARKER: == sanityn test complete, duration 7642 sec ============== 00:00:06 (1767330006) [ 8084.974599] Lustre: DEBUG MARKER: === sanityn: start cleanup 00:00:07 (1767330007) === [ 8161.213783] LustreError: 452015:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9744180000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8161.217585] LustreError: 452015:0:(obd_class.h:479:obd_check_dev()) Device 12 not setup [ 8161.236096] Lustre: Unmounted lustre-client [ 8162.728840] Lustre: DEBUG MARKER: === sanityn: finish cleanup 00:01:24 (1767330084) === [ 8163.059753] LustreError: 452319:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8a9743cd6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8163.062419] LustreError: 452319:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 8163.068171] LustreError: 452319:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 8163.070786] LustreError: 452319:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 8163.102527] Lustre: Unmounted lustre-client [ 8204.374616] Key type lgssc unregistered [ 8204.509061] LNet: 453059:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8204.512627] LNetError: 453059:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8204.528449] LNet: Removed LNI 192.168.204.26@tcp [ 8204.884217] Key type .llcrypt unregistered [ 8204.885696] Key type ._llcrypt unregistered