[ 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 583678570 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2400.000 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.001015] APIC: Switch to symmetric I/O mode setup [ 0.003309] x2apic enabled [ 0.004010] Switched APIC routing to physical x2apic. [ 0.005000] kvm-guest: setup PV IPIs [ 0.007536] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x22983777dd9, max_idle_ns: 440795300422 ns [ 0.008025] Calibrating delay loop (skipped) preset value.. 4800.00 BogoMIPS (lpj=2400000) [ 0.009014] pid_max: default: 32768 minimum: 301 [ 0.011076] LSM: Security Framework initializing [ 0.013030] Yama: becoming mindful. [ 0.014049] SELinux: Initializing. [ 0.016030] *** VALIDATE selinux *** [ 0.024540] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.029411] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.030151] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031119] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032138] *** VALIDATE tmpfs *** [ 0.034292] *** VALIDATE proc *** [ 0.035226] *** VALIDATE cgroup *** [ 0.036010] *** VALIDATE cgroup2 *** [ 0.037283] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.039083] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.040010] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.041030] Spectre V2 : User space: Vulnerable [ 0.042015] Speculative Store Bypass: Vulnerable [ 0.044867] debug: unmapping init [mem 0xffffffffb4a59000-0xffffffffb4a60fff] [ 0.047249] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.048903] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.049031] ... version: 2 [ 0.050026] ... bit width: 48 [ 0.051015] ... generic registers: 4 [ 0.052015] ... value mask: 0000ffffffffffff [ 0.053020] ... max period: 00007fffffffffff [ 0.054029] ... fixed-purpose events: 3 [ 0.055021] ... event mask: 000000070000000f [ 0.057384] rcu: Hierarchical SRCU implementation. [ 0.059971] smp: Bringing up secondary CPUs ... [ 0.060638] x86: Booting SMP configuration: [ 0.061039] .... node #0, CPUs: #1 #2 #3 [ 0.071019] smp: Brought up 1 node, 4 CPUs [ 0.073022] smpboot: Max logical packages: 1 [ 0.074013] smpboot: Total of 4 processors activated (19200.00 BogoMIPS) [ 0.131025] node 0 deferred pages initialised in 55ms [ 0.139167] devtmpfs: initialized [ 0.140216] x86/mm: Memory block size: 128MB [ 0.146008] gcov: version magic: 0x41383552 [ 0.148243] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.149088] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.150249] pinctrl core: initialized pinctrl subsystem [ 0.151197] [ 0.151718] ************************************************************* [ 0.152018] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.153017] ** ** [ 0.154013] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.155017] ** ** [ 0.156030] ** This means that this kernel is built to expose internal ** [ 0.157027] ** IOMMU data structures, which may compromise security on ** [ 0.158014] ** your system. ** [ 0.159013] ** ** [ 0.160012] ** If you see this message and you are not debugging the ** [ 0.161011] ** kernel, report this immediately to your vendor! ** [ 0.162014] ** ** [ 0.163012] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.164012] ************************************************************* [ 0.165721] NET: Registered protocol family 16 [ 0.166626] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.167094] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.168077] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.169713] cpuidle: using governor menu [ 0.172299] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.173531] PCI: Using configuration type 1 for base access [ 0.174132] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.182138] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.184024] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.188071] cryptd: max_cpu_qlen set to 1000 [ 0.190130] ACPI: Added _OSI(Module Device) [ 0.191010] ACPI: Added _OSI(Processor Device) [ 0.192015] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.193012] ACPI: Added _OSI(Processor Aggregator Device) [ 0.197974] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.201120] ACPI: Interpreter enabled [ 0.202060] ACPI: PM: (supports S0 S3 S4 S5) [ 0.203014] ACPI: Using IOAPIC for interrupt routing [ 0.204095] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.205652] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.220752] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.221046] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.222021] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.224058] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.226872] acpiphp: Slot [2] registered [ 0.227185] acpiphp: Slot [5] registered [ 0.228245] acpiphp: Slot [6] registered [ 0.229176] acpiphp: Slot [3] registered [ 0.231059] acpiphp: Slot [4] registered [ 0.232139] acpiphp: Slot [7] registered [ 0.233153] acpiphp: Slot [8] registered [ 0.234150] acpiphp: Slot [9] registered [ 0.235141] acpiphp: Slot [10] registered [ 0.236138] acpiphp: Slot [11] registered [ 0.237115] acpiphp: Slot [12] registered [ 0.238109] acpiphp: Slot [13] registered [ 0.239303] acpiphp: Slot [14] registered [ 0.240991] acpiphp: Slot [15] registered [ 0.241123] acpiphp: Slot [16] registered [ 0.242099] acpiphp: Slot [17] registered [ 0.243122] acpiphp: Slot [18] registered [ 0.244126] acpiphp: Slot [19] registered [ 0.245152] acpiphp: Slot [20] registered [ 0.246138] acpiphp: Slot [21] registered [ 0.247093] acpiphp: Slot [22] registered [ 0.248143] acpiphp: Slot [23] registered [ 0.249144] acpiphp: Slot [24] registered [ 0.250140] acpiphp: Slot [25] registered [ 0.251142] acpiphp: Slot [26] registered [ 0.252119] acpiphp: Slot [27] registered [ 0.253113] acpiphp: Slot [28] registered [ 0.254138] acpiphp: Slot [29] registered [ 0.255118] acpiphp: Slot [30] registered [ 0.256104] acpiphp: Slot [31] registered [ 0.257059] PCI host bridge to bus 0000:00 [ 0.258020] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.259026] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.260020] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.261101] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.262027] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.263028] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.264157] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.266059] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.268304] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.272991] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.276188] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.277022] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.278020] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.279020] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.281848] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.282812] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.283044] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.285040] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.287824] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.293014] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.295017] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.299680] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.302017] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.305019] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.312027] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.322152] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.333017] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.342017] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.362017] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.374183] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.376463] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.379357] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.381637] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.384229] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.389139] iommu: Default domain type: Passthrough [ 0.390000] SCSI subsystem initialized [ 0.391206] ACPI: bus type USB registered [ 0.393138] usbcore: registered new interface driver usbfs [ 0.395078] usbcore: registered new interface driver hub [ 0.397082] usbcore: registered new device driver usb [ 0.399207] pps_core: LinuxPPS API ver. 1 registered [ 0.401013] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.404059] PTP clock support registered [ 0.406204] EDAC MC: Ver: 3.0.0 [ 0.408083] PCI: Using ACPI for IRQ routing [ 0.409948] NetLabel: Initializing [ 0.411011] NetLabel: domain hash size = 128 [ 0.413010] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.414078] NetLabel: unlabeled traffic allowed by default [ 0.416485] vgaarb: loaded [ 0.418345] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.421016] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.426409] clocksource: Switched to clocksource kvm-clock [ 0.585750] VFS: Disk quotas dquot_6.6.0 [ 0.586950] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.589142] *** VALIDATE ramfs *** [ 0.590353] *** VALIDATE hugetlbfs *** [ 0.591741] pnp: PnP ACPI init [ 0.594780] pnp: PnP ACPI: found 6 devices [ 0.609903] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.612889] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.614766] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.616606] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.618844] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.620832] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.623257] NET: Registered protocol family 2 [ 0.625287] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.629706] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.632582] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.637070] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.640113] TCP: Hash tables configured (established 65536 bind 65536) [ 0.642672] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.645314] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.647624] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.649984] NET: Registered protocol family 1 [ 0.652052] RPC: Registered named UNIX socket transport module. [ 0.654203] RPC: Registered udp transport module. [ 0.655600] RPC: Registered tcp transport module. [ 0.656954] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.659404] NET: Registered protocol family 44 [ 0.660738] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.662713] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.664469] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.666429] PCI: CLS 0 bytes, default 64 [ 0.667960] Unpacking initramfs... [ 2.497302] debug: unmapping init [mem 0xffff9c7f3cc64000-0xffff9c7f3ffcffff] [ 2.501551] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.503866] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.506954] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x22983777dd9, max_idle_ns: 440795300422 ns [ 3.211365] Initialise system trusted keyrings [ 3.213074] Key type blacklist registered [ 3.214861] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 3.224275] zbud: loaded [ 3.227244] *** VALIDATE nfs *** [ 3.228365] *** VALIDATE nfs4 *** [ 3.229873] pstore: using deflate compression [ 3.233950] Platform Keyring initialized [ 3.350452] NET: Registered protocol family 38 [ 3.351829] Key type asymmetric registered [ 3.353068] Asymmetric key parser 'x509' registered [ 3.354824] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 3.357805] io scheduler mq-deadline registered [ 3.359329] io scheduler kyber registered [ 3.360652] io scheduler bfq registered [ 3.364855] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 3.368253] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 3.371185] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 3.374278] ACPI: Power Button [PWRF] [ 3.379914] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.387624] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.397939] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.426926] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.456338] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.460883] Non-volatile memory driver v1.3 [ 3.462500] Linux agpgart interface v0.103 [ 3.497528] virtio_blk virtio1: [vda] 134696 512-byte logical blocks (69.0 MB/65.8 MiB) [ 3.500241] vda: detected capacity change from 0 to 68964352 [ 3.533617] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.536551] vdb: detected capacity change from 0 to 1073741824 [ 3.543426] libphy: Fixed MDIO Bus: probed [ 3.576096] usbcore: registered new interface driver usbserial_generic [ 3.579420] usbserial: USB Serial support registered for generic [ 3.582344] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.587652] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.589697] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.592922] mousedev: PS/2 mouse device common for all mice [ 3.596786] rtc_cmos 00:05: RTC can wake from S4 [ 3.602070] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.608143] rtc_cmos 00:05: registered as rtc0 [ 3.610782] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.613645] intel_pstate: CPU model not supported [ 3.617691] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.654698] hid: raw HID events driver (C) Jiri Kosina [ 3.657109] usbcore: registered new interface driver usbhid [ 3.659260] usbhid: USB HID core driver [ 3.660916] drop_monitor: Initializing network drop monitor service [ 3.663640] Initializing XFRM netlink socket [ 3.665973] NET: Registered protocol family 10 [ 3.723155] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.727108] Segment Routing with IPv6 [ 3.729667] NET: Registered protocol family 17 [ 3.731871] mpls_gso: MPLS GSO support [ 3.741350] RAS: Correctable Errors collector initialized. [ 3.743646] AVX version of gcm_enc/dec engaged. [ 3.746611] AES CTR mode by8 optimization enabled [ 3.885393] sched_clock: Marking stable (3885232283, 0)->(5164321248, -1279088965) [ 3.889866] registered taskstats version 1 [ 3.892398] Loading compiled-in X.509 certificates [ 3.894959] zswap: loaded using pool lzo/zbud [ 3.928974] Key type big_key registered [ 3.950509] Key type encrypted registered [ 3.952752] ima: No TPM chip found, activating TPM-bypass! [ 3.955514] ima: Allocated hash algorithm: sha1 [ 3.957870] ima: No architecture policies found [ 3.960436] evm: Initialising EVM extended attributes: [ 3.963260] evm: security.selinux [ 3.964944] evm: security.ima [ 3.966594] evm: security.capability [ 3.968557] evm: HMAC attrs: 0x1 [ 3.975906] rtc_cmos 00:05: setting system clock to 2026-03-02 16:35:21 UTC (1772469321) [ 3.989318] debug: unmapping init [mem 0xffffffffb5a03000-0xffffffffb5bfffff] [ 3.994586] debug: unmapping init [mem 0xffffffffb4782000-0xffffffffb4a58fff] [ 4.001543] Write protecting the kernel read-only data: 28672k [ 4.007733] debug: unmapping init [mem 0xffffffffb2e03000-0xffffffffb2ffffff] [ 4.011659] debug: unmapping init [mem 0xffffffffb3714000-0xffffffffb37fffff] [ 4.067577] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 4.078636] systemd[1]: Detected virtualization kvm. [ 4.081122] systemd[1]: Detected architecture x86-64. [ 4.083779] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 4.124954] systemd[1]: No hostname configured. [ 4.127433] systemd[1]: Set hostname to . [ 4.130645] random: systemd: uninitialized urandom read (16 bytes read) [ 4.134046] systemd[1]: Initializing machine ID from random generator. [ 4.269555] random: ln: uninitialized urandom read (6 bytes read) [ 4.511967] random: systemd: uninitialized urandom read (16 bytes read) [ 4.515438] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 4.523167] systemd[1]: Reached target Initrd Root Device. [ OK ] Reached target Initrd Root Device. [ 4.531831] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Slices. [ OK ] Reached target Timers. [ OK ] Listening on Journal Socket. [ OK ] Started Memstrack Anylazing Service. Starting Journal Service... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Sockets. Starting Create Volatile Files and Directories... Starting Apply Kernel Variables... [ OK ] Reached target Paths. Starting Setup Virtual Console... [ OK ] Reached target Swap. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Apply Kernel Variables. Starting Create Static Device Nodes in /dev... [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Journal Service. [ OK ] Started Setup Virtual Console. [ OK ] Started Create Static Device Nodes in /dev. Starting dracut cmdline hook... [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 6.583303] device-mapper: uevent: version 1.0.3 [ 6.585640] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. [ 7.071228] random: fast init done Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ 9.009387] virtio_net virtio0 ens2: renamed from eth0 [ 9.338212] scsi host0: ata_piix [ 9.347079] scsi host1: ata_piix [ 9.348655] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 9.352653] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 14.481285] random: crng init done [ 14.487631] random: 7 urandom warning(s) missed due to ratelimiting [ 16.946059] dracut-initqueue[592]: 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... [ 17.844608] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target 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. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Swap. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. Stopping udev Kernel Device Manager... [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Closed udev Control Socket. [ OK ] Closed udev Kernel Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 20.084488] printk: systemd: 22 output lines suppressed due to ratelimiting [ 20.553711] SELinux: Disabled at runtime. [ 20.625833] 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) [ 20.637574] systemd[1]: Detected virtualization kvm. [ 20.640375] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 22.062467] systemd[1]: initrd-switch-root.service: Succeeded. [ 22.067462] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 22.078882] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 22.086846] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 22.090754] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 22.105510] systemd[1]: Starting Journal Service... Starting Journal Service... [ 22.114518] systemd[1]: Reached target rpc_pipefs.target. [ OK ] Reached target rpc_pipefs.target. Mounting Huge Pages File System... Starting Create list of required st…ce nodes for the current kernel... [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Created slice User and Session Slice. Starting Apply Kernel Variables... [ OK ] Created slice system-getty.slice. [ OK ] Listening on Process Core Dump Socket. Mounting POSIX Message Queue File System... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. Activating swap /dev/disk/by-label/SWAP... Starting Remount Root and Kernel File Systems... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Reached target Slices. Mounting Kernel Debug File System... [ OK [ 22.381519] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS ] Created slice system-serial\x2dgetty.slice. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Listening on udev Kernel Socket. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. [ OK ] Mounted Huge Pages File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Journal Service. [ OK ] Started Apply Kernel Variables. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [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. Starting Configure read-only root support... [ OK ] Reached target Swap. Starting Flush Journal to Persistent Storage... Starting Create Static Device Nodes in /dev... [ OK ] Started udev Coldplug all Devices. [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 24.043819] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 25.427690] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 25.502809] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 26.601714] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 28.006618] EDAC sbridge: Ver: 1.1.2 [* ] A start job is running for Configur…-only root support (8s / no limit)[ 30.582829] Key type dns_resolver registered [** ] A start job is running for Configur…-only root support (9s / no limit)[ 31.244817] NFS: Registering the id_resolver key type [ 31.246863] Key type id_resolver registered [ 31.248693] Key type id_legacy registered [*** ] A start job is running for Configur…-only root support (9s / no limit) [ 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 dnf makecache --timer. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Login Service... Starting Restore /run/initramfs on shutdown... [ OK ] Started D-Bus System Message Bus. [ OK ] Reached target sshd-keygen.target. [ OK ] Started irqbalance daemon. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting Network Manager... [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. Starting Network Manager Wait Online... [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting OpenSSH server daemon... Starting Dynamic System Tuning Daemon... Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started OpenSSH server daemon. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg417-client login: [ 91.571777] libcfs: loading out-of-tree module taints kernel. [ 91.722446] Key type ._llcrypt registered [ 91.735199] Key type .llcrypt registered [ 92.238978] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 92.255501] alg: No test for adler32 (adler32-zlib) [ 93.529955] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 94.117982] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 95.856189] Key type lgssc registered [ 97.892699] Lustre: Echo OBD driver; http://www.lustre.org/ [ 179.437666] hrtimer: interrupt took 6214063 ns [ 229.181536] Lustre: Mounted lustre-client [ 235.990632] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 254.952662] Lustre: lustre-OST0000-osc-ffff9c7f89a7b000: disconnect after 23s idle [ 259.292915] Lustre: DEBUG MARKER: oleg417-client.virtnet: executing check_logdir /tmp/testlogs/ [ 265.187487] Lustre: DEBUG MARKER: oleg417-client.virtnet: executing yml_node [ 270.710228] Lustre: DEBUG MARKER: Client: 2.17.50.195 [ 273.451456] Lustre: DEBUG MARKER: MDS: 2.17.50.195 [ 276.224494] Lustre: DEBUG MARKER: OSS: 2.17.50.195 [ 278.418935] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Mon Mar 2 11:39:54 EST 2026 [ 298.351884] Lustre: DEBUG MARKER: excepting tests: 27 40a 102 [ 300.493881] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 302.384513] Lustre: DEBUG MARKER: === sanityn: start setup 11:40:18 (1772469618) === [ 303.415578] Lustre: Mounted lustre-client [ 307.692917] Lustre: DEBUG MARKER: oleg417-client.virtnet: executing check_config_client /mnt/lustre [ 326.451685] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 339.499486] Lustre: DEBUG MARKER: === sanityn: finish setup 11:40:55 (1772469655) === [ 342.385371] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 11:40:58 (1772469658) [ 352.370553] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 11:41:08 (1772469668) [ 359.909732] Lustre: lustre-OST0001-osc-ffff9c7f89a7b000: disconnect after 20s idle [ 359.915990] Lustre: Skipped 1 previous similar message [ 360.191711] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 11:41:16 (1772469676) [ 369.064856] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 11:41:24 (1772469684) [ 376.314222] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 11:41:32 (1772469692) [ 383.450468] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 11:41:39 (1772469699) [ 391.768285] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 11:41:47 (1772469707) [ 399.098079] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 11:41:55 (1772469715) [ 400.915965] Lustre: DEBUG MARKER: SKIP: sanityn test_2f needs >= 2 MDTs [ 403.214698] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 11:41:58 (1772469718) [ 412.724907] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 11:42:08 (1772469728) [ 421.085759] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 11:42:17 (1772469737) [ 426.464477] Lustre: lustre-OST0000-osc-ffff9c7f89a7b000: disconnect after 22s idle [ 429.590574] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 11:42:25 (1772469745) [ 437.429964] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 11:42:33 (1772469753) [ 441.840046] Lustre: lustre-OST0001-osc-ffff9c7f8a5c0000: disconnect after 20s idle [ 445.211705] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 11:42:41 (1772469761) [ 452.863698] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 11:42:48 (1772469768) [ 460.650461] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 11:42:56 (1772469776) [ 462.306447] Lustre: lustre-OST0000-osc-ffff9c7f89a7b000: disconnect after 22s idle [ 469.511850] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 11:43:05 (1772469785) [ 478.593715] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 11:43:14 (1772469794) [ 487.613401] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 11:43:23 (1772469803) [ 497.457566] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 11:43:32 (1772469812) [ 508.724175] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 11:43:44 (1772469824) [ 509.533969] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205272502302 file: /mnt/lustre/lockdir/lockfile=144115205272502300 [ 650.393948] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 11:46:06 (1772469966) [ 660.457842] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 11:46:16 (1772469976) [ 669.212259] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 11:46:25 (1772469985) [ 676.534740] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 11:46:32 (1772469992) [ 684.364749] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 11:46:40 (1772470000) [ 693.731587] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 11:46:49 (1772470009) [ 696.160785] Lustre: DEBUG MARKER: chmod [ 704.770413] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 11:47:00 (1772470020) [ 743.756154] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7528448KB free > 800000KB, increase MAXFREE (or reduce fs size) [ 762.997287] Lustre: DEBUG MARKER: == sanityn test 16a: 500 iterations of dual-mount fsx ==== 11:47:58 (1772470078) [ 825.260714] Lustre: DEBUG MARKER: == sanityn test 16b: 500 iterations of dual-mount fsx at small size ========================================================== 11:49:00 (1772470140) [ 862.250687] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 11:49:37 (1772470177) [ 865.967905] Lustre: DEBUG MARKER: SKIP: sanityn test_16c dio on ldiskfs only [ 869.042809] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 11:49:43 (1772470183) [ 917.985833] Lustre: lustre-OST0001-osc-ffff9c7f8a5c0000: disconnect after 22s idle [ 928.924840] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 11:50:45 (1772470245) [ 937.755951] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 11:50:53 (1772470253) [ 939.443840] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 939.545070] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 939.651171] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 939.787640] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 939.896661] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 940.007272] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 940.148037] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 940.288330] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 940.405766] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 940.489696] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 940.647899] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 940.735789] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 940.844529] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 940.961439] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 941.075831] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 941.188057] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 941.278138] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 941.388988] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 941.499395] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 941.600299] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 941.701029] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 941.786466] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 941.852203] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 941.934868] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 942.012989] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 942.097159] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 942.172137] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 942.266915] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 942.350929] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 942.437926] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 942.541466] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 942.635328] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 942.747533] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 942.830873] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 942.928567] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 943.001946] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 943.079110] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 943.202296] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 943.315500] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 943.434383] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 943.543364] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 943.645562] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 943.772462] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 943.927101] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 944.044263] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 944.134265] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 944.238908] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 944.365333] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 944.448696] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 944.558208] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 944.656203] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 944.702727] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 944.852167] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 944.949900] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 945.066963] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 945.166547] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 945.290881] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 945.409080] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 945.534064] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 945.639570] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 945.755848] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 945.849835] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 945.940891] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 946.062483] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 946.161073] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 946.272757] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 946.395226] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 946.555965] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 946.649670] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 946.788089] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 946.927611] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 947.036339] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 947.145807] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 947.239512] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 947.367729] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 947.487491] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 947.626611] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 947.745165] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 947.834338] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 947.937558] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 948.011744] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 948.087850] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 948.227385] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 948.350315] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 948.507335] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 948.668262] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 948.747771] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 948.824594] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 948.875437] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 948.935350] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 949.014689] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 949.104746] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 949.208843] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 949.299464] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 949.373761] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 949.437580] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 949.531800] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 949.598594] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 949.684968] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 949.773513] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 949.868925] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 949.958222] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 950.036238] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 950.113505] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 950.202330] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 950.298670] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 950.369775] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 950.471894] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 950.580521] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 950.689086] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 950.837892] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 950.949747] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 951.061244] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 951.160670] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 951.266326] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 951.369476] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 951.454475] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 951.545091] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 951.639067] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 951.732273] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 951.833961] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 951.968347] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 952.088300] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 952.210958] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 952.321946] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 952.438876] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 952.528861] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 952.691120] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 952.796373] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 952.878800] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 952.994709] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 953.078574] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 953.175735] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 953.292087] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 953.409812] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 953.477256] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 953.569561] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 953.651436] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 953.730850] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 953.821842] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 953.831847] Lustre: lustre-OST0000-osc-ffff9c7f8a5c0000: disconnect after 24s idle [ 953.908164] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 953.998611] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 954.052875] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 954.126309] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 954.203963] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 954.348769] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 954.485980] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 954.643364] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 954.727474] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 954.863971] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 954.995127] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 955.145746] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 955.232651] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 955.324279] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 955.431186] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 955.549778] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 955.635793] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 955.750178] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 955.869538] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 955.990310] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 956.107167] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 956.209300] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 956.274689] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 956.364349] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 956.470320] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 956.556456] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 956.687390] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 956.761348] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 956.866450] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 956.971652] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 957.090319] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 957.238077] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 957.384772] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 957.518792] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 957.619955] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 957.743805] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 957.887347] rw_seq_cst_vs_d (29507): drop_caches: 3 [ 967.019941] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 11:51:22 (1772470282) [ 967.818941] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 967.869144] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 967.975885] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 968.031846] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 968.167600] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 968.260577] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 968.327120] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 968.407413] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 968.538174] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 968.721222] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 968.800031] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 968.897238] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 968.968481] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 969.058541] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 969.160321] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 969.232319] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 969.428972] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 969.609934] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 969.702692] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 969.832301] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 970.018471] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 970.286873] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 970.363070] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 970.551593] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 970.678739] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 970.747412] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 970.942349] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 971.050816] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 971.210540] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 971.296316] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 971.428819] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 971.488230] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 971.627084] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 971.697919] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 971.930245] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 972.049464] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 972.159747] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 972.224884] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 972.282734] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 972.465181] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 972.597822] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 972.811729] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 972.929875] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 972.983318] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 973.126653] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 973.163874] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 973.307589] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 973.369496] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 973.578588] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 973.715450] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 973.921848] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 974.018259] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 974.051309] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 974.136804] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 974.267905] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 974.449124] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 974.554035] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 974.704289] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 974.874323] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 974.947676] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 975.117077] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 975.242151] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 975.375858] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 975.533168] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 975.582565] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 975.649885] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 975.899664] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 975.962846] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 976.107795] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 976.184295] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 976.466759] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 976.630201] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 976.785612] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 976.908124] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 977.079396] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 977.156671] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 977.357888] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 977.471825] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 977.674382] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 977.856341] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 977.972233] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 978.012451] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 978.129627] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 978.201069] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 978.256383] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 978.394714] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 978.473379] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 978.509255] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 978.568972] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 978.656588] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 978.755232] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 978.801723] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 978.940595] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 979.017751] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 979.178635] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 979.309765] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 979.475330] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 979.543195] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 979.653601] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 979.825933] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 979.888925] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 980.036378] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 980.084865] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 980.214068] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 980.260560] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 980.381096] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 980.484920] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 980.544750] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 980.626393] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 980.741399] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 980.887876] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 980.930613] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 981.014239] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 981.141815] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 981.234626] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 981.376789] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 981.515873] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 981.658681] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 981.786216] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 982.045699] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 982.236745] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 982.384061] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 982.527803] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 982.595056] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 982.667263] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 982.749904] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 982.873743] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 982.960704] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 983.151314] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 983.249553] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 983.468855] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 983.538068] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 983.598336] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 983.709658] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 983.951635] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 984.247845] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 984.318504] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 984.618288] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 984.669043] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 984.822636] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 984.914108] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 985.095812] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 985.351031] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 985.395517] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 985.573494] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 985.643625] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 985.809594] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 985.922633] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 986.100472] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 986.172213] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 986.272918] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 986.323154] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 986.402748] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 986.620023] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 986.718868] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 986.787766] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 986.827432] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 986.974476] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 987.132026] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 987.242086] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 987.400380] rw_seq_cst_vs_d (30088): drop_caches: 3 [ 996.257396] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 11:51:51 (1772470311) [ 1005.441860] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 11:52:01 (1772470321) [ 1014.002737] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 11:52:09 (1772470329) [ 1060.542203] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 11:52:56 (1772470376) [ 1061.177961] bash (32541): drop_caches: 3 [ 1064.482869] bash (32541): drop_caches: 3 [ 1067.745085] bash (32541): drop_caches: 3 [ 1071.630286] bash (32541): drop_caches: 3 [ 1074.956081] bash (32541): drop_caches: 3 [ 1078.087812] bash (32541): drop_caches: 3 [ 1081.202323] bash (32541): drop_caches: 3 [ 1084.396352] bash (32541): drop_caches: 3 [ 1087.548643] bash (32541): drop_caches: 3 [ 1090.688618] bash (32541): drop_caches: 3 [ 1093.869989] bash (32541): drop_caches: 3 [ 1097.073785] bash (32541): drop_caches: 3 [ 1100.262482] bash (32541): drop_caches: 3 [ 1103.466641] bash (32541): drop_caches: 3 [ 1106.667739] bash (32541): drop_caches: 3 [ 1109.876102] bash (32541): drop_caches: 3 [ 1113.090091] bash (32541): drop_caches: 3 [ 1116.249447] bash (32541): drop_caches: 3 [ 1119.432567] bash (32541): drop_caches: 3 [ 1124.525862] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 11:54:00 (1772470440) [ 1135.820143] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 11:54:12 (1772470452) [ 1253.802995] LustreError: lustre-OST0000-osc-ffff9c7f89a7b000: operation ost_write to node 192.168.204.117@tcp failed: rc = -107 [ 1253.816779] Lustre: lustre-OST0000-osc-ffff9c7f89a7b000: Connection to lustre-OST0000 (at 192.168.204.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1253.840356] LustreError: lustre-OST0000-osc-ffff9c7f89a7b000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1253.859534] Lustre: 2365:0:(llite_lib.c:4187:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.117@tcp:/lustre/fid: [0x200000401:0x6c:0x0]// may get corrupted (rc -5) [ 1253.869340] LustreError: lustre-OST0001-osc-ffff9c7f8a5c0000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1253.881980] Lustre: lustre-OST0000-osc-ffff9c7f89a7b000: Connection restored to 192.168.204.117@tcp (at 192.168.204.117@tcp) [ 1253.889286] Lustre: 2367:0:(llite_lib.c:4187:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.204.117@tcp:/lustre/fid: [0x200000401:0x6b:0x0]// may get corrupted (rc -5) [ 1273.157069] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 11:56:28 (1772470588) [ 1276.389976] Lustre: lustre-OST0001-osc-ffff9c7f8a5c0000: disconnect after 22s idle [ 1276.407229] Lustre: Skipped 3 previous similar messages [ 1276.529417] Lustre: DEBUG MARKER: SKIP: sanityn test_19 not cache-capable obdfilter [ 1278.138487] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 11:56:34 (1772470594) [ 1286.768924] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 11:56:42 (1772470602) [ 1294.156405] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 11:56:50 (1772470610) [ 1365.340616] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 11:58:01 (1772470681) [ 1373.385962] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 11:58:09 (1772470689) [ 1381.202235] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 11:58:17 (1772470697) [ 1389.121504] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 11:58:25 (1772470705) [ 1391.100337] Lustre: DEBUG MARKER: SKIP: sanityn test_25b needs >= 2 MDTs [ 1393.237983] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 11:58:28 (1772470708) [ 1394.144283] Lustre: lustre-OST0000-osc-ffff9c7f89a7b000: disconnect after 20s idle [ 1394.152328] Lustre: Skipped 5 previous similar messages [ 1400.938963] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 11:58:37 (1772470717) [ 1410.165727] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 11:58:46 (1772470726) [ 1418.972247] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1421.207980] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 11:58:57 (1772470737) [ 1431.971610] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 11:59:07 (1772470747) [ 1432.458489] Lustre: *** cfs_fail_loc=314, val=0*** [ 1433.505474] Lustre: *** cfs_fail_loc=314, val=0*** [ 1433.511701] Lustre: Skipped 2 previous similar messages [ 1440.586178] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 11:59:16 (1772470756) [ 1455.509453] Lustre: *** cfs_fail_loc=314, val=0*** [ 1455.568573] LustreError: lustre-OST0000-osc-ffff9c7f8a5c0000: operation ldlm_enqueue to node 192.168.204.117@tcp failed: rc = -107 [ 1455.582687] LustreError: Skipped 1 previous similar message [ 1455.587577] Lustre: lustre-OST0000-osc-ffff9c7f8a5c0000: Connection to lustre-OST0000 (at 192.168.204.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1455.612605] Lustre: Skipped 1 previous similar message [ 1455.619431] LustreError: lustre-OST0000-osc-ffff9c7f8a5c0000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1455.632795] LustreError: 41810:0:(ldlm_resource.c:1170:ldlm_resource_complain()) lustre-OST0000-osc-ffff9c7f8a5c0000: namespace resource [0x240000400:0x35:0x0].0x0 (ffff9c7f831c5a00) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 1455.678747] Lustre: lustre-OST0000-osc-ffff9c7f8a5c0000: Connection restored to 192.168.204.117@tcp (at 192.168.204.117@tcp) [ 1455.695942] Lustre: Skipped 1 previous similar message [ 1464.048804] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 11:59:39 (1772470779) [ 1464.415467] LustreError: 42393:0:(file.c:785:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 1467.448213] LustreError: 42393:0:(file.c:785:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 1475.089903] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 11:59:50 (1772470790) [ 1483.355270] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 11:59:58 (1772470798) [ 1493.337238] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 1495.187295] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 12:00:11 (1772470811) [ 1497.062884] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 1498.937089] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 12:00:15 (1772470815) [ 1500.888152] Lustre: DEBUG MARKER: SKIP: sanityn test_33c needs >= 2 MDTs [ 1502.880544] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 12:00:18 (1772470818) [ 1504.761696] Lustre: DEBUG MARKER: SKIP: sanityn test_33d needs >= 2 MDTs [ 1506.568749] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 12:00:22 (1772470822) [ 1508.733960] Lustre: DEBUG MARKER: SKIP: sanityn test_33e needs >= 2 MDTs [ 1510.703771] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 12:00:26 (1772470826) [ 1568.235032] Lustre: lustre-OST0000-osc-ffff9c7f89a7b000: Connection to lustre-OST0000 (at 192.168.204.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1568.257824] LustreError: lustre-OST0000-osc-ffff9c7f89a7b000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1568.268215] LustreError: lustre-OST0000-osc-ffff9c7f8a5c0000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1568.275390] Lustre: lustre-OST0000-osc-ffff9c7f89a7b000: Connection restored to 192.168.204.117@tcp (at 192.168.204.117@tcp) [ 1588.706740] Lustre: lustre-OST0000-osc-ffff9c7f89a7b000: disconnect after 21s idle [ 1588.715868] Lustre: Skipped 1 previous similar message [ 1588.724184] Lustre: lustre-OST0001-osc-ffff9c7f89a7b000: Connection to lustre-OST0001 (at 192.168.204.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1588.741284] Lustre: Skipped 1 previous similar message [ 1588.764278] LustreError: lustre-OST0001-osc-ffff9c7f89a7b000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1588.788870] Lustre: lustre-OST0001-osc-ffff9c7f89a7b000: Connection restored to 192.168.204.117@tcp (at 192.168.204.117@tcp) [ 1588.811551] Lustre: Skipped 1 previous similar message [ 1604.509622] Lustre: DEBUG MARKER: oleg417-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9c7f89a7b000.ost_server_uuid 50 [ 1606.696341] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9c7f89a7b000.ost_server_uuid in IDLE state after 0 sec [ 1611.021822] Lustre: DEBUG MARKER: oleg417-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9c7f89a7b000.ost_server_uuid 50 [ 1613.503989] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9c7f89a7b000.ost_server_uuid in IDLE state after 0 sec [ 1620.052843] Lustre: DEBUG MARKER: oleg417-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9c7f89a7b000.ost_server_uuid 50 [ 1622.115839] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9c7f89a7b000.ost_server_uuid in IDLE state after 0 sec [ 1626.680596] Lustre: DEBUG MARKER: oleg417-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9c7f89a7b000.ost_server_uuid 50 [ 1628.496211] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9c7f89a7b000.ost_server_uuid in IDLE state after 0 sec [ 1640.608967] Lustre: DEBUG MARKER: oleg417-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9c7f89a7b000.ost_server_uuid 50 [ 1642.314871] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9c7f89a7b000.ost_server_uuid in IDLE state after 0 sec [ 1646.641941] Lustre: DEBUG MARKER: oleg417-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9c7f89a7b000.ost_server_uuid 50 [ 1648.353342] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9c7f89a7b000.ost_server_uuid in IDLE state after 0 sec [ 1650.859988] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 12:02:46 (1772470966) [ 1653.901915] Lustre: DEBUG MARKER: Race attempt 0 [ 1657.545113] Lustre: DEBUG MARKER: Wait for 49041 49062 for 60 sec... [ 1725.245633] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 12:04:01 (1772471041) [ 1732.690968] Lustre: DEBUG MARKER: start test - cycle (0) [ 1758.315418] Lustre: DEBUG MARKER: start test - cycle (1) [ 1784.457349] Lustre: DEBUG MARKER: start test - cycle (2) [ 1814.609634] Lustre: DEBUG MARKER: start test - cycle (3) [ 1844.708069] Lustre: lustre-OST0000-osc-ffff9c7f8a5c0000: disconnect after 21s idle [ 1844.727990] Lustre: Skipped 9 previous similar messages [ 1845.464896] Lustre: DEBUG MARKER: start test - cycle (4) [ 1871.819746] Lustre: DEBUG MARKER: start test - cycle (5) [ 1901.355401] Lustre: DEBUG MARKER: start test - cycle (6) [ 1920.309895] Lustre: DEBUG MARKER: start test - cycle (7) [ 1947.437545] Lustre: DEBUG MARKER: start test - cycle (8) [ 1972.376943] Lustre: DEBUG MARKER: start test - cycle (9) [ 1998.541811] Lustre: DEBUG MARKER: start test - cycle (10) [ 2035.776064] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 12:09:11 (1772471351) [ 2130.370802] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 12:10:46 (1772471446) [ 2137.814954] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 12:10:54 (1772471454) [ 2146.921307] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 12:11:03 (1772471463) [ 2155.710275] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 12:11:11 (1772471471) [ 2156.016743] Lustre: *** cfs_fail_loc=411, val=0*** [ 2162.953602] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 2164.787398] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 12:11:20 (1772471480) [ 2184.931225] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 12:11:40 (1772471500) [ 2204.681935] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 12:12:00 (1772471520) [ 2221.322402] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 12:12:17 (1772471537) [ 2238.323909] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 12:12:34 (1772471554) [ 2252.695029] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 12:12:48 (1772471568) [ 2265.651856] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 12:13:01 (1772471581) [ 2281.010550] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 12:13:16 (1772471596) [ 2296.535757] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 12:13:32 (1772471612) [ 2311.527799] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 12:13:47 (1772471627) [ 2325.553976] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 12:14:01 (1772471641) [ 2338.532777] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 12:14:14 (1772471654) [ 2352.359232] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 12:14:28 (1772471668) [ 2372.064862] Lustre: lustre-OST0000-osc-ffff9c7f89a7b000: disconnect after 21s idle [ 2372.070416] Lustre: Skipped 21 previous similar messages [ 3468.330635] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 12:33:03 (1772472783) [ 3483.664509] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 12:33:19 (1772472799) [ 3498.166934] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 12:33:34 (1772472814) [ 3512.542566] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 12:33:48 (1772472828) [ 3527.175275] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 12:34:03 (1772472843) [ 3529.186123] Lustre: lustre-OST0001-osc-ffff9c7f89a7b000: disconnect after 21s idle [ 3529.197992] Lustre: Skipped 2 previous similar messages [ 3543.143568] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 12:34:19 (1772472859) [ 3558.245316] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 12:34:34 (1772472874) [ 3573.548072] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 12:34:49 (1772472889) [ 3590.819268] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 12:35:06 (1772472906) [ 3657.943899] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 12:36:14 (1772472974) [ 3673.121429] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 12:36:28 (1772472988) [ 3687.725271] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 12:36:43 (1772473003) [ 3702.978728] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 12:36:58 (1772473018) [ 3719.455741] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 12:37:15 (1772473035) [ 3734.951215] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 12:37:30 (1772473050) [ 3753.324765] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 12:37:48 (1772473068) [ 3769.170611] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 12:38:05 (1772473085) [ 3771.174911] Lustre: DEBUG MARKER: SKIP: sanityn test_43i needs >= 2 MDTs [ 3773.677117] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 12:38:09 (1772473089) [ 3914.847949] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 12:40:30 (1772473230) [ 4317.664302] Lustre: lustre-OST0000-osc-ffff9c7f89a7b000: disconnect after 22s idle [ 4317.674353] Lustre: Skipped 7 previous similar messages [ 4839.273116] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 12:55:55 (1772474155) [ 4848.549491] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 12:56:05 (1772474165) [ 4857.401602] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 12:56:14 (1772474174) [ 4866.984116] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 12:56:23 (1772474183) [ 4875.344955] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 12:56:32 (1772474192) [ 4884.268349] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 12:56:40 (1772474200) [ 4892.352954] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 12:56:49 (1772474209) [ 4900.563471] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 12:56:57 (1772474217) [ 4908.898664] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 12:57:05 (1772474225) [ 4909.700490] Lustre: DEBUG MARKER: SKIP: sanityn test_44i needs >= 2 MDTs [ 4910.679835] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 12:57:07 (1772474227) [ 4921.825336] Lustre: lustre-OST0001-osc-ffff9c7f89a7b000: disconnect after 22s idle [ 4921.830490] Lustre: Skipped 4 previous similar messages [ 4987.808647] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 12:58:24 (1772474304) [ 4996.262341] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 12:58:32 (1772474312) [ 5005.612404] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 12:58:42 (1772474322) [ 5014.703424] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 12:58:51 (1772474331) [ 5023.339784] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 12:58:59 (1772474339) [ 5033.137269] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 12:59:09 (1772474349) [ 5042.366955] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 12:59:19 (1772474359) [ 5050.329677] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 12:59:26 (1772474366) [ 5051.208266] Lustre: DEBUG MARKER: SKIP: sanityn test_45i needs >= 2 MDTs [ 5052.386446] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 12:59:28 (1772474368) [ 5700.141355] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 13:10:16 (1772475016) [ 5706.783901] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 13:10:23 (1772475023) [ 5713.323248] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 13:10:30 (1772475030) [ 5719.753558] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 13:10:36 (1772475036) [ 5720.544258] Lustre: lustre-OST0000-osc-ffff9c7f8a5c0000: disconnect after 25s idle [ 5720.547069] Lustre: Skipped 5 previous similar messages [ 5726.112759] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 13:10:43 (1772475043) [ 5732.946339] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 13:10:49 (1772475049) [ 5740.087522] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 13:10:56 (1772475056) [ 5746.860729] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 13:11:03 (1772475063) [ 5753.454381] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 13:11:10 (1772475070) [ 5754.056577] Lustre: DEBUG MARKER: SKIP: sanityn test_46i needs >= 2 MDTs [ 5754.802389] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 13:11:11 (1772475071) [ 5755.463212] Lustre: DEBUG MARKER: SKIP: sanityn test_47a needs >= 2 MDTs [ 5756.253608] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 13:11:13 (1772475073) [ 5757.039588] Lustre: DEBUG MARKER: SKIP: sanityn test_47b needs >= 2 MDTs [ 5758.025931] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 13:11:14 (1772475074) [ 5758.859820] Lustre: DEBUG MARKER: SKIP: sanityn test_47c needs >= 2 MDTs [ 5759.728001] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 13:11:16 (1772475076) [ 5760.424889] Lustre: DEBUG MARKER: SKIP: sanityn test_47d needs >= 2 MDTs [ 5761.361453] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 13:11:18 (1772475078) [ 5762.074328] Lustre: DEBUG MARKER: SKIP: sanityn test_47e needs >= 2 MDTs [ 5762.879953] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 13:11:19 (1772475079) [ 5763.601769] Lustre: DEBUG MARKER: SKIP: sanityn test_47f needs >= 2 MDTs [ 5764.453516] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 13:11:21 (1772475081) [ 5765.113541] Lustre: DEBUG MARKER: SKIP: sanityn test_47g needs >= 2 MDTs [ 5765.926291] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 13:11:22 (1772475082) [ 5766.071264] LustreError: 5549:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 5768.160174] LustreError: 5549:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 5773.837235] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 13:11:30 (1772475090) [ 5778.829597] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 13:11:35 (1772475095) [ 5779.014046] LustreError: 217305:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5783.072433] LustreError: 217305:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 5783.109957] LustreError: 217305:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5787.184111] LustreError: 217305:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 5787.222570] LustreError: 217311:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 5791.288119] LustreError: 217311:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 5795.211651] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 13:11:52 (1772475112) [ 5802.876254] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 13:11:59 (1772475119) [ 5806.621496] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 13:12:03 (1772475123) [ 5811.662872] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 13:12:08 (1772475128) [ 5837.631538] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 13:12:34 (1772475154) [ 5846.502705] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 13:12:43 (1772475163) [ 5855.545186] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 13:12:52 (1772475172) [ 5869.700850] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 13:13:06 (1772475186) [ 5879.972511] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 13:13:16 (1772475196) [ 5880.607652] Lustre: DEBUG MARKER: SKIP: sanityn test_55e needs >= 2 MDTs [ 5881.339392] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 13:13:18 (1772475198) [ 5895.470675] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 13:13:32 (1772475212) [ 5910.520978] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 13:13:47 (1772475227) [ 5955.740801] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 13:14:32 (1772475272) [ 5998.960616] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 13:15:15 (1772475315) [ 6002.130695] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 6004.624123] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 13:15:21 (1772475321) [ 6008.274787] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 13:15:25 (1772475325) [ 6009.193824] Lustre: DEBUG MARKER: SKIP: sanityn test_71a ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 6009.992833] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 13:15:26 (1772475326) [ 6010.750148] Lustre: DEBUG MARKER: SKIP: sanityn test_71b ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 6011.604331] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 13:15:28 (1772475328) [ 6012.357835] Lustre: DEBUG MARKER: SKIP: sanityn test_71c support only ldiskfs ost [ 6013.181671] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 13:15:30 (1772475330) [ 6013.994319] Lustre: DEBUG MARKER: SKIP: sanityn test_71d ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 6014.670317] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 13:15:31 (1772475331) [ 6017.565579] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 13:15:34 (1772475334) [ 6020.892085] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 13:15:37 (1772475337) [ 6024.024599] LustreError: lustre-MDT0000-mdc-ffff9c7f89a7b000: operation ldlm_enqueue to node 192.168.204.117@tcp failed: rc = -35 [ 6024.030340] LustreError: Skipped 1 previous similar message [ 6027.926077] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 13:15:44 (1772475344) [ 6028.269170] LustreError: 2368:0:(osc_request.c:3117:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 6030.360127] LustreError: 2368:0:(osc_request.c:3117:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 6036.283487] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 13:15:53 (1772475353) [ 6067.325266] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 13:16:24 (1772475384) [ 6071.122256] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 13:16:27 (1772475387) [ 6077.007586] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 13:16:33 (1772475393) [ 6083.960795] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 13:16:40 (1772475400) [ 6090.676324] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 13:16:47 (1772475407) [ 6101.427675] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 13:16:58 (1772475418) [ 6111.518360] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 13:17:08 (1772475428) [ 6115.642202] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 13:17:12 (1772475432) [ 6120.148699] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 13:17:17 (1772475437) [ 6128.948979] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 13:17:25 (1772475445) [ 6180.268500] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 13:18:17 (1772475497) [ 6317.804642] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 13:20:34 (1772475634) [ 6345.184221] Lustre: lustre-OST0001-osc-ffff9c7f89a7b000: disconnect after 23s idle [ 6345.186703] Lustre: Skipped 12 previous similar messages [ 6457.257347] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 13:22:54 (1772475774) [ 6819.652643] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 13:28:56 (1772476136) [ 6857.035885] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 13:29:34 (1772476174) [ 6897.493399] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 13:30:14 (1772476214) [ 6928.722514] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 13:30:45 (1772476245) [ 6954.464222] Lustre: lustre-OST0001-osc-ffff9c7f89a7b000: disconnect after 23s idle [ 6954.466642] Lustre: Skipped 16 previous similar messages [ 7010.749771] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 13:32:07 (1772476327) [ 7077.173623] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 13:33:14 (1772476394) [ 7199.731614] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 13:35:16 (1772476516) [ 7200.645909] LustreError: 259543:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f89a7b000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7200.658912] Lustre: Unmounted lustre-client [ 7201.498336] LustreError: 259556:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f8a5c0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7201.501364] LustreError: 259556:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 7201.513098] Lustre: Unmounted lustre-client [ 7225.382567] Lustre: Mounted lustre-client [ 7226.934624] Lustre: Mounted lustre-client [ 7227.989693] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 7314.731418] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 13:37:11 (1772476631) [ 7322.384384] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 13:37:19 (1772476639) [ 7325.658764] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 13:37:22 (1772476642) [ 7375.891496] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 13:38:12 (1772476692) [ 7441.207347] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 13:39:18 (1772476758) [ 7444.771108] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 13:39:21 (1772476761) [ 7480.851349] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 13:39:57 (1772476797) [ 7491.656344] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 13:40:08 (1772476808) [ 7532.123672] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 13:40:49 (1772476849) [ 7535.224790] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 13:40:52 (1772476852) [ 7547.900708] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 13:41:04 (1772476864) [ 7548.388510] Lustre: DEBUG MARKER: SKIP: sanityn test_80a needs >= 2 MDTs [ 7548.967683] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 13:41:06 (1772476866) [ 7549.466199] Lustre: DEBUG MARKER: SKIP: sanityn test_80b needs >= 2 MDTs [ 7550.029554] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 13:41:07 (1772476867) [ 7550.537039] Lustre: DEBUG MARKER: SKIP: sanityn test_81a needs >= 2 MDTs [ 7551.097670] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 13:41:08 (1772476868) [ 7551.590124] Lustre: DEBUG MARKER: SKIP: sanityn test_81b We need at least 2 MDTs for this test [ 7552.166223] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 13:41:09 (1772476869) [ 7552.646810] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 7553.163125] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 13:41:10 (1772476870) [ 7559.648128] Lustre: lustre-OST0000-osc-ffff9c7f8820c800: disconnect after 23s idle [ 7559.652580] Lustre: Skipped 15 previous similar messages [ 7578.587798] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 13:41:35 (1772476895) [ 7580.821383] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 13:41:37 (1772476897) [ 7581.316876] Lustre: DEBUG MARKER: SKIP: sanityn test_83 needs >= 2 MDTs [ 7581.876849] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 13:41:38 (1772476898) [ 7589.323477] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 13:41:46 (1772476906) [ 7592.094908] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 13:41:49 (1772476909) [ 7592.598848] Lustre: DEBUG MARKER: SKIP: sanityn test_90 needs >= 2 MDTs [ 7593.215362] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 13:41:50 (1772476910) [ 7593.749761] Lustre: DEBUG MARKER: SKIP: sanityn test_91 needs >= 2 MDTs [ 7594.317774] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 13:41:51 (1772476911) [ 7594.824776] Lustre: DEBUG MARKER: SKIP: sanityn test_92 needs >= 2 MDTs [ 7595.431739] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 13:41:52 (1772476912) [ 7604.239761] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 13:42:01 (1772476921) [ 7604.317167] Lustre: DEBUG MARKER: write [ 7604.332770] LustreError: 261476:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 7606.341887] Lustre: DEBUG MARKER: kill 289979 [ 7606.344767] LustreError: 289979:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 7609.432122] LustreError: 261476:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 7612.384153] LustreError: 289979:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 7614.588667] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 13:42:11 (1772476931) [ 7616.806330] LustreError: 290585:0:(rw.c:1960:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 7626.896194] LustreError: 290585:0:(rw.c:1960:ll_readpage()) cfs_fail_timeout id 1422 awake [ 7629.308774] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 13:42:26 (1772476946) [ 7629.411353] LustreError: 291164:0:(rw.c:2211:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 7631.496101] LustreError: 291164:0:(rw.c:2211:ll_readpage()) cfs_fail_timeout interrupted [ 7637.528025] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 13:42:34 (1772476954) [ 7638.036679] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 7638.583514] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 13:42:35 (1772476955) [ 7640.857985] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 13:42:37 (1772476957) [ 7643.248992] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 13:42:40 (1772476960) [ 7645.442150] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 13:42:42 (1772476962) [ 7647.757147] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 13:42:44 (1772476964) [ 7649.980442] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 13:42:47 (1772476967) [ 7652.224234] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 13:42:49 (1772476969) [ 7655.319977] Lustre: DEBUG MARKER: SKIP: sanityn test_102 skipping ALWAYS excluded test 102 [ 7655.911634] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 13:42:52 (1772476972) [ 7656.552802] Lustre: *** cfs_fail_loc=415, val=0*** [ 7663.126098] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 13:43:00 (1772476980) [ 7663.622314] Lustre: DEBUG MARKER: SKIP: sanityn test_104 ldiskfs only test [ 7664.171780] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 13:43:01 (1772476981) [ 7664.244466] LustreError: 260955:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [ 7664.248521] LustreError: 260955:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [ 7669.344133] LustreError: 260956:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 7669.347104] LustreError: 260956:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [ 7679.536127] LustreError: 260955:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 7679.539348] LustreError: 260955:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [ 7687.116729] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 13:43:24 (1772477004) [ 7687.626320] Lustre: DEBUG MARKER: SKIP: sanityn test_106a Test only for ldiskfs and statx() supported [ 7688.182689] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 13:43:25 (1772477005) [ 7690.793079] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 13:43:27 (1772477007) [ 7692.935579] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 13:43:29 (1772477009) [ 7697.159164] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 13:43:34 (1772477014) [ 7705.482664] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 13:43:42 (1772477022) [ 7705.649044] LustreError: 279662:0:(osc_request.c:2968:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 7705.651769] LustreError: 279662:0:(osc_request.c:2968:osc_build_rpc()) Skipped 5 previous similar messages [ 7709.712082] LustreError: 279662:0:(osc_request.c:2968:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 7709.714371] LustreError: 279662:0:(osc_request.c:2968:osc_build_rpc()) Skipped 1 previous similar message [ 7711.995664] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 13:43:49 (1772477029) [ 7712.826818] LustreError: 301105:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f8820c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7712.829841] LustreError: 301105:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 7712.848096] Lustre: Unmounted lustre-client [ 7713.623225] Lustre: DEBUG MARKER: Iteration 1 [ 7713.720300] LustreError: 301285:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7713.720332] LustreError: 301286:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7713.725129] LustreError: 301285:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7713.775356] Lustre: Mounted lustre-client [ 7713.777171] Lustre: Skipped 1 previous similar message [ 7715.258474] Key type lgssc unregistered [ 7715.380410] LNet: 301629:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7715.383285] LNetError: 301629:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7715.391989] LNet: Removed LNI 192.168.204.17@tcp [ 7715.697104] Key type .llcrypt unregistered [ 7715.698353] Key type ._llcrypt unregistered [ 7715.991517] Key type ._llcrypt registered [ 7715.992767] Key type .llcrypt registered [ 7716.254048] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7716.258749] alg: No test for adler32 (adler32-zlib) [ 7717.242462] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7717.517358] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7719.128101] Key type lgssc registered [ 7719.663990] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7723.800614] Lustre: DEBUG MARKER: Iteration 2 [ 7723.912309] LustreError: 302451:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7723.912377] LustreError: 302452:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7723.918239] LustreError: 302451:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7724.972771] Lustre: Mounted lustre-client [ 7724.974314] Lustre: Skipped 1 previous similar message [ 7725.434501] LustreError: 302561:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f8655b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7725.438391] LustreError: 302561:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7725.466559] Lustre: Unmounted lustre-client [ 7726.415340] Key type lgssc unregistered [ 7726.532518] LNet: 302803:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7726.535592] LNetError: 302803:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7726.544160] LNet: Removed LNI 192.168.204.17@tcp [ 7726.792092] Key type .llcrypt unregistered [ 7726.793289] Key type ._llcrypt unregistered [ 7727.055794] Key type ._llcrypt registered [ 7727.056938] Key type .llcrypt registered [ 7727.267719] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7727.273029] alg: No test for adler32 (adler32-zlib) [ 7728.132836] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7728.217480] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7729.800248] Key type lgssc registered [ 7730.198304] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7733.681248] Lustre: DEBUG MARKER: Iteration 3 [ 7733.778236] LustreError: 303624:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7733.778289] LustreError: 303625:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7733.782859] LustreError: 303624:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7734.822142] Lustre: Mounted lustre-client [ 7734.823474] Lustre: Skipped 1 previous similar message [ 7735.280829] LustreError: 303726:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7fb7f60800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7735.286140] LustreError: 303726:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7735.300780] Lustre: Unmounted lustre-client [ 7736.300640] Key type lgssc unregistered [ 7736.426572] LNet: 303969:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7736.429234] LNetError: 303969:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7736.442642] LNet: Removed LNI 192.168.204.17@tcp [ 7736.680121] Key type .llcrypt unregistered [ 7736.682042] Key type ._llcrypt unregistered [ 7736.963229] Key type ._llcrypt registered [ 7736.966351] Key type .llcrypt registered [ 7737.166210] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7737.172160] alg: No test for adler32 (adler32-zlib) [ 7738.031562] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7738.119767] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7739.704141] Key type lgssc registered [ 7740.116206] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7743.576185] Lustre: DEBUG MARKER: Iteration 4 [ 7743.677374] LustreError: 304788:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7743.679559] LustreError: 304791:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7743.681770] LustreError: 304788:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [ 7744.720045] Lustre: Mounted lustre-client [ 7744.722411] Lustre: Skipped 1 previous similar message [ 7745.205406] LustreError: 304897:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f87ca9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7745.210238] LustreError: 304897:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7745.233087] Lustre: Unmounted lustre-client [ 7746.220048] Key type lgssc unregistered [ 7746.337389] LNet: 305139:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7746.339688] LNetError: 305139:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7746.351150] LNet: Removed LNI 192.168.204.17@tcp [ 7746.607102] Key type .llcrypt unregistered [ 7746.608383] Key type ._llcrypt unregistered [ 7746.913257] Key type ._llcrypt registered [ 7746.914523] Key type .llcrypt registered [ 7747.057920] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7747.063100] alg: No test for adler32 (adler32-zlib) [ 7747.929039] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7748.012560] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7749.592181] Key type lgssc registered [ 7750.018646] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7753.618224] Lustre: DEBUG MARKER: Iteration 5 [ 7753.746236] LustreError: 305959:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7753.746321] LustreError: 305960:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7753.752983] LustreError: 305959:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7754.813163] Lustre: Mounted lustre-client [ 7754.814577] Lustre: Skipped 1 previous similar message [ 7755.321622] LustreError: 306068:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f86b48800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7755.326325] LustreError: 306068:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7755.363330] Lustre: Unmounted lustre-client [ 7756.327241] Key type lgssc unregistered [ 7756.442420] LNet: 306311:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7756.444935] LNetError: 306311:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7756.453714] LNet: Removed LNI 192.168.204.17@tcp [ 7756.671096] Key type .llcrypt unregistered [ 7756.672430] Key type ._llcrypt unregistered [ 7756.968022] Key type ._llcrypt registered [ 7756.970118] Key type .llcrypt registered [ 7757.138363] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7757.144328] alg: No test for adler32 (adler32-zlib) [ 7757.999930] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7758.080755] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7759.672113] Key type lgssc registered [ 7760.075016] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7763.497683] Lustre: DEBUG MARKER: Iteration 6 [ 7763.593187] LustreError: 307131:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7763.593473] LustreError: 307132:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7763.597467] LustreError: 307131:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7764.647134] Lustre: Mounted lustre-client [ 7765.108584] LustreError: 307236:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f849b7800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7765.112785] LustreError: 307236:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7765.129078] Lustre: Unmounted lustre-client [ 7766.150543] Key type lgssc unregistered [ 7766.276442] LNet: 307478:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7766.279344] LNetError: 307478:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7766.289648] LNet: Removed LNI 192.168.204.17@tcp [ 7766.534146] Key type .llcrypt unregistered [ 7766.536071] Key type ._llcrypt unregistered [ 7766.839532] Key type ._llcrypt registered [ 7766.842790] Key type .llcrypt registered [ 7767.040636] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7767.047897] alg: No test for adler32 (adler32-zlib) [ 7767.923870] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7768.008920] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7769.592135] Key type lgssc registered [ 7770.007266] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7774.000871] Lustre: DEBUG MARKER: Iteration 7 [ 7774.104923] LustreError: 308298:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7774.104973] LustreError: 308299:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7774.111880] LustreError: 308298:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7775.169772] Lustre: Mounted lustre-client [ 7775.171226] Lustre: Skipped 1 previous similar message [ 7775.771052] LustreError: 308408:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f8b3c6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7775.775882] LustreError: 308408:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7775.797537] Lustre: Unmounted lustre-client [ 7776.889669] Key type lgssc unregistered [ 7777.017598] LNet: 308651:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7777.020409] LNetError: 308651:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7777.029835] LNet: Removed LNI 192.168.204.17@tcp [ 7777.275106] Key type .llcrypt unregistered [ 7777.276468] Key type ._llcrypt unregistered [ 7777.578232] Key type ._llcrypt registered [ 7777.579468] Key type .llcrypt registered [ 7777.769065] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7777.774875] alg: No test for adler32 (adler32-zlib) [ 7778.640972] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7778.742216] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7780.336244] Key type lgssc registered [ 7780.789548] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7784.514267] Lustre: DEBUG MARKER: Iteration 8 [ 7784.637403] LustreError: 309472:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7784.637455] LustreError: 309473:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7784.642869] LustreError: 309472:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7785.705847] Lustre: Mounted lustre-client [ 7785.707370] Lustre: Skipped 1 previous similar message [ 7786.219733] LustreError: 309576:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f8820f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7786.225498] LustreError: 309576:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7786.252097] Lustre: Unmounted lustre-client [ 7787.294253] Key type lgssc unregistered [ 7787.416557] LNet: 309819:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7787.419531] LNetError: 309819:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7787.426720] LNet: Removed LNI 192.168.204.17@tcp [ 7787.688142] Key type .llcrypt unregistered [ 7787.689448] Key type ._llcrypt unregistered [ 7788.002057] Key type ._llcrypt registered [ 7788.003612] Key type .llcrypt registered [ 7788.210729] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7788.215656] alg: No test for adler32 (adler32-zlib) [ 7789.094187] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7789.205793] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7790.792202] Key type lgssc registered [ 7791.208899] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7794.983973] Lustre: DEBUG MARKER: Iteration 9 [ 7795.087871] LustreError: 310639:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7795.087970] LustreError: 310640:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7795.092653] LustreError: 310639:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7796.155279] Lustre: Mounted lustre-client [ 7796.157156] Lustre: Skipped 1 previous similar message [ 7796.643528] LustreError: 310748:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7fbd0c0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7796.649066] LustreError: 310748:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7796.662145] Lustre: Unmounted lustre-client [ 7797.639073] Key type lgssc unregistered [ 7797.763342] LNet: 310990:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7797.765480] LNetError: 310990:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7797.773846] LNet: Removed LNI 192.168.204.17@tcp [ 7798.023144] Key type .llcrypt unregistered [ 7798.024397] Key type ._llcrypt unregistered [ 7798.308449] Key type ._llcrypt registered [ 7798.311461] Key type .llcrypt registered [ 7798.515711] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7798.521733] alg: No test for adler32 (adler32-zlib) [ 7799.406767] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7799.510332] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7801.104168] Key type lgssc registered [ 7801.516919] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7805.203775] Lustre: DEBUG MARKER: Iteration 10 [ 7805.301850] LustreError: 311811:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7805.303477] LustreError: 311812:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7805.306229] LustreError: 311811:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7806.356381] Lustre: Mounted lustre-client [ 7806.781872] LustreError: 311921:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7fa5b79000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7806.785294] LustreError: 311921:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7806.818120] Lustre: Unmounted lustre-client [ 7807.781057] Key type lgssc unregistered [ 7807.903638] LNet: 312164:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7807.906450] LNetError: 312164:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7807.915155] LNet: Removed LNI 192.168.204.17@tcp [ 7808.139099] Key type .llcrypt unregistered [ 7808.140105] Key type ._llcrypt unregistered [ 7808.376270] Key type ._llcrypt registered [ 7808.377452] Key type .llcrypt registered [ 7808.622451] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7808.627046] alg: No test for adler32 (adler32-zlib) [ 7809.482319] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7809.565405] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7811.144194] Key type lgssc registered [ 7811.542327] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7814.587281] Lustre: DEBUG MARKER: Iteration 11 [ 7814.679856] LustreError: 312985:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7814.679883] LustreError: 312986:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7814.685092] LustreError: 312985:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7815.725502] Lustre: Mounted lustre-client [ 7815.727441] Lustre: Skipped 1 previous similar message [ 7816.126982] LustreError: 313096:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f996c9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7816.131460] LustreError: 313096:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7816.151267] Lustre: Unmounted lustre-client [ 7817.013202] Key type lgssc unregistered [ 7817.113466] LNet: 313339:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7817.115750] LNetError: 313339:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7817.122647] LNet: Removed LNI 192.168.204.17@tcp [ 7817.334092] Key type .llcrypt unregistered [ 7817.335025] Key type ._llcrypt unregistered [ 7817.573927] Key type ._llcrypt registered [ 7817.574830] Key type .llcrypt registered [ 7817.740461] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7817.745608] alg: No test for adler32 (adler32-zlib) [ 7818.590915] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7818.663666] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7820.240115] Key type lgssc registered [ 7820.566572] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7823.864605] Lustre: DEBUG MARKER: Iteration 12 [ 7823.962720] LustreError: 314160:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7823.963804] LustreError: 314161:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7823.967083] LustreError: 314160:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7825.017801] Lustre: Mounted lustre-client [ 7825.019033] Lustre: Skipped 1 previous similar message [ 7825.488376] LustreError: 314268:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f8569d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7825.521553] Lustre: Unmounted lustre-client [ 7825.522602] Lustre: Skipped 1 previous similar message [ 7826.504531] Key type lgssc unregistered [ 7826.631578] LNet: 314512:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7826.634312] LNetError: 314512:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7826.644599] LNet: Removed LNI 192.168.204.17@tcp [ 7826.866124] Key type .llcrypt unregistered [ 7826.867186] Key type ._llcrypt unregistered [ 7827.123165] Key type ._llcrypt registered [ 7827.124750] Key type .llcrypt registered [ 7827.333692] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7827.338474] alg: No test for adler32 (adler32-zlib) [ 7828.193564] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7828.275057] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7829.856102] Key type lgssc registered [ 7830.253143] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7833.625717] Lustre: DEBUG MARKER: Iteration 13 [ 7833.733468] LustreError: 315331:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7833.733504] LustreError: 315332:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7833.739790] LustreError: 315331:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7834.783894] Lustre: Mounted lustre-client [ 7834.784953] Lustre: Skipped 1 previous similar message [ 7835.238759] LustreError: 315438:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f8820f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7835.242446] LustreError: 315438:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7835.259111] Lustre: Unmounted lustre-client [ 7836.179240] Key type lgssc unregistered [ 7836.299754] LNet: 315680:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7836.302455] LNetError: 315680:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7836.313220] LNet: Removed LNI 192.168.204.17@tcp [ 7836.535120] Key type .llcrypt unregistered [ 7836.536898] Key type ._llcrypt unregistered [ 7836.819530] Key type ._llcrypt registered [ 7836.821299] Key type .llcrypt registered [ 7836.986757] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7836.991512] alg: No test for adler32 (adler32-zlib) [ 7837.844192] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7837.925209] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7839.504106] Key type lgssc registered [ 7839.863991] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7843.446338] Lustre: DEBUG MARKER: Iteration 14 [ 7843.544584] LustreError: 316500:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7843.544611] LustreError: 316501:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7843.548624] LustreError: 316500:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7844.594774] Lustre: Mounted lustre-client [ 7844.596034] Lustre: Skipped 1 previous similar message [ 7845.240402] LustreError: 316606:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f84d79800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7845.275796] Lustre: Unmounted lustre-client [ 7846.184780] Key type lgssc unregistered [ 7846.298505] LNet: 316849:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7846.300698] LNetError: 316849:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7846.309649] LNet: Removed LNI 192.168.204.17@tcp [ 7846.521113] Key type .llcrypt unregistered [ 7846.522169] Key type ._llcrypt unregistered [ 7846.818410] Key type ._llcrypt registered [ 7846.820783] Key type .llcrypt registered [ 7847.067628] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7847.073463] alg: No test for adler32 (adler32-zlib) [ 7847.937662] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7848.023035] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7849.616133] Key type lgssc registered [ 7850.035849] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7853.338731] Lustre: DEBUG MARKER: Iteration 15 [ 7853.440813] LustreError: 317670:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7853.441402] LustreError: 317671:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7853.445567] LustreError: 317670:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7854.494488] Lustre: Mounted lustre-client [ 7854.496739] Lustre: Skipped 1 previous similar message [ 7854.947393] LustreError: 317777:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f86b2d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7854.950597] LustreError: 317777:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7854.990102] Lustre: Unmounted lustre-client [ 7855.929978] Key type lgssc unregistered [ 7856.034901] LNet: 318020:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7856.037358] LNetError: 318020:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7856.044662] LNet: Removed LNI 192.168.204.17@tcp [ 7856.292104] Key type .llcrypt unregistered [ 7856.293165] Key type ._llcrypt unregistered [ 7856.685300] Key type ._llcrypt registered [ 7856.686429] Key type .llcrypt registered [ 7856.847811] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7856.852431] alg: No test for adler32 (adler32-zlib) [ 7857.729028] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7857.825579] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7859.416111] Key type lgssc registered [ 7859.780047] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7863.066877] Lustre: DEBUG MARKER: Iteration 16 [ 7863.165699] LustreError: 318841:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7863.170115] LustreError: 318843:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7863.172753] LustreError: 318841:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7864.219150] Lustre: Mounted lustre-client [ 7864.221151] Lustre: Skipped 1 previous similar message [ 7864.672617] LustreError: 318950:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f86665800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7864.677118] LustreError: 318950:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7864.696861] Lustre: Unmounted lustre-client [ 7865.627262] Key type lgssc unregistered [ 7865.748351] LNet: 319192:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7865.751751] LNetError: 319192:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7865.764734] LNet: Removed LNI 192.168.204.17@tcp [ 7865.989104] Key type .llcrypt unregistered [ 7865.990346] Key type ._llcrypt unregistered [ 7866.318288] Key type ._llcrypt registered [ 7866.320029] Key type .llcrypt registered [ 7866.451713] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7866.456451] alg: No test for adler32 (adler32-zlib) [ 7867.301686] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7867.381861] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7868.968149] Key type lgssc registered [ 7869.341852] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7872.781358] Lustre: DEBUG MARKER: Iteration 17 [ 7872.877128] LustreError: 320014:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7872.877159] LustreError: 320015:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7872.882647] LustreError: 320014:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7873.927119] Lustre: Mounted lustre-client [ 7873.928560] Lustre: Skipped 1 previous similar message [ 7874.382574] LustreError: 320121:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f8655b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7874.386824] LustreError: 320121:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7874.405085] Lustre: Unmounted lustre-client [ 7875.346075] Key type lgssc unregistered [ 7875.460348] LNet: 320363:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7875.462721] LNetError: 320363:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7875.476631] LNet: Removed LNI 192.168.204.17@tcp [ 7875.713102] Key type .llcrypt unregistered [ 7875.714756] Key type ._llcrypt unregistered [ 7876.037208] Key type ._llcrypt registered [ 7876.038282] Key type .llcrypt registered [ 7876.165247] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7876.170196] alg: No test for adler32 (adler32-zlib) [ 7877.027931] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7877.109143] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7878.696142] Key type lgssc registered [ 7879.094912] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7882.608513] Lustre: DEBUG MARKER: Iteration 18 [ 7882.719000] LustreError: 321183:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7882.719077] LustreError: 321184:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7882.724055] LustreError: 321183:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7883.774486] Lustre: Mounted lustre-client [ 7883.775551] Lustre: Skipped 1 previous similar message [ 7884.237028] LustreError: 321290:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f86667000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7884.241191] LustreError: 321290:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7884.264389] Lustre: Unmounted lustre-client [ 7885.205204] Key type lgssc unregistered [ 7885.320543] LNet: 321532:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7885.322794] LNetError: 321532:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7885.331700] LNet: Removed LNI 192.168.204.17@tcp [ 7885.562142] Key type .llcrypt unregistered [ 7885.563283] Key type ._llcrypt unregistered [ 7885.924865] Key type ._llcrypt registered [ 7885.926072] Key type .llcrypt registered [ 7886.022138] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7886.027649] alg: No test for adler32 (adler32-zlib) [ 7886.883815] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7886.966568] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7888.552158] Key type lgssc registered [ 7888.915565] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7892.683280] Lustre: DEBUG MARKER: Iteration 19 [ 7892.793661] LustreError: 322353:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7892.793714] LustreError: 322354:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7892.799963] LustreError: 322353:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 7893.845687] Lustre: Mounted lustre-client [ 7893.847057] Lustre: Skipped 1 previous similar message [ 7894.427554] LustreError: 322460:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f8655e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7894.432230] LustreError: 322460:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7894.445279] Lustre: Unmounted lustre-client [ 7895.490560] Key type lgssc unregistered [ 7895.604345] LNet: 322703:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7895.607196] LNetError: 322703:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7895.615788] LNet: Removed LNI 192.168.204.17@tcp [ 7895.858125] Key type .llcrypt unregistered [ 7895.859303] Key type ._llcrypt unregistered [ 7896.148041] Key type ._llcrypt registered [ 7896.149088] Key type .llcrypt registered [ 7896.331676] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7896.337076] alg: No test for adler32 (adler32-zlib) [ 7897.193260] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7897.291259] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7898.872111] Key type lgssc registered [ 7899.268886] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7903.060526] Lustre: DEBUG MARKER: Iteration 20 [ 7903.163742] LustreError: 323523:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7903.163773] LustreError: 323524:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7903.168306] LustreError: 323523:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7904.219616] Lustre: Mounted lustre-client [ 7904.220836] Lustre: Skipped 1 previous similar message [ 7904.673973] LustreError: 323634:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f83191800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7904.678627] LustreError: 323634:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7904.694100] Lustre: Unmounted lustre-client [ 7905.677261] Key type lgssc unregistered [ 7905.791547] LNet: 323877:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7905.794420] LNetError: 323877:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7905.803633] LNet: Removed LNI 192.168.204.17@tcp [ 7906.049108] Key type .llcrypt unregistered [ 7906.050354] Key type ._llcrypt unregistered [ 7906.349442] Key type ._llcrypt registered [ 7906.358527] Key type .llcrypt registered [ 7906.546542] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7906.552532] alg: No test for adler32 (adler32-zlib) [ 7907.413730] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7907.498000] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7909.080169] Key type lgssc registered [ 7909.487400] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7913.032787] Lustre: DEBUG MARKER: Iteration 21 [ 7913.158893] LustreError: 324697:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7913.160177] LustreError: 324698:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7913.163483] LustreError: 324697:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7914.213546] Lustre: Mounted lustre-client [ 7914.215333] Lustre: Skipped 1 previous similar message [ 7914.660889] LustreError: 324800:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f8a155800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7914.664172] LustreError: 324800:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7914.692093] Lustre: Unmounted lustre-client [ 7915.628726] Key type lgssc unregistered [ 7915.743510] LNet: 325042:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7915.745841] LNetError: 325042:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7915.754744] LNet: Removed LNI 192.168.204.17@tcp [ 7915.990138] Key type .llcrypt unregistered [ 7915.991228] Key type ._llcrypt unregistered [ 7916.269635] Key type ._llcrypt registered [ 7916.270668] Key type .llcrypt registered [ 7916.466311] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7916.471502] alg: No test for adler32 (adler32-zlib) [ 7917.328969] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7917.415098] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7918.992127] Key type lgssc registered [ 7919.375749] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7922.902352] Lustre: DEBUG MARKER: Iteration 22 [ 7922.994689] LustreError: 325862:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7922.994995] LustreError: 325863:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7922.999703] LustreError: 325862:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7924.062101] Lustre: Mounted lustre-client [ 7924.063532] Lustre: Skipped 1 previous similar message [ 7924.518608] LustreError: 325968:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f86663800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7924.523923] LustreError: 325968:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7924.542072] Lustre: Unmounted lustre-client [ 7925.550490] Key type lgssc unregistered [ 7925.666537] LNet: 326211:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7925.669404] LNetError: 326211:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7925.680307] LNet: Removed LNI 192.168.204.17@tcp [ 7925.933139] Key type .llcrypt unregistered [ 7925.935081] Key type ._llcrypt unregistered [ 7926.392665] Key type ._llcrypt registered [ 7926.395458] Key type .llcrypt registered [ 7926.524525] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7926.530794] alg: No test for adler32 (adler32-zlib) [ 7927.387828] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7927.480595] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7929.064145] Key type lgssc registered [ 7929.453246] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7933.019771] Lustre: DEBUG MARKER: Iteration 23 [ 7933.136549] LustreError: 327031:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7933.137955] LustreError: 327032:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7933.141948] LustreError: 327031:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7934.195957] Lustre: Mounted lustre-client [ 7934.198078] Lustre: Skipped 1 previous similar message [ 7934.661235] LustreError: 327139:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f84d7e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7934.665507] LustreError: 327139:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7934.687523] Lustre: Unmounted lustre-client [ 7935.612887] Key type lgssc unregistered [ 7935.744507] LNet: 327382:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7935.747240] LNetError: 327382:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7935.755690] LNet: Removed LNI 192.168.204.17@tcp [ 7935.983170] Key type .llcrypt unregistered [ 7935.984889] Key type ._llcrypt unregistered [ 7936.253309] Key type ._llcrypt registered [ 7936.254316] Key type .llcrypt registered [ 7936.458566] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7936.464775] alg: No test for adler32 (adler32-zlib) [ 7937.333161] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7937.418457] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7939.008089] Key type lgssc registered [ 7939.404144] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7942.895416] Lustre: DEBUG MARKER: Iteration 24 [ 7942.996485] LustreError: 328202:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7942.997127] LustreError: 328203:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7943.001938] LustreError: 328202:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7944.054487] Lustre: Mounted lustre-client [ 7944.056223] Lustre: Skipped 1 previous similar message [ 7944.513091] LustreError: 328312:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f996ce000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7944.516718] LustreError: 328312:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7944.555104] Lustre: Unmounted lustre-client [ 7945.548666] Key type lgssc unregistered [ 7945.658398] LNet: 328555:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7945.661111] LNetError: 328555:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7945.670423] LNet: Removed LNI 192.168.204.17@tcp [ 7945.906118] Key type .llcrypt unregistered [ 7945.907310] Key type ._llcrypt unregistered [ 7946.191624] Key type ._llcrypt registered [ 7946.192967] Key type .llcrypt registered [ 7946.395769] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7946.403114] alg: No test for adler32 (adler32-zlib) [ 7947.271019] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7947.367389] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7948.960173] Key type lgssc registered [ 7949.337629] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7953.041044] Lustre: DEBUG MARKER: Iteration 25 [ 7953.163102] LustreError: 329376:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7953.165071] LustreError: 329377:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7953.167065] LustreError: 329376:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7954.230877] Lustre: Mounted lustre-client [ 7954.232242] Lustre: Skipped 1 previous similar message [ 7954.790783] LustreError: 329483:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f8382d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7954.795217] LustreError: 329483:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7954.834559] Lustre: Unmounted lustre-client [ 7955.897093] Key type lgssc unregistered [ 7956.026478] LNet: 329726:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7956.028869] LNetError: 329726:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7956.035712] LNet: Removed LNI 192.168.204.17@tcp [ 7956.260087] Key type .llcrypt unregistered [ 7956.261116] Key type ._llcrypt unregistered [ 7956.517513] Key type ._llcrypt registered [ 7956.518630] Key type .llcrypt registered [ 7956.721262] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7956.727804] alg: No test for adler32 (adler32-zlib) [ 7957.586783] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7957.678485] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7959.272116] Key type lgssc registered [ 7959.688644] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7963.343651] Lustre: DEBUG MARKER: Iteration 26 [ 7963.461064] LustreError: 330547:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7963.462089] LustreError: 330548:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7963.467688] LustreError: 330547:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7964.532541] Lustre: Mounted lustre-client [ 7964.534481] Lustre: Skipped 1 previous similar message [ 7964.985200] LustreError: 330655:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f8820b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7964.990238] LustreError: 330655:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7965.043421] Lustre: Unmounted lustre-client [ 7965.044533] Lustre: Skipped 1 previous similar message [ 7965.989892] Key type lgssc unregistered [ 7966.110358] LNet: 330899:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7966.113838] LNetError: 330899:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7966.121870] LNet: Removed LNI 192.168.204.17@tcp [ 7966.353112] Key type .llcrypt unregistered [ 7966.354286] Key type ._llcrypt unregistered [ 7966.641624] Key type ._llcrypt registered [ 7966.643220] Key type .llcrypt registered [ 7966.819360] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7966.824480] alg: No test for adler32 (adler32-zlib) [ 7967.689838] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7967.776401] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7969.360157] Key type lgssc registered [ 7969.779813] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7973.901420] Lustre: DEBUG MARKER: Iteration 27 [ 7974.022119] LustreError: 331719:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7974.022164] LustreError: 331720:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7974.027350] LustreError: 331719:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7975.077737] Lustre: Mounted lustre-client [ 7975.079226] Lustre: Skipped 1 previous similar message [ 7975.604308] LustreError: 331827:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f8635a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7975.607988] LustreError: 331827:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7975.626115] Lustre: Unmounted lustre-client [ 7976.573967] Key type lgssc unregistered [ 7976.685388] LNet: 332070:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7976.688139] LNetError: 332070:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7976.695578] LNet: Removed LNI 192.168.204.17@tcp [ 7976.922122] Key type .llcrypt unregistered [ 7976.923290] Key type ._llcrypt unregistered [ 7977.167616] Key type ._llcrypt registered [ 7977.168645] Key type .llcrypt registered [ 7977.351242] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7977.355678] alg: No test for adler32 (adler32-zlib) [ 7978.218911] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7978.312128] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7979.904160] Key type lgssc registered [ 7980.230621] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7983.218469] Lustre: DEBUG MARKER: Iteration 28 [ 7983.310252] LustreError: 332890:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7983.310281] LustreError: 332891:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7983.315307] LustreError: 332890:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 7984.352778] Lustre: Mounted lustre-client [ 7984.354503] Lustre: Skipped 1 previous similar message [ 7984.755548] LustreError: 332993:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f892a4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7984.778073] Lustre: Unmounted lustre-client [ 7985.596416] Key type lgssc unregistered [ 7985.713453] LNet: 333236:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7985.715692] LNetError: 333236:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7985.722666] LNet: Removed LNI 192.168.204.17@tcp [ 7985.940143] Key type .llcrypt unregistered [ 7985.941640] Key type ._llcrypt unregistered [ 7986.231309] Key type ._llcrypt registered [ 7986.248028] Key type .llcrypt registered [ 7986.364048] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7986.368462] alg: No test for adler32 (adler32-zlib) [ 7987.211817] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7987.286794] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7988.864140] Key type lgssc registered [ 7989.226722] Lustre: Echo OBD driver; http://www.lustre.org/ [ 7992.557333] Lustre: DEBUG MARKER: Iteration 29 [ 7992.668261] LustreError: 334056:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 7992.668303] LustreError: 334057:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 7992.673699] LustreError: 334056:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 7993.724090] Lustre: Mounted lustre-client [ 7993.726191] Lustre: Skipped 1 previous similar message [ 7994.209148] LustreError: 334163:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7fbeb0c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 7994.212252] LustreError: 334163:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 7994.244074] Lustre: Unmounted lustre-client [ 7995.211553] Key type lgssc unregistered [ 7995.313527] LNet: 334407:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 7995.315877] LNetError: 334407:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 7995.322538] LNet: Removed LNI 192.168.204.17@tcp [ 7995.545168] Key type .llcrypt unregistered [ 7995.546090] Key type ._llcrypt unregistered [ 7995.906627] Key type ._llcrypt registered [ 7995.907374] Key type .llcrypt registered [ 7996.105864] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 7996.112038] alg: No test for adler32 (adler32-zlib) [ 7996.959181] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 7997.036399] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 7998.616179] Key type lgssc registered [ 7999.018879] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8002.456776] Lustre: DEBUG MARKER: Iteration 30 [ 8002.570467] LustreError: 335228:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 8002.570499] LustreError: 335229:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 8002.576304] LustreError: 335228:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8003.620323] Lustre: Mounted lustre-client [ 8004.072360] LustreError: 335336:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f82c6d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8004.075497] LustreError: 335336:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8004.128379] Lustre: Unmounted lustre-client [ 8004.129492] Lustre: Skipped 1 previous similar message [ 8005.098403] Key type lgssc unregistered [ 8005.214512] LNet: 335578:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8005.216960] LNetError: 335578:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8005.227626] LNet: Removed LNI 192.168.204.17@tcp [ 8005.451098] Key type .llcrypt unregistered [ 8005.452212] Key type ._llcrypt unregistered [ 8005.799933] Key type ._llcrypt registered [ 8005.800969] Key type .llcrypt registered [ 8005.938583] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8005.944162] alg: No test for adler32 (adler32-zlib) [ 8006.800893] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 8006.883287] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 8008.464119] Key type lgssc registered [ 8008.824146] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8012.385834] Lustre: DEBUG MARKER: Iteration 31 [ 8012.485203] LustreError: 336400:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 8012.488929] LustreError: 336401:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 8012.491435] LustreError: 336400:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8013.538974] Lustre: Mounted lustre-client [ 8013.540759] Lustre: Skipped 1 previous similar message [ 8014.094507] LustreError: 336508:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7fbeb0d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8014.097858] LustreError: 336508:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8014.109471] Lustre: Unmounted lustre-client [ 8015.111514] Key type lgssc unregistered [ 8015.232562] LNet: 336751:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8015.234966] LNetError: 336751:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8015.245880] LNet: Removed LNI 192.168.204.17@tcp [ 8015.490115] Key type .llcrypt unregistered [ 8015.491790] Key type ._llcrypt unregistered [ 8015.802110] Key type ._llcrypt registered [ 8015.803216] Key type .llcrypt registered [ 8015.984562] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8015.990817] alg: No test for adler32 (adler32-zlib) [ 8016.850401] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 8016.945183] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 8018.536161] Key type lgssc registered [ 8018.927792] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8022.673074] Lustre: DEBUG MARKER: Iteration 32 [ 8022.763395] LustreError: 337571:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 8022.769126] LustreError: 337572:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 8022.771549] LustreError: 337571:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4994 [ 8023.829112] Lustre: Mounted lustre-client [ 8023.830691] Lustre: Skipped 1 previous similar message [ 8024.303576] LustreError: 337680:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f88209800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8024.308678] LustreError: 337680:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8024.321977] Lustre: Unmounted lustre-client [ 8025.290655] Key type lgssc unregistered [ 8025.406478] LNet: 337922:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8025.409230] LNetError: 337922:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8025.417846] LNet: Removed LNI 192.168.204.17@tcp [ 8025.662150] Key type .llcrypt unregistered [ 8025.664054] Key type ._llcrypt unregistered [ 8025.957712] Key type ._llcrypt registered [ 8025.959548] Key type .llcrypt registered [ 8026.176044] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8026.181171] alg: No test for adler32 (adler32-zlib) [ 8027.047882] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 8027.142838] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 8028.736176] Key type lgssc registered [ 8029.155888] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8032.622526] Lustre: DEBUG MARKER: Iteration 33 [ 8032.709477] LustreError: 338743:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 8032.709512] LustreError: 338744:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 8032.713968] LustreError: 338743:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8033.754849] Lustre: Mounted lustre-client [ 8033.756518] Lustre: Skipped 1 previous similar message [ 8034.166934] LustreError: 338851:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7fa594f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8034.170804] LustreError: 338851:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8034.221486] Lustre: Unmounted lustre-client [ 8035.114293] Key type lgssc unregistered [ 8035.238411] LNet: 339092:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8035.241379] LNetError: 339092:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8035.249641] LNet: Removed LNI 192.168.204.17@tcp [ 8035.480104] Key type .llcrypt unregistered [ 8035.481080] Key type ._llcrypt unregistered [ 8035.729399] Key type ._llcrypt registered [ 8035.730665] Key type .llcrypt registered [ 8035.932399] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8035.938158] alg: No test for adler32 (adler32-zlib) [ 8036.792275] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 8036.865526] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 8038.448132] Key type lgssc registered [ 8038.806781] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8041.915481] Lustre: DEBUG MARKER: Iteration 34 [ 8042.005390] LustreError: 339913:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 8042.006070] LustreError: 339914:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 8042.010242] LustreError: 339913:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8043.056623] Lustre: Mounted lustre-client [ 8043.057778] Lustre: Skipped 1 previous similar message [ 8043.461743] LustreError: 340016:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7fa615a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8043.464922] LustreError: 340016:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8043.482150] Lustre: Unmounted lustre-client [ 8044.303927] Key type lgssc unregistered [ 8044.411423] LNet: 340257:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8044.413776] LNetError: 340257:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8044.424821] LNet: Removed LNI 192.168.204.17@tcp [ 8044.633090] Key type .llcrypt unregistered [ 8044.634174] Key type ._llcrypt unregistered [ 8044.862725] Key type ._llcrypt registered [ 8044.864153] Key type .llcrypt registered [ 8045.045306] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8045.050877] alg: No test for adler32 (adler32-zlib) [ 8045.896390] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 8045.972812] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 8047.552104] Key type lgssc registered [ 8047.880235] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8051.107951] Lustre: DEBUG MARKER: Iteration 35 [ 8051.210917] LustreError: 341078:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 8051.210947] LustreError: 341079:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 8051.215639] LustreError: 341078:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8052.266109] Lustre: Mounted lustre-client [ 8052.268106] Lustre: Skipped 1 previous similar message [ 8052.846551] LustreError: 341180:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f86b2d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8052.914314] Lustre: Unmounted lustre-client [ 8052.915439] Lustre: Skipped 1 previous similar message [ 8053.875818] Key type lgssc unregistered [ 8053.999554] LNet: 341422:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8054.002048] LNetError: 341422:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8054.008645] LNet: Removed LNI 192.168.204.17@tcp [ 8054.236186] Key type .llcrypt unregistered [ 8054.237412] Key type ._llcrypt unregistered [ 8054.472473] Key type ._llcrypt registered [ 8054.475229] Key type .llcrypt registered [ 8054.664857] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8054.678911] alg: No test for adler32 (adler32-zlib) [ 8055.534158] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 8055.613805] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 8057.200105] Key type lgssc registered [ 8057.589500] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8061.141891] Lustre: DEBUG MARKER: Iteration 36 [ 8061.266678] LustreError: 342241:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 8061.266735] LustreError: 342242:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 8061.272089] LustreError: 342241:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8062.332537] Lustre: Mounted lustre-client [ 8062.884583] LustreError: 342349:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f8c6e0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8062.889930] LustreError: 342349:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8062.901315] Lustre: Unmounted lustre-client [ 8063.890507] Key type lgssc unregistered [ 8064.011540] LNet: 342593:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8064.013970] LNetError: 342593:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8064.022698] LNet: Removed LNI 192.168.204.17@tcp [ 8064.257096] Key type .llcrypt unregistered [ 8064.258615] Key type ._llcrypt unregistered [ 8064.536821] Key type ._llcrypt registered [ 8064.537819] Key type .llcrypt registered [ 8064.749191] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8064.753901] alg: No test for adler32 (adler32-zlib) [ 8065.612100] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 8065.698681] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 8067.288116] Key type lgssc registered [ 8067.671207] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8071.295315] Lustre: DEBUG MARKER: Iteration 37 [ 8071.426345] LustreError: 343426:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 8071.426374] LustreError: 343425:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 8071.433771] LustreError: 343426:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8072.487538] Lustre: Mounted lustre-client [ 8072.489430] Lustre: Skipped 1 previous similar message [ 8073.010311] LustreError: 343527:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f84d84000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8073.013291] LustreError: 343527:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8073.044236] Lustre: Unmounted lustre-client [ 8074.031781] Key type lgssc unregistered [ 8074.151642] LNet: 343770:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8074.154441] LNetError: 343770:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8074.165146] LNet: Removed LNI 192.168.204.17@tcp [ 8074.434145] Key type .llcrypt unregistered [ 8074.435289] Key type ._llcrypt unregistered [ 8074.744940] Key type ._llcrypt registered [ 8074.747972] Key type .llcrypt registered [ 8074.950394] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8074.957800] alg: No test for adler32 (adler32-zlib) [ 8075.834565] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 8075.920524] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 8077.512174] Key type lgssc registered [ 8077.970465] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8081.579697] Lustre: DEBUG MARKER: Iteration 38 [ 8081.724972] LustreError: 344591:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 8081.725061] LustreError: 344590:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 8081.728911] LustreError: 344591:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 8082.784596] Lustre: Mounted lustre-client [ 8083.356899] LustreError: 344696:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7fbe97a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8083.360769] LustreError: 344696:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8083.381642] Lustre: Unmounted lustre-client [ 8084.412251] Key type lgssc unregistered [ 8084.535568] LNet: 344941:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8084.538073] LNetError: 344941:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8084.552040] LNet: Removed LNI 192.168.204.17@tcp [ 8084.794139] Key type .llcrypt unregistered [ 8084.796144] Key type ._llcrypt unregistered [ 8085.108884] Key type ._llcrypt registered [ 8085.110389] Key type .llcrypt registered [ 8085.285148] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8085.290702] alg: No test for adler32 (adler32-zlib) [ 8086.159183] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 8086.257245] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 8087.848095] Key type lgssc registered [ 8088.246856] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8091.970300] Lustre: DEBUG MARKER: Iteration 39 [ 8092.072355] LustreError: 345762:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 8092.072756] LustreError: 345763:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 8092.077167] LustreError: 345762:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8093.126772] Lustre: Mounted lustre-client [ 8093.598559] LustreError: 345863:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f87cac800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8093.637109] Lustre: Unmounted lustre-client [ 8093.638721] Lustre: Skipped 1 previous similar message [ 8094.595044] Key type lgssc unregistered [ 8094.708554] LNet: 346106:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8094.710760] LNetError: 346106:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8094.721781] LNet: Removed LNI 192.168.204.17@tcp [ 8094.963132] Key type .llcrypt unregistered [ 8094.965052] Key type ._llcrypt unregistered [ 8095.295577] Key type ._llcrypt registered [ 8095.297308] Key type .llcrypt registered [ 8095.466414] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8095.472097] alg: No test for adler32 (adler32-zlib) [ 8096.326723] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 8096.406814] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 8097.992163] Key type lgssc registered [ 8098.390978] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8101.791872] Lustre: DEBUG MARKER: Iteration 40 [ 8101.913201] LustreError: 346927:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 8101.913522] LustreError: 346926:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 8101.919600] LustreError: 346927:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 8102.967971] Lustre: Mounted lustre-client [ 8102.969345] Lustre: Skipped 1 previous similar message [ 8103.427221] LustreError: 347033:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7fba1cb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8103.431174] LustreError: 347033:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8103.455113] Lustre: Unmounted lustre-client [ 8104.390906] Key type lgssc unregistered [ 8104.496558] LNet: 347277:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8104.500209] LNetError: 347277:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8104.513624] LNet: Removed LNI 192.168.204.17@tcp [ 8104.744113] Key type .llcrypt unregistered [ 8104.745841] Key type ._llcrypt unregistered [ 8105.022651] Key type ._llcrypt registered [ 8105.023729] Key type .llcrypt registered [ 8105.215779] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8105.220369] alg: No test for adler32 (adler32-zlib) [ 8106.086066] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 8106.186091] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 8107.768124] Key type lgssc registered [ 8108.170899] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8111.896126] Lustre: DEBUG MARKER: Iteration 41 [ 8111.999739] LustreError: 348097:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 8111.999793] LustreError: 348098:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 8112.004462] LustreError: 348097:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8113.066287] Lustre: Mounted lustre-client [ 8113.068054] Lustre: Skipped 1 previous similar message [ 8113.560212] LustreError: 348203:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f85ecf800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8113.596860] Lustre: Unmounted lustre-client [ 8114.670392] Key type lgssc unregistered [ 8114.794411] LNet: 348446:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8114.798272] LNetError: 348446:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8114.804571] LNet: Removed LNI 192.168.204.17@tcp [ 8115.098160] Key type .llcrypt unregistered [ 8115.099562] Key type ._llcrypt unregistered [ 8115.466317] Key type ._llcrypt registered [ 8115.467777] Key type .llcrypt registered [ 8115.646741] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8115.655163] alg: No test for adler32 (adler32-zlib) [ 8116.530452] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 8116.627764] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 8118.216127] Key type lgssc registered [ 8118.620732] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8122.240198] Lustre: DEBUG MARKER: Iteration 42 [ 8122.373614] LustreError: 349266:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 8122.373688] LustreError: 349267:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 8122.379980] LustreError: 349266:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8123.434457] Lustre: Mounted lustre-client [ 8123.988115] LustreError: 349372:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f996c8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8123.991705] LustreError: 349372:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8124.007141] Lustre: Unmounted lustre-client [ 8125.078054] Key type lgssc unregistered [ 8125.195621] LNet: 349616:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8125.198198] LNetError: 349616:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8125.206780] LNet: Removed LNI 192.168.204.17@tcp [ 8125.451126] Key type .llcrypt unregistered [ 8125.452785] Key type ._llcrypt unregistered [ 8125.748669] Key type ._llcrypt registered [ 8125.749788] Key type .llcrypt registered [ 8125.935255] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8125.940214] alg: No test for adler32 (adler32-zlib) [ 8126.807276] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 8126.886445] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 8128.472117] Key type lgssc registered [ 8128.893901] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8132.224611] Lustre: DEBUG MARKER: Iteration 43 [ 8132.324495] LustreError: 350437:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 8132.324518] LustreError: 350438:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 8132.329614] LustreError: 350437:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8133.382347] Lustre: Mounted lustre-client [ 8133.383543] Lustre: Skipped 1 previous similar message [ 8133.822414] LustreError: 350539:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7fa5779800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8133.826150] LustreError: 350539:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8133.851112] Lustre: Unmounted lustre-client [ 8134.770631] Key type lgssc unregistered [ 8134.894477] LNet: 350782:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8134.896838] LNetError: 350782:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8134.906813] LNet: Removed LNI 192.168.204.17@tcp [ 8135.143085] Key type .llcrypt unregistered [ 8135.144236] Key type ._llcrypt unregistered [ 8135.394572] Key type ._llcrypt registered [ 8135.395618] Key type .llcrypt registered [ 8135.601442] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8135.616667] alg: No test for adler32 (adler32-zlib) [ 8136.472943] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 8136.560426] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 8138.144096] Key type lgssc registered [ 8138.527833] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8142.193531] Lustre: DEBUG MARKER: Iteration 44 [ 8142.293754] LustreError: 351602:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 8142.296308] LustreError: 351603:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 8142.299075] LustreError: 351602:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8143.338248] Lustre: Mounted lustre-client [ 8143.783129] LustreError: 351706:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f86b2d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8143.787796] LustreError: 351706:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8143.811287] Lustre: Unmounted lustre-client [ 8144.778804] Key type lgssc unregistered [ 8144.883542] LNet: 351948:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8144.887189] LNetError: 351948:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8144.894666] LNet: Removed LNI 192.168.204.17@tcp [ 8145.114092] Key type .llcrypt unregistered [ 8145.115187] Key type ._llcrypt unregistered [ 8145.454664] Key type ._llcrypt registered [ 8145.455757] Key type .llcrypt registered [ 8145.631691] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8145.640670] alg: No test for adler32 (adler32-zlib) [ 8146.491494] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 8146.573882] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 8148.168136] Key type lgssc registered [ 8148.606212] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8152.484219] Lustre: DEBUG MARKER: Iteration 45 [ 8152.597483] LustreError: 352769:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 8152.597520] LustreError: 352770:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 8152.602230] LustreError: 352769:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8153.660701] Lustre: Mounted lustre-client [ 8153.662058] Lustre: Skipped 1 previous similar message [ 8154.231562] LustreError: 352876:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7fb2524000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8154.279139] Lustre: Unmounted lustre-client [ 8155.325719] Key type lgssc unregistered [ 8155.457735] LNet: 353119:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8155.461344] LNetError: 353119:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8155.476374] LNet: Removed LNI 192.168.204.17@tcp [ 8155.762199] Key type .llcrypt unregistered [ 8155.764108] Key type ._llcrypt unregistered [ 8156.072543] Key type ._llcrypt registered [ 8156.074379] Key type .llcrypt registered [ 8156.255718] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8156.261222] alg: No test for adler32 (adler32-zlib) [ 8157.119924] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 8157.208564] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 8158.800114] Key type lgssc registered [ 8159.200869] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8162.867280] Lustre: DEBUG MARKER: Iteration 46 [ 8162.984599] LustreError: 353939:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 8162.984864] LustreError: 353940:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 8162.990670] LustreError: 353939:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8164.049709] Lustre: Mounted lustre-client [ 8164.452432] LustreError: 354048:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7fb2522000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8164.457130] LustreError: 354048:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8164.468060] Lustre: Unmounted lustre-client [ 8165.294213] Key type lgssc unregistered [ 8165.396359] LNet: 354292:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8165.398585] LNetError: 354292:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8165.407735] LNet: Removed LNI 192.168.204.17@tcp [ 8165.618131] Key type .llcrypt unregistered [ 8165.619068] Key type ._llcrypt unregistered [ 8165.874498] Key type ._llcrypt registered [ 8165.875928] Key type .llcrypt registered [ 8166.053558] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8166.059204] alg: No test for adler32 (adler32-zlib) [ 8166.906243] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 8166.988399] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 8168.568127] Key type lgssc registered [ 8168.893118] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8171.928400] Lustre: DEBUG MARKER: Iteration 47 [ 8172.013235] LustreError: 355111:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 8172.013286] LustreError: 355113:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 8172.017422] LustreError: 355111:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8173.058208] Lustre: Mounted lustre-client [ 8173.059859] Lustre: Skipped 1 previous similar message [ 8173.454735] LustreError: 355214:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f996cc000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8173.458889] LustreError: 355214:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8173.482576] Lustre: Unmounted lustre-client [ 8174.339548] Key type lgssc unregistered [ 8174.448485] LNet: 355457:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8174.451494] LNetError: 355457:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8174.459650] LNet: Removed LNI 192.168.204.17@tcp [ 8174.668121] Key type .llcrypt unregistered [ 8174.669649] Key type ._llcrypt unregistered [ 8174.882229] Key type ._llcrypt registered [ 8174.890558] Key type .llcrypt registered [ 8175.036529] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8175.058206] alg: No test for adler32 (adler32-zlib) [ 8175.910826] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 8175.986753] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 8177.560088] Key type lgssc registered [ 8177.901361] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8181.079335] Lustre: DEBUG MARKER: Iteration 48 [ 8181.180314] LustreError: 356276:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 8181.180354] LustreError: 356277:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 8181.184843] LustreError: 356276:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8182.239736] Lustre: Mounted lustre-client [ 8182.241915] Lustre: Skipped 1 previous similar message [ 8182.694458] LustreError: 356385:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f86666800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8182.697725] LustreError: 356385:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8182.730370] Lustre: Unmounted lustre-client [ 8183.702712] Key type lgssc unregistered [ 8183.800511] LNet: 356628:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8183.802696] LNetError: 356628:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8183.813722] LNet: Removed LNI 192.168.204.17@tcp [ 8184.033102] Key type .llcrypt unregistered [ 8184.034159] Key type ._llcrypt unregistered [ 8184.353492] Key type ._llcrypt registered [ 8184.354660] Key type .llcrypt registered [ 8184.519554] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8184.525830] alg: No test for adler32 (adler32-zlib) [ 8185.383821] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 8185.464747] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 8187.040118] Key type lgssc registered [ 8187.415525] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8190.767363] Lustre: DEBUG MARKER: Iteration 49 [ 8190.865412] LustreError: 357448:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 8190.865747] LustreError: 357449:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 8190.869405] LustreError: 357448:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 8191.913291] Lustre: Mounted lustre-client [ 8192.359099] LustreError: 357556:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7fba054000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8192.363175] LustreError: 357556:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8192.389081] Lustre: Unmounted lustre-client [ 8193.316294] Key type lgssc unregistered [ 8193.420445] LNet: 357798:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8193.422816] LNetError: 357798:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8193.433656] LNet: Removed LNI 192.168.204.17@tcp [ 8193.649121] Key type .llcrypt unregistered [ 8193.650372] Key type ._llcrypt unregistered [ 8193.885077] Key type ._llcrypt registered [ 8193.886821] Key type .llcrypt registered [ 8194.073203] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8194.080053] alg: No test for adler32 (adler32-zlib) [ 8194.937577] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 8195.019484] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 8196.600135] Key type lgssc registered [ 8197.033361] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8200.664265] Lustre: DEBUG MARKER: Iteration 50 [ 8200.770096] LustreError: 358618:0:(llite_lib.c:1397:ll_fill_super()) cfs_race id 1417 sleeping [ 8200.771278] LustreError: 358619:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 waking [ 8200.774296] LustreError: 358618:0:(llite_lib.c:1397:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 8201.824544] Lustre: Mounted lustre-client [ 8202.385594] LustreError: 358725:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7fba055000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8202.391964] LustreError: 358725:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 8202.399757] Lustre: Unmounted lustre-client [ 8203.334877] Key type lgssc unregistered [ 8203.446445] LNet: 358966:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8203.448952] LNetError: 358966:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8203.455919] LNet: Removed LNI 192.168.204.17@tcp [ 8203.707122] Key type .llcrypt unregistered [ 8203.708730] Key type ._llcrypt unregistered [ 8203.993359] Key type ._llcrypt registered [ 8203.994377] Key type .llcrypt registered [ 8204.169950] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 8204.174623] alg: No test for adler32 (adler32-zlib) [ 8205.042598] Lustre: Lustre: Build Version: 2.17.50_195_gf35968a [ 8205.121060] LNet: Added LNI 192.168.204.17@tcp [8/256/0/180] [ 8206.696195] Key type lgssc registered [ 8207.102950] Lustre: Echo OBD driver; http://www.lustre.org/ [ 8211.448149] Lustre: Mounted lustre-client [ 8213.880189] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 13:52:10 (1772477530) [ 8230.368120] Lustre: 360298:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772477531/real 1772477531] req@ffff9c7fa60e1f80 x1858577390117632/t0(0) o36->lustre-MDT0000-mdc-ffff9c7f85699000@192.168.204.117@tcp:12/10 lens 496/440 e 0 to 1 dl 1772477547 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 8230.378548] Lustre: lustre-MDT0000-mdc-ffff9c7f85699000: Connection to lustre-MDT0000 (at 192.168.204.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8230.392209] Lustre: lustre-MDT0000-mdc-ffff9c7f85699000: Connection restored to 192.168.204.117@tcp (at 192.168.204.117@tcp) [ 8245.728247] Lustre: 360298:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772477547/real 1772477547] req@ffff9c7fa60e1f80 x1858577390117632/t0(0) o36->lustre-MDT0000-mdc-ffff9c7f85699000@192.168.204.117@tcp:12/10 lens 496/440 e 0 to 1 dl 1772477563 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 8245.743094] Lustre: lustre-MDT0000-mdc-ffff9c7f85699000: Connection to lustre-MDT0000 (at 192.168.204.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8245.760504] Lustre: lustre-MDT0000-mdc-ffff9c7f85699000: Connection restored to 192.168.204.117@tcp (at 192.168.204.117@tcp) [ 8262.112121] Lustre: 360298:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772477563/real 1772477563] req@ffff9c7fa60e1f80 x1858577390117632/t0(0) o36->lustre-MDT0000-mdc-ffff9c7f85699000@192.168.204.117@tcp:12/10 lens 496/440 e 0 to 1 dl 1772477579 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 8262.125457] Lustre: lustre-MDT0000-mdc-ffff9c7f85699000: Connection to lustre-MDT0000 (at 192.168.204.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8262.137052] Lustre: lustre-MDT0000-mdc-ffff9c7f85699000: Connection restored to 192.168.204.117@tcp (at 192.168.204.117@tcp) [ 8278.496153] Lustre: 360298:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772477579/real 1772477579] req@ffff9c7fa60e1f80 x1858577390117632/t0(0) o36->lustre-MDT0000-mdc-ffff9c7f85699000@192.168.204.117@tcp:12/10 lens 496/440 e 0 to 1 dl 1772477595 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 8278.503667] Lustre: lustre-MDT0000-mdc-ffff9c7f85699000: Connection to lustre-MDT0000 (at 192.168.204.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8278.512380] Lustre: lustre-MDT0000-mdc-ffff9c7f85699000: Connection restored to 192.168.204.117@tcp (at 192.168.204.117@tcp) [ 8279.065479] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 13:53:16 (1772477596) [ 8279.595413] Lustre: DEBUG MARKER: SKIP: sanityn test_111 needs >= 2 MDTs [ 8280.228504] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 13:53:17 (1772477597) [ 8280.784533] Lustre: DEBUG MARKER: SKIP: sanityn test_112 We need at least 2 MDTs for this test [ 8281.329643] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 13:53:18 (1772477598) [ 8283.487224] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 13:53:20 (1772477600) [ 8283.965906] Lustre: DEBUG MARKER: SKIP: sanityn test_114 We need at least 2 MDTs for this test [ 8284.515864] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 13:53:21 (1772477601) [ 8285.008929] Lustre: DEBUG MARKER: SKIP: sanityn test_115 ldiskfs only test [ 8285.631279] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 13:53:22 (1772477602) [ 8286.179838] Lustre: DEBUG MARKER: SKIP: sanityn test_116 needs >= 2 MDTs [ 8286.769925] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 13:53:23 (1772477603) [ 8286.834496] LustreError: 362952:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [ 8288.920122] LustreError: 362952:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [ 8291.097552] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 13:53:28 (1772477608) [ 8309.216254] Lustre: 359161:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772477610/real 1772477610] req@ffff9c7fa6eeca80 x1858577390149120/t0(0) o4->lustre-OST0000-osc-ffff9c7f85699000@192.168.204.117@tcp:6/4 lens 4584/448 e 0 to 1 dl 1772477626 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 8309.216293] Lustre: lustre-OST0000-osc-ffff9c7f85699000: Connection to lustre-OST0000 (at 192.168.204.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8309.222568] Lustre: 359161:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 8309.231327] Lustre: lustre-OST0000-osc-ffff9c7f85699000: Connection restored to 192.168.204.117@tcp (at 192.168.204.117@tcp) [ 8324.576125] Lustre: 359159:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772477626/real 1772477626] req@ffff9c7fa5a8e680 x1858577390150272/t0(0) o4->lustre-OST0000-osc-ffff9c7f85699000@192.168.204.117@tcp:6/4 lens 4584/448 e 0 to 1 dl 1772477642 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 8324.576171] Lustre: lustre-OST0000-osc-ffff9c7f85699000: Connection to lustre-OST0000 (at 192.168.204.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8324.582663] Lustre: 359159:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [ 8324.590253] Lustre: lustre-OST0000-osc-ffff9c7f85699000: Connection restored to 192.168.204.117@tcp (at 192.168.204.117@tcp) [ 8340.960145] Lustre: 359158:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1772477642/real 1772477642] req@ffff9c7fa5a8c380 x1858577390150656/t0(0) o4->lustre-OST0000-osc-ffff9c7f85699000@192.168.204.117@tcp:6/4 lens 4584/448 e 0 to 1 dl 1772477658 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 8340.960207] Lustre: lustre-OST0000-osc-ffff9c7f85699000: Connection to lustre-OST0000 (at 192.168.204.117@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 8340.966599] Lustre: 359158:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 1 previous similar message [ 8340.975138] Lustre: lustre-OST0000-osc-ffff9c7f85699000: Connection restored to 192.168.204.117@tcp (at 192.168.204.117@tcp) [ 8356.677258] Lustre: DEBUG MARKER: oleg417-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9c7f83863800.ost_server_uuid 50 [ 8357.230960] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9c7f83863800.ost_server_uuid in IDLE state after 0 sec [ 8357.753824] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 8358.308467] Lustre: DEBUG MARKER: == sanityn test complete, duration 8079 sec ============== 13:54:35 (1772477675) [ 8358.835558] Lustre: DEBUG MARKER: === sanityn: start cleanup 13:54:35 (1772477675) === [ 8400.139232] LustreError: 364945:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f83863800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8400.157131] Lustre: Unmounted lustre-client [ 8401.464503] Lustre: DEBUG MARKER: === sanityn: finish cleanup 13:55:18 (1772477718) === [ 8401.799144] LustreError: 365245:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9c7f85699000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 8401.802685] LustreError: 365245:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 8401.823088] Lustre: Unmounted lustre-client [ 8413.460624] Key type lgssc unregistered [ 8413.568285] LNet: 365726:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 8413.570452] LNetError: 365726:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [ 8413.578830] LNet: Removed LNI 192.168.204.17@tcp [ 8413.809097] Key type .llcrypt unregistered [ 8413.810173] Key type ._llcrypt unregistered