[ 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 385398909 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, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001009] APIC: Switch to symmetric I/O mode setup [ 0.002188] x2apic enabled [ 0.003004] Switched APIC routing to physical x2apic. [ 0.003960] kvm-guest: setup PV IPIs [ 0.005843] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.006000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.006011] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.007005] pid_max: default: 32768 minimum: 301 [ 0.008096] LSM: Security Framework initializing [ 0.009026] Yama: becoming mindful. [ 0.010019] SELinux: Initializing. [ 0.010673] *** VALIDATE selinux *** [ 0.017111] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.021841] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.022136] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.023091] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.024095] *** VALIDATE tmpfs *** [ 0.025437] *** VALIDATE proc *** [ 0.026148] *** VALIDATE cgroup *** [ 0.027005] *** VALIDATE cgroup2 *** [ 0.028178] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.029099] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.030004] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.031024] Spectre V2 : User space: Vulnerable [ 0.032005] Speculative Store Bypass: Vulnerable [ 0.035091] debug: unmapping init [mem 0xffffffffa6659000-0xffffffffa6660fff] [ 0.037154] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.038497] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.039012] ... version: 2 [ 0.039897] ... bit width: 48 [ 0.040008] ... generic registers: 4 [ 0.040790] ... value mask: 0000ffffffffffff [ 0.041006] ... max period: 00007fffffffffff [ 0.042006] ... fixed-purpose events: 3 [ 0.042786] ... event mask: 000000070000000f [ 0.043260] rcu: Hierarchical SRCU implementation. [ 0.045191] smp: Bringing up secondary CPUs ... [ 0.046599] x86: Booting SMP configuration: [ 0.047018] .... node #0, CPUs: #1 #2 #3 [ 0.049850] smp: Brought up 1 node, 4 CPUs [ 0.051009] smpboot: Max logical packages: 1 [ 0.052008] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.147020] node 0 deferred pages initialised in 94ms [ 0.149281] devtmpfs: initialized [ 0.150188] x86/mm: Memory block size: 128MB [ 0.152209] gcov: version magic: 0x41383552 [ 0.153546] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.154043] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.155149] pinctrl core: initialized pinctrl subsystem [ 0.156096] [ 0.156379] ************************************************************* [ 0.157008] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.158006] ** ** [ 0.159006] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.160006] ** ** [ 0.161007] ** This means that this kernel is built to expose internal ** [ 0.162006] ** IOMMU data structures, which may compromise security on ** [ 0.163007] ** your system. ** [ 0.164006] ** ** [ 0.165006] ** If you see this message and you are not debugging the ** [ 0.166006] ** kernel, report this immediately to your vendor! ** [ 0.167007] ** ** [ 0.168006] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.169006] ************************************************************* [ 0.170518] NET: Registered protocol family 16 [ 0.171351] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.172026] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.173026] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.174313] cpuidle: using governor menu [ 0.175441] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.177368] PCI: Using configuration type 1 for base access [ 0.178088] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.184078] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.185016] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.186123] cryptd: max_cpu_qlen set to 1000 [ 0.187799] ACPI: Added _OSI(Module Device) [ 0.189012] ACPI: Added _OSI(Processor Device) [ 0.189931] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.191007] ACPI: Added _OSI(Processor Aggregator Device) [ 0.194681] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.200333] ACPI: Interpreter enabled [ 0.202044] ACPI: PM: (supports S0 S3 S4 S5) [ 0.203006] ACPI: Using IOAPIC for interrupt routing [ 0.203901] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.206258] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.214199] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.216017] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.217010] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.219042] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.223156] acpiphp: Slot [2] registered [ 0.224136] acpiphp: Slot [5] registered [ 0.225095] acpiphp: Slot [6] registered [ 0.225941] acpiphp: Slot [3] registered [ 0.227044] acpiphp: Slot [4] registered [ 0.227845] acpiphp: Slot [7] registered [ 0.228044] acpiphp: Slot [8] registered [ 0.228731] acpiphp: Slot [9] registered [ 0.230054] acpiphp: Slot [10] registered [ 0.230844] acpiphp: Slot [11] registered [ 0.231044] acpiphp: Slot [12] registered [ 0.231872] acpiphp: Slot [13] registered [ 0.233047] acpiphp: Slot [14] registered [ 0.234063] acpiphp: Slot [15] registered [ 0.235012] acpiphp: Slot [16] registered [ 0.235921] acpiphp: Slot [17] registered [ 0.237059] acpiphp: Slot [18] registered [ 0.238036] acpiphp: Slot [19] registered [ 0.239045] acpiphp: Slot [20] registered [ 0.240052] acpiphp: Slot [21] registered [ 0.240949] acpiphp: Slot [22] registered [ 0.242062] acpiphp: Slot [23] registered [ 0.242953] acpiphp: Slot [24] registered [ 0.244077] acpiphp: Slot [25] registered [ 0.245047] acpiphp: Slot [26] registered [ 0.246079] acpiphp: Slot [27] registered [ 0.247047] acpiphp: Slot [28] registered [ 0.248055] acpiphp: Slot [29] registered [ 0.249069] acpiphp: Slot [30] registered [ 0.250057] acpiphp: Slot [31] registered [ 0.251073] PCI host bridge to bus 0000:00 [ 0.252013] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.254009] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.256016] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.258021] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.260016] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.262038] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.264234] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.267139] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.270284] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.278016] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.281484] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.283012] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.285010] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.287012] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.289567] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.291553] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.294027] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.295597] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.298992] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.305009] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.308357] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.312713] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.317008] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.321013] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.330967] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.337220] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.342014] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.345011] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.355015] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.363241] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.365257] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.367213] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.368186] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.369105] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.372251] iommu: Default domain type: Passthrough [ 0.374363] SCSI subsystem initialized [ 0.375080] ACPI: bus type USB registered [ 0.377070] usbcore: registered new interface driver usbfs [ 0.378034] usbcore: registered new interface driver hub [ 0.380045] usbcore: registered new device driver usb [ 0.381129] pps_core: LinuxPPS API ver. 1 registered [ 0.382010] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.385054] PTP clock support registered [ 0.387166] EDAC MC: Ver: 3.0.0 [ 0.389147] PCI: Using ACPI for IRQ routing [ 0.390844] NetLabel: Initializing [ 0.392007] NetLabel: domain hash size = 128 [ 0.392970] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.395071] NetLabel: unlabeled traffic allowed by default [ 0.397146] vgaarb: loaded [ 0.398287] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.400014] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.406345] clocksource: Switched to clocksource kvm-clock [ 0.527949] VFS: Disk quotas dquot_6.6.0 [ 0.529518] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.532038] *** VALIDATE ramfs *** [ 0.533215] *** VALIDATE hugetlbfs *** [ 0.534687] pnp: PnP ACPI init [ 0.537288] pnp: PnP ACPI: found 6 devices [ 0.553124] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.556231] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.558177] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.560279] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.562615] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.564880] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.567749] NET: Registered protocol family 2 [ 0.570275] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.575170] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.578645] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.583755] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.587028] TCP: Hash tables configured (established 65536 bind 65536) [ 0.589791] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.592665] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.595275] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.598145] NET: Registered protocol family 1 [ 0.600707] RPC: Registered named UNIX socket transport module. [ 0.602162] RPC: Registered udp transport module. [ 0.603353] RPC: Registered tcp transport module. [ 0.604364] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.605874] NET: Registered protocol family 44 [ 0.606940] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.608332] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.609971] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.611439] PCI: CLS 0 bytes, default 64 [ 0.612658] Unpacking initramfs... [ 1.933224] debug: unmapping init [mem 0xffff9cbc7cc64000-0xffff9cbc7ffcffff] [ 1.939254] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 1.941018] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 1.944569] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.464722] Initialise system trusted keyrings [ 2.466145] Key type blacklist registered [ 2.468352] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.477399] zbud: loaded [ 2.480464] *** VALIDATE nfs *** [ 2.481900] *** VALIDATE nfs4 *** [ 2.483389] pstore: using deflate compression [ 2.486837] Platform Keyring initialized [ 2.596053] NET: Registered protocol family 38 [ 2.597247] Key type asymmetric registered [ 2.598172] Asymmetric key parser 'x509' registered [ 2.599336] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.603177] io scheduler mq-deadline registered [ 2.604640] io scheduler kyber registered [ 2.605577] io scheduler bfq registered [ 2.606708] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.608763] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.610738] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.612451] ACPI: Power Button [PWRF] [ 2.616082] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.620632] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.629689] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.656268] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.687319] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.691686] Non-volatile memory driver v1.3 [ 2.693366] Linux agpgart interface v0.103 [ 2.724711] virtio_blk virtio1: [vda] 134032 512-byte logical blocks (68.6 MB/65.4 MiB) [ 2.727500] vda: detected capacity change from 0 to 68624384 [ 2.740918] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.743845] vdb: detected capacity change from 0 to 1073741824 [ 2.749592] libphy: Fixed MDIO Bus: probed [ 2.756353] usbcore: registered new interface driver usbserial_generic [ 2.757987] usbserial: USB Serial support registered for generic [ 2.759796] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.762699] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.763932] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.765553] mousedev: PS/2 mouse device common for all mice [ 2.767391] rtc_cmos 00:05: RTC can wake from S4 [ 2.769829] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.771896] rtc_cmos 00:05: registered as rtc0 [ 2.776678] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.778103] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.780226] intel_pstate: CPU model not supported [ 2.785776] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.787506] hid: raw HID events driver (C) Jiri Kosina [ 2.790969] usbcore: registered new interface driver usbhid [ 2.792338] usbhid: USB HID core driver [ 2.793424] drop_monitor: Initializing network drop monitor service [ 2.794957] Initializing XFRM netlink socket [ 2.796429] NET: Registered protocol family 10 [ 2.799212] Segment Routing with IPv6 [ 2.800114] NET: Registered protocol family 17 [ 2.801446] mpls_gso: MPLS GSO support [ 2.806043] RAS: Correctable Errors collector initialized. [ 2.807404] AVX version of gcm_enc/dec engaged. [ 2.808383] AES CTR mode by8 optimization enabled [ 2.874467] sched_clock: Marking stable (2874451251, 0)->(3553377398, -678926147) [ 2.880716] registered taskstats version 1 [ 2.883932] Loading compiled-in X.509 certificates [ 2.885908] zswap: loaded using pool lzo/zbud [ 2.904810] Key type big_key registered [ 2.916757] Key type encrypted registered [ 2.918133] ima: No TPM chip found, activating TPM-bypass! [ 2.920126] ima: Allocated hash algorithm: sha1 [ 2.921478] ima: No architecture policies found [ 2.922591] evm: Initialising EVM extended attributes: [ 2.923678] evm: security.selinux [ 2.924493] evm: security.ima [ 2.925190] evm: security.capability [ 2.925910] evm: HMAC attrs: 0x1 [ 2.927579] rtc_cmos 00:05: setting system clock to 2026-01-02 00:45:53 UTC (1767314753) [ 2.932632] debug: unmapping init [mem 0xffffffffa7603000-0xffffffffa77fffff] [ 2.934804] debug: unmapping init [mem 0xffffffffa6382000-0xffffffffa6658fff] [ 2.941082] Write protecting the kernel read-only data: 28672k [ 2.943446] debug: unmapping init [mem 0xffffffffa4a03000-0xffffffffa4bfffff] [ 2.945067] debug: unmapping init [mem 0xffffffffa5314000-0xffffffffa53fffff] [ 2.969297] 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.974751] systemd[1]: Detected virtualization kvm. [ 2.975865] systemd[1]: Detected architecture x86-64. [ 2.977054] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.002719] systemd[1]: No hostname configured. [ 3.003762] systemd[1]: Set hostname to . [ 3.004953] random: systemd: uninitialized urandom read (16 bytes read) [ 3.006589] systemd[1]: Initializing machine ID from random generator. [ 3.056616] random: ln: uninitialized urandom read (6 bytes read) [ 3.154056] random: systemd: uninitialized urandom read (16 bytes read) [ 3.155905] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 3.159918] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 3.162775] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ OK ] Reached target Slices. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Initrd Root Device. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Paths. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Swap. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... [ OK ] Reached target Sockets. [ OK ] Started Memstrack Anylazing Service. Starting Create list of required st…ce nodes for the current kernel... Starting Create Volatile Files and Directories... Starting Apply Kernel Variables... Starting Journal Service... [ OK ] Started Setup Virtual Console. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Journal Service. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.821144] device-mapper: uevent: version 1.0.3 [ 3.823789] 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... [ 4.214917] random: fast init done [ 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.835159] virtio_net virtio0 ens2: renamed from eth0 [ 5.638184] scsi host0: ata_piix [ 5.657294] scsi host1: ata_piix [ 5.658733] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.661066] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 10.433094] random: crng init done [ 10.434503] random: 7 urandom warning(s) missed due to ratelimiting [ 10.976373] dracut-initqueue[587]: RTNETLINK answers: File exists Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 13.125651] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Sockets. [ OK ] Stopped target Paths. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped target Swap. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 15.219928] printk: systemd: 26 output lines suppressed due to ratelimiting [ 15.820576] SELinux: Disabled at runtime. [ 15.903464] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 15.915573] systemd[1]: Detected virtualization kvm. [ 15.917426] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 17.111655] systemd[1]: initrd-switch-root.service: Succeeded. [ 17.115997] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 17.123543] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 17.131486] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 17.137535] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 17.151080] systemd[1]: Starting Journal Service... Starting Journal Service... [ 17.167979] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Created slice system-getty.slice. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target rpc_pipefs.target. Activating swap /dev/disk/by-label/SWAP... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. [ OK ] Created slice system-serial\x2dgetty.slice. Starting Apply Kernel Variables... [ 17.434220] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting Remount Root and Kernel File Systems... [ OK ] Created slice User and Session Slice. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Stopped target Initrd File Systems. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Slices. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. Mounting Kernel Debug File System... Mounting Huge Pages File System... [ OK ] Reached target Paths. Mounting POSIX Message Queue File System... [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Apply Kernel Variables. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. Starting Configure read-only root support... Starting Flush Journal to Persistent Storage... [ OK ] Reached target Swap. Starting Create Static Device Nodes in /dev... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Coldplug all Devices. [ OK ] Started Create Static Device Nodes in /dev. Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... [ OK ] Mounted /mnt. [ 18.203663] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 18.748742] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 18.764940] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 19.116420] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 19.160155] EDAC sbridge: Ver: 1.1.2 [ 21.236669] Key type dns_resolver registered [ 21.847394] NFS: Registering the id_resolver key type [ 21.849018] Key type id_resolver registered [ 21.850107] 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 Update UTMP about System Boot/Shutdown... Starting RPC Bind... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Login Service... [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started D-Bus System Message Bus. Starting Restore /run/initramfs on shutdown... Starting Network Manager... [ OK ] Reached target sshd-keygen.target. [ OK ] Started irqbalance daemon. [ OK ] Started Login Service. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ 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 Serial Getty on ttyS1. [ OK ] Started Command Scheduler. [ 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 System Logging Service... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting 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 Authorization Manager. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg456-client login: [ 88.617173] libcfs: loading out-of-tree module taints kernel. [ 88.806065] Key type ._llcrypt registered [ 88.815091] Key type .llcrypt registered [ 89.243344] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 89.253126] alg: No test for adler32 (adler32-zlib) [ 90.765502] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 91.533461] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 93.367422] Key type lgssc registered [ 95.324724] Lustre: Echo OBD driver; http://www.lustre.org/ [ 226.477282] hrtimer: interrupt took 3444142 ns [ 321.919079] Lustre: Mounted lustre-client [ 327.087888] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 347.615249] Lustre: lustre-OST0000-osc-ffff9cbcc65ee000: disconnect after 23s idle [ 347.629496] Lustre: DEBUG MARKER: oleg456-client.virtnet: executing check_logdir /tmp/testlogs/ [ 353.665571] Lustre: DEBUG MARKER: oleg456-client.virtnet: executing yml_node [ 372.438679] Lustre: DEBUG MARKER: Client: 2.17.0.RC4 [ 375.496692] Lustre: DEBUG MARKER: MDS: 2.17.0.RC4 [ 378.708028] Lustre: DEBUG MARKER: OSS: 2.17.0.RC4 [ 380.729352] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Thu Jan 1 19:52:09 EST 2026 [ 406.384973] Lustre: DEBUG MARKER: excepting tests: 27 40a 102 [ 408.109668] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 410.161374] Lustre: DEBUG MARKER: === sanityn: start setup 19:52:39 (1767315159) === [ 411.400388] Lustre: Mounted lustre-client [ 415.456979] Lustre: DEBUG MARKER: oleg456-client.virtnet: executing check_config_client /mnt/lustre [ 457.009345] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 470.213850] Lustre: DEBUG MARKER: === sanityn: finish setup 19:53:39 (1767315219) === [ 473.079503] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 19:53:42 (1767315222) [ 482.910950] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 19:53:51 (1767315231) [ 491.765758] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 19:54:00 (1767315240) [ 499.914680] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 19:54:09 (1767315249) [ 508.113717] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 19:54:16 (1767315256) [ 514.939431] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 19:54:24 (1767315264) [ 522.171837] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 19:54:31 (1767315271) [ 530.679420] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 19:54:39 (1767315279) [ 532.759610] Lustre: DEBUG MARKER: SKIP: sanityn test_2f needs >= 2 MDTs [ 534.464602] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 19:54:43 (1767315283) [ 542.835660] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 19:54:51 (1767315291) [ 550.894742] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 19:54:59 (1767315299) [ 554.983766] Lustre: lustre-OST0001-osc-ffff9cbcc65ee000: disconnect after 20s idle [ 555.027591] Lustre: Skipped 1 previous similar message [ 560.776625] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 19:55:09 (1767315309) [ 568.485599] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 19:55:17 (1767315317) [ 575.458638] Lustre: lustre-OST0000-osc-ffff9cbcd175e000: disconnect after 24s idle [ 577.169586] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 19:55:26 (1767315326) [ 584.828613] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 19:55:33 (1767315333) [ 590.816048] Lustre: lustre-OST0001-osc-ffff9cbcc65ee000: disconnect after 20s idle [ 592.410964] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 19:55:41 (1767315341) [ 600.822911] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 19:55:49 (1767315349) [ 609.870163] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 19:55:58 (1767315358) [ 620.407205] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 19:56:08 (1767315368) [ 629.036718] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 19:56:17 (1767315377) [ 637.112533] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 19:56:26 (1767315386) [ 638.016156] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205272502304 file: /mnt/lustre/lockdir/lockfile=144115205272502302 [ 778.841259] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 19:58:48 (1767315528) [ 788.341431] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 19:58:57 (1767315537) [ 795.912457] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 19:59:04 (1767315544) [ 803.567779] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 19:59:12 (1767315552) [ 813.450296] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 19:59:22 (1767315562) [ 827.529785] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 19:59:34 (1767315574) [ 831.864670] Lustre: DEBUG MARKER: chmod [ 840.977558] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 19:59:49 (1767315589) [ 879.513083] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7528448KB free > 800000KB, increase MAXFREE (or reduce fs size) [ 900.535531] Lustre: DEBUG MARKER: == sanityn test 16a: 500 iterations of dual-mount fsx ==== 20:00:48 (1767315648) [ 973.589150] Lustre: DEBUG MARKER: == sanityn test 16b: 500 iterations of dual-mount fsx at small size ========================================================== 20:02:02 (1767315722) [ 1011.365056] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 20:02:40 (1767315760) [ 1015.282738] Lustre: DEBUG MARKER: SKIP: sanityn test_16c dio on ldiskfs only [ 1017.741764] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 20:02:46 (1767315766) [ 1066.976662] Lustre: lustre-OST0000-osc-ffff9cbcd175e000: disconnect after 20s idle [ 1085.461958] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 20:03:54 (1767315834) [ 1094.368970] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 20:04:03 (1767315843) [ 1096.244803] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1096.302895] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1096.388505] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1096.491428] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1096.640085] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1096.728230] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1096.864292] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1096.998273] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1097.121037] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1097.203190] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1097.301475] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1097.362915] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1097.455862] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1097.531742] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1097.619751] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1097.690570] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1097.774883] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1097.847092] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1097.907620] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1097.963757] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1098.043729] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1098.137397] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1098.246690] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1098.349304] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1098.474050] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1098.578625] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1098.694123] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1098.784603] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1098.876221] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1098.953535] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1099.009602] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1099.062753] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1099.162696] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1099.245309] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1099.327374] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1099.408845] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1099.500128] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1099.569685] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1099.700428] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1099.788519] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1099.917805] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1099.996769] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1100.074988] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1100.158775] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1100.234912] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1100.336363] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1100.424531] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1100.522284] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1100.654973] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1100.792409] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1100.908486] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1101.009974] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1101.135588] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1101.245281] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1101.339851] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1101.419681] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1101.520128] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1101.645857] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1101.749316] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1101.846859] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1101.952139] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1102.077252] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1102.238202] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1102.372869] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1102.493942] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1102.584380] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1102.698758] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1102.791695] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1102.910552] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1102.983445] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1103.076328] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1103.175464] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1103.288648] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1103.402980] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1103.519691] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1103.635370] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1103.749839] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1103.896835] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1104.083000] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1104.203455] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1104.271882] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1104.380682] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1104.486682] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1104.560949] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1104.687322] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1104.822846] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1104.915238] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1104.993891] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1105.080539] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1105.186577] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1105.324714] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1105.481498] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1105.575430] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1105.686157] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1105.844309] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1105.976792] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1106.135412] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1106.322178] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1106.499776] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1106.669617] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1106.838212] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1106.962972] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1107.042206] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1107.189287] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1107.334470] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1107.473519] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1107.610091] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1107.724929] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1107.839340] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1107.934645] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1107.941852] Lustre: lustre-OST0001-osc-ffff9cbcd175e000: disconnect after 21s idle [ 1108.026811] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1108.136162] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1108.240496] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1108.366933] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1108.462100] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1108.572525] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1108.726869] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1108.827488] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1108.925183] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1109.012570] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1109.094330] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1109.189202] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1109.341165] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1109.507383] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1109.665108] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1109.820691] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1109.955212] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1110.058898] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1110.138078] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1110.246727] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1110.352632] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1110.457412] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1110.565529] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1110.707958] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1110.803526] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1110.921531] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1111.042944] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1111.144703] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1111.243415] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1111.325551] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1111.414933] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1111.508489] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1111.644909] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1111.763040] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1111.861190] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1112.015870] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1112.136669] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1112.226044] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1112.318869] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1112.406784] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1112.496289] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1112.586880] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1112.699692] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1112.850187] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1112.957481] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1113.099829] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1113.212502] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1113.326185] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1113.414289] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1113.549794] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1113.692702] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1113.781935] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1113.876162] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1113.966195] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1114.064438] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1114.167059] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1114.259885] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1114.341681] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1114.439614] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1114.523665] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1114.601549] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1114.719705] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1114.810452] rw_seq_cst_vs_d (31526): drop_caches: 3 [ 1124.436969] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 20:04:33 (1767315873) [ 1125.378401] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1125.523183] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1125.681051] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1125.724056] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1125.831707] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1125.992223] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1126.064760] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1126.198605] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1126.283303] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1126.344305] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1126.485512] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1126.645505] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1126.824872] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1126.910167] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1127.081522] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1127.237111] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1127.337381] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1127.449944] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1127.514447] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1127.574256] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1127.764869] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1127.908311] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1128.052524] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1128.258343] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1128.354764] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1128.452478] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1128.515244] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1128.696842] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1128.790243] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1128.941321] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1129.027519] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1129.097598] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1129.204266] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1129.286963] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1129.502928] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1129.735223] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1129.831127] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1129.894077] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1130.057775] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1130.302647] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1130.376960] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1130.544295] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1130.631699] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1130.789355] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1130.918524] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1131.056079] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1131.106960] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1131.277244] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1131.486560] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1131.548801] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1131.636114] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1131.680281] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1131.882263] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1131.938855] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1132.179511] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1132.352549] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1132.471110] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1132.609214] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1132.796299] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1132.991244] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1133.050461] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1133.234384] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1133.344166] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1133.534200] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1133.578826] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1133.775760] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1133.866953] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1134.047519] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1134.266866] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1134.403957] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1134.515218] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1134.562419] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1134.719789] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1134.849469] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1134.927946] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1135.119520] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1135.310336] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1135.540938] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1135.723373] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1135.967720] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1136.123757] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1136.182585] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1136.341181] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1136.418429] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1136.619926] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1136.689969] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1136.732847] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1136.848455] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1136.998762] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1137.129438] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1137.231732] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1137.289945] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1137.336712] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1137.468518] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1137.536594] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1137.656952] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1137.858982] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1137.941459] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1138.096579] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1138.247842] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1138.365413] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1138.441350] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1138.544342] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1138.655309] Lustre: lustre-OST0000-osc-ffff9cbcc65ee000: disconnect after 20s idle [ 1138.667307] Lustre: Skipped 1 previous similar message [ 1138.716160] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1138.912302] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1138.967408] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1139.154240] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1139.220374] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1139.342556] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1139.612220] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1139.740162] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1139.865172] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1139.909235] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1140.100234] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1140.167363] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1140.328093] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1140.410072] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1140.456285] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1140.577468] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1140.731942] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1140.804146] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1140.843248] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1140.942982] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1141.170486] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1141.308401] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1141.446959] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1141.508576] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1141.634741] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1141.736323] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1142.016460] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1142.208861] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1142.296034] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1142.408970] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1142.538764] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1142.607123] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1142.796487] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1142.883963] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1142.981202] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1143.110802] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1143.149637] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1143.312525] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1143.359939] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1143.510738] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1143.677346] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1143.814735] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1143.935385] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1144.015718] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1144.120616] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1144.307504] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1144.370230] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1144.485171] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1144.648561] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1144.730267] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1144.809912] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1144.873756] rw_seq_cst_vs_d (32104): drop_caches: 3 [ 1153.308747] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 20:05:02 (1767315902) [ 1160.633193] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 20:05:09 (1767315909) [ 1168.894897] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 20:05:18 (1767315918) [ 1215.785657] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 20:06:05 (1767315965) [ 1216.383879] bash (34558): drop_caches: 3 [ 1219.636847] bash (34558): drop_caches: 3 [ 1222.867205] bash (34558): drop_caches: 3 [ 1226.651858] bash (34558): drop_caches: 3 [ 1230.387910] bash (34558): drop_caches: 3 [ 1233.520215] bash (34558): drop_caches: 3 [ 1236.755858] bash (34558): drop_caches: 3 [ 1239.936725] bash (34558): drop_caches: 3 [ 1243.062442] bash (34558): drop_caches: 3 [ 1246.229733] bash (34558): drop_caches: 3 [ 1249.395389] bash (34558): drop_caches: 3 [ 1252.470249] bash (34558): drop_caches: 3 [ 1255.657974] bash (34558): drop_caches: 3 [ 1258.865520] bash (34558): drop_caches: 3 [ 1262.047179] bash (34558): drop_caches: 3 [ 1265.189331] bash (34558): drop_caches: 3 [ 1268.350527] bash (34558): drop_caches: 3 [ 1271.499851] bash (34558): drop_caches: 3 [ 1274.632710] bash (34558): drop_caches: 3 [ 1279.643743] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 20:07:08 (1767316028) [ 1289.557654] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 20:07:18 (1767316038) [ 1318.024735] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 20:07:47 (1767316067) [ 1321.262736] Lustre: DEBUG MARKER: SKIP: sanityn test_19 not cache-capable obdfilter [ 1322.976752] Lustre: lustre-OST0000-osc-ffff9cbcd175e000: disconnect after 20s idle [ 1322.996273] Lustre: Skipped 1 previous similar message [ 1323.331829] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 20:07:52 (1767316072) [ 1331.661656] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 20:08:00 (1767316080) [ 1338.125486] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 20:08:07 (1767316087) [ 1408.757478] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 20:09:17 (1767316157) [ 1416.011397] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 20:09:25 (1767316165) [ 1423.995839] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 20:09:32 (1767316172) [ 1431.768725] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 20:09:40 (1767316180) [ 1433.199857] Lustre: DEBUG MARKER: SKIP: sanityn test_25b needs >= 2 MDTs [ 1434.957826] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 20:09:44 (1767316184) [ 1440.735424] Lustre: lustre-OST0001-osc-ffff9cbcc65ee000: disconnect after 24s idle [ 1440.746770] Lustre: Skipped 5 previous similar messages [ 1443.685513] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 20:09:52 (1767316192) [ 1452.652921] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 20:10:01 (1767316201) [ 1460.485846] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1462.913270] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 20:10:11 (1767316211) [ 1472.797858] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 20:10:21 (1767316221) [ 1473.375194] Lustre: *** cfs_fail_loc=314, val=0*** [ 1474.399299] Lustre: *** cfs_fail_loc=314, val=0*** [ 1474.401107] Lustre: Skipped 2 previous similar messages [ 1480.830737] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 20:10:30 (1767316230) [ 1494.511684] Lustre: *** cfs_fail_loc=314, val=0*** [ 1494.601594] LustreError: lustre-OST0000-osc-ffff9cbcd175e000: operation ldlm_enqueue to node 192.168.204.156@tcp failed: rc = -107 [ 1494.610530] Lustre: lustre-OST0000-osc-ffff9cbcd175e000: Connection to lustre-OST0000 (at 192.168.204.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1494.639270] LustreError: lustre-OST0000-osc-ffff9cbcd175e000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1494.652071] Lustre: 2488:0:(llite_lib.c:4226:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.156@tcp:/lustre/fid: [0x200000402:0x25:0x0]// may get corrupted (rc -108) [ 1494.671595] LustreError: 43805:0:(ldlm_resource.c:981:ldlm_resource_complain()) lustre-OST0000-osc-ffff9cbcd175e000: namespace resource [0x280000400:0x35:0x0].0x0 (ffff9cbcd8e2a600) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 1494.697514] Lustre: lustre-OST0000-osc-ffff9cbcd175e000: Connection restored to 192.168.204.156@tcp (at 192.168.204.156@tcp) [ 1501.731803] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 20:10:51 (1767316251) [ 1502.135183] LustreError: 44387:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 1505.167157] LustreError: 44387:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 1512.362938] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 20:11:01 (1767316261) [ 1520.390163] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 20:11:09 (1767316269) [ 1528.634446] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 1530.921880] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 20:11:19 (1767316279) [ 1532.421807] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 1534.232250] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 20:11:23 (1767316283) [ 1535.608815] Lustre: DEBUG MARKER: SKIP: sanityn test_33c needs >= 2 MDTs [ 1537.440631] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 20:11:26 (1767316286) [ 1539.934804] Lustre: DEBUG MARKER: SKIP: sanityn test_33d needs >= 2 MDTs [ 1542.211244] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 20:11:30 (1767316290) [ 1543.972993] Lustre: DEBUG MARKER: SKIP: sanityn test_33e needs >= 2 MDTs [ 1546.129189] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 20:11:34 (1767316294) [ 1604.610452] Lustre: lustre-OST0001-osc-ffff9cbcc65ee000: Connection to lustre-OST0001 (at 192.168.204.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1604.635349] LustreError: lustre-OST0001-osc-ffff9cbcc65ee000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1604.655958] LustreError: lustre-OST0001-osc-ffff9cbcd175e000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1604.671235] Lustre: lustre-OST0001-osc-ffff9cbcc65ee000: Connection restored to 192.168.204.156@tcp (at 192.168.204.156@tcp) [ 1604.700336] Lustre: Skipped 1 previous similar message [ 1624.027178] Lustre: lustre-OST0000-osc-ffff9cbcc65ee000: Connection to lustre-OST0000 (at 192.168.204.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1624.051313] Lustre: Skipped 1 previous similar message [ 1624.084309] LustreError: lustre-OST0000-osc-ffff9cbcc65ee000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1624.106152] Lustre: lustre-OST0000-osc-ffff9cbcc65ee000: Connection restored to 192.168.204.156@tcp (at 192.168.204.156@tcp) [ 1625.055327] Lustre: lustre-OST0001-osc-ffff9cbcc65ee000: disconnect after 20s idle [ 1625.059934] Lustre: Skipped 2 previous similar messages [ 1641.110640] Lustre: DEBUG MARKER: oleg456-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9cbcc65ee000.ost_server_uuid 50 [ 1642.970446] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9cbcc65ee000.ost_server_uuid in FULL state after 0 sec [ 1647.014744] Lustre: DEBUG MARKER: oleg456-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9cbcc65ee000.ost_server_uuid 50 [ 1648.881901] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9cbcc65ee000.ost_server_uuid in IDLE state after 0 sec [ 1656.093708] Lustre: DEBUG MARKER: oleg456-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9cbcc65ee000.ost_server_uuid 50 [ 1658.150207] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9cbcc65ee000.ost_server_uuid in IDLE state after 0 sec [ 1662.973483] Lustre: DEBUG MARKER: oleg456-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9cbcc65ee000.ost_server_uuid 50 [ 1664.926035] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9cbcc65ee000.ost_server_uuid in IDLE state after 0 sec [ 1675.943905] Lustre: DEBUG MARKER: oleg456-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9cbcc65ee000.ost_server_uuid 50 [ 1677.585747] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9cbcc65ee000.ost_server_uuid in IDLE state after 0 sec [ 1682.012629] Lustre: DEBUG MARKER: oleg456-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9cbcc65ee000.ost_server_uuid 50 [ 1683.712156] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9cbcc65ee000.ost_server_uuid in IDLE state after 0 sec [ 1685.312774] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 20:13:54 (1767316434) [ 1687.790740] Lustre: DEBUG MARKER: Race attempt 0 [ 1691.320944] Lustre: DEBUG MARKER: Wait for 51034 51046 for 60 sec... [ 1758.503358] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 20:15:07 (1767316507) [ 1766.599335] Lustre: DEBUG MARKER: start test - cycle (0) [ 1790.765680] Lustre: DEBUG MARKER: start test - cycle (1) [ 1816.198697] Lustre: DEBUG MARKER: start test - cycle (2) [ 1841.087219] Lustre: DEBUG MARKER: start test - cycle (3) [ 1866.430183] Lustre: DEBUG MARKER: start test - cycle (4) [ 1892.702787] Lustre: DEBUG MARKER: start test - cycle (5) [ 1896.416548] Lustre: lustre-OST0001-osc-ffff9cbcd175e000: disconnect after 22s idle [ 1896.424915] Lustre: Skipped 6 previous similar messages [ 1919.390163] Lustre: DEBUG MARKER: start test - cycle (6) [ 1945.433120] Lustre: DEBUG MARKER: start test - cycle (7) [ 1969.527846] Lustre: DEBUG MARKER: start test - cycle (8) [ 1996.184647] Lustre: DEBUG MARKER: start test - cycle (9) [ 2020.416083] Lustre: DEBUG MARKER: start test - cycle (10) [ 2053.796821] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 20:20:02 (1767316802) [ 2142.818597] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 20:21:31 (1767316891) [ 2149.636494] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 20:21:38 (1767316898) [ 2159.021376] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 20:21:48 (1767316908) [ 2168.006572] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 20:21:57 (1767316917) [ 2168.337832] Lustre: *** cfs_fail_loc=411, val=0*** [ 2174.300924] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 2176.528519] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 20:22:05 (1767316925) [ 2193.952726] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 20:22:23 (1767316943) [ 2210.434923] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 20:22:39 (1767316959) [ 2226.673533] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 20:22:55 (1767316975) [ 2242.412635] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 20:23:11 (1767316991) [ 2255.854283] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 20:23:25 (1767317005) [ 2267.422874] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 20:23:36 (1767317016) [ 2279.711658] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 20:23:49 (1767317029) [ 2291.954531] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 20:24:01 (1767317041) [ 2304.312323] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 20:24:13 (1767317053) [ 2317.395821] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 20:24:26 (1767317066) [ 2330.819339] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 20:24:39 (1767317079) [ 2342.872672] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 20:24:52 (1767317092) [ 2961.375505] Lustre: lustre-OST0000-osc-ffff9cbcd175e000: disconnect after 24s idle [ 2961.379195] Lustre: Skipped 16 previous similar messages [ 3368.873456] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 20:41:58 (1767318118) [ 3379.601806] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 20:42:09 (1767318129) [ 3389.938689] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 20:42:19 (1767318139) [ 3400.612439] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 20:42:29 (1767318149) [ 3411.642666] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 20:42:41 (1767318161) [ 3424.081348] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 20:42:53 (1767318173) [ 3436.882839] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 20:43:06 (1767318186) [ 3449.066807] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 20:43:18 (1767318198) [ 3461.258200] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 20:43:30 (1767318210) [ 3519.367237] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 20:44:28 (1767318268) [ 3533.396712] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 20:44:42 (1767318282) [ 3546.666591] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 20:44:55 (1767318295) [ 3557.669321] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 20:45:07 (1767318307) [ 3569.142646] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 20:45:18 (1767318318) [ 3581.108578] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 20:45:30 (1767318330) [ 3592.565840] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 20:45:42 (1767318342) [ 3605.268171] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 20:45:54 (1767318354) [ 3606.556735] Lustre: DEBUG MARKER: SKIP: sanityn test_43i needs >= 2 MDTs [ 3608.008387] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 20:45:57 (1767318357) [ 3611.616836] Lustre: lustre-OST0000-osc-ffff9cbcc65ee000: disconnect after 24s idle [ 3611.625370] Lustre: Skipped 4 previous similar messages [ 3717.660944] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 20:47:46 (1767318466) [ 4220.897534] Lustre: lustre-OST0000-osc-ffff9cbcd175e000: disconnect after 21s idle [ 4220.903887] Lustre: Skipped 2 previous similar messages [ 4817.840727] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 21:06:07 (1767319567) [ 4825.056079] Lustre: lustre-OST0001-osc-ffff9cbcd175e000: disconnect after 23s idle [ 4829.801813] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 21:06:19 (1767319579) [ 4840.429103] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 21:06:29 (1767319589) [ 4853.321985] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 21:06:42 (1767319602) [ 4865.187385] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 21:06:54 (1767319614) [ 4877.518561] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 21:07:06 (1767319626) [ 4889.764813] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 21:07:19 (1767319639) [ 4901.389857] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 21:07:30 (1767319650) [ 4913.147686] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 21:07:42 (1767319662) [ 4914.284451] Lustre: DEBUG MARKER: SKIP: sanityn test_44i needs >= 2 MDTs [ 4915.466243] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 21:07:45 (1767319665) [ 5013.271702] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 21:09:22 (1767319762) [ 5024.915868] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 21:09:34 (1767319774) [ 5037.135748] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 21:09:46 (1767319786) [ 5051.269568] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 21:10:00 (1767319800) [ 5064.490771] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 21:10:13 (1767319813) [ 5077.039543] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 21:10:26 (1767319826) [ 5090.081486] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 21:10:39 (1767319839) [ 5101.000375] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 21:10:50 (1767319850) [ 5102.079475] Lustre: DEBUG MARKER: SKIP: sanityn test_45i needs >= 2 MDTs [ 5103.357154] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 21:10:52 (1767319852) [ 5680.097111] Lustre: lustre-OST0001-osc-ffff9cbcc65ee000: disconnect after 24s idle [ 5680.101789] Lustre: Skipped 8 previous similar messages [ 6198.959133] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 21:29:08 (1767320948) [ 6210.915371] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 21:29:20 (1767320960) [ 6224.179500] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 21:29:33 (1767320973) [ 6236.821649] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 21:29:46 (1767320986) [ 6248.077554] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 21:29:57 (1767320997) [ 6260.333641] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 21:30:09 (1767321009) [ 6272.239980] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 21:30:21 (1767321021) [ 6284.261487] Lustre: lustre-OST0001-osc-ffff9cbcc65ee000: disconnect after 21s idle [ 6284.284626] Lustre: Skipped 2 previous similar messages [ 6285.006666] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 21:30:34 (1767321034) [ 6298.507200] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 21:30:47 (1767321047) [ 6300.105872] Lustre: DEBUG MARKER: SKIP: sanityn test_46i needs >= 2 MDTs [ 6301.908913] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 21:30:51 (1767321051) [ 6303.643131] Lustre: DEBUG MARKER: SKIP: sanityn test_47a needs >= 2 MDTs [ 6304.953563] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 21:30:54 (1767321054) [ 6306.208345] Lustre: DEBUG MARKER: SKIP: sanityn test_47b needs >= 2 MDTs [ 6307.766719] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 21:30:57 (1767321057) [ 6309.368748] Lustre: DEBUG MARKER: SKIP: sanityn test_47c needs >= 2 MDTs [ 6311.127320] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 21:31:00 (1767321060) [ 6312.503407] Lustre: DEBUG MARKER: SKIP: sanityn test_47d needs >= 2 MDTs [ 6313.940279] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 21:31:03 (1767321063) [ 6315.490339] Lustre: DEBUG MARKER: SKIP: sanityn test_47e needs >= 2 MDTs [ 6316.824257] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 21:31:06 (1767321066) [ 6318.057370] Lustre: DEBUG MARKER: SKIP: sanityn test_47f needs >= 2 MDTs [ 6319.681038] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 21:31:09 (1767321069) [ 6321.219220] Lustre: DEBUG MARKER: SKIP: sanityn test_47g needs >= 2 MDTs [ 6322.592210] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 21:31:12 (1767321072) [ 6323.198262] LustreError: 7208:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 6325.287129] LustreError: 7208:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 6333.635583] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 21:31:23 (1767321083) [ 6341.294996] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 21:31:30 (1767321090) [ 6341.691805] LustreError: 218633:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 6345.759812] LustreError: 218633:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 6345.802086] LustreError: 218633:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 6349.895851] LustreError: 218633:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 6349.951081] LustreError: 218639:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 6354.031300] LustreError: 218639:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 6362.034797] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 21:31:50 (1767321110) [ 6373.655185] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 21:32:02 (1767321122) [ 6381.625348] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 21:32:10 (1767321130) [ 6389.273571] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 21:32:18 (1767321138) [ 6421.011916] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 21:32:50 (1767321170) [ 6434.265821] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 21:33:03 (1767321183) [ 6446.305761] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 21:33:15 (1767321195) [ 6464.168783] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 21:33:33 (1767321213) [ 6479.524780] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 21:33:48 (1767321228) [ 6480.945201] Lustre: DEBUG MARKER: SKIP: sanityn test_55e needs >= 2 MDTs [ 6482.873082] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 21:33:51 (1767321231) [ 6501.687561] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 21:34:10 (1767321250) [ 6527.810894] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 21:34:36 (1767321276) [ 6622.478679] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 21:36:11 (1767321371) [ 6730.331978] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 21:37:59 (1767321479) [ 6737.284756] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 6743.667725] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 21:38:12 (1767321492) [ 6750.750381] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 21:38:20 (1767321500) [ 6752.694529] Lustre: DEBUG MARKER: SKIP: sanityn test_71a ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 6754.622987] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 21:38:23 (1767321503) [ 6756.437604] Lustre: DEBUG MARKER: SKIP: sanityn test_71b ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 6757.807599] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 21:38:27 (1767321507) [ 6759.306241] Lustre: DEBUG MARKER: SKIP: sanityn test_71c support only ldiskfs ost [ 6761.327224] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 21:38:30 (1767321510) [ 6762.751744] Lustre: DEBUG MARKER: SKIP: sanityn test_71d ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 6764.484869] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 21:38:33 (1767321513) [ 6771.503534] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 21:38:40 (1767321520) [ 6778.177604] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 21:38:47 (1767321527) [ 6781.557696] LustreError: lustre-MDT0000-mdc-ffff9cbcd175e000: operation ldlm_enqueue to node 192.168.204.156@tcp failed: rc = -35 [ 6788.633605] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 21:38:58 (1767321538) [ 6789.229981] LustreError: 2489:0:(osc_request.c:3093:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 6791.239084] LustreError: 2489:0:(osc_request.c:3093:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 6799.737425] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 21:39:09 (1767321549) [ 6852.351454] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 21:40:01 (1767321601) [ 6861.353687] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 21:40:10 (1767321610) [ 6876.881982] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 21:40:25 (1767321625) [ 6899.862610] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 21:40:48 (1767321648) [ 6914.932812] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 21:41:04 (1767321664) [ 6936.022030] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 21:41:25 (1767321685) [ 6960.869857] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 21:41:49 (1767321709) [ 6972.325273] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 21:42:01 (1767321721) [ 6975.456155] Lustre: lustre-OST0001-osc-ffff9cbcc65ee000: disconnect after 22s idle [ 6975.467565] Lustre: Skipped 7 previous similar messages [ 6985.348549] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 21:42:14 (1767321734) [ 7007.551362] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 21:42:36 (1767321756) [ 7074.464377] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 21:43:43 (1767321823) [ 7226.872610] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 21:46:15 (1767321975) [ 7382.508555] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 21:48:51 (1767322131) [ 7630.817841] Lustre: lustre-OST0001-osc-ffff9cbcc65ee000: disconnect after 20s idle [ 7630.829257] Lustre: Skipped 17 previous similar messages [ 7826.525467] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 21:56:15 (1767322575) [ 7879.613262] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 21:57:08 (1767322628) [ 7930.630267] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 21:58:00 (1767322680) [ 7976.967783] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 21:58:46 (1767322726) [ 8067.685559] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 22:00:17 (1767322817) [ 8139.537644] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 22:01:28 (1767322888) [ 8270.820298] Lustre: lustre-OST0000-osc-ffff9cbcc65ee000: disconnect after 22s idle [ 8270.826869] Lustre: Skipped 17 previous similar messages [ 8273.884424] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 22:03:43 (1767323023) [ 8277.294578] LustreError: 257771:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcc65ee000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8277.305729] LustreError: 257771:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 8277.322253] Lustre: Unmounted lustre-client [ 8277.794218] LustreError: 257773:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcd175e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8277.799847] LustreError: 257773:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 8277.819251] LustreError: 257773:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 8277.822321] LustreError: 257773:0:(obd_class.h:479:obd_check_dev()) Skipped 9 previous similar messages [ 8277.826937] Lustre: Unmounted lustre-client [ 8352.367900] Lustre: Mounted lustre-client [ 8355.132307] Lustre: Mounted lustre-client [ 8358.513746] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 8467.962332] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 22:06:57 (1767323217) [ 8483.887882] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 22:07:13 (1767323233) [ 8493.221790] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 22:07:22 (1767323242) [ 8549.519854] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 22:08:19 (1767323299) [ 8629.420252] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 22:09:38 (1767323378) [ 8639.225959] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 22:09:48 (1767323388) [ 8739.535333] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 22:11:28 (1767323488) [ 8768.531356] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 22:11:58 (1767323518) [ 8821.022996] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 22:12:50 (1767323570) [ 8827.532266] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 22:12:56 (1767323576) [ 8844.381527] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 22:13:13 (1767323593) [ 8845.465012] Lustre: DEBUG MARKER: SKIP: sanityn test_80a needs >= 2 MDTs [ 8846.765988] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 22:13:16 (1767323596) [ 8847.827851] Lustre: DEBUG MARKER: SKIP: sanityn test_80b needs >= 2 MDTs [ 8848.986952] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 22:13:18 (1767323598) [ 8850.046144] Lustre: DEBUG MARKER: SKIP: sanityn test_81a needs >= 2 MDTs [ 8851.324275] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 22:13:20 (1767323600) [ 8852.490978] Lustre: DEBUG MARKER: SKIP: sanityn test_81b We need at least 2 MDTs for this test [ 8853.969621] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 22:13:23 (1767323603) [ 8855.162715] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 8856.655995] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 22:13:26 (1767323606) [ 8964.655909] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 22:15:14 (1767323714) [ 8969.422200] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 22:15:19 (1767323719) [ 8970.232262] Lustre: DEBUG MARKER: SKIP: sanityn test_83 needs >= 2 MDTs [ 8971.634714] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 22:15:21 (1767323721) [ 8981.342356] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 22:15:31 (1767323731) [ 8985.058186] Lustre: lustre-OST0001-osc-ffff9cbcc8163000: disconnect after 20s idle [ 8985.060948] Lustre: Skipped 13 previous similar messages [ 8987.713997] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 22:15:37 (1767323737) [ 8988.839853] Lustre: DEBUG MARKER: SKIP: sanityn test_90 needs >= 2 MDTs [ 8990.302912] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 22:15:39 (1767323739) [ 8991.306294] Lustre: DEBUG MARKER: SKIP: sanityn test_91 needs >= 2 MDTs [ 8993.087046] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 22:15:42 (1767323742) [ 8994.461744] Lustre: DEBUG MARKER: SKIP: sanityn test_92 needs >= 2 MDTs [ 8996.090235] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 22:15:45 (1767323745) [ 9008.877134] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 22:15:58 (1767323758) [ 9009.085263] Lustre: DEBUG MARKER: write [ 9009.124545] LustreError: 259519:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 9011.121809] Lustre: DEBUG MARKER: kill 284730 [ 9011.124748] LustreError: 284730:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 9014.127243] LustreError: 259519:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 9017.167117] LustreError: 284730:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 9021.396523] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 22:16:10 (1767323770) [ 9023.922906] LustreError: 285337:0:(rw.c:1957:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 9033.943342] LustreError: 285337:0:(rw.c:1957:ll_readpage()) cfs_fail_timeout id 1422 awake [ 9037.754059] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 22:16:27 (1767323787) [ 9037.961637] LustreError: 285916:0:(rw.c:2202:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 9040.049675] LustreError: 285916:0:(rw.c:2202:ll_readpage()) cfs_fail_timeout interrupted [ 9047.474049] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 22:16:37 (1767323797) [ 9048.460647] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 9049.682368] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 22:16:39 (1767323799) [ 9053.903622] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 22:16:43 (1767323803) [ 9057.921769] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 22:16:47 (1767323807) [ 9062.867429] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 22:16:52 (1767323812) [ 9067.302209] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 22:16:57 (1767323817) [ 9071.825307] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 22:17:01 (1767323821) [ 9076.687606] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 22:17:06 (1767323826) [ 9082.469516] Lustre: DEBUG MARKER: SKIP: sanityn test_102 skipping ALWAYS excluded test 102 [ 9083.679986] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 22:17:13 (1767323833) [ 9084.808974] Lustre: *** cfs_fail_loc=415, val=0*** [ 9094.022486] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 22:17:23 (1767323843) [ 9095.083956] Lustre: DEBUG MARKER: SKIP: sanityn test_104 ldiskfs only test [ 9096.333538] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 22:17:25 (1767323845) [ 9096.730193] LustreError: 260708:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [ 9096.739691] LustreError: 260708:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [ 9101.735129] LustreError: 260708:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 9112.023134] LustreError: 260192:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 9112.027606] LustreError: 260192:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 5 previous similar messages [ 9122.058809] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 22:17:51 (1767323871) [ 9123.112834] Lustre: DEBUG MARKER: SKIP: sanityn test_106a Test only for ldiskfs and statx() supported [ 9124.161845] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 22:17:53 (1767323873) [ 9128.619306] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 22:17:58 (1767323878) [ 9132.452574] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 22:18:02 (1767323882) [ 9139.003910] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 22:18:08 (1767323888) [ 9149.612507] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 22:18:19 (1767323899) [ 9150.100340] LustreError: 276019:0:(osc_request.c:2944:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 9150.107928] LustreError: 276019:0:(osc_request.c:2944:osc_build_rpc()) Skipped 5 previous similar messages [ 9154.183323] LustreError: 276019:0:(osc_request.c:2944:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 9154.186663] LustreError: 276019:0:(osc_request.c:2944:osc_build_rpc()) Skipped 2 previous similar messages [ 9158.965315] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 22:18:28 (1767323908) [ 9160.747812] LustreError: 295865:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcc8163000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9160.756978] LustreError: 295865:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 9160.768919] LustreError: 295865:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 9160.775601] LustreError: 295865:0:(obd_class.h:479:obd_check_dev()) Skipped 3 previous similar messages [ 9160.803279] Lustre: Unmounted lustre-client [ 9161.800624] LustreError: 295886:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcd176b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9161.806046] LustreError: 295886:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 9161.815505] LustreError: 295886:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 9161.817644] LustreError: 295886:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 9161.847635] Lustre: Unmounted lustre-client [ 9162.918550] Lustre: DEBUG MARKER: Iteration 1 [ 9163.129814] LustreError: 296042:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9163.132495] LustreError: 296045:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9163.145626] LustreError: 296042:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [ 9163.279906] Lustre: Mounted lustre-client [ 9164.266948] LustreError: 296150:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcc65ec800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9164.273631] LustreError: 296150:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 9164.283350] LustreError: 296150:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9164.286369] LustreError: 296150:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 9164.311689] Lustre: Unmounted lustre-client [ 9165.912541] Key type lgssc unregistered [ 9166.098946] LNet: 296393:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9166.111400] LNetError: 296393:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9166.131277] LNet: Removed LNI 192.168.204.56@tcp [ 9166.672241] Key type .llcrypt unregistered [ 9166.674088] Key type ._llcrypt unregistered [ 9167.221832] Key type ._llcrypt registered [ 9167.224484] Key type .llcrypt registered [ 9167.501415] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9167.506975] alg: No test for adler32 (adler32-zlib) [ 9168.747712] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9169.238549] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9170.895173] Key type lgssc registered [ 9171.892710] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9178.393881] Lustre: DEBUG MARKER: Iteration 2 [ 9178.569713] LustreError: 297209:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9178.575504] LustreError: 297213:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9178.580732] LustreError: 297209:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9179.738698] Lustre: Mounted lustre-client [ 9180.987756] LustreError: 297315:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcc4d4b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9181.001767] LustreError: 297315:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 9181.036086] Lustre: Unmounted lustre-client [ 9182.938514] Key type lgssc unregistered [ 9183.118722] LNet: 297563:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9183.127507] LNetError: 297563:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9183.141685] LNet: Removed LNI 192.168.204.56@tcp [ 9183.531152] Key type .llcrypt unregistered [ 9183.532665] Key type ._llcrypt unregistered [ 9184.006311] Key type ._llcrypt registered [ 9184.011498] Key type .llcrypt registered [ 9184.301545] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9184.314438] alg: No test for adler32 (adler32-zlib) [ 9185.307499] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9185.506331] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9187.135619] Key type lgssc registered [ 9188.007478] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9195.398962] Lustre: DEBUG MARKER: Iteration 3 [ 9195.673405] LustreError: 298381:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9195.675276] LustreError: 298382:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9195.686634] LustreError: 298381:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 9196.865550] Lustre: Mounted lustre-client [ 9198.206685] LustreError: 298489:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcc7c16800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9198.219393] LustreError: 298489:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 9198.239702] Lustre: Unmounted lustre-client [ 9199.746930] Key type lgssc unregistered [ 9199.935639] LNet: 298732:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9199.939119] LNetError: 298732:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9199.955542] LNet: Removed LNI 192.168.204.56@tcp [ 9200.340162] Key type .llcrypt unregistered [ 9200.342384] Key type ._llcrypt unregistered [ 9200.770167] Key type ._llcrypt registered [ 9200.771778] Key type .llcrypt registered [ 9201.094213] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9201.107779] alg: No test for adler32 (adler32-zlib) [ 9202.062416] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9202.224948] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9203.855179] Key type lgssc registered [ 9204.645469] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9211.808396] Lustre: DEBUG MARKER: Iteration 4 [ 9212.026184] LustreError: 299551:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9212.026581] LustreError: 299553:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9212.036267] LustreError: 299551:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 9214.294636] Lustre: Mounted lustre-client [ 9215.245175] LustreError: 299660:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbd002d5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9215.254016] LustreError: 299660:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9215.254090] LustreError: 299662:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 9215.290946] Lustre: Unmounted lustre-client [ 9216.871760] Key type lgssc unregistered [ 9217.043769] LNet: 299903:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9217.047534] LNetError: 299903:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9217.059645] LNet: Removed LNI 192.168.204.56@tcp [ 9217.459448] Key type .llcrypt unregistered [ 9217.462433] Key type ._llcrypt unregistered [ 9217.897742] Key type ._llcrypt registered [ 9217.902051] Key type .llcrypt registered [ 9218.205194] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9218.215414] alg: No test for adler32 (adler32-zlib) [ 9219.157781] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9219.291455] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9220.911156] Key type lgssc registered [ 9221.653717] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9229.334455] Lustre: DEBUG MARKER: Iteration 5 [ 9229.580547] LustreError: 300721:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9229.582401] LustreError: 300722:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9229.599216] LustreError: 300721:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [ 9230.849582] Lustre: Mounted lustre-client [ 9231.958817] LustreError: 300832:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcc5773800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9231.973425] LustreError: 300832:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9231.985642] LustreError: 300824:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 9231.990871] LustreError: 300824:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 9232.015492] Lustre: Unmounted lustre-client [ 9233.939181] Key type lgssc unregistered [ 9234.157667] LNet: 301072:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9234.166308] LNetError: 301072:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9234.193332] LNet: Removed LNI 192.168.204.56@tcp [ 9234.695174] Key type .llcrypt unregistered [ 9234.696791] Key type ._llcrypt unregistered [ 9235.342099] Key type ._llcrypt registered [ 9235.343507] Key type .llcrypt registered [ 9235.652478] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9235.669353] alg: No test for adler32 (adler32-zlib) [ 9236.634417] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9236.799420] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9238.463191] Key type lgssc registered [ 9239.238509] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9247.232235] Lustre: DEBUG MARKER: Iteration 6 [ 9247.486577] LustreError: 301890:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9247.487082] LustreError: 301889:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9247.505728] LustreError: 301890:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4987 [ 9248.698986] Lustre: Mounted lustre-client [ 9248.707436] Lustre: Skipped 1 previous similar message [ 9249.681722] LustreError: 301994:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcd0503000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9249.699518] LustreError: 301994:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 9249.709215] LustreError: 301994:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 9249.719227] Lustre: Unmounted lustre-client [ 9251.588493] Key type lgssc unregistered [ 9251.814063] LNet: 302240:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9251.820532] LNetError: 302240:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9251.834508] LNet: Removed LNI 192.168.204.56@tcp [ 9252.348147] Key type .llcrypt unregistered [ 9252.350640] Key type ._llcrypt unregistered [ 9253.011499] Key type ._llcrypt registered [ 9253.013172] Key type .llcrypt registered [ 9253.201145] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9253.210326] alg: No test for adler32 (adler32-zlib) [ 9254.160955] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9254.309717] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9255.943132] Key type lgssc registered [ 9256.693637] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9265.028408] Lustre: DEBUG MARKER: Iteration 7 [ 9265.431384] LustreError: 303060:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9265.435279] LustreError: 303061:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9265.453082] LustreError: 303060:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [ 9267.734521] Lustre: Mounted lustre-client [ 9267.735886] Lustre: Skipped 1 previous similar message [ 9268.797621] LustreError: 303166:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcf5ce6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9268.819578] LustreError: 303166:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9268.824698] LustreError: 303166:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 9268.843907] Lustre: Unmounted lustre-client [ 9270.726883] Key type lgssc unregistered [ 9270.893610] LNet: 303410:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9270.908945] LNetError: 303410:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9270.919923] LNet: Removed LNI 192.168.204.56@tcp [ 9271.488153] Key type .llcrypt unregistered [ 9271.489467] Key type ._llcrypt unregistered [ 9272.019429] Key type ._llcrypt registered [ 9272.021247] Key type .llcrypt registered [ 9272.346912] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9272.355951] alg: No test for adler32 (adler32-zlib) [ 9273.351929] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9273.510085] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9275.159206] Key type lgssc registered [ 9276.117190] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9284.597366] Lustre: DEBUG MARKER: Iteration 8 [ 9284.858616] LustreError: 304229:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9284.859301] LustreError: 304230:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9284.879470] LustreError: 304229:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [ 9287.069742] Lustre: Mounted lustre-client [ 9287.076241] Lustre: Skipped 1 previous similar message [ 9288.082441] LustreError: 304331:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcc5e16000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9288.104164] LustreError: 304331:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 9288.128782] Lustre: Unmounted lustre-client [ 9289.787296] Key type lgssc unregistered [ 9289.963902] LNet: 304580:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9289.968492] LNetError: 304580:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9289.979845] LNet: Removed LNI 192.168.204.56@tcp [ 9290.349206] Key type .llcrypt unregistered [ 9290.350437] Key type ._llcrypt unregistered [ 9290.875561] Key type ._llcrypt registered [ 9290.877190] Key type .llcrypt registered [ 9291.153906] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9291.166390] alg: No test for adler32 (adler32-zlib) [ 9292.133400] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9292.290563] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9293.919616] Key type lgssc registered [ 9294.694535] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9301.635464] Lustre: DEBUG MARKER: Iteration 9 [ 9301.916437] LustreError: 305399:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9301.916515] LustreError: 305398:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9301.932747] LustreError: 305399:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9303.123514] Lustre: Mounted lustre-client [ 9304.403754] LustreError: 305509:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcc2cdf800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9304.420912] LustreError: 305510:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9304.446716] Lustre: Unmounted lustre-client [ 9305.980421] Key type lgssc unregistered [ 9306.124766] LNet: 305752:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9306.130262] LNetError: 305752:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9306.148469] LNet: Removed LNI 192.168.204.56@tcp [ 9306.479153] Key type .llcrypt unregistered [ 9306.481447] Key type ._llcrypt unregistered [ 9307.057463] Key type ._llcrypt registered [ 9307.059368] Key type .llcrypt registered [ 9307.234466] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9307.242084] alg: No test for adler32 (adler32-zlib) [ 9308.191141] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9308.320675] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9309.935208] Key type lgssc registered [ 9310.711489] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9318.919439] Lustre: DEBUG MARKER: Iteration 10 [ 9319.182735] LustreError: 306570:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9319.183115] LustreError: 306569:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9319.191287] LustreError: 306570:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 9321.375668] Lustre: Mounted lustre-client [ 9322.317631] LustreError: 306680:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcc2fda800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9322.328913] LustreError: 306680:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 9322.331218] LustreError: 306680:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 9322.348154] Lustre: Unmounted lustre-client [ 9323.832573] Key type lgssc unregistered [ 9323.968568] LNet: 306922:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9323.975829] LNetError: 306922:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9323.995793] LNet: Removed LNI 192.168.204.56@tcp [ 9324.467129] Key type .llcrypt unregistered [ 9324.468989] Key type ._llcrypt unregistered [ 9324.886888] Key type ._llcrypt registered [ 9324.888948] Key type .llcrypt registered [ 9325.103135] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9325.114285] alg: No test for adler32 (adler32-zlib) [ 9326.078792] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9326.218771] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9327.831171] Key type lgssc registered [ 9328.571445] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9336.055369] Lustre: DEBUG MARKER: Iteration 11 [ 9336.321062] LustreError: 307741:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9336.325768] LustreError: 307742:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9336.331348] LustreError: 307741:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 9337.479458] Lustre: Mounted lustre-client [ 9338.462436] LustreError: 307845:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcd1591800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9338.481952] LustreError: 307848:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 9338.486591] LustreError: 307848:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 9338.520163] Lustre: Unmounted lustre-client [ 9340.125244] Key type lgssc unregistered [ 9340.305476] LNet: 308090:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9340.312566] LNetError: 308090:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9340.329858] LNet: Removed LNI 192.168.204.56@tcp [ 9340.723154] Key type .llcrypt unregistered [ 9340.724535] Key type ._llcrypt unregistered [ 9341.278460] Key type ._llcrypt registered [ 9341.285664] Key type .llcrypt registered [ 9341.626843] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9341.643818] alg: No test for adler32 (adler32-zlib) [ 9342.613966] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9342.726786] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9344.345970] Key type lgssc registered [ 9345.085542] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9351.982926] Lustre: DEBUG MARKER: Iteration 12 [ 9352.245496] LustreError: 308909:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9352.248257] LustreError: 308910:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9352.253983] LustreError: 308909:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9353.394602] Lustre: Mounted lustre-client [ 9353.400608] Lustre: Skipped 1 previous similar message [ 9354.245773] LustreError: 309012:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcc5775000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9354.255289] LustreError: 309012:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9354.278146] Lustre: Unmounted lustre-client [ 9355.730283] Key type lgssc unregistered [ 9355.864705] LNet: 309261:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9355.869592] LNetError: 309261:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9355.885604] LNet: Removed LNI 192.168.204.56@tcp [ 9356.262131] Key type .llcrypt unregistered [ 9356.264563] Key type ._llcrypt unregistered [ 9356.738206] Key type ._llcrypt registered [ 9356.739899] Key type .llcrypt registered [ 9356.914866] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9356.923794] alg: No test for adler32 (adler32-zlib) [ 9357.849153] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9357.985415] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9359.591169] Key type lgssc registered [ 9360.299726] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9366.486406] Lustre: DEBUG MARKER: Iteration 13 [ 9366.682132] LustreError: 310080:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9366.682680] LustreError: 310081:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9366.692192] LustreError: 310080:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9368.896321] Lustre: Mounted lustre-client [ 9369.889989] LustreError: 310191:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcf51d8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9369.906143] LustreError: 310191:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 9369.931083] Lustre: Unmounted lustre-client [ 9371.467915] Key type lgssc unregistered [ 9371.643258] LNet: 310433:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9371.648210] LNetError: 310433:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9371.658495] LNet: Removed LNI 192.168.204.56@tcp [ 9371.958137] Key type .llcrypt unregistered [ 9371.960031] Key type ._llcrypt unregistered [ 9372.558270] Key type ._llcrypt registered [ 9372.560895] Key type .llcrypt registered [ 9372.702194] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9372.710057] alg: No test for adler32 (adler32-zlib) [ 9373.644143] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9373.836974] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9375.463309] Key type lgssc registered [ 9376.319509] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9382.947796] Lustre: DEBUG MARKER: Iteration 14 [ 9383.077260] LustreError: 311250:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9383.078096] LustreError: 311252:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9383.086109] LustreError: 311250:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 9384.222720] Lustre: Mounted lustre-client [ 9384.226283] Lustre: Skipped 1 previous similar message [ 9385.015770] LustreError: 311355:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcf51dd000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9385.026208] LustreError: 311355:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 9385.051088] Lustre: Unmounted lustre-client [ 9386.571955] Key type lgssc unregistered [ 9386.740712] LNet: 311604:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9386.744811] LNetError: 311604:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9386.752917] LNet: Removed LNI 192.168.204.56@tcp [ 9387.172240] Key type .llcrypt unregistered [ 9387.174890] Key type ._llcrypt unregistered [ 9387.570303] Key type ._llcrypt registered [ 9387.592327] Key type .llcrypt registered [ 9387.809350] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9387.817403] alg: No test for adler32 (adler32-zlib) [ 9388.808134] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9389.008793] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9390.623175] Key type lgssc registered [ 9391.198591] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9398.149855] Lustre: DEBUG MARKER: Iteration 15 [ 9398.343761] LustreError: 312423:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9398.344256] LustreError: 312424:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9398.356851] LustreError: 312423:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [ 9399.521210] Lustre: Mounted lustre-client [ 9400.398931] LustreError: 312529:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbce0710000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9400.407432] LustreError: 312529:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9400.412455] LustreError: 312528:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 9400.429339] Lustre: Unmounted lustre-client [ 9402.037984] Key type lgssc unregistered [ 9402.199714] LNet: 312770:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9402.204650] LNetError: 312770:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9402.222212] LNet: Removed LNI 192.168.204.56@tcp [ 9402.697513] Key type .llcrypt unregistered [ 9402.699682] Key type ._llcrypt unregistered [ 9403.255618] Key type ._llcrypt registered [ 9403.257571] Key type .llcrypt registered [ 9403.451586] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9403.464059] alg: No test for adler32 (adler32-zlib) [ 9404.391752] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9404.558639] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9406.191149] Key type lgssc registered [ 9406.969174] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9413.759823] Lustre: DEBUG MARKER: Iteration 16 [ 9413.962254] LustreError: 313585:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9413.962837] LustreError: 313587:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9413.968641] LustreError: 313585:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9415.149264] Lustre: Mounted lustre-client [ 9415.936374] LustreError: 313692:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcd0503800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9415.950846] LustreError: 313692:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 9415.974132] Lustre: Unmounted lustre-client [ 9417.491894] Key type lgssc unregistered [ 9417.679357] LNet: 313936:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9417.689654] LNetError: 313936:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9417.702375] LNet: Removed LNI 192.168.204.56@tcp [ 9418.141972] Key type .llcrypt unregistered [ 9418.145861] Key type ._llcrypt unregistered [ 9418.649309] Key type ._llcrypt registered [ 9418.651043] Key type .llcrypt registered [ 9418.797414] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9418.808291] alg: No test for adler32 (adler32-zlib) [ 9419.827858] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9420.004699] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9421.623332] Key type lgssc registered [ 9422.376913] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9429.500607] Lustre: DEBUG MARKER: Iteration 17 [ 9429.839064] LustreError: 314756:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9429.839811] LustreError: 314757:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9429.850437] LustreError: 314756:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4989 [ 9431.045983] Lustre: Mounted lustre-client [ 9432.036741] LustreError: 314866:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcf5726000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9432.058720] LustreError: 314866:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 9432.092884] Lustre: Unmounted lustre-client [ 9434.085353] Key type lgssc unregistered [ 9434.269633] LNet: 315108:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9434.274070] LNetError: 315108:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9434.290564] LNet: Removed LNI 192.168.204.56@tcp [ 9434.771899] Key type .llcrypt unregistered [ 9434.774285] Key type ._llcrypt unregistered [ 9435.318646] Key type ._llcrypt registered [ 9435.329682] Key type .llcrypt registered [ 9435.609944] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9435.624407] alg: No test for adler32 (adler32-zlib) [ 9436.560555] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9436.699880] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9438.335160] Key type lgssc registered [ 9438.911345] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9445.669847] Lustre: DEBUG MARKER: Iteration 18 [ 9445.872426] LustreError: 315922:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9445.877110] LustreError: 315930:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9445.882434] LustreError: 315922:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 9447.044322] Lustre: Mounted lustre-client [ 9447.045919] Lustre: Skipped 1 previous similar message [ 9447.739378] LustreError: 316030:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcc8164000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9447.749929] LustreError: 316030:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 9447.771276] Lustre: Unmounted lustre-client [ 9449.366749] Key type lgssc unregistered [ 9449.562078] LNet: 316279:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9449.564982] LNetError: 316279:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9449.580875] LNet: Removed LNI 192.168.204.56@tcp [ 9449.907125] Key type .llcrypt unregistered [ 9449.908473] Key type ._llcrypt unregistered [ 9450.257255] Key type ._llcrypt registered [ 9450.259252] Key type .llcrypt registered [ 9450.624262] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9450.635545] alg: No test for adler32 (adler32-zlib) [ 9451.602632] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9451.757173] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9453.367199] Key type lgssc registered [ 9453.978504] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9461.365418] Lustre: DEBUG MARKER: Iteration 19 [ 9461.671727] LustreError: 317097:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9461.672193] LustreError: 317098:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9461.680423] LustreError: 317097:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9462.861991] Lustre: Mounted lustre-client [ 9463.833255] LustreError: 317204:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcd0501000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9463.851555] LustreError: 317204:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 9463.879804] Lustre: Unmounted lustre-client [ 9465.574530] Key type lgssc unregistered [ 9465.744740] LNet: 317445:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9465.749561] LNetError: 317445:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9465.765086] LNet: Removed LNI 192.168.204.56@tcp [ 9466.114161] Key type .llcrypt unregistered [ 9466.116503] Key type ._llcrypt unregistered [ 9466.597205] Key type ._llcrypt registered [ 9466.604342] Key type .llcrypt registered [ 9466.813358] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9466.820637] alg: No test for adler32 (adler32-zlib) [ 9467.772519] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9467.916983] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9469.535756] Key type lgssc registered [ 9470.143062] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9477.135654] Lustre: DEBUG MARKER: Iteration 20 [ 9477.406864] LustreError: 318261:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9477.425081] LustreError: 318276:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9477.427507] LustreError: 318261:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4986 [ 9479.617543] Lustre: Mounted lustre-client [ 9480.565347] LustreError: 318372:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcf8e86800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9480.572702] LustreError: 318372:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9480.578086] LustreError: 318374:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 9480.601052] Lustre: Unmounted lustre-client [ 9482.200313] Key type lgssc unregistered [ 9482.368837] LNet: 318614:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9482.374354] LNetError: 318614:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9482.387768] LNet: Removed LNI 192.168.204.56@tcp [ 9482.801144] Key type .llcrypt unregistered [ 9482.803110] Key type ._llcrypt unregistered [ 9483.151573] Key type ._llcrypt registered [ 9483.152990] Key type .llcrypt registered [ 9483.468646] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9483.476453] alg: No test for adler32 (adler32-zlib) [ 9484.405678] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9484.534943] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9486.143160] Key type lgssc registered [ 9486.936235] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9494.110208] Lustre: DEBUG MARKER: Iteration 21 [ 9494.336408] LustreError: 319432:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9494.340135] LustreError: 319433:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9494.344985] LustreError: 319432:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9495.492458] Lustre: Mounted lustre-client [ 9495.496471] Lustre: Skipped 1 previous similar message [ 9496.558939] LustreError: 319538:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcd0505000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9496.564725] LustreError: 319538:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9496.569975] LustreError: 319539:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 9496.576860] LustreError: 319539:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 9496.600197] Lustre: Unmounted lustre-client [ 9498.075675] Key type lgssc unregistered [ 9498.266762] LNet: 319779:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9498.276191] LNetError: 319779:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9498.289306] LNet: Removed LNI 192.168.204.56@tcp [ 9498.725156] Key type .llcrypt unregistered [ 9498.726773] Key type ._llcrypt unregistered [ 9499.362232] Key type ._llcrypt registered [ 9499.376966] Key type .llcrypt registered [ 9499.588554] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9499.597154] alg: No test for adler32 (adler32-zlib) [ 9500.528745] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9500.669300] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9502.287171] Key type lgssc registered [ 9503.159762] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9510.711987] Lustre: DEBUG MARKER: Iteration 22 [ 9510.926782] LustreError: 320598:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9510.928431] LustreError: 320599:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9510.937804] LustreError: 320598:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9512.121810] Lustre: Mounted lustre-client [ 9513.068877] LustreError: 320701:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcc7c16800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9513.081147] LustreError: 320701:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 9513.106484] Lustre: Unmounted lustre-client [ 9514.526126] Key type lgssc unregistered [ 9514.750342] LNet: 320949:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9514.758529] LNetError: 320949:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9514.772729] LNet: Removed LNI 192.168.204.56@tcp [ 9515.183135] Key type .llcrypt unregistered [ 9515.184792] Key type ._llcrypt unregistered [ 9515.584439] Key type ._llcrypt registered [ 9515.585681] Key type .llcrypt registered [ 9515.784267] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9515.791947] alg: No test for adler32 (adler32-zlib) [ 9516.722972] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9516.850360] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9518.463207] Key type lgssc registered [ 9519.172739] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9526.400853] Lustre: DEBUG MARKER: Iteration 23 [ 9526.717541] LustreError: 321771:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9526.717724] LustreError: 321770:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9526.726113] LustreError: 321771:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 9527.917418] Lustre: Mounted lustre-client [ 9528.904853] LustreError: 321879:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbce22d3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9528.919540] LustreError: 321879:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 9528.943665] Lustre: Unmounted lustre-client [ 9530.666605] Key type lgssc unregistered [ 9530.859943] LNet: 322120:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9530.864587] LNetError: 322120:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9530.877052] LNet: Removed LNI 192.168.204.56@tcp [ 9531.244503] Key type .llcrypt unregistered [ 9531.247600] Key type ._llcrypt unregistered [ 9531.790546] Key type ._llcrypt registered [ 9531.799114] Key type .llcrypt registered [ 9532.243920] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9532.249687] alg: No test for adler32 (adler32-zlib) [ 9533.270837] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9533.426905] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9535.039259] Key type lgssc registered [ 9535.972198] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9543.325904] Lustre: DEBUG MARKER: Iteration 24 [ 9543.564976] LustreError: 322938:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9543.565481] LustreError: 322943:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9543.578181] LustreError: 322938:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [ 9544.756241] Lustre: Mounted lustre-client [ 9544.758906] Lustre: Skipped 1 previous similar message [ 9545.836582] LustreError: 323038:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbce21ad800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9545.859689] LustreError: 323038:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 9545.891584] Lustre: Unmounted lustre-client [ 9547.503233] Key type lgssc unregistered [ 9547.662231] LNet: 323287:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9547.665191] LNetError: 323287:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9547.677224] LNet: Removed LNI 192.168.204.56@tcp [ 9548.061146] Key type .llcrypt unregistered [ 9548.063346] Key type ._llcrypt unregistered [ 9548.456475] Key type ._llcrypt registered [ 9548.461904] Key type .llcrypt registered [ 9548.697985] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9548.705422] alg: No test for adler32 (adler32-zlib) [ 9549.692670] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9549.845970] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9551.496081] Key type lgssc registered [ 9552.358566] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9559.254073] Lustre: DEBUG MARKER: Iteration 25 [ 9559.435978] LustreError: 324106:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9559.437505] LustreError: 324107:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9559.443102] LustreError: 324106:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9560.583831] Lustre: Mounted lustre-client [ 9560.588504] Lustre: Skipped 1 previous similar message [ 9561.649930] LustreError: 324215:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbceb708800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9561.667823] LustreError: 324215:0:(obd_class.h:479:obd_check_dev()) Device 7 not setup [ 9561.702740] Lustre: Unmounted lustre-client [ 9563.642714] Key type lgssc unregistered [ 9563.852377] LNet: 324457:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9563.858535] LNetError: 324457:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9563.870590] LNet: Removed LNI 192.168.204.56@tcp [ 9564.333194] Key type .llcrypt unregistered [ 9564.334053] Key type ._llcrypt unregistered [ 9564.917306] Key type ._llcrypt registered [ 9564.918393] Key type .llcrypt registered [ 9565.217445] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9565.227532] alg: No test for adler32 (adler32-zlib) [ 9566.181527] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9566.333135] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9567.967414] Key type lgssc registered [ 9568.617489] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9575.614827] Lustre: DEBUG MARKER: Iteration 26 [ 9575.817591] LustreError: 325276:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9575.817730] LustreError: 325278:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9575.832085] LustreError: 325276:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9576.980703] Lustre: Mounted lustre-client [ 9576.988344] Lustre: Skipped 1 previous similar message [ 9577.798432] LustreError: 325378:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbce0713000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9577.806606] LustreError: 325378:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 9577.829295] Lustre: Unmounted lustre-client [ 9579.286451] Key type lgssc unregistered [ 9579.427926] LNet: 325627:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9579.431986] LNetError: 325627:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9579.440025] LNet: Removed LNI 192.168.204.56@tcp [ 9579.777152] Key type .llcrypt unregistered [ 9579.779539] Key type ._llcrypt unregistered [ 9580.261788] Key type ._llcrypt registered [ 9580.263345] Key type .llcrypt registered [ 9580.434176] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9580.449480] alg: No test for adler32 (adler32-zlib) [ 9581.409626] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9581.555550] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9583.183180] Key type lgssc registered [ 9584.047534] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9591.910630] Lustre: DEBUG MARKER: Iteration 27 [ 9592.126419] LustreError: 326444:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9592.128653] LustreError: 326445:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9592.137277] LustreError: 326444:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 9593.308563] Lustre: Mounted lustre-client [ 9594.492793] LustreError: 326546:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbce0d76800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9594.516986] LustreError: 326546:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 9594.546823] Lustre: Unmounted lustre-client [ 9596.435362] Key type lgssc unregistered [ 9596.607685] LNet: 326794:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9596.617571] LNetError: 326794:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9596.638420] LNet: Removed LNI 192.168.204.56@tcp [ 9597.179313] Key type .llcrypt unregistered [ 9597.181853] Key type ._llcrypt unregistered [ 9597.722819] Key type ._llcrypt registered [ 9597.725162] Key type .llcrypt registered [ 9598.077244] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9598.087446] alg: No test for adler32 (adler32-zlib) [ 9599.028721] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9599.151200] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9600.791181] Key type lgssc registered [ 9601.586532] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9609.041842] Lustre: DEBUG MARKER: Iteration 28 [ 9609.216460] LustreError: 327612:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9609.221191] LustreError: 327614:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9609.224961] LustreError: 327612:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 9610.394170] Lustre: Mounted lustre-client [ 9611.401346] LustreError: 327712:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcd909e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9611.429091] LustreError: 327712:0:(obd_class.h:479:obd_check_dev()) Device 7 not setup [ 9611.465184] Lustre: Unmounted lustre-client [ 9612.959133] Key type lgssc unregistered [ 9613.130964] LNet: 327961:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9613.137497] LNetError: 327961:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9613.157792] LNet: Removed LNI 192.168.204.56@tcp [ 9613.503441] Key type .llcrypt unregistered [ 9613.506484] Key type ._llcrypt unregistered [ 9613.919905] Key type ._llcrypt registered [ 9613.922566] Key type .llcrypt registered [ 9614.132987] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9614.139763] alg: No test for adler32 (adler32-zlib) [ 9615.033854] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9615.157377] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9616.777508] Key type lgssc registered [ 9617.427783] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9623.669370] Lustre: DEBUG MARKER: Iteration 29 [ 9623.867898] LustreError: 328776:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9623.868584] LustreError: 328778:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9623.880268] LustreError: 328776:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 9624.986072] Lustre: Mounted lustre-client [ 9625.791397] LustreError: 328879:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcc8160800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9625.800881] LustreError: 328879:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 9625.823095] Lustre: Unmounted lustre-client [ 9627.318847] Key type lgssc unregistered [ 9627.499766] LNet: 329129:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9627.504549] LNetError: 329129:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9627.515387] LNet: Removed LNI 192.168.204.56@tcp [ 9627.914169] Key type .llcrypt unregistered [ 9627.916363] Key type ._llcrypt unregistered [ 9628.488466] Key type ._llcrypt registered [ 9628.493133] Key type .llcrypt registered [ 9628.596364] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9628.602693] alg: No test for adler32 (adler32-zlib) [ 9629.549741] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9629.680619] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9631.279348] Key type lgssc registered [ 9631.923213] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9638.567401] Lustre: DEBUG MARKER: Iteration 30 [ 9638.813312] LustreError: 329947:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9638.813416] LustreError: 329948:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9638.829200] LustreError: 329947:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4993 [ 9640.023878] Lustre: Mounted lustre-client [ 9641.093044] LustreError: 330051:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbce8b88800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9641.104990] LustreError: 330051:0:(obd_class.h:479:obd_check_dev()) Device 7 not setup [ 9641.126212] Lustre: Unmounted lustre-client [ 9642.665783] Key type lgssc unregistered [ 9642.836432] LNet: 330297:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9642.841988] LNetError: 330297:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9642.855230] LNet: Removed LNI 192.168.204.56@tcp [ 9643.250168] Key type .llcrypt unregistered [ 9643.251679] Key type ._llcrypt unregistered [ 9643.780019] Key type ._llcrypt registered [ 9643.781468] Key type .llcrypt registered [ 9643.909530] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9643.915356] alg: No test for adler32 (adler32-zlib) [ 9644.859328] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9644.999598] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9646.631511] Key type lgssc registered [ 9647.402140] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9653.755725] Lustre: DEBUG MARKER: Iteration 31 [ 9653.928667] LustreError: 331119:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9653.928754] LustreError: 331118:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9653.938415] LustreError: 331119:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9655.033114] Lustre: Mounted lustre-client [ 9655.039413] Lustre: Skipped 1 previous similar message [ 9655.724743] LustreError: 331217:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcd0500800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9655.735974] LustreError: 331217:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 9655.760323] Lustre: Unmounted lustre-client [ 9657.420060] Key type lgssc unregistered [ 9657.603882] LNet: 331466:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9657.611959] LNetError: 331466:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9657.630974] LNet: Removed LNI 192.168.204.56@tcp [ 9658.042197] Key type .llcrypt unregistered [ 9658.046859] Key type ._llcrypt unregistered [ 9658.557299] Key type ._llcrypt registered [ 9658.558698] Key type .llcrypt registered [ 9658.804486] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9658.812904] alg: No test for adler32 (adler32-zlib) [ 9659.759698] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9659.898835] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9661.503171] Key type lgssc registered [ 9662.278791] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9668.502097] Lustre: DEBUG MARKER: Iteration 32 [ 9668.695688] LustreError: 332286:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9668.697140] LustreError: 332285:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9668.708161] LustreError: 332286:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4992 [ 9669.855047] Lustre: Mounted lustre-client [ 9670.686310] LustreError: 332394:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcd1793800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9670.692465] LustreError: 332394:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9670.693122] LustreError: 332393:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 9670.723908] Lustre: Unmounted lustre-client [ 9670.727725] Lustre: Skipped 1 previous similar message [ 9672.074708] Key type lgssc unregistered [ 9672.254106] LNet: 332634:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9672.259186] LNetError: 332634:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9672.271141] LNet: Removed LNI 192.168.204.56@tcp [ 9672.639129] Key type .llcrypt unregistered [ 9672.641526] Key type ._llcrypt unregistered [ 9672.923900] Key type ._llcrypt registered [ 9672.925661] Key type .llcrypt registered [ 9673.130745] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9673.146152] alg: No test for adler32 (adler32-zlib) [ 9674.075537] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9674.186223] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9675.783177] Key type lgssc registered [ 9676.303651] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9681.304127] Lustre: DEBUG MARKER: Iteration 33 [ 9681.466045] LustreError: 333449:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9681.469842] LustreError: 333457:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9681.473610] LustreError: 333449:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9682.541376] Lustre: Mounted lustre-client [ 9683.045281] LustreError: 333560:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcd0503000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9683.051351] LustreError: 333561:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 9683.051704] LustreError: 333560:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9683.079863] Lustre: Unmounted lustre-client [ 9683.081140] Lustre: Skipped 1 previous similar message [ 9684.289340] Key type lgssc unregistered [ 9684.444373] LNet: 333803:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9684.448336] LNetError: 333803:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9684.459317] LNet: Removed LNI 192.168.204.56@tcp [ 9684.730131] Key type .llcrypt unregistered [ 9684.731573] Key type ._llcrypt unregistered [ 9685.048509] Key type ._llcrypt registered [ 9685.050147] Key type .llcrypt registered [ 9685.275190] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9685.281792] alg: No test for adler32 (adler32-zlib) [ 9686.211369] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9686.347283] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9687.959770] Key type lgssc registered [ 9688.546548] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9693.144738] Lustre: DEBUG MARKER: Iteration 34 [ 9693.285598] LustreError: 334620:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9693.285844] LustreError: 334622:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9693.290260] LustreError: 334620:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9694.393142] Lustre: Mounted lustre-client [ 9694.394778] Lustre: Skipped 1 previous similar message [ 9694.853139] LustreError: 334732:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbce22d3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9694.857109] LustreError: 334732:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9694.862642] LustreError: 334732:0:(obd_class.h:479:obd_check_dev()) Device 7 not setup [ 9694.865056] LustreError: 334732:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 9694.876110] Lustre: Unmounted lustre-client [ 9696.101892] Key type lgssc unregistered [ 9696.267584] LNet: 334973:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9696.270532] LNetError: 334973:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9697.321816] LNet: Removed LNI 192.168.204.56@tcp [ 9697.692155] Key type .llcrypt unregistered [ 9697.693760] Key type ._llcrypt unregistered [ 9698.127700] Key type ._llcrypt registered [ 9698.129066] Key type .llcrypt registered [ 9698.296125] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9698.302488] alg: No test for adler32 (adler32-zlib) [ 9699.226687] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9699.331464] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9700.943257] Key type lgssc registered [ 9701.650161] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9707.638137] Lustre: DEBUG MARKER: Iteration 35 [ 9707.858682] LustreError: 335791:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9707.861148] LustreError: 335792:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9707.864800] LustreError: 335791:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9708.944820] Lustre: Mounted lustre-client [ 9709.511325] LustreError: 335892:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcd0507000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9709.518356] LustreError: 335892:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 9709.542875] Lustre: Unmounted lustre-client [ 9710.870207] Key type lgssc unregistered [ 9711.038976] LNet: 336141:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9711.042821] LNetError: 336141:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9711.053302] LNet: Removed LNI 192.168.204.56@tcp [ 9711.386112] Key type .llcrypt unregistered [ 9711.387382] Key type ._llcrypt unregistered [ 9711.680149] Key type ._llcrypt registered [ 9711.681640] Key type .llcrypt registered [ 9711.905873] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9711.915078] alg: No test for adler32 (adler32-zlib) [ 9712.808841] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9712.915236] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9714.519163] Key type lgssc registered [ 9715.146463] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9719.599883] Lustre: DEBUG MARKER: Iteration 36 [ 9719.730611] LustreError: 336960:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9719.731835] LustreError: 336961:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9719.737849] LustreError: 336960:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9720.847540] Lustre: Mounted lustre-client [ 9721.429752] LustreError: 337065:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcd1795800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9721.446874] LustreError: 337070:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 9721.449813] LustreError: 337070:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 9721.471462] Lustre: Unmounted lustre-client [ 9722.659394] Key type lgssc unregistered [ 9722.805699] LNet: 337311:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9722.809583] LNetError: 337311:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9722.817766] LNet: Removed LNI 192.168.204.56@tcp [ 9723.136147] Key type .llcrypt unregistered [ 9723.139464] Key type ._llcrypt unregistered [ 9723.452505] Key type ._llcrypt registered [ 9723.453876] Key type .llcrypt registered [ 9723.648596] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9723.655790] alg: No test for adler32 (adler32-zlib) [ 9724.554376] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9724.657334] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9726.256141] Key type lgssc registered [ 9726.814386] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9731.348391] Lustre: DEBUG MARKER: Iteration 37 [ 9731.513115] LustreError: 338131:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9731.514317] LustreError: 338130:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9731.520202] LustreError: 338131:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4995 [ 9732.640103] Lustre: Mounted lustre-client [ 9733.220970] LustreError: 338239:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbce8ea4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9733.227866] LustreError: 338239:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 9733.260433] Lustre: Unmounted lustre-client [ 9734.557541] Key type lgssc unregistered [ 9734.706478] LNet: 338483:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9734.709966] LNetError: 338483:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9734.721521] LNet: Removed LNI 192.168.204.56@tcp [ 9735.118309] Key type .llcrypt unregistered [ 9735.119686] Key type ._llcrypt unregistered [ 9735.572286] Key type ._llcrypt registered [ 9735.573747] Key type .llcrypt registered [ 9735.743559] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9735.751634] alg: No test for adler32 (adler32-zlib) [ 9736.659805] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9736.796739] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9738.408180] Key type lgssc registered [ 9738.986760] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9744.243707] Lustre: DEBUG MARKER: Iteration 38 [ 9744.427804] LustreError: 339301:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9744.428118] LustreError: 339303:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9744.433894] LustreError: 339301:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9745.566663] Lustre: Mounted lustre-client [ 9746.148645] LustreError: 339412:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcc4d4f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9746.158169] LustreError: 339412:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9746.163236] LustreError: 339413:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 9746.192086] Lustre: Unmounted lustre-client [ 9747.641539] Key type lgssc unregistered [ 9747.804561] LNet: 339653:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9747.808329] LNetError: 339653:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9747.819215] LNet: Removed LNI 192.168.204.56@tcp [ 9748.165372] Key type .llcrypt unregistered [ 9748.167703] Key type ._llcrypt unregistered [ 9748.510183] Key type ._llcrypt registered [ 9748.512921] Key type .llcrypt registered [ 9748.722616] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9748.731135] alg: No test for adler32 (adler32-zlib) [ 9749.627387] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9749.741517] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9751.360106] Key type lgssc registered [ 9752.044897] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9757.772727] Lustre: DEBUG MARKER: Iteration 39 [ 9757.930992] LustreError: 340472:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9757.931225] LustreError: 340473:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9757.937700] LustreError: 340472:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9759.039879] Lustre: Mounted lustre-client [ 9759.041562] Lustre: Skipped 1 previous similar message [ 9759.507263] LustreError: 340581:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcfc8c6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9759.517883] LustreError: 340581:0:(obd_class.h:479:obd_check_dev()) Device 7 not setup [ 9759.521133] LustreError: 340581:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 9759.536099] Lustre: Unmounted lustre-client [ 9760.719796] Key type lgssc unregistered [ 9760.857524] LNet: 340825:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9760.860549] LNetError: 340825:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9760.872949] LNet: Removed LNI 192.168.204.56@tcp [ 9761.193852] Key type .llcrypt unregistered [ 9761.195243] Key type ._llcrypt unregistered [ 9761.598833] Key type ._llcrypt registered [ 9761.600586] Key type .llcrypt registered [ 9761.849306] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9761.856338] alg: No test for adler32 (adler32-zlib) [ 9762.790449] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9762.901866] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9764.528173] Key type lgssc registered [ 9765.137558] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9769.923426] Lustre: DEBUG MARKER: Iteration 40 [ 9770.080411] LustreError: 341642:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9770.082396] LustreError: 341643:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9770.091910] LustreError: 341642:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4990 [ 9771.216537] Lustre: Mounted lustre-client [ 9771.796770] LustreError: 341752:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcc4d49800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9771.801866] LustreError: 341752:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9771.804962] LustreError: 341751:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 9771.822773] Lustre: Unmounted lustre-client [ 9773.290167] Key type lgssc unregistered [ 9773.452570] LNet: 341994:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9773.457690] LNetError: 341994:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9773.469517] LNet: Removed LNI 192.168.204.56@tcp [ 9773.804165] Key type .llcrypt unregistered [ 9773.806728] Key type ._llcrypt unregistered [ 9774.165537] Key type ._llcrypt registered [ 9774.168585] Key type .llcrypt registered [ 9774.387378] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9774.395551] alg: No test for adler32 (adler32-zlib) [ 9775.299050] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9775.415758] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9777.055186] Key type lgssc registered [ 9777.703259] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9783.172067] Lustre: DEBUG MARKER: Iteration 41 [ 9783.325740] LustreError: 342811:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9783.327123] LustreError: 342813:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9783.333638] LustreError: 342811:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9784.434468] Lustre: Mounted lustre-client [ 9785.368680] LustreError: 342917:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbced061000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9785.380785] LustreError: 342917:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 9785.403521] Lustre: Unmounted lustre-client [ 9787.018489] Key type lgssc unregistered [ 9787.179431] LNet: 343159:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9787.182792] LNetError: 343159:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9787.203590] LNet: Removed LNI 192.168.204.56@tcp [ 9787.553267] Key type .llcrypt unregistered [ 9787.557560] Key type ._llcrypt unregistered [ 9788.030096] Key type ._llcrypt registered [ 9788.040970] Key type .llcrypt registered [ 9788.234312] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9788.247367] alg: No test for adler32 (adler32-zlib) [ 9789.168591] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9789.304307] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9790.927204] Key type lgssc registered [ 9791.595832] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9797.126903] Lustre: DEBUG MARKER: Iteration 42 [ 9797.266730] LustreError: 343976:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9797.267102] LustreError: 343979:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9797.273486] LustreError: 343976:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9798.364777] Lustre: Mounted lustre-client [ 9798.947864] LustreError: 344088:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcc4d4e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9798.956182] LustreError: 344088:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 9798.959073] LustreError: 344088:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 9798.974104] Lustre: Unmounted lustre-client [ 9800.221391] Key type lgssc unregistered [ 9800.368812] LNet: 344330:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9800.373190] LNetError: 344330:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9800.383741] LNet: Removed LNI 192.168.204.56@tcp [ 9800.653128] Key type .llcrypt unregistered [ 9800.654745] Key type ._llcrypt unregistered [ 9800.996236] Key type ._llcrypt registered [ 9800.997413] Key type .llcrypt registered [ 9801.204112] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9801.211447] alg: No test for adler32 (adler32-zlib) [ 9802.154687] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9802.292627] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9803.895164] Key type lgssc registered [ 9804.382703] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9809.066733] Lustre: DEBUG MARKER: Iteration 43 [ 9809.202751] LustreError: 345148:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9809.203100] LustreError: 345149:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9809.209518] LustreError: 345148:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9810.277549] Lustre: Mounted lustre-client [ 9810.282240] Lustre: Skipped 1 previous similar message [ 9810.739657] LustreError: 345253:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcc2cdd800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9810.746723] LustreError: 345253:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 9810.761106] Lustre: Unmounted lustre-client [ 9811.851917] Key type lgssc unregistered [ 9811.984022] LNet: 345498:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9811.986956] LNetError: 345498:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9811.997400] LNet: Removed LNI 192.168.204.56@tcp [ 9812.287126] Key type .llcrypt unregistered [ 9812.289646] Key type ._llcrypt unregistered [ 9812.614331] Key type ._llcrypt registered [ 9812.616526] Key type .llcrypt registered [ 9812.838182] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9812.848247] alg: No test for adler32 (adler32-zlib) [ 9813.760017] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9813.876238] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9815.471857] Key type lgssc registered [ 9816.018063] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9821.196588] Lustre: DEBUG MARKER: Iteration 44 [ 9821.339367] LustreError: 346315:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9821.339942] LustreError: 346316:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9821.345150] LustreError: 346315:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9822.435746] Lustre: Mounted lustre-client [ 9822.440760] Lustre: Skipped 1 previous similar message [ 9822.950691] LustreError: 346423:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbceb552800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9822.956306] LustreError: 346423:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9822.968403] LustreError: 346424:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 9822.996900] Lustre: Unmounted lustre-client [ 9823.000831] Lustre: Skipped 1 previous similar message [ 9824.118882] Key type lgssc unregistered [ 9824.250362] LNet: 346665:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9824.256788] LNetError: 346665:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9824.267172] LNet: Removed LNI 192.168.204.56@tcp [ 9824.542152] Key type .llcrypt unregistered [ 9824.543991] Key type ._llcrypt unregistered [ 9824.863721] Key type ._llcrypt registered [ 9824.867169] Key type .llcrypt registered [ 9825.110887] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9825.117712] alg: No test for adler32 (adler32-zlib) [ 9826.005262] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9826.126730] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9827.727162] Key type lgssc registered [ 9828.246992] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9832.549406] Lustre: DEBUG MARKER: Iteration 45 [ 9832.699428] LustreError: 347483:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9832.700278] LustreError: 347484:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9832.711598] LustreError: 347483:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4991 [ 9833.854565] Lustre: Mounted lustre-client [ 9833.856715] Lustre: Skipped 1 previous similar message [ 9834.343906] LustreError: 347594:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbce21ad800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9834.347098] LustreError: 347593:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 9834.348331] LustreError: 347594:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9834.362985] Lustre: Unmounted lustre-client [ 9835.535016] Key type lgssc unregistered [ 9835.666396] LNet: 347835:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9835.669052] LNetError: 347835:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9835.675701] LNet: Removed LNI 192.168.204.56@tcp [ 9835.936147] Key type .llcrypt unregistered [ 9835.938366] Key type ._llcrypt unregistered [ 9836.198662] Key type ._llcrypt registered [ 9836.200736] Key type .llcrypt registered [ 9836.444992] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9836.450775] alg: No test for adler32 (adler32-zlib) [ 9837.362885] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9837.459940] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9839.055288] Key type lgssc registered [ 9839.571540] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9844.364201] Lustre: DEBUG MARKER: Iteration 46 [ 9844.535551] LustreError: 348655:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9844.535576] LustreError: 348654:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9844.544315] LustreError: 348655:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 9845.664273] Lustre: Mounted lustre-client [ 9846.330987] LustreError: 348756:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbceb550800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9846.343779] LustreError: 348756:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [ 9846.363799] Lustre: Unmounted lustre-client [ 9847.714753] Key type lgssc unregistered [ 9847.864834] LNet: 349007:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9847.869712] LNetError: 349007:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9847.877962] LNet: Removed LNI 192.168.204.56@tcp [ 9848.184050] Key type .llcrypt unregistered [ 9848.185651] Key type ._llcrypt unregistered [ 9848.496723] Key type ._llcrypt registered [ 9848.498349] Key type .llcrypt registered [ 9848.701308] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9848.707114] alg: No test for adler32 (adler32-zlib) [ 9849.602864] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9849.716872] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9851.327202] Key type lgssc registered [ 9851.833363] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9856.168591] Lustre: DEBUG MARKER: Iteration 47 [ 9856.306064] LustreError: 349825:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9856.306064] LustreError: 349824:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9856.312126] LustreError: 349825:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 9857.384426] Lustre: Mounted lustre-client [ 9857.385710] Lustre: Skipped 1 previous similar message [ 9857.855934] LustreError: 349936:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcd091f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9857.861363] LustreError: 349935:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 9857.862153] LustreError: 349936:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9857.879362] Lustre: Unmounted lustre-client [ 9858.927918] Key type lgssc unregistered [ 9859.049373] LNet: 350178:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9859.053116] LNetError: 350178:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9859.060830] LNet: Removed LNI 192.168.204.56@tcp [ 9859.327111] Key type .llcrypt unregistered [ 9859.328543] Key type ._llcrypt unregistered [ 9859.625227] Key type ._llcrypt registered [ 9859.626364] Key type .llcrypt registered [ 9859.832525] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9859.840072] alg: No test for adler32 (adler32-zlib) [ 9860.739694] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9860.836218] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9862.431137] Key type lgssc registered [ 9862.893909] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9867.583611] Lustre: DEBUG MARKER: Iteration 48 [ 9867.833157] LustreError: 350995:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9867.833213] LustreError: 350996:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9867.838130] LustreError: 350995:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 9868.906803] Lustre: Mounted lustre-client [ 9868.908897] Lustre: Skipped 1 previous similar message [ 9869.410114] LustreError: 351102:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbce0713000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9869.414319] LustreError: 351102:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9869.419701] LustreError: 351102:0:(obd_class.h:479:obd_check_dev()) Device 7 not setup [ 9869.422671] LustreError: 351102:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 9869.439351] Lustre: Unmounted lustre-client [ 9870.537375] Key type lgssc unregistered [ 9870.676465] LNet: 351344:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9870.681634] LNetError: 351344:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9870.697287] LNet: Removed LNI 192.168.204.56@tcp [ 9870.981187] Key type .llcrypt unregistered [ 9870.982584] Key type ._llcrypt unregistered [ 9871.390853] Key type ._llcrypt registered [ 9871.392191] Key type .llcrypt registered [ 9871.616297] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9871.628289] alg: No test for adler32 (adler32-zlib) [ 9872.516962] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9872.633068] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9874.240686] Key type lgssc registered [ 9874.764340] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9879.367715] Lustre: DEBUG MARKER: Iteration 49 [ 9879.511467] LustreError: 352162:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9879.514787] LustreError: 352163:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9879.519465] LustreError: 352162:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9880.635092] Lustre: Mounted lustre-client [ 9881.149584] LustreError: 352264:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcd864b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9881.157581] LustreError: 352264:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 9881.177090] Lustre: Unmounted lustre-client [ 9882.413069] Key type lgssc unregistered [ 9882.555597] LNet: 352513:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9882.564780] LNetError: 352513:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9882.576118] LNet: Removed LNI 192.168.204.56@tcp [ 9882.868349] Key type .llcrypt unregistered [ 9882.870135] Key type ._llcrypt unregistered [ 9883.228120] Key type ._llcrypt registered [ 9883.229553] Key type .llcrypt registered [ 9883.380772] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9883.387816] alg: No test for adler32 (adler32-zlib) [ 9884.267644] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9884.360709] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9885.959438] Key type lgssc registered [ 9886.499641] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9891.654483] Lustre: DEBUG MARKER: Iteration 50 [ 9891.814480] LustreError: 353331:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 9891.817273] LustreError: 353332:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 9891.826881] LustreError: 353331:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 9892.968678] Lustre: Mounted lustre-client [ 9892.970026] Lustre: Skipped 1 previous similar message [ 9893.510733] LustreError: 353442:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcd0504000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 9893.517571] LustreError: 353442:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 9893.519777] LustreError: 353443:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 9893.544134] Lustre: Unmounted lustre-client [ 9894.846024] Key type lgssc unregistered [ 9895.002449] LNet: 353684:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 9895.006944] LNetError: 353684:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 9895.016567] LNet: Removed LNI 192.168.204.56@tcp [ 9895.329154] Key type .llcrypt unregistered [ 9895.331678] Key type ._llcrypt unregistered [ 9895.697539] Key type ._llcrypt registered [ 9895.703060] Key type .llcrypt registered [ 9895.866929] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 9895.874631] alg: No test for adler32 (adler32-zlib) [ 9896.806471] Lustre: Lustre: Build Version: 2.17.0_RC4_4_g6421352 [ 9896.907321] LNet: Added LNI 192.168.204.56@tcp [8/256/0/180] [ 9898.511185] Key type lgssc registered [ 9899.085287] Lustre: Echo OBD driver; http://www.lustre.org/ [ 9904.291272] Lustre: Mounted lustre-client [ 9907.464093] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 22:30:57 (1767324657) [ 9923.551140] Lustre: 355018:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767324658/real 1767324658] req@ffff9cbcf98e0000 x1853174209061632/t0(0) o36->lustre-MDT0000-mdc-ffff9cbcfdb10800@192.168.204.156@tcp:12/10 lens 496/440 e 0 to 1 dl 1767324674 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 9923.561242] Lustre: lustre-MDT0000-mdc-ffff9cbcfdb10800: Connection to lustre-MDT0000 (at 192.168.204.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9923.574487] Lustre: lustre-MDT0000-mdc-ffff9cbcfdb10800: Connection restored to 192.168.204.156@tcp (at 192.168.204.156@tcp) [ 9939.935152] Lustre: 355018:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767324674/real 1767324674] req@ffff9cbcf98e0000 x1853174209061632/t0(0) o36->lustre-MDT0000-mdc-ffff9cbcfdb10800@192.168.204.156@tcp:12/10 lens 496/440 e 0 to 1 dl 1767324690 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 9939.947135] Lustre: lustre-MDT0000-mdc-ffff9cbcfdb10800: Connection to lustre-MDT0000 (at 192.168.204.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9939.969115] Lustre: lustre-MDT0000-mdc-ffff9cbcfdb10800: Connection restored to 192.168.204.156@tcp (at 192.168.204.156@tcp) [ 9956.319676] Lustre: 355018:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767324690/real 1767324690] req@ffff9cbcf98e0000 x1853174209061632/t0(0) o36->lustre-MDT0000-mdc-ffff9cbcfdb10800@192.168.204.156@tcp:12/10 lens 496/440 e 0 to 1 dl 1767324706 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 9956.335272] Lustre: lustre-MDT0000-mdc-ffff9cbcfdb10800: Connection to lustre-MDT0000 (at 192.168.204.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9956.349933] Lustre: lustre-MDT0000-mdc-ffff9cbcfdb10800: Connection restored to 192.168.204.156@tcp (at 192.168.204.156@tcp) [ 9971.679163] Lustre: 355018:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767324706/real 1767324706] req@ffff9cbcf98e0000 x1853174209061632/t0(0) o36->lustre-MDT0000-mdc-ffff9cbcfdb10800@192.168.204.156@tcp:12/10 lens 496/440 e 0 to 1 dl 1767324722 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 9971.691469] Lustre: lustre-MDT0000-mdc-ffff9cbcfdb10800: Connection to lustre-MDT0000 (at 192.168.204.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 9971.704362] Lustre: lustre-MDT0000-mdc-ffff9cbcfdb10800: Connection restored to 192.168.204.156@tcp (at 192.168.204.156@tcp) [ 9972.443836] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 22:32:02 (1767324722) [ 9973.171093] Lustre: DEBUG MARKER: SKIP: sanityn test_111 needs >= 2 MDTs [ 9974.111420] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 22:32:03 (1767324723) [ 9974.878531] Lustre: DEBUG MARKER: SKIP: sanityn test_112 We need at least 2 MDTs for this test [ 9975.665789] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 22:32:05 (1767324725) [ 9978.558294] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 22:32:08 (1767324728) [ 9979.298463] Lustre: DEBUG MARKER: SKIP: sanityn test_114 We need at least 2 MDTs for this test [ 9980.148233] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 22:32:10 (1767324730) [ 9980.881341] Lustre: DEBUG MARKER: SKIP: sanityn test_115 ldiskfs only test [ 9981.651832] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 22:32:11 (1767324731) [ 9982.400915] Lustre: DEBUG MARKER: SKIP: sanityn test_116 needs >= 2 MDTs [ 9983.331398] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 22:32:13 (1767324733) [ 9983.453680] LustreError: 357673:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [ 9985.535120] LustreError: 357673:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [ 9988.439226] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 22:32:18 (1767324738) [10006.495158] Lustre: 353873:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767324741/real 1767324741] req@ffff9cbcf98e1f80 x1853174209093632/t0(0) o4->lustre-OST0000-osc-ffff9cbcfdb10800@192.168.204.156@tcp:6/4 lens 4584/448 e 0 to 1 dl 1767324757 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [10006.496119] Lustre: lustre-OST0000-osc-ffff9cbcfdb10800: Connection to lustre-OST0000 (at 192.168.204.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [10006.508777] Lustre: 353873:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 1 previous similar message [10006.522879] Lustre: lustre-OST0000-osc-ffff9cbcfdb10800: Connection restored to 192.168.204.156@tcp (at 192.168.204.156@tcp) [10022.879224] Lustre: 353875:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1767324757/real 1767324757] req@ffff9cbcec30f480 x1853174209095296/t0(0) o4->lustre-OST0000-osc-ffff9cbcfdb10800@192.168.204.156@tcp:6/4 lens 4584/448 e 0 to 1 dl 1767324773 ref 2 fl Rpc:XQr/602/ffffffff rc -11/-1 job:'dd.0' uid:0 gid:0 projid:0 [10022.879259] Lustre: lustre-OST0000-osc-ffff9cbcfdb10800: Connection to lustre-OST0000 (at 192.168.204.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [10022.887557] Lustre: 353875:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 4 previous similar messages [10022.903227] Lustre: lustre-OST0000-osc-ffff9cbcfdb10800: Connection restored to 192.168.204.156@tcp (at 192.168.204.156@tcp) [10056.775646] Lustre: DEBUG MARKER: oleg456-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9cbcd1791800.ost_server_uuid 50 [10057.704564] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9cbcd1791800.ost_server_uuid in IDLE state after 0 sec [10058.552555] Lustre: DEBUG MARKER: cleanup: ====================================================== [10059.489890] Lustre: DEBUG MARKER: == sanityn test complete, duration 9678 sec ============== 22:33:29 (1767324809) [10060.423660] Lustre: DEBUG MARKER: === sanityn: start cleanup 22:33:30 (1767324810) === [10168.528211] LustreError: 359675:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcd1791800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10168.533417] LustreError: 359675:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [10168.547133] Lustre: Unmounted lustre-client [10170.303396] Lustre: DEBUG MARKER: === sanityn: finish cleanup 22:35:20 (1767324920) === [10170.959076] LustreError: 359984:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cbcfdb10800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [10170.964417] LustreError: 359984:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [10170.971700] LustreError: 359984:0:(obd_class.h:479:obd_check_dev()) Device 4 not setup [10170.974541] LustreError: 359984:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [10170.995137] Lustre: Unmounted lustre-client [10187.724342] Key type lgssc unregistered [10187.862646] LNet: 360575:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [10187.867505] LNetError: 360575:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [10187.876973] LNet: Removed LNI 192.168.204.56@tcp [10188.219154] Key type .llcrypt unregistered [10188.221418] Key type ._llcrypt unregistered