[ 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 438142079 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f54d0-0x000f54df] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F52F0 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE2421 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE22BD 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 00227D (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE2331 000090 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE23C1 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE23F9 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe22bd-0xbffe2330] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe22bc] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe2331-0xbffe23c0] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe23c1-0xbffe23f8] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe23f9-0xbffe2420] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 2752MB for crashkernel (System RAM: 4205MB) [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000ffffffff] [ 0.000000] Normal [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000bffd9fff] [ 0.000000] node 0: [mem 0x0000000100000000-0x0000000146dfffff] [ 0.000000] Zeroed struct page in unavailable ranges: 4744 pages [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000146dfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.000000] Using ACPI (MADT) for SMP configuration information [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.000000] TSC deadline timer available [ 0.000000] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.000000] kvm-guest: KVM setup pv remote TLB flush [ 0.000000] kvm-guest: setup PV sched yield [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff] [ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff] [ 0.000000] PM: Registered nosave memory: [mem 0xbffda000-0xbfffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xc0000000-0xfeffbfff] [ 0.000000] PM: Registered nosave memory: [mem 0xfeffc000-0xfeffffff] [ 0.000000] PM: Registered nosave memory: [mem 0xff000000-0xfffbffff] [ 0.000000] PM: Registered nosave memory: [mem 0xfffc0000-0xffffffff] [ 0.000000] [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 63 pages/cpu s221184 r8192 d28672 u524288 [ 0.000000] kvm-guest: PV spinlocks enabled [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 1059618 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://catalog.redhat.com. [ 0.000000] audit: disabled (until reboot) [ 0.000000] software IO TLB: area num 4. [ 0.000000] Memory: 2829700K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001012] APIC: Switch to symmetric I/O mode setup [ 0.003164] x2apic enabled [ 0.004011] Switched APIC routing to physical x2apic. [ 0.005031] kvm-guest: setup PV IPIs [ 0.008539] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.009000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.009031] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.010016] pid_max: default: 32768 minimum: 301 [ 0.011160] LSM: Security Framework initializing [ 0.012072] Yama: becoming mindful. [ 0.013043] SELinux: Initializing. [ 0.015027] *** VALIDATE selinux *** [ 0.023403] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.029059] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.030183] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031129] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.032108] *** VALIDATE tmpfs *** [ 0.033520] *** VALIDATE proc *** [ 0.035244] *** VALIDATE cgroup *** [ 0.036011] *** VALIDATE cgroup2 *** [ 0.038165] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.039156] 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.042011] Speculative Store Bypass: Vulnerable [ 0.045341] debug: unmapping init [mem 0xffffffffaee59000-0xffffffffaee60fff] [ 0.048188] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.049710] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.050028] ... version: 2 [ 0.051015] ... bit width: 48 [ 0.052018] ... generic registers: 4 [ 0.053013] ... value mask: 0000ffffffffffff [ 0.054017] ... max period: 00007fffffffffff [ 0.055017] ... fixed-purpose events: 3 [ 0.056015] ... event mask: 000000070000000f [ 0.057298] rcu: Hierarchical SRCU implementation. [ 0.059507] smp: Bringing up secondary CPUs ... [ 0.060695] x86: Booting SMP configuration: [ 0.061031] .... node #0, CPUs: #1 #2 #3 [ 0.064494] smp: Brought up 1 node, 4 CPUs [ 0.066015] smpboot: Max logical packages: 1 [ 0.067020] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.154662] node 0 deferred pages initialised in 84ms [ 0.157305] devtmpfs: initialized [ 0.159343] x86/mm: Memory block size: 128MB [ 0.162089] gcov: version magic: 0x41383552 [ 0.165251] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.168218] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.171370] pinctrl core: initialized pinctrl subsystem [ 0.173166] [ 0.173586] ************************************************************* [ 0.176014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.178011] ** ** [ 0.181099] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.183011] ** ** [ 0.185013] ** This means that this kernel is built to expose internal ** [ 0.188012] ** IOMMU data structures, which may compromise security on ** [ 0.190019] ** your system. ** [ 0.192017] ** ** [ 0.195016] ** If you see this message and you are not debugging the ** [ 0.197015] ** kernel, report this immediately to your vendor! ** [ 0.199027] ** ** [ 0.202014] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.204014] ************************************************************* [ 0.206576] NET: Registered protocol family 16 [ 0.208495] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.210075] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.212046] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.215015] cpuidle: using governor menu [ 0.216557] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.218481] PCI: Using configuration type 1 for base access [ 0.221140] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.230088] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.232074] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.236080] cryptd: max_cpu_qlen set to 1000 [ 0.239286] ACPI: Added _OSI(Module Device) [ 0.240017] ACPI: Added _OSI(Processor Device) [ 0.242023] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.244043] ACPI: Added _OSI(Processor Aggregator Device) [ 0.248760] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.255433] ACPI: Interpreter enabled [ 0.256086] ACPI: PM: (supports S0 S3 S4 S5) [ 0.258014] ACPI: Using IOAPIC for interrupt routing [ 0.259116] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.261395] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.271514] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.273038] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.276030] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.279103] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.284000] acpiphp: Slot [2] registered [ 0.284000] acpiphp: Slot [5] registered [ 0.286110] acpiphp: Slot [6] registered [ 0.287096] acpiphp: Slot [3] registered [ 0.289095] acpiphp: Slot [4] registered [ 0.290119] acpiphp: Slot [7] registered [ 0.292097] acpiphp: Slot [8] registered [ 0.293131] acpiphp: Slot [9] registered [ 0.294106] acpiphp: Slot [10] registered [ 0.296111] acpiphp: Slot [11] registered [ 0.297103] acpiphp: Slot [12] registered [ 0.298116] acpiphp: Slot [13] registered [ 0.300105] acpiphp: Slot [14] registered [ 0.301104] acpiphp: Slot [15] registered [ 0.303078] acpiphp: Slot [16] registered [ 0.304093] acpiphp: Slot [17] registered [ 0.304940] acpiphp: Slot [18] registered [ 0.306086] acpiphp: Slot [19] registered [ 0.306899] acpiphp: Slot [20] registered [ 0.308085] acpiphp: Slot [21] registered [ 0.309059] acpiphp: Slot [22] registered [ 0.309973] acpiphp: Slot [23] registered [ 0.311062] acpiphp: Slot [24] registered [ 0.312063] acpiphp: Slot [25] registered [ 0.312931] acpiphp: Slot [26] registered [ 0.313058] acpiphp: Slot [27] registered [ 0.313774] acpiphp: Slot [28] registered [ 0.315061] acpiphp: Slot [29] registered [ 0.315937] acpiphp: Slot [30] registered [ 0.317110] acpiphp: Slot [31] registered [ 0.318040] PCI host bridge to bus 0000:00 [ 0.320019] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.321021] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.324027] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.327022] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.329017] pci_bus 0000:00: root bus resource [mem 0xe0000000000-0xe007fffffff window] [ 0.331028] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.332219] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.334836] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.337213] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.343351] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.346446] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.348012] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.350014] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.352021] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.355759] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.358721] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.360038] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.362694] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.365015] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.373019] pci 0000:00:02.0: reg 0x20: [mem 0xe0000000000-0xe0000003fff 64bit pref] [ 0.377015] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.381025] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.388016] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.393016] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.405015] pci 0000:00:05.0: reg 0x20: [mem 0xe0000004000-0xe0000007fff 64bit pref] [ 0.412065] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.418017] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.423016] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.434017] pci 0000:00:06.0: reg 0x20: [mem 0xe0000008000-0xe000000bfff 64bit pref] [ 0.443696] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.445228] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.447263] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.448217] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.449160] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.454032] iommu: Default domain type: Passthrough [ 0.455492] SCSI subsystem initialized [ 0.456107] ACPI: bus type USB registered [ 0.457089] usbcore: registered new interface driver usbfs [ 0.458055] usbcore: registered new interface driver hub [ 0.459053] usbcore: registered new device driver usb [ 0.461131] pps_core: LinuxPPS API ver. 1 registered [ 0.462007] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.463046] PTP clock support registered [ 0.465078] EDAC MC: Ver: 3.0.0 [ 0.466085] PCI: Using ACPI for IRQ routing [ 0.467719] NetLabel: Initializing [ 0.469011] NetLabel: domain hash size = 128 [ 0.469975] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.471118] NetLabel: unlabeled traffic allowed by default [ 0.472104] vgaarb: loaded [ 0.473212] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.474008] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.481315] clocksource: Switched to clocksource kvm-clock [ 0.561760] VFS: Disk quotas dquot_6.6.0 [ 0.562847] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.564652] *** VALIDATE ramfs *** [ 0.565461] *** VALIDATE hugetlbfs *** [ 0.566507] pnp: PnP ACPI init [ 0.568895] pnp: PnP ACPI: found 6 devices [ 0.585025] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.588192] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.589816] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.591538] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.593252] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.594832] pci_bus 0000:00: resource 8 [mem 0xe0000000000-0xe007fffffff window] [ 0.596666] NET: Registered protocol family 2 [ 0.598409] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.601954] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.604036] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.608173] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.611910] TCP: Hash tables configured (established 65536 bind 65536) [ 0.614851] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.617869] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.620457] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.623051] NET: Registered protocol family 1 [ 0.625697] RPC: Registered named UNIX socket transport module. [ 0.627817] RPC: Registered udp transport module. [ 0.629940] RPC: Registered tcp transport module. [ 0.631685] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.633503] NET: Registered protocol family 44 [ 0.635122] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.637042] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.638408] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.639707] PCI: CLS 0 bytes, default 64 [ 0.640862] Unpacking initramfs... [ 2.021311] debug: unmapping init [mem 0xffff8f6f7cc64000-0xffff8f6f7ffcffff] [ 2.025180] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.027422] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.030244] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.552919] Initialise system trusted keyrings [ 2.554652] Key type blacklist registered [ 2.556637] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.566374] zbud: loaded [ 2.569666] *** VALIDATE nfs *** [ 2.571048] *** VALIDATE nfs4 *** [ 2.572545] pstore: using deflate compression [ 2.576075] Platform Keyring initialized [ 2.673602] NET: Registered protocol family 38 [ 2.674973] Key type asymmetric registered [ 2.676214] Asymmetric key parser 'x509' registered [ 2.677304] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.679732] io scheduler mq-deadline registered [ 2.680791] io scheduler kyber registered [ 2.682230] io scheduler bfq registered [ 2.683515] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.685515] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.687358] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.689218] ACPI: Power Button [PWRF] [ 2.693744] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 2.699832] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 2.708809] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.737001] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 2.765600] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.769518] Non-volatile memory driver v1.3 [ 2.770701] Linux agpgart interface v0.103 [ 2.796089] virtio_blk virtio1: [vda] 134584 512-byte logical blocks (68.9 MB/65.7 MiB) [ 2.798224] vda: detected capacity change from 0 to 68907008 [ 2.809737] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.811840] vdb: detected capacity change from 0 to 1073741824 [ 2.816248] libphy: Fixed MDIO Bus: probed [ 2.823925] usbcore: registered new interface driver usbserial_generic [ 2.825885] usbserial: USB Serial support registered for generic [ 2.827428] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.830175] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.831302] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.832949] mousedev: PS/2 mouse device common for all mice [ 2.834804] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 2.837409] rtc_cmos 00:05: RTC can wake from S4 [ 2.841055] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 2.841589] rtc_cmos 00:05: registered as rtc0 [ 2.845171] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 2.847954] intel_pstate: CPU model not supported [ 2.848072] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 2.852672] hid: raw HID events driver (C) Jiri Kosina [ 2.855082] usbcore: registered new interface driver usbhid [ 2.857323] usbhid: USB HID core driver [ 2.859109] drop_monitor: Initializing network drop monitor service [ 2.861585] Initializing XFRM netlink socket [ 2.863348] NET: Registered protocol family 10 [ 2.866129] Segment Routing with IPv6 [ 2.867575] NET: Registered protocol family 17 [ 2.869340] mpls_gso: MPLS GSO support [ 2.874894] RAS: Correctable Errors collector initialized. [ 2.876723] AVX version of gcm_enc/dec engaged. [ 2.878555] AES CTR mode by8 optimization enabled [ 2.951292] sched_clock: Marking stable (2951266555, 0)->(3815732789, -864466234) [ 2.955298] registered taskstats version 1 [ 2.957903] Loading compiled-in X.509 certificates [ 2.960730] zswap: loaded using pool lzo/zbud [ 2.983375] Key type big_key registered [ 2.995189] Key type encrypted registered [ 2.996815] ima: No TPM chip found, activating TPM-bypass! [ 2.999261] ima: Allocated hash algorithm: sha1 [ 3.001163] ima: No architecture policies found [ 3.003102] evm: Initialising EVM extended attributes: [ 3.004741] evm: security.selinux [ 3.005906] evm: security.ima [ 3.007092] evm: security.capability [ 3.008532] evm: HMAC attrs: 0x1 [ 3.010759] rtc_cmos 00:05: setting system clock to 2026-03-15 20:54:03 UTC (1773608043) [ 3.016871] debug: unmapping init [mem 0xffffffffafe03000-0xffffffffafffffff] [ 3.020120] debug: unmapping init [mem 0xffffffffaeb82000-0xffffffffaee58fff] [ 3.032344] Write protecting the kernel read-only data: 28672k [ 3.035619] debug: unmapping init [mem 0xffffffffad203000-0xffffffffad3fffff] [ 3.038303] debug: unmapping init [mem 0xffffffffadb14000-0xffffffffadbfffff] [ 3.068511] 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) [ 3.075669] systemd[1]: Detected virtualization kvm. [ 3.077637] systemd[1]: Detected architecture x86-64. [ 3.080428] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.106109] systemd[1]: No hostname configured. [ 3.107838] systemd[1]: Set hostname to . [ 3.109849] random: systemd: uninitialized urandom read (16 bytes read) [ 3.112273] systemd[1]: Initializing machine ID from random generator. [ 3.156953] random: ln: uninitialized urandom read (6 bytes read) [ 3.230609] random: systemd: uninitialized urandom read (16 bytes read) [ 3.232664] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ 3.237804] systemd[1]: Reached target Paths. [ OK ] Reached target Paths. [ 3.240889] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target Slices. [ OK ] Reached target Timers. [ OK ] Listening on udev Control Socket. [ OK ] Listening on Journal Socket. Starting Setup Virtual Console... Starting Apply Kernel Variables... [ OK ] Reached target Local File Systems. [ OK ] Started Memstrack Anylazing Service. Starting Create Volatile Files and Directories... [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Reached target Sockets. Starting Journal Service... [ OK ] Reached target Initrd Root Device. [ OK ] Reached target Local Encrypted Volumes. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Create list of required sta…vice nodes for the current kernel. Starting Create Static Device Nodes in /dev... Starting dracut cmdline hook... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.853520] device-mapper: uevent: version 1.0.3 [ 3.855091] device-mapper: ioctl: 4.46.0-ioctl (2022-02-22) initialised: dm-devel@redhat.com [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [ OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Kernel Configuration File System... [ OK ] Mounted Kernel Configuration File System. [ OK ] Started udev Coldplug all Devices. [ OK ] Reached target System Initialization. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting dracut initqueue hook... [ 4.579868] virtio_net virtio0 ens2: renamed from eth0 [ 4.643281] scsi host0: ata_piix [ 4.672635] scsi host1: ata_piix [ 4.674745] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.677044] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.296356] dracut-initqueue[581]: RTNETLINK answers: File exists [ 9.195938] random: fast init done [ 9.514677] random: crng init done [ 9.516052] random: 7 urandom warning(s) missed due to ratelimiting Starting nbd nbd0... [ OK ] Started nbd nbd0. [ OK ] Started dracut initqueue hook. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Mounting /sysroot... [ 9.926613] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped target Timers. Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target System Initialization. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. [ OK ] Stopped target Slices. [ OK ] Stopped target Sockets. [ 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... [ 11.143572] printk: systemd: 25 output lines suppressed due to ratelimiting [ 11.412577] SELinux: Disabled at runtime. [ 11.473604] systemd[1]: systemd 239 (239-82.el8_10.5) running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) [ 11.482334] systemd[1]: Detected virtualization kvm. [ 11.484236] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 11.957503] systemd[1]: initrd-switch-root.service: Succeeded. [ 11.962210] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 11.966471] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 11.970125] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 11.972965] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 11.979269] systemd[1]: Starting Journal Service... Starting Journal Service... [ 11.983196] systemd[1]: proc-sys-fs-binfmt_misc.automount: Refusing to start, unit to trigger not loaded. [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Stopped target Switch Root. Mounting Huge Pages File System... [ OK ] Listening on udev Control Socket. Mounting Kernel Debug File System... [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Listening on udev Kernel Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Created slice system-sshd\x2dkeygen.slice. Starting Remount Root and Kernel File Systems... Starting udev Coldplug all Devices... [ OK ] Created slice system-getty.slice. [ OK ] Listening on Process Core Dump Socket. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Reached target rpc_pipefs.target. Mounting POSIX Message Queue File System... Starting Apply Kernel Variables... [ OK ] Stopped target Initrd File Systems. Activating swap /dev/disk/by-label/SWAP... [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Listening on initctl Compatibility Named Pipe. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Stopped target Initrd Root File System. [ 12.170456] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] Started Journal Service. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted Kernel Debug File System. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Started Apply Kernel Variables. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Reached target Swap. Starting Configure read-only root support... Starting Create Static Device Nodes in /dev... Starting Flush Journal to Persistent Storage... [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Reached target Local File Systems (Pre). Mounting /home/green/git/lustre-release... Mounting /mnt... Starting udev Kernel Device Manager... [ OK ] Mounted /mnt. [ 12.388039] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Coldplug all Devices. [ OK ] Started udev Kernel Device Manager. [ 12.726266] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 12.758781] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 12.854281] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 12.871420] EDAC sbridge: Ver: 1.1.2 [ 13.839529] Key type dns_resolver registered [ 14.116406] NFS: Registering the id_resolver key type [ 14.118509] Key type id_resolver registered [ 14.120191] Key type id_legacy registered [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Create Volatile Files and Directories... [ OK ] Started Load/Save Random Seed. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Create Volatile Files and Directories. Starting 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 ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. Starting Restore /run/initramfs on shutdown... [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started D-Bus System Message Bus. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Reached target Timers. Starting Login Service... Starting Network Manager... [ OK ] Reached target sshd-keygen.target. [ 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 OpenSSH server daemon... Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... [ OK ] Started OpenSSH server daemon. Starting Hostname Service... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Reached target NFS client services. [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. Starting Permit User Sessions... [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting Notify NFS peers of a restart... Starting Crash recovery kernel arming... Starting System Logging Service... [ OK ] Started Notify NFS peers of a restart. [ OK ] Started System Logging Service. Starting Authorization Manager... [ OK ] Started Authorization Manager. [ OK ] Started Dynamic System Tuning Daemon. [ OK ] Reached target Multi-User System. [ OK ] Reached target Graphical Interface. Starting Update UTMP about System Runlevel Changes... [ OK ] Started Update UTMP about System Runlevel Changes. Rocky Linux 8.10 (Green Obsidian) Kernel 4.18.0rh8.10-debug on an x86_64 oleg441-client login: [ 56.680664] libcfs: loading out-of-tree module taints kernel. [ 56.861883] Key type ._llcrypt registered [ 56.864670] Key type .llcrypt registered [ 57.705910] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 57.716419] alg: No test for adler32 (adler32-zlib) [ 59.173485] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [ 60.410220] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [ 62.208755] Key type lgssc registered [ 65.163470] Lustre: Echo OBD driver; http://www.lustre.org/ [ 261.036332] Lustre: Mounted lustre-client [ 267.347551] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 275.680027] hrtimer: interrupt took 1961462 ns [ 286.687182] Lustre: lustre-OST0000-osc-ffff8f6fc7e42000: disconnect after 23s idle [ 287.483539] Lustre: DEBUG MARKER: oleg441-client.virtnet: executing check_logdir /tmp/testlogs/ [ 293.349739] Lustre: DEBUG MARKER: oleg441-client.virtnet: executing yml_node [ 299.094642] Lustre: DEBUG MARKER: Client: 2.17.51.1 [ 302.452587] Lustre: DEBUG MARKER: MDS: 2.17.51.1 [ 305.480725] Lustre: DEBUG MARKER: OSS: 2.17.51.1 [ 307.429567] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Sun Mar 15 16:59:06 EDT 2026 [ 326.981080] Lustre: DEBUG MARKER: excepting tests: 27 40a [ 328.751455] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 330.689433] Lustre: DEBUG MARKER: === sanityn: start setup 16:59:29 (1773608369) === [ 331.299568] Lustre: Mounted lustre-client [ 334.969448] Lustre: DEBUG MARKER: oleg441-client.virtnet: executing check_config_client /mnt/lustre [ 359.117131] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 375.804429] Lustre: DEBUG MARKER: === sanityn: finish setup 17:00:14 (1773608414) === [ 378.366866] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 17:00:17 (1773608417) [ 390.386180] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 17:00:28 (1773608428) [ 397.813324] Lustre: lustre-OST0000-osc-ffff8f6fc7e42000: disconnect after 22s idle [ 397.832341] Lustre: Skipped 1 previous similar message [ 401.265140] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 17:00:39 (1773608439) [ 410.752266] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 17:00:48 (1773608448) [ 419.876143] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 17:00:58 (1773608458) [ 428.488629] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 17:01:07 (1773608467) [ 436.445440] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 17:01:15 (1773608475) [ 444.617928] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 17:01:23 (1773608483) [ 453.535032] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 17:01:32 (1773608492) [ 461.547676] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 17:01:40 (1773608500) [ 469.517337] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 17:01:48 (1773608508) [ 474.593674] Lustre: lustre-OST0001-osc-ffff8f6fc7e42000: disconnect after 21s idle [ 478.713849] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 17:01:57 (1773608517) [ 485.970660] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 17:02:05 (1773608525) [ 493.563413] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 17:02:12 (1773608532) [ 495.072758] Lustre: lustre-OST0000-osc-ffff8f6fd8a05000: disconnect after 24s idle [ 500.468387] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 17:02:19 (1773608539) [ 507.253444] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 17:02:26 (1773608546) [ 515.461832] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 17:02:34 (1773608554) [ 515.553390] Lustre: lustre-OST0001-osc-ffff8f6fc7e42000: disconnect after 24s idle [ 524.440705] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 17:02:42 (1773608562) [ 532.325820] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 17:02:51 (1773608571) [ 540.384564] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 17:02:59 (1773608579) [ 548.756222] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 17:03:07 (1773608587) [ 549.667409] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205289279517 file: /mnt/lustre/lockdir/lockfile=144115205289279516 [ 690.168195] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 17:05:28 (1773608728) [ 702.102377] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 17:05:40 (1773608740) [ 711.655522] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 17:05:50 (1773608750) [ 720.075091] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 17:05:58 (1773608758) [ 729.101784] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 17:06:07 (1773608767) [ 738.605198] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 17:06:17 (1773608777) [ 740.991867] Lustre: DEBUG MARKER: chmod [ 749.640739] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 17:06:28 (1773608788) [ 1841.088551] Lustre: DEBUG MARKER: == sanityn test 16a: 2500 iterations of dual-mount fsx === 17:24:40 (1773609880) [ 1841.632158] Lustre: lustre-OST0000-osc-ffff8f6fd8a05000: disconnect after 21s idle [ 2010.591850] Lustre: lustre-OST0000-osc-ffff8f6fd8a05000: disconnect after 23s idle [ 2010.601781] Lustre: Skipped 1 previous similar message [ 2121.338609] Lustre: DEBUG MARKER: == sanityn test 16b: 2500 iterations of dual-mount fsx at small size ========================================================== 17:29:20 (1773610160) [ 2264.620442] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 17:31:42 (1773610302) [ 2455.322996] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 17:34:54 (1773610494) [ 2496.993485] Lustre: lustre-OST0001-osc-ffff8f6fd8a05000: disconnect after 22s idle [ 2507.532107] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 17:35:45 (1773610545) [ 2516.056099] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 17:35:55 (1773610555) [ 2517.488238] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2517.595771] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2517.685441] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2517.747029] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2517.814804] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2517.885891] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2517.964189] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2518.004633] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2518.076195] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2518.153984] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2518.226125] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2518.300516] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2518.387359] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2518.475972] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2518.562715] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2518.684141] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2518.778984] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2518.852213] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2518.948250] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2519.065492] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2519.162711] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2519.285509] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2519.381081] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2519.506594] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2519.600215] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2519.680477] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2519.786750] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2519.856417] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2519.941717] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2520.023718] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2520.108576] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2520.196121] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2520.271673] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2520.343143] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2520.412666] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2520.484460] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2520.560917] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2520.629307] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2520.724026] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2520.800868] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2520.864799] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2520.931309] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2520.976930] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2521.022759] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2521.063637] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2521.111301] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2521.195984] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2521.293357] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2521.417665] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2521.496603] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2521.609444] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2521.700315] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2521.794395] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2521.880503] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2521.927521] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2521.986842] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2522.101710] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2522.205428] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2522.291368] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2522.386959] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2522.464288] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2522.519383] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2522.588773] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2522.647924] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2522.751426] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2522.823676] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2522.893396] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2522.930090] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2522.981507] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2523.041247] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2523.120798] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2523.218085] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2523.308266] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2523.378557] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2523.470890] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2523.610468] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2523.702473] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2523.828080] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2523.941914] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2524.051956] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2524.172102] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2524.252224] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2524.342038] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2524.421862] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2524.474236] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2524.546790] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2524.632902] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2524.720832] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2524.817649] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2524.925241] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2525.012714] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2525.092976] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2525.179188] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2525.268053] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2525.371248] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2525.456343] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2525.562733] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2525.638961] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2525.738505] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2525.829233] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2525.900708] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2525.989965] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2526.078065] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2526.164864] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2526.234194] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2526.317919] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2526.411641] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2526.531544] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2526.619459] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2526.716351] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2526.829312] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2526.999983] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2527.133869] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2527.230500] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2527.305760] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2527.405142] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2527.504676] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2527.606046] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2527.709076] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2527.830011] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2527.916708] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2528.026808] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2528.116399] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2528.237054] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2528.311967] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2528.379933] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2528.454933] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2528.525651] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2528.600866] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2528.671516] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2528.748412] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2528.831711] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2528.923616] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2528.993535] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2529.066367] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2529.134244] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2529.232275] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2529.311517] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2529.407978] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2529.491085] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2529.574451] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2529.655487] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2529.736313] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2529.815211] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2529.901400] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2529.965856] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2530.057144] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2530.134928] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2530.204392] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2530.287926] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2530.383582] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2530.452600] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2530.535520] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2530.600692] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2530.670648] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2530.769873] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2530.853449] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2530.927681] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2531.013366] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2531.080403] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2531.154289] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2531.223974] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2531.281558] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2531.365892] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2531.445420] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2531.551839] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2531.649932] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2531.725303] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2531.791261] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2531.862591] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2531.948046] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2532.023569] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2532.112324] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2532.191604] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2532.272520] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2532.363649] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2532.460613] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2532.533822] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2532.612715] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2532.696924] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2532.796442] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2532.831252] Lustre: lustre-OST0000-osc-ffff8f6fc7e42000: disconnect after 23s idle [ 2532.896610] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2532.975841] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2533.074889] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2533.172666] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2533.261752] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2533.347702] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2533.434722] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2533.528659] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2533.604608] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2533.683996] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2533.768203] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2533.837133] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2533.910865] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2534.001355] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2534.065307] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2534.177585] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2534.265877] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2534.347194] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2534.483143] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2534.567802] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2534.656333] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2534.756875] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2534.870582] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2534.988155] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2535.100190] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2535.198451] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2535.290610] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2535.382880] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2535.503827] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2535.605893] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2535.736128] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2535.828119] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2535.957369] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2536.083086] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2536.194790] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2536.290715] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2536.390673] rw_seq_cst_vs_d (32393): drop_caches: 3 [ 2545.601364] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 17:36:24 (1773610584) [ 2546.272057] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2546.315805] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2546.449961] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2546.570461] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2546.742592] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2546.911407] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2547.038747] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2547.087731] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2547.173066] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2547.379238] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2547.654023] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2547.820204] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2547.868705] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2547.959348] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2548.120727] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2548.227739] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2548.340094] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2548.467048] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2548.527835] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2548.666390] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2548.750480] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2549.040696] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2549.214301] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2549.272283] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2549.369827] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2549.472758] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2549.639940] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2549.982558] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2550.047829] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2550.122036] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2550.219764] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2550.281771] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2550.430480] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2550.576288] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2550.665379] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2550.782518] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2550.858486] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2551.067249] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2551.124662] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2551.239630] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2551.515518] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2551.694239] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2551.802224] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2551.903541] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2552.014722] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2552.189261] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2552.410916] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2552.600843] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2552.771564] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2552.864217] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2552.977915] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2553.059642] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2553.219369] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2553.377327] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2553.418447] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2553.578463] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2553.758994] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2553.968970] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2554.162704] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2554.310921] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2554.417948] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2554.582865] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2554.805246] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2554.962436] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2555.184945] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2555.242359] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2555.319566] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2555.478342] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2555.617989] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2555.736628] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2555.806740] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2555.865917] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2556.024504] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2556.087281] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2556.184159] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2556.411404] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2556.650380] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2556.747290] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2556.804807] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2556.950341] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2557.300177] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2557.406334] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2557.570415] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2557.664135] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2557.824201] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2557.983350] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2558.006943] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2558.094948] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2558.274306] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2558.348394] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2558.466801] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2558.560641] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2558.714808] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2558.820415] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2558.916792] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2559.130170] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2559.261331] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2559.298159] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2559.392173] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2559.519418] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2559.659833] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2559.751238] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2559.840589] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2559.969839] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2560.099789] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2560.266413] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2560.386299] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2560.484201] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2560.590136] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2560.704973] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2560.810531] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2560.855064] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2560.965886] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2561.058892] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2561.135608] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2561.182697] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2561.355100] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2561.417332] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2561.489803] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2561.625896] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2561.793101] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2561.919855] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2562.064460] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2562.286619] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2562.346515] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2562.464704] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2562.594527] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2562.882272] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2562.954339] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2563.149918] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2563.201040] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2563.278383] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2563.382044] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2563.554168] Lustre: lustre-OST0001-osc-ffff8f6fc7e42000: disconnect after 22s idle [ 2563.560200] Lustre: Skipped 1 previous similar message [ 2563.609562] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2563.695929] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2563.983620] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2564.111850] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2564.284680] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2564.347265] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2564.479856] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2564.613626] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2564.726768] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2564.848123] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2564.994931] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2565.125535] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2565.187609] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2565.332222] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2565.401798] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2565.529452] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2565.598963] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2565.672191] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2565.802320] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2565.872897] rw_seq_cst_vs_d (32980): drop_caches: 3 [ 2575.139824] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 17:36:54 (1773610614) [ 2583.185298] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 17:37:02 (1773610622) [ 2590.673928] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 17:37:09 (1773610629) [ 2626.831344] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 17:37:45 (1773610665) [ 2627.698250] bash (35468): drop_caches: 3 [ 2630.962163] bash (35468): drop_caches: 3 [ 2634.179846] bash (35468): drop_caches: 3 [ 2637.366866] bash (35468): drop_caches: 3 [ 2640.587692] bash (35468): drop_caches: 3 [ 2643.768412] bash (35468): drop_caches: 3 [ 2647.090254] bash (35468): drop_caches: 3 [ 2650.322669] bash (35468): drop_caches: 3 [ 2653.520799] bash (35468): drop_caches: 3 [ 2656.672408] bash (35468): drop_caches: 3 [ 2659.855689] bash (35468): drop_caches: 3 [ 2663.065709] bash (35468): drop_caches: 3 [ 2666.304362] bash (35468): drop_caches: 3 [ 2669.491373] bash (35468): drop_caches: 3 [ 2673.059813] bash (35468): drop_caches: 3 [ 2676.171628] bash (35468): drop_caches: 3 [ 2679.336347] bash (35468): drop_caches: 3 [ 2682.609481] bash (35468): drop_caches: 3 [ 2685.895962] bash (35468): drop_caches: 3 [ 2689.164558] bash (35468): drop_caches: 3 [ 2694.855227] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 17:38:53 (1773610733) [ 2706.993861] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 17:39:06 (1773610746) [ 2717.152403] Lustre: lustre-OST0001-osc-ffff8f6fd8a05000: disconnect after 21s idle [ 2717.169629] Lustre: Skipped 1 previous similar message [ 2736.117406] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 17:39:34 (1773610774) [ 2747.730138] Lustre: DEBUG MARKER: loop 5 [ 2753.812266] Lustre: DEBUG MARKER: loop 10 [ 2760.214044] Lustre: DEBUG MARKER: loop 15 [ 2766.890599] Lustre: DEBUG MARKER: loop 20 [ 2778.115613] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 17:40:15 (1773610815) [ 2787.512208] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 17:40:26 (1773610826) [ 2796.446508] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 17:40:34 (1773610834) [ 2799.071378] Lustre: lustre-OST0000-osc-ffff8f6fc7e42000: disconnect after 20s idle [ 2799.085251] Lustre: Skipped 2 previous similar messages [ 2867.431607] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 17:41:46 (1773610906) [ 2876.640431] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 17:41:54 (1773610914) [ 2887.166974] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 17:42:05 (1773610925) [ 2896.991784] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 17:42:15 (1773610935) [ 2905.776592] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 17:42:24 (1773610944) [ 2914.258703] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 17:42:33 (1773610953) [ 2916.832125] Lustre: lustre-OST0001-osc-ffff8f6fc7e42000: disconnect after 22s idle [ 2916.839337] Lustre: Skipped 3 previous similar messages [ 2925.409359] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 17:42:44 (1773610964) [ 2934.987979] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 2936.903561] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 17:42:55 (1773610975) [ 2949.178758] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 17:43:07 (1773610987) [ 2949.867768] Lustre: *** cfs_fail_loc=314, val=0*** [ 2950.944737] Lustre: *** cfs_fail_loc=314, val=0*** [ 2950.955679] Lustre: Skipped 2 previous similar messages [ 2960.342734] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 17:43:18 (1773610998) [ 2972.533798] Lustre: *** cfs_fail_loc=314, val=0*** [ 2972.650078] LustreError: lustre-OST0000-osc-ffff8f6fd8a05000: operation ldlm_enqueue to node 192.168.204.141@tcp failed: rc = -107 [ 2972.667528] Lustre: lustre-OST0000-osc-ffff8f6fd8a05000: Connection to lustre-OST0000 (at 192.168.204.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2972.698570] LustreError: lustre-OST0000-osc-ffff8f6fd8a05000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 2972.711220] LustreError: 46400:0:(ldlm_resource.c:1170:ldlm_resource_complain()) lustre-OST0000-osc-ffff8f6fd8a05000: namespace resource [0x280000401:0x39:0x0].0x0 (ffff8f6fc7179f00) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 2972.739090] Lustre: lustre-OST0000-osc-ffff8f6fd8a05000: Connection restored to 192.168.204.141@tcp (at 192.168.204.141@tcp) [ 2982.138646] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 17:43:40 (1773611020) [ 2982.603582] LustreError: 46990:0:(file.c:790:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 2985.639569] LustreError: 46990:0:(file.c:790:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 2993.547740] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 17:43:52 (1773611032) [ 3003.459388] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 17:44:01 (1773611041) [ 3012.737463] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 3015.241612] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 17:44:13 (1773611053) [ 3017.307492] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 3019.082414] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 17:44:18 (1773611058) [ 3024.376258] Lustre: lustre-MDT0000-mdc-ffff8f6fc7e42000: Connection to lustre-MDT0000 (at 192.168.204.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3034.629816] LustreError: MGC192.168.204.141@tcp: Connection to MGS (at 192.168.204.141@tcp) was lost; in progress operations using this service will fail [ 3034.661547] Lustre: Evicted from MGS (at 192.168.204.141@tcp) after server handle changed from 0x2cec6d8a614124b8 to 0x2cec6d8a614b1b90 [ 3034.683680] Lustre: MGC192.168.204.141@tcp: Connection restored to 192.168.204.141@tcp (at 192.168.204.141@tcp) [ 3038.523538] Lustre: lustre-MDT0000-mdc-ffff8f6fd8a05000: Connection restored to 192.168.204.141@tcp (at 192.168.204.141@tcp) [ 3075.552159] Lustre: lustre-OST0000-osc-ffff8f6fd8a05000: disconnect after 21s idle [ 3075.560667] Lustre: Skipped 4 previous similar messages [ 3091.081694] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 17:45:29 (1773611129) [ 3172.902823] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 17:46:51 (1773611211) [ 3201.162803] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 17:47:19 (1773611239) [ 3259.883765] Lustre: lustre-OST0000-osc-ffff8f6fc7e42000: Connection to lustre-OST0000 (at 192.168.204.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3259.889975] LustreError: lustre-OST0000-osc-ffff8f6fd8a05000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 3259.898393] Lustre: Skipped 2 previous similar messages [ 3259.929979] Lustre: lustre-OST0000-osc-ffff8f6fd8a05000: Connection restored to 192.168.204.141@tcp (at 192.168.204.141@tcp) [ 3259.931914] LustreError: lustre-OST0000-osc-ffff8f6fc7e42000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 3259.940092] Lustre: Skipped 1 previous similar message [ 3274.213312] Lustre: lustre-OST0001-osc-ffff8f6fc7e42000: Connection to lustre-OST0001 (at 192.168.204.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3274.260629] LustreError: lustre-OST0001-osc-ffff8f6fc7e42000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 3274.293631] Lustre: lustre-OST0001-osc-ffff8f6fc7e42000: Connection restored to 192.168.204.141@tcp (at 192.168.204.141@tcp) [ 3274.300700] Lustre: Skipped 1 previous similar message [ 3291.918115] Lustre: DEBUG MARKER: oleg441-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8f6fc7e42000.ost_server_uuid 50 [ 3293.477271] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8f6fc7e42000.ost_server_uuid in IDLE state after 0 sec [ 3298.756481] Lustre: DEBUG MARKER: oleg441-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8f6fc7e42000.ost_server_uuid 50 [ 3300.323807] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8f6fc7e42000.ost_server_uuid in IDLE state after 0 sec [ 3306.557592] Lustre: DEBUG MARKER: oleg441-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8f6fc7e42000.ost_server_uuid 50 [ 3308.782218] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8f6fc7e42000.ost_server_uuid in IDLE state after 0 sec [ 3314.634782] Lustre: DEBUG MARKER: oleg441-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8f6fc7e42000.ost_server_uuid 50 [ 3316.800913] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8f6fc7e42000.ost_server_uuid in IDLE state after 0 sec [ 3328.541520] Lustre: DEBUG MARKER: oleg441-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8f6fc7e42000.ost_server_uuid 50 [ 3330.549928] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8f6fc7e42000.ost_server_uuid in IDLE state after 0 sec [ 3336.013935] Lustre: DEBUG MARKER: oleg441-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8f6fc7e42000.ost_server_uuid 50 [ 3338.256946] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8f6fc7e42000.ost_server_uuid in IDLE state after 0 sec [ 3340.218309] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 17:49:39 (1773611379) [ 3343.784433] Lustre: DEBUG MARKER: Race attempt 0 [ 3347.377445] Lustre: DEBUG MARKER: Wait for 58355 58394 for 60 sec... [ 3415.506921] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 17:50:54 (1773611454) [ 3424.469421] Lustre: DEBUG MARKER: start test - cycle (0) [ 3453.911703] Lustre: DEBUG MARKER: start test - cycle (1) [ 3464.682317] Lustre: lustre-OST0000-osc-ffff8f6fd8a05000: disconnect after 24s idle [ 3464.687525] Lustre: Skipped 5 previous similar messages [ 3484.114663] Lustre: DEBUG MARKER: start test - cycle (2) [ 3516.614877] Lustre: DEBUG MARKER: start test - cycle (3) [ 3544.023297] Lustre: DEBUG MARKER: start test - cycle (4) [ 3570.791957] Lustre: DEBUG MARKER: start test - cycle (5) [ 3594.626871] Lustre: DEBUG MARKER: start test - cycle (6) [ 3620.929227] Lustre: DEBUG MARKER: start test - cycle (7) [ 3646.571884] Lustre: DEBUG MARKER: start test - cycle (8) [ 3673.487880] Lustre: DEBUG MARKER: start test - cycle (9) [ 3703.759059] Lustre: DEBUG MARKER: start test - cycle (10) [ 3737.917375] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 17:56:16 (1773611776) [ 3820.163739] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 17:57:39 (1773611859) [ 3830.401895] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 17:57:48 (1773611868) [ 3839.209356] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 17:57:58 (1773611878) [ 3848.817598] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 17:58:07 (1773611887) [ 3849.187743] Lustre: *** cfs_fail_loc=411, val=0*** [ 3856.735306] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 3858.691188] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 17:58:17 (1773611897) [ 3879.513175] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 17:58:38 (1773611918) [ 3898.838832] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 17:58:57 (1773611937) [ 3918.129737] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 17:59:17 (1773611957) [ 3936.516098] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 17:59:35 (1773611975) [ 3950.846673] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 17:59:49 (1773611989) [ 3966.009224] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 18:00:05 (1773612005) [ 3982.509503] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 18:00:21 (1773612021) [ 3997.152610] Lustre: lustre-OST0000-osc-ffff8f6fc7e42000: disconnect after 21s idle [ 3997.159143] Lustre: Skipped 22 previous similar messages [ 3997.858961] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 18:00:36 (1773612036) [ 4013.967459] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 18:00:52 (1773612052) [ 4029.608711] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 18:01:08 (1773612068) [ 4044.329158] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 18:01:23 (1773612083) [ 4063.638822] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 18:01:42 (1773612102) [ 4678.111308] Lustre: lustre-OST0000-osc-ffff8f6fd8a05000: disconnect after 21s idle [ 4678.113878] Lustre: Skipped 5 previous similar messages [ 5138.024160] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 18:19:37 (1773613177) [ 5147.992148] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 18:19:47 (1773613187) [ 5157.672995] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 18:19:57 (1773613197) [ 5166.250184] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 18:20:05 (1773613205) [ 5176.305647] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 18:20:15 (1773613215) [ 5186.784881] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 18:20:26 (1773613226) [ 5197.073845] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 18:20:36 (1773613236) [ 5206.836710] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 18:20:46 (1773613246) [ 5217.007291] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 18:20:56 (1773613256) [ 5294.129072] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 18:22:13 (1773613333) [ 5306.152259] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 18:22:25 (1773613345) [ 5316.770225] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 18:22:36 (1773613356) [ 5338.342343] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 18:22:56 (1773613376) [ 5338.593721] Lustre: lustre-OST0001-osc-ffff8f6fc7e42000: disconnect after 25s idle [ 5338.605079] Lustre: Skipped 4 previous similar messages [ 5357.957441] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 18:23:16 (1773613396) [ 5379.537279] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 18:23:37 (1773613417) [ 5399.737668] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 18:23:58 (1773613438) [ 5416.765083] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 18:24:15 (1773613455) [ 5431.491727] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 18:24:30 (1773613470) [ 5584.278747] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 18:27:03 (1773613623) [ 6032.863549] Lustre: lustre-OST0000-osc-ffff8f6fd8a05000: disconnect after 21s idle [ 6032.892094] Lustre: Skipped 10 previous similar messages [ 6647.264270] Lustre: lustre-OST0000-osc-ffff8f6fd8a05000: disconnect after 21s idle [ 6647.273761] Lustre: Skipped 7 previous similar messages [ 7256.544430] Lustre: lustre-OST0001-osc-ffff8f6fd8a05000: disconnect after 20s idle [ 7256.554804] Lustre: Skipped 17 previous similar messages [ 7266.649104] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 18:55:04 (1773615304) [ 7287.752538] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 18:55:25 (1773615325) [ 7310.154874] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 18:55:48 (1773615348) [ 7331.500550] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 18:56:09 (1773615369) [ 7350.094798] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 18:56:28 (1773615388) [ 7369.867808] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 18:56:48 (1773615408) [ 7390.879947] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 18:57:08 (1773615428) [ 7411.081726] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 18:57:29 (1773615449) [ 7433.119892] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 18:57:51 (1773615471) [ 7452.738170] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 18:58:11 (1773615491) [ 7671.869335] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 19:01:50 (1773615710) [ 7691.493281] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 19:02:09 (1773615729) [ 7709.058975] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 19:02:27 (1773615747) [ 7726.884603] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 19:02:45 (1773615765) [ 7744.187698] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 19:03:02 (1773615782) [ 7760.743340] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 19:03:19 (1773615799) [ 7780.396945] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 19:03:38 (1773615818) [ 7799.281583] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 19:03:57 (1773615837) [ 7818.686363] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 19:04:17 (1773615857) [ 9218.682325] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 19:27:37 (1773617257) [ 9229.992843] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 19:27:49 (1773617269) [ 9230.822219] Lustre: lustre-OST0001-osc-ffff8f6fd8a05000: disconnect after 23s idle [ 9230.827665] Lustre: Skipped 14 previous similar messages [ 9242.640294] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 19:28:01 (1773617281) [ 9254.509533] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 19:28:13 (1773617293) [ 9267.628197] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 19:28:26 (1773617306) [ 9280.685899] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 19:28:39 (1773617319) [ 9294.075709] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 19:28:53 (1773617333) [ 9306.910425] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 19:29:06 (1773617346) [ 9307.616306] Lustre: lustre-OST0000-osc-ffff8f6fc7e42000: disconnect after 25s idle [ 9319.909727] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 19:29:19 (1773617359) [ 9332.342643] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 19:29:31 (1773617371) [ 9344.711310] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 19:29:44 (1773617384) [ 9358.429505] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 19:29:57 (1773617397) [ 9372.138682] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 19:30:10 (1773617410) [ 9384.794622] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 19:30:23 (1773617423) [ 9398.393476] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 19:30:37 (1773617437) [ 9410.670981] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 19:30:50 (1773617450) [ 9427.100860] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 19:31:06 (1773617466) [ 9427.446457] LustreError: 6174:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 9429.543148] LustreError: 6174:0:(ldlm_lockd.c:2079:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 9438.519342] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 19:31:17 (1773617477) [ 9447.155502] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 19:31:26 (1773617486) [ 9447.447276] LustreError: 239746:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 9451.511189] LustreError: 239746:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 9451.545028] LustreError: 239746:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 9455.607152] LustreError: 239746:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 9455.654411] LustreError: 239753:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 9459.711159] LustreError: 239753:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 9465.840610] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 19:31:45 (1773617505) [ 9477.064183] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 19:31:56 (1773617516) [ 9483.965917] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 19:32:03 (1773617523) [ 9486.830589] Lustre: lustre-OST0001-osc-ffff8f6fc7e42000: disconnect after 21s idle [ 9486.833095] Lustre: Skipped 4 previous similar messages [ 9492.310819] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 19:32:11 (1773617531) [ 9523.815068] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 19:32:42 (1773617562) [ 9535.929082] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 19:32:55 (1773617575) [ 9547.451719] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 19:33:06 (1773617586) [ 9564.419116] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 19:33:23 (1773617603) [ 9578.675486] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 19:33:37 (1773617617) [ 9596.076670] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 19:33:55 (1773617635) [ 9614.010699] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 19:34:13 (1773617653) [ 9635.245409] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 19:34:34 (1773617674) [ 9653.615872] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 19:34:53 (1773617693) [ 9727.747844] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 19:36:07 (1773617767) [ 9734.687253] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 9740.805993] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 19:36:20 (1773617780) [ 9747.756609] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 19:36:26 (1773617786) [ 9754.019946] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 19:36:33 (1773617793) [ 9758.410152] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 19:36:37 (1773617797) [ 9794.847278] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 19:37:14 (1773617834) [ 9819.615861] Lustre: lustre-OST0001-osc-ffff8f6fc7e42000: disconnect after 25s idle [ 9840.016781] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 19:37:59 (1773617879) [ 9846.888265] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 19:38:05 (1773617885) [ 9852.021385] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 19:38:11 (1773617891) [ 9855.294395] LustreError: lustre-MDT0000-mdc-ffff8f6fd8a05000: operation ldlm_enqueue to node 192.168.204.141@tcp failed: rc = -35 [ 9860.935511] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 19:38:20 (1773617900) [ 9861.532707] LustreError: 2413:0:(osc_request.c:3132:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 9863.623160] LustreError: 2413:0:(osc_request.c:3132:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 9870.722215] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 19:38:30 (1773617910) [ 9997.541810] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 19:40:37 (1773618037) [10007.850428] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 19:40:47 (1773618047) [10017.309929] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 19:40:56 (1773618056) [10028.355979] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 19:41:07 (1773618067) [10040.258884] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 19:41:19 (1773618079) [10057.812446] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 19:41:37 (1773618097) [10075.521619] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 19:41:54 (1773618114) [10083.714612] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 19:42:03 (1773618123) [10092.619710] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 19:42:12 (1773618132) [10108.539292] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 19:42:27 (1773618147) [10155.260090] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 19:43:14 (1773618194) [10284.706723] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 19:45:24 (1773618324) [10410.399579] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 19:47:30 (1773618450) [10439.135261] Lustre: lustre-OST0001-osc-ffff8f6fc7e42000: disconnect after 21s idle [10439.140871] Lustre: Skipped 13 previous similar messages [10721.956054] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 19:52:41 (1773618761) [10753.798525] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 19:53:13 (1773618793) [10784.986268] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 19:53:44 (1773618824) [10811.718488] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 19:54:11 (1773618851) [10871.735757] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 19:55:11 (1773618911) [10928.806160] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 19:56:08 (1773618968) [11030.398223] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 19:57:50 (1773619070) [11032.199233] LustreError: 286509:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fc7e42000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11032.229969] Lustre: Unmounted lustre-client [11033.496157] LustreError: 286522:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fd8a05000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [11033.500570] LustreError: 286522:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [11033.537979] Lustre: Unmounted lustre-client [11110.405385] Lustre: Mounted lustre-client [11112.283859] Lustre: Mounted lustre-client [11113.803993] Lustre: DEBUG MARKER: Using TIMEOUT=20 [11132.895299] Lustre: lustre-OST0000-osc-ffff8f6ffb86e000: disconnect after 21s idle [11132.899442] Lustre: Skipped 11 previous similar messages [11183.201782] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 20:00:22 (1773619222) [11193.233314] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 20:00:32 (1773619232) [11198.550052] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 20:00:38 (1773619238) [11251.837780] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 20:01:31 (1773619291) [11309.680329] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 20:02:29 (1773619349) [11315.103393] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 20:02:34 (1773619354) [11363.250511] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 20:03:23 (1773619403) [11378.625854] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 20:03:38 (1773619418) [11419.752901] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 20:04:19 (1773619459) [11423.600938] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 20:04:23 (1773619463) [11437.706047] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 20:04:37 (1773619477) [11443.103846] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 20:04:42 (1773619482) [11443.837874] LustreError: 305630:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x2ba:0x0]: rc = -5 [11443.843075] LustreError: 305630:0:(llite_lib.c:3713:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [11444.458465] LustreError: 305825:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x2c2:0x0]: rc = -5 [11444.463215] LustreError: 305825:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 4 previous similar messages [11444.466394] LustreError: 305825:0:(llite_lib.c:3713:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [11444.472336] LustreError: 305825:0:(llite_lib.c:3713:ll_prep_inode()) Skipped 4 previous similar messages [11445.663241] LustreError: 305866:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x2f:0x0]: rc = -5 [11445.670729] LustreError: 305866:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 7 previous similar messages [11445.675652] LustreError: 305866:0:(llite_lib.c:3713:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [11445.678422] LustreError: 305866:0:(llite_lib.c:3713:ll_prep_inode()) Skipped 7 previous similar messages [11447.830770] LustreError: 305968:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x4d:0x0]: rc = -5 [11447.835153] LustreError: 305968:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 19 previous similar messages [11447.839295] LustreError: 305968:0:(llite_lib.c:3713:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [11447.842849] LustreError: 305968:0:(llite_lib.c:3713:ll_prep_inode()) Skipped 19 previous similar messages [11451.877740] LustreError: 306154:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff8f6ffb86e000: inode [0x2000013a1:0x33a:0x0] mdc close failed: rc = -2 [11451.924652] LustreError: 306156:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x33f:0x0]: rc = -5 [11451.933118] LustreError: 306156:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 35 previous similar messages [11451.937238] LustreError: 306156:0:(llite_lib.c:3713:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [11451.941091] LustreError: 306156:0:(llite_lib.c:3713:ll_prep_inode()) Skipped 35 previous similar messages [11455.172080] LustreError: 306285:0:(file.c:249:ll_close_inode_openhandle()) lustre-clilmv-ffff8f6ffb86e000: inode [0x2000013a1:0x360:0x0] mdc close failed: rc = -2 [11460.037378] LustreError: 306514:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x2000013a1:0x3bf:0x0]: rc = -5 [11460.045254] LustreError: 306514:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 69 previous similar messages [11460.050210] LustreError: 306514:0:(llite_lib.c:3713:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [11460.054385] LustreError: 306514:0:(llite_lib.c:3713:ll_prep_inode()) Skipped 69 previous similar messages [11568.930588] LustreError: 305630:0:(lcommon_cl.c:187:cl_file_inode_init()) lustre: failed to initialize cl_object [0x240000bd0:0x14c:0x0]: rc = -5 [11568.935659] LustreError: 305630:0:(lcommon_cl.c:187:cl_file_inode_init()) Skipped 68 previous similar messages [11568.938346] LustreError: 305630:0:(llite_lib.c:3713:ll_prep_inode()) lustre: new_inode - fatal error: rc = -5 [11568.944630] LustreError: 305630:0:(llite_lib.c:3713:ll_prep_inode()) Skipped 68 previous similar messages [11568.964514] LustreError: lustre-MDT0001-mdc-ffff8f6fdd053800: operation mds_getattr_lock to node 192.168.204.141@tcp failed: rc = -107 [11568.970876] Lustre: lustre-MDT0001-mdc-ffff8f6fdd053800: Connection to lustre-MDT0001 (at 192.168.204.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [11568.992179] LustreError: lustre-MDT0001-mdc-ffff8f6fdd053800: This client was evicted by lustre-MDT0001; in progress operations using this service will fail. [11569.006778] Lustre: lustre-MDT0001-mdc-ffff8f6fdd053800: Connection restored to 192.168.204.141@tcp (at 192.168.204.141@tcp) [11572.870887] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 20:06:52 (1773619612) [11576.920107] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 20:06:56 (1773619616) [11688.863337] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 20:08:48 (1773619728) [11689.628579] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [11690.576697] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 20:08:50 (1773619730) [11800.010637] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 20:10:39 (1773619839) [11803.126108] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 20:10:42 (1773619842) [11824.095181] Lustre: lustre-OST0001-osc-ffff8f6ffb86e000: disconnect after 23s idle [11824.097064] Lustre: Skipped 6 previous similar messages [11925.862273] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 20:12:45 (1773619965) [11933.679971] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 20:12:53 (1773619973) [11937.706962] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 20:12:57 (1773619977) [12120.293377] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 20:16:00 (1773620160) [12302.916896] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 20:19:02 (1773620342) [12305.372932] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 20:19:05 (1773620345) [12314.632623] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 20:19:14 (1773620354) [12314.686471] Lustre: DEBUG MARKER: write [12314.708355] LustreError: 288953:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [12316.716738] Lustre: DEBUG MARKER: kill 355560 [12316.719118] LustreError: 355560:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [12319.807120] LustreError: 288953:0:(ldlm_request.c:1525:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [12322.751137] LustreError: 355560:0:(ldlm_request.c:1393:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [12324.972114] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 20:19:24 (1773620364) [12327.179805] LustreError: 356174:0:(rw.c:1868:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [12337.271150] LustreError: 356174:0:(rw.c:1868:ll_readpage()) cfs_fail_timeout id 1422 awake [12339.937730] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 20:19:39 (1773620379) [12340.095459] LustreError: 356763:0:(rw.c:2116:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [12342.175135] LustreError: 356763:0:(rw.c:2116:ll_readpage()) cfs_fail_timeout interrupted [12348.513269] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 20:19:48 (1773620388) [12349.100726] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [12349.784260] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 20:19:49 (1773620389) [12352.499889] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 20:19:52 (1773620392) [12355.194053] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 20:19:55 (1773620395) [12357.694378] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 20:19:57 (1773620397) [12360.520930] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 20:20:00 (1773620400) [12362.947838] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 20:20:02 (1773620402) [12365.850070] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 20:20:05 (1773620405) [12369.401895] Lustre: DEBUG MARKER: == sanityn test 102: Test open by handle of unlinked file ========================================================== 20:20:09 (1773620409) [12372.844333] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 20:20:12 (1773620412) [12373.570931] Lustre: *** cfs_fail_loc=415, val=0*** [12380.773969] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 20:20:20 (1773620420) [12400.880930] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 20:20:40 (1773620440) [12400.998538] LustreError: 288771:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [12401.006862] LustreError: 288771:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [12406.007145] LustreError: 288771:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [12416.207184] LustreError: 288771:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [12416.209706] LustreError: 288771:0:(osc_lock.c:435:osc_dlm_blocking_ast0()) Skipped 6 previous similar messages [12423.936577] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 20:21:03 (1773620463) [12426.696612] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 20:21:06 (1773620466) [12429.486431] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 20:21:09 (1773620469) [12432.305839] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 20:21:12 (1773620472) [12436.940245] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 20:21:16 (1773620476) [12445.788481] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 20:21:25 (1773620485) [12446.018801] LustreError: 367494:0:(osc_request.c:2983:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [12446.022194] LustreError: 367494:0:(osc_request.c:2983:osc_build_rpc()) Skipped 6 previous similar messages [12450.079133] LustreError: 367494:0:(osc_request.c:2983:osc_build_rpc()) cfs_fail_timeout id 414 awake [12450.083054] LustreError: 367494:0:(osc_request.c:2983:osc_build_rpc()) Skipped 2 previous similar messages [12452.689745] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 20:21:32 (1773620492) [12454.323019] LustreError: 368203:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fdd053800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12454.326618] LustreError: 368203:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [12454.353124] Lustre: Unmounted lustre-client [12454.907445] LustreError: 368223:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6ffb86e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12454.911349] LustreError: 368223:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [12454.951976] Lustre: Unmounted lustre-client [12455.472310] Lustre: DEBUG MARKER: Iteration 1 [12455.579798] LustreError: 368387:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12455.579843] LustreError: 368388:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12455.585465] LustreError: 368387:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12455.658755] Lustre: Mounted lustre-client [12455.660123] Lustre: Skipped 1 previous similar message [12456.119093] LustreError: 368497:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fd030d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12456.123043] LustreError: 368497:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [12456.188824] Lustre: Unmounted lustre-client [12457.161466] Key type lgssc unregistered [12457.284082] LNet: 368741:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12457.286996] LNetError: 368741:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12457.293648] LNet: Removed LNI 192.168.204.41@tcp [12457.579122] Key type .llcrypt unregistered [12457.580181] Key type ._llcrypt unregistered [12458.021314] Key type ._llcrypt registered [12458.022263] Key type .llcrypt registered [12458.193490] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12458.200289] alg: No test for adler32 (adler32-zlib) [12459.173070] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12459.449993] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12461.063208] Key type lgssc registered [12461.675254] Lustre: Echo OBD driver; http://www.lustre.org/ [12466.264799] Lustre: DEBUG MARKER: Iteration 2 [12466.397353] LustreError: 369573:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12466.397728] LustreError: 369575:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12466.404126] LustreError: 369573:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12467.480974] Lustre: Mounted lustre-client [12467.990568] LustreError: 369685:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fffe12000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12468.048934] Lustre: Unmounted lustre-client [12469.045405] Key type lgssc unregistered [12469.180451] LNet: 369930:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12469.183194] LNetError: 369930:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12469.189624] LNet: Removed LNI 192.168.204.41@tcp [12469.418116] Key type .llcrypt unregistered [12469.419116] Key type ._llcrypt unregistered [12469.721858] Key type ._llcrypt registered [12469.730607] Key type .llcrypt registered [12469.886650] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12469.892176] alg: No test for adler32 (adler32-zlib) [12470.767773] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12470.865484] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12472.455183] Key type lgssc registered [12472.880645] Lustre: Echo OBD driver; http://www.lustre.org/ [12476.766462] Lustre: DEBUG MARKER: Iteration 3 [12476.896832] LustreError: 370761:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12476.900038] LustreError: 370762:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12476.902609] LustreError: 370761:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12477.970231] Lustre: Mounted lustre-client [12477.971342] Lustre: Skipped 1 previous similar message [12478.417609] LustreError: 370876:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fc51d4000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12478.421797] LustreError: 370876:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12478.475153] Lustre: Unmounted lustre-client [12479.527739] Key type lgssc unregistered [12479.652513] LNet: 371118:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12479.655377] LNetError: 371118:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12479.665677] LNet: Removed LNI 192.168.204.41@tcp [12479.904134] Key type .llcrypt unregistered [12479.905558] Key type ._llcrypt unregistered [12480.185744] Key type ._llcrypt registered [12480.186899] Key type .llcrypt registered [12480.387166] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12480.393246] alg: No test for adler32 (adler32-zlib) [12481.288581] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12481.389021] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12482.991205] Key type lgssc registered [12483.481762] Lustre: Echo OBD driver; http://www.lustre.org/ [12487.601376] Lustre: DEBUG MARKER: Iteration 4 [12487.707204] LustreError: 371950:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12487.708313] LustreError: 371951:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12487.713888] LustreError: 371950:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12488.790465] Lustre: Mounted lustre-client [12488.792019] Lustre: Skipped 1 previous similar message [12489.267898] LustreError: 372064:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fd9337000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12489.272119] LustreError: 372064:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12489.333442] Lustre: Unmounted lustre-client [12490.352251] Key type lgssc unregistered [12490.470064] LNet: 372307:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12490.472917] LNetError: 372307:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12490.485775] LNet: Removed LNI 192.168.204.41@tcp [12490.736233] Key type .llcrypt unregistered [12490.739098] Key type ._llcrypt unregistered [12491.033544] Key type ._llcrypt registered [12491.034590] Key type .llcrypt registered [12491.216642] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12491.222385] alg: No test for adler32 (adler32-zlib) [12492.084471] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12492.173436] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12493.759170] Key type lgssc registered [12494.187044] Lustre: Echo OBD driver; http://www.lustre.org/ [12497.972584] Lustre: DEBUG MARKER: Iteration 5 [12498.074048] LustreError: 373138:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12498.074050] LustreError: 373139:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12498.079581] LustreError: 373138:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [12499.139421] Lustre: Mounted lustre-client [12499.140709] Lustre: Skipped 1 previous similar message [12499.623776] LustreError: 373252:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fc9b6f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12499.682996] Lustre: Unmounted lustre-client [12500.740760] Key type lgssc unregistered [12500.867491] LNet: 373496:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12500.869915] LNetError: 373496:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12500.878472] LNet: Removed LNI 192.168.204.41@tcp [12501.174129] Key type .llcrypt unregistered [12501.175699] Key type ._llcrypt unregistered [12501.563897] Key type ._llcrypt registered [12501.565259] Key type .llcrypt registered [12501.735034] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12501.741915] alg: No test for adler32 (adler32-zlib) [12502.606391] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12502.707522] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12504.303384] Key type lgssc registered [12504.772179] Lustre: Echo OBD driver; http://www.lustre.org/ [12508.677509] Lustre: DEBUG MARKER: Iteration 6 [12508.772891] LustreError: 374328:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12508.772955] LustreError: 374329:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12508.778411] LustreError: 374328:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12509.840887] Lustre: Mounted lustre-client [12510.271464] LustreError: 374441:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fffc77000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12510.275917] LustreError: 374441:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12510.331688] Lustre: Unmounted lustre-client [12511.330172] Key type lgssc unregistered [12511.450484] LNet: 374685:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12511.453071] LNetError: 374685:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12511.460600] LNet: Removed LNI 192.168.204.41@tcp [12511.700135] Key type .llcrypt unregistered [12511.701464] Key type ._llcrypt unregistered [12511.972602] Key type ._llcrypt registered [12511.974173] Key type .llcrypt registered [12512.152179] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12512.157969] alg: No test for adler32 (adler32-zlib) [12513.017294] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12513.101666] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12514.687263] Key type lgssc registered [12515.096389] Lustre: Echo OBD driver; http://www.lustre.org/ [12518.731146] Lustre: DEBUG MARKER: Iteration 7 [12518.833491] LustreError: 375516:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12518.833546] LustreError: 375517:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12518.839207] LustreError: 375516:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12519.907249] Lustre: Mounted lustre-client [12519.908537] Lustre: Skipped 1 previous similar message [12520.352264] LustreError: 375630:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fc703c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12520.429668] Lustre: Unmounted lustre-client [12521.478445] Key type lgssc unregistered [12521.608149] LNet: 375873:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12521.611546] LNetError: 375873:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12521.622051] LNet: Removed LNI 192.168.204.41@tcp [12521.882130] Key type .llcrypt unregistered [12521.883229] Key type ._llcrypt unregistered [12522.138922] Key type ._llcrypt registered [12522.140420] Key type .llcrypt registered [12522.331253] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12522.337354] alg: No test for adler32 (adler32-zlib) [12523.191237] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12523.275052] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12524.855160] Key type lgssc registered [12525.266393] Lustre: Echo OBD driver; http://www.lustre.org/ [12529.020921] Lustre: DEBUG MARKER: Iteration 8 [12529.142150] LustreError: 376705:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12529.142956] LustreError: 376704:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12529.147126] LustreError: 376705:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12530.208206] Lustre: Mounted lustre-client [12530.690935] LustreError: 376820:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fc83ed000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12530.695468] LustreError: 376820:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12530.750529] Lustre: Unmounted lustre-client [12531.740518] Key type lgssc unregistered [12531.859504] LNet: 377064:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12531.862396] LNetError: 377064:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12531.871236] LNet: Removed LNI 192.168.204.41@tcp [12532.120155] Key type .llcrypt unregistered [12532.121626] Key type ._llcrypt unregistered [12532.514217] Key type ._llcrypt registered [12532.515755] Key type .llcrypt registered [12532.680400] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12532.685797] alg: No test for adler32 (adler32-zlib) [12533.549250] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12533.637826] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12535.223156] Key type lgssc registered [12535.629249] Lustre: Echo OBD driver; http://www.lustre.org/ [12539.422146] Lustre: DEBUG MARKER: Iteration 9 [12539.521941] LustreError: 377896:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12539.521992] LustreError: 377897:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12539.527038] LustreError: 377896:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12540.598678] Lustre: Mounted lustre-client [12540.599871] Lustre: Skipped 1 previous similar message [12541.057517] LustreError: 378008:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fc4f8c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12541.061874] LustreError: 378008:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12541.104080] Lustre: Unmounted lustre-client [12542.120676] Key type lgssc unregistered [12542.240477] LNet: 378251:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12542.242807] LNetError: 378251:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12542.252641] LNet: Removed LNI 192.168.204.41@tcp [12542.493130] Key type .llcrypt unregistered [12542.494384] Key type ._llcrypt unregistered [12542.778723] Key type ._llcrypt registered [12542.783388] Key type .llcrypt registered [12543.010239] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12543.016568] alg: No test for adler32 (adler32-zlib) [12543.875401] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12543.954564] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12545.543164] Key type lgssc registered [12545.937860] Lustre: Echo OBD driver; http://www.lustre.org/ [12549.613994] Lustre: DEBUG MARKER: Iteration 10 [12549.706814] LustreError: 379082:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12549.706846] LustreError: 379083:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12549.712579] LustreError: 379082:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12550.783367] Lustre: Mounted lustre-client [12550.785217] Lustre: Skipped 1 previous similar message [12551.228327] LustreError: 379193:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fffc71000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12551.232522] LustreError: 379193:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12551.283809] Lustre: Unmounted lustre-client [12552.256232] Key type lgssc unregistered [12552.366396] LNet: 379437:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12552.368785] LNetError: 379437:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12552.376673] LNet: Removed LNI 192.168.204.41@tcp [12552.608126] Key type .llcrypt unregistered [12552.609561] Key type ._llcrypt unregistered [12552.867124] Key type ._llcrypt registered [12552.871130] Key type .llcrypt registered [12553.045839] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12553.050749] alg: No test for adler32 (adler32-zlib) [12553.900310] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12553.983610] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12555.567157] Key type lgssc registered [12555.964339] Lustre: Echo OBD driver; http://www.lustre.org/ [12559.423262] Lustre: DEBUG MARKER: Iteration 11 [12559.527984] LustreError: 380269:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12559.528688] LustreError: 380270:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12559.532875] LustreError: 380269:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [12560.591709] Lustre: Mounted lustre-client [12560.592789] Lustre: Skipped 1 previous similar message [12561.041298] LustreError: 380386:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fd06b2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12561.045443] LustreError: 380386:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12561.088067] Lustre: Unmounted lustre-client [12562.039203] Key type lgssc unregistered [12562.150746] LNet: 380631:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12562.153497] LNetError: 380631:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12562.161659] LNet: Removed LNI 192.168.204.41@tcp [12562.394100] Key type .llcrypt unregistered [12562.395141] Key type ._llcrypt unregistered [12562.687244] Key type ._llcrypt registered [12562.688685] Key type .llcrypt registered [12562.842591] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12562.847776] alg: No test for adler32 (adler32-zlib) [12563.701804] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12563.779914] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12565.359121] Key type lgssc registered [12565.762659] Lustre: Echo OBD driver; http://www.lustre.org/ [12569.364536] Lustre: DEBUG MARKER: Iteration 12 [12569.459943] LustreError: 381463:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12569.460384] LustreError: 381464:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12569.464836] LustreError: 381463:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [12570.522855] Lustre: Mounted lustre-client [12570.965847] LustreError: 381580:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fc352f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12571.016495] Lustre: Unmounted lustre-client [12571.966729] Key type lgssc unregistered [12572.081400] LNet: 381824:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12572.084286] LNetError: 381824:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12572.092623] LNet: Removed LNI 192.168.204.41@tcp [12572.350175] Key type .llcrypt unregistered [12572.351603] Key type ._llcrypt unregistered [12572.677966] Key type ._llcrypt registered [12572.679438] Key type .llcrypt registered [12572.809335] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12572.814717] alg: No test for adler32 (adler32-zlib) [12573.671012] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12573.750383] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12575.327142] Key type lgssc registered [12575.703405] Lustre: Echo OBD driver; http://www.lustre.org/ [12579.140055] Lustre: DEBUG MARKER: Iteration 13 [12579.231573] LustreError: 382655:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12579.231867] LustreError: 382656:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12579.236156] LustreError: 382655:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [12580.278623] Lustre: Mounted lustre-client [12580.283180] Lustre: Skipped 1 previous similar message [12580.725201] LustreError: 382770:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fc307c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12580.729236] LustreError: 382770:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12580.777747] Lustre: Unmounted lustre-client [12581.796621] Key type lgssc unregistered [12581.915573] LNet: 383014:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12581.918748] LNetError: 383014:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12581.929216] LNet: Removed LNI 192.168.204.41@tcp [12582.189124] Key type .llcrypt unregistered [12582.190874] Key type ._llcrypt unregistered [12582.458429] Key type ._llcrypt registered [12582.460423] Key type .llcrypt registered [12582.637342] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12582.643223] alg: No test for adler32 (adler32-zlib) [12583.504227] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12583.589773] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12585.183150] Key type lgssc registered [12585.542595] Lustre: Echo OBD driver; http://www.lustre.org/ [12589.264132] Lustre: DEBUG MARKER: Iteration 14 [12589.382690] LustreError: 383845:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12589.383268] LustreError: 383846:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12589.388488] LustreError: 383845:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [12590.453865] Lustre: Mounted lustre-client [12590.989502] LustreError: 383960:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fc51d5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12591.065835] Lustre: Unmounted lustre-client [12592.035037] Key type lgssc unregistered [12592.158679] LNet: 384204:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12592.161812] LNetError: 384204:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12592.171323] LNet: Removed LNI 192.168.204.41@tcp [12592.401119] Key type .llcrypt unregistered [12592.402471] Key type ._llcrypt unregistered [12592.656795] Key type ._llcrypt registered [12592.658185] Key type .llcrypt registered [12592.843742] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12592.849362] alg: No test for adler32 (adler32-zlib) [12593.700273] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12593.783174] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12595.367125] Key type lgssc registered [12595.790944] Lustre: Echo OBD driver; http://www.lustre.org/ [12599.255842] Lustre: DEBUG MARKER: Iteration 15 [12599.350261] LustreError: 385035:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12599.350593] LustreError: 385036:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12599.355286] LustreError: 385035:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12600.431820] Lustre: Mounted lustre-client [12600.433133] Lustre: Skipped 1 previous similar message [12600.976744] LustreError: 385143:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fc9ba7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12601.044538] Lustre: Unmounted lustre-client [12601.045705] Lustre: Skipped 1 previous similar message [12602.194268] Key type lgssc unregistered [12602.337425] LNet: 385392:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12602.339892] LNetError: 385392:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12602.348689] LNet: Removed LNI 192.168.204.41@tcp [12602.614101] Key type .llcrypt unregistered [12602.615196] Key type ._llcrypt unregistered [12602.881572] Key type ._llcrypt registered [12602.882602] Key type .llcrypt registered [12603.048306] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12603.053263] alg: No test for adler32 (adler32-zlib) [12603.906106] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12603.988455] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12605.567148] Key type lgssc registered [12605.968157] Lustre: Echo OBD driver; http://www.lustre.org/ [12609.757504] Lustre: DEBUG MARKER: Iteration 16 [12609.901409] LustreError: 386223:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12609.901471] LustreError: 386224:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12609.906936] LustreError: 386223:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12610.964146] Lustre: Mounted lustre-client [12610.965791] Lustre: Skipped 1 previous similar message [12611.401223] LustreError: 386337:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fc932c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12611.449027] Lustre: Unmounted lustre-client [12611.450322] Lustre: Skipped 1 previous similar message [12612.499293] Key type lgssc unregistered [12612.621818] LNet: 386578:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12612.625309] LNetError: 386578:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12612.638815] LNet: Removed LNI 192.168.204.41@tcp [12612.898114] Key type .llcrypt unregistered [12612.899383] Key type ._llcrypt unregistered [12613.185472] Key type ._llcrypt registered [12613.186823] Key type .llcrypt registered [12613.370849] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12613.376246] alg: No test for adler32 (adler32-zlib) [12614.242787] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12614.335261] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12615.919202] Key type lgssc registered [12616.316358] Lustre: Echo OBD driver; http://www.lustre.org/ [12620.001612] Lustre: DEBUG MARKER: Iteration 17 [12620.099659] LustreError: 387409:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12620.100088] LustreError: 387410:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12620.104930] LustreError: 387409:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [12621.161266] Lustre: Mounted lustre-client [12621.609314] LustreError: 387525:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fc3408000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12621.612819] LustreError: 387525:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12621.699124] Lustre: Unmounted lustre-client [12621.700528] Lustre: Skipped 1 previous similar message [12622.702655] Key type lgssc unregistered [12622.842443] LNet: 387768:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12622.844878] LNetError: 387768:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12622.855844] LNet: Removed LNI 192.168.204.41@tcp [12623.104153] Key type .llcrypt unregistered [12623.105672] Key type ._llcrypt unregistered [12623.379430] Key type ._llcrypt registered [12623.381164] Key type .llcrypt registered [12623.554710] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12623.559451] alg: No test for adler32 (adler32-zlib) [12624.415172] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12624.495804] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12626.087181] Key type lgssc registered [12626.520826] Lustre: Echo OBD driver; http://www.lustre.org/ [12630.418471] Lustre: DEBUG MARKER: Iteration 18 [12630.519751] LustreError: 388598:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12630.520235] LustreError: 388600:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12630.524059] LustreError: 388598:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12631.591747] Lustre: Mounted lustre-client [12631.593270] Lustre: Skipped 1 previous similar message [12632.037250] LustreError: 388712:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fe7105800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12632.105093] Lustre: Unmounted lustre-client [12633.164873] Key type lgssc unregistered [12633.284607] LNet: 388956:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12633.287219] LNetError: 388956:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12633.297566] LNet: Removed LNI 192.168.204.41@tcp [12633.547147] Key type .llcrypt unregistered [12633.548465] Key type ._llcrypt unregistered [12633.807828] Key type ._llcrypt registered [12633.809517] Key type .llcrypt registered [12634.030215] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12634.049351] alg: No test for adler32 (adler32-zlib) [12634.910156] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12635.006324] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12636.599159] Key type lgssc registered [12636.992453] Lustre: Echo OBD driver; http://www.lustre.org/ [12640.628255] Lustre: DEBUG MARKER: Iteration 19 [12640.752779] LustreError: 389787:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12640.753142] LustreError: 389786:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12640.758501] LustreError: 389787:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12641.819123] Lustre: Mounted lustre-client [12641.823796] Lustre: Skipped 1 previous similar message [12642.297347] LustreError: 389905:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fe7102800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12642.302855] LustreError: 389905:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12642.349405] Lustre: Unmounted lustre-client [12643.471296] Key type lgssc unregistered [12643.594619] LNet: 390147:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12643.598100] LNetError: 390147:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12643.607641] LNet: Removed LNI 192.168.204.41@tcp [12643.858139] Key type .llcrypt unregistered [12643.859544] Key type ._llcrypt unregistered [12644.176842] Key type ._llcrypt registered [12644.178271] Key type .llcrypt registered [12644.334637] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12644.341552] alg: No test for adler32 (adler32-zlib) [12645.201797] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12645.291449] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12646.879121] Key type lgssc registered [12647.271401] Lustre: Echo OBD driver; http://www.lustre.org/ [12650.877046] Lustre: DEBUG MARKER: Iteration 20 [12650.969298] LustreError: 390978:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12650.969337] LustreError: 390979:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12650.973312] LustreError: 390978:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [12652.022639] Lustre: Mounted lustre-client [12652.023661] Lustre: Skipped 1 previous similar message [12652.421061] LustreError: 391091:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fc2c68000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12652.426105] LustreError: 391091:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12652.477237] Lustre: Unmounted lustre-client [12653.400955] Key type lgssc unregistered [12653.515495] LNet: 391334:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12653.518307] LNetError: 391334:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12653.525619] LNet: Removed LNI 192.168.204.41@tcp [12653.756114] Key type .llcrypt unregistered [12653.757066] Key type ._llcrypt unregistered [12654.024676] Key type ._llcrypt registered [12654.026387] Key type .llcrypt registered [12654.204040] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12654.208358] alg: No test for adler32 (adler32-zlib) [12655.074785] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12655.159858] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12656.751174] Key type lgssc registered [12657.150106] Lustre: Echo OBD driver; http://www.lustre.org/ [12660.801507] Lustre: DEBUG MARKER: Iteration 21 [12660.948492] LustreError: 392166:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12660.948850] LustreError: 392167:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12660.954450] LustreError: 392166:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [12662.020275] Lustre: Mounted lustre-client [12662.536321] LustreError: 392280:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fd1851000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12662.540850] LustreError: 392280:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12662.601479] Lustre: Unmounted lustre-client [12663.590641] Key type lgssc unregistered [12663.707448] LNet: 392524:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12663.710430] LNetError: 392524:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12663.722660] LNet: Removed LNI 192.168.204.41@tcp [12663.959147] Key type .llcrypt unregistered [12663.960843] Key type ._llcrypt unregistered [12664.393858] Key type ._llcrypt registered [12664.395120] Key type .llcrypt registered [12664.465058] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12664.470915] alg: No test for adler32 (adler32-zlib) [12665.326354] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12665.405959] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12666.991157] Key type lgssc registered [12667.370224] Lustre: Echo OBD driver; http://www.lustre.org/ [12671.244093] Lustre: DEBUG MARKER: Iteration 22 [12671.355596] LustreError: 393356:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12671.355664] LustreError: 393357:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12671.361027] LustreError: 393356:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [12672.426046] Lustre: Mounted lustre-client [12672.879291] LustreError: 393473:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f7000b24800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12672.953304] Lustre: Unmounted lustre-client [12672.954517] Lustre: Skipped 1 previous similar message [12673.985960] Key type lgssc unregistered [12674.109797] LNet: 393716:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12674.112608] LNetError: 393716:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12674.123663] LNet: Removed LNI 192.168.204.41@tcp [12674.379140] Key type .llcrypt unregistered [12674.380664] Key type ._llcrypt unregistered [12674.665570] Key type ._llcrypt registered [12674.668852] Key type .llcrypt registered [12674.867591] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12674.873530] alg: No test for adler32 (adler32-zlib) [12675.775833] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12675.865074] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12677.479148] Key type lgssc registered [12677.909511] Lustre: Echo OBD driver; http://www.lustre.org/ [12681.882910] Lustre: DEBUG MARKER: Iteration 23 [12681.979898] LustreError: 394548:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12681.979942] LustreError: 394549:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12681.985815] LustreError: 394548:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12683.043352] Lustre: Mounted lustre-client [12683.577422] LustreError: 394663:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fc703f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12683.627869] Lustre: Unmounted lustre-client [12684.664057] Key type lgssc unregistered [12684.789499] LNet: 394907:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12684.792256] LNetError: 394907:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12684.799696] LNet: Removed LNI 192.168.204.41@tcp [12685.031123] Key type .llcrypt unregistered [12685.032434] Key type ._llcrypt unregistered [12685.304433] Key type ._llcrypt registered [12685.306022] Key type .llcrypt registered [12685.486524] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12685.492675] alg: No test for adler32 (adler32-zlib) [12686.369688] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12686.460457] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12688.055172] Key type lgssc registered [12688.484589] Lustre: Echo OBD driver; http://www.lustre.org/ [12692.499611] Lustre: DEBUG MARKER: Iteration 24 [12692.614078] LustreError: 395738:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12692.614127] LustreError: 395739:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12692.619327] LustreError: 395738:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12693.687981] Lustre: Mounted lustre-client [12694.140253] LustreError: 395851:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f7000b25800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12694.143789] LustreError: 395851:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12694.191542] Lustre: Unmounted lustre-client [12695.166843] Key type lgssc unregistered [12695.279389] LNet: 396095:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12695.282816] LNetError: 396095:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12695.290732] LNet: Removed LNI 192.168.204.41@tcp [12695.534125] Key type .llcrypt unregistered [12695.535504] Key type ._llcrypt unregistered [12695.803976] Key type ._llcrypt registered [12695.808911] Key type .llcrypt registered [12696.002158] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12696.006996] alg: No test for adler32 (adler32-zlib) [12696.868557] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12696.951109] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12698.535141] Key type lgssc registered [12699.011312] Lustre: Echo OBD driver; http://www.lustre.org/ [12702.849326] Lustre: DEBUG MARKER: Iteration 25 [12702.963994] LustreError: 396926:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12702.964052] LustreError: 396927:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12702.969429] LustreError: 396926:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12704.045772] Lustre: Mounted lustre-client [12704.047635] Lustre: Skipped 1 previous similar message [12704.524467] LustreError: 397042:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fd0048800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12704.529244] LustreError: 397042:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12704.589079] Lustre: Unmounted lustre-client [12705.561674] Key type lgssc unregistered [12705.683317] LNet: 397285:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12705.686030] LNetError: 397285:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12705.692831] LNet: Removed LNI 192.168.204.41@tcp [12705.961123] Key type .llcrypt unregistered [12705.962757] Key type ._llcrypt unregistered [12706.232122] Key type ._llcrypt registered [12706.233164] Key type .llcrypt registered [12706.427858] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12706.436043] alg: No test for adler32 (adler32-zlib) [12707.301300] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12707.383902] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12708.967146] Key type lgssc registered [12709.337321] Lustre: Echo OBD driver; http://www.lustre.org/ [12713.067362] Lustre: DEBUG MARKER: Iteration 26 [12713.181644] LustreError: 398117:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12713.182084] LustreError: 398116:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12713.189583] LustreError: 398117:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12714.272793] Lustre: Mounted lustre-client [12714.274027] Lustre: Skipped 1 previous similar message [12714.732414] LustreError: 398232:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fc7ad0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12714.737553] LustreError: 398232:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12714.784081] Lustre: Unmounted lustre-client [12714.785418] Lustre: Skipped 1 previous similar message [12715.791705] Key type lgssc unregistered [12715.913709] LNet: 398477:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12715.916581] LNetError: 398477:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12715.925653] LNet: Removed LNI 192.168.204.41@tcp [12716.169102] Key type .llcrypt unregistered [12716.170203] Key type ._llcrypt unregistered [12716.453564] Key type ._llcrypt registered [12716.454908] Key type .llcrypt registered [12716.629533] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12716.634811] alg: No test for adler32 (adler32-zlib) [12717.514180] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12717.598334] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12719.191167] Key type lgssc registered [12719.648558] Lustre: Echo OBD driver; http://www.lustre.org/ [12723.537716] Lustre: DEBUG MARKER: Iteration 27 [12723.657074] LustreError: 399308:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12723.657152] LustreError: 399309:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12723.662962] LustreError: 399308:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12724.739224] Lustre: Mounted lustre-client [12725.203961] LustreError: 399424:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fffe16800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12725.208299] LustreError: 399424:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12725.263082] Lustre: Unmounted lustre-client [12726.304850] Key type lgssc unregistered [12726.429267] LNet: 399668:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12726.432364] LNetError: 399668:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12726.443216] LNet: Removed LNI 192.168.204.41@tcp [12726.711146] Key type .llcrypt unregistered [12726.712575] Key type ._llcrypt unregistered [12727.020499] Key type ._llcrypt registered [12727.022603] Key type .llcrypt registered [12727.217681] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12727.223882] alg: No test for adler32 (adler32-zlib) [12728.093905] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12728.180613] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12729.768223] Key type lgssc registered [12730.274209] Lustre: Echo OBD driver; http://www.lustre.org/ [12734.276648] Lustre: DEBUG MARKER: Iteration 28 [12734.397135] LustreError: 400500:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12734.400089] LustreError: 400501:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12734.405029] LustreError: 400500:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [12735.493101] Lustre: Mounted lustre-client [12735.494547] Lustre: Skipped 1 previous similar message [12735.930189] LustreError: 400615:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fd0a58000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12736.062416] Lustre: Unmounted lustre-client [12737.081767] Key type lgssc unregistered [12737.202803] LNet: 400858:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12737.207151] LNetError: 400858:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12737.216833] LNet: Removed LNI 192.168.204.41@tcp [12737.495191] Key type .llcrypt unregistered [12737.498048] Key type ._llcrypt unregistered [12737.732364] Key type ._llcrypt registered [12737.734243] Key type .llcrypt registered [12737.919461] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12737.935324] alg: No test for adler32 (adler32-zlib) [12738.811323] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12738.915062] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12740.519165] Key type lgssc registered [12740.954562] Lustre: Echo OBD driver; http://www.lustre.org/ [12744.652556] Lustre: DEBUG MARKER: Iteration 29 [12744.763393] LustreError: 401690:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12744.763443] LustreError: 401691:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12744.769943] LustreError: 401690:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12745.848453] Lustre: Mounted lustre-client [12745.850271] Lustre: Skipped 1 previous similar message [12746.349849] LustreError: 401806:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fd1852800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12746.354794] LustreError: 401806:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12746.392308] Lustre: Unmounted lustre-client [12747.408502] Key type lgssc unregistered [12747.534397] LNet: 402049:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12747.537217] LNetError: 402049:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12747.546593] LNet: Removed LNI 192.168.204.41@tcp [12747.787153] Key type .llcrypt unregistered [12747.788483] Key type ._llcrypt unregistered [12748.092507] Key type ._llcrypt registered [12748.093849] Key type .llcrypt registered [12748.317261] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12748.324091] alg: No test for adler32 (adler32-zlib) [12749.192423] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12749.282077] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12750.871134] Key type lgssc registered [12751.239204] Lustre: Echo OBD driver; http://www.lustre.org/ [12754.857420] Lustre: DEBUG MARKER: Iteration 30 [12754.955824] LustreError: 402881:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12754.957091] LustreError: 402882:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12754.961041] LustreError: 402881:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12756.016131] Lustre: Mounted lustre-client [12756.508407] LustreError: 402993:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fc2d69800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12756.565155] Lustre: Unmounted lustre-client [12757.543895] Key type lgssc unregistered [12757.656538] LNet: 403236:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12757.659488] LNetError: 403236:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12757.671814] LNet: Removed LNI 192.168.204.41@tcp [12757.915107] Key type .llcrypt unregistered [12757.916528] Key type ._llcrypt unregistered [12758.199725] Key type ._llcrypt registered [12758.202471] Key type .llcrypt registered [12758.383699] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12758.389492] alg: No test for adler32 (adler32-zlib) [12759.267628] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12759.349102] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12760.935141] Key type lgssc registered [12761.303941] Lustre: Echo OBD driver; http://www.lustre.org/ [12764.823054] Lustre: DEBUG MARKER: Iteration 31 [12764.935407] LustreError: 404067:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12764.935521] LustreError: 404068:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12764.940751] LustreError: 404067:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [12765.999126] Lustre: Mounted lustre-client [12766.440041] LustreError: 404182:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fd1856800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12766.444105] LustreError: 404182:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12766.491049] Lustre: Unmounted lustre-client [12767.415320] Key type lgssc unregistered [12767.529494] LNet: 404426:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12767.532982] LNetError: 404426:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12767.542783] LNet: Removed LNI 192.168.204.41@tcp [12767.775136] Key type .llcrypt unregistered [12767.776718] Key type ._llcrypt unregistered [12768.075060] Key type ._llcrypt registered [12768.076446] Key type .llcrypt registered [12768.245732] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12768.251016] alg: No test for adler32 (adler32-zlib) [12769.113335] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12769.203573] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12770.791277] Key type lgssc registered [12771.209612] Lustre: Echo OBD driver; http://www.lustre.org/ [12774.824871] Lustre: DEBUG MARKER: Iteration 32 [12774.935753] LustreError: 405257:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12774.936033] LustreError: 405258:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12774.941728] LustreError: 405257:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12776.015582] Lustre: Mounted lustre-client [12776.017042] Lustre: Skipped 1 previous similar message [12776.467842] LustreError: 405372:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6ffc6a7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12776.472506] LustreError: 405372:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12776.524653] Lustre: Unmounted lustre-client [12776.525808] Lustre: Skipped 1 previous similar message [12777.535583] Key type lgssc unregistered [12777.653437] LNet: 405616:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12777.656102] LNetError: 405616:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12777.667176] LNet: Removed LNI 192.168.204.41@tcp [12777.902128] Key type .llcrypt unregistered [12777.903547] Key type ._llcrypt unregistered [12778.208268] Key type ._llcrypt registered [12778.209680] Key type .llcrypt registered [12778.467336] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12778.472657] alg: No test for adler32 (adler32-zlib) [12779.350440] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12779.438897] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12781.031130] Key type lgssc registered [12781.417196] Lustre: Echo OBD driver; http://www.lustre.org/ [12785.107358] Lustre: DEBUG MARKER: Iteration 33 [12785.204769] LustreError: 406447:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12785.205856] LustreError: 406448:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12785.210349] LustreError: 406447:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12786.286069] Lustre: Mounted lustre-client [12786.288570] Lustre: Skipped 1 previous similar message [12786.727103] LustreError: 406561:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6ffc6af000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12786.731570] LustreError: 406561:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12786.793318] Lustre: Unmounted lustre-client [12787.755216] Key type lgssc unregistered [12787.870459] LNet: 406804:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12787.873178] LNetError: 406804:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12787.885674] LNet: Removed LNI 192.168.204.41@tcp [12788.107106] Key type .llcrypt unregistered [12788.108234] Key type ._llcrypt unregistered [12788.378172] Key type ._llcrypt registered [12788.380250] Key type .llcrypt registered [12788.571768] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12788.576796] alg: No test for adler32 (adler32-zlib) [12789.455624] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12789.544150] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12791.135138] Key type lgssc registered [12791.555357] Lustre: Echo OBD driver; http://www.lustre.org/ [12795.302869] Lustre: DEBUG MARKER: Iteration 34 [12795.407663] LustreError: 407635:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12795.408826] LustreError: 407636:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12795.412943] LustreError: 407635:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [12796.481950] Lustre: Mounted lustre-client [12796.482912] Lustre: Skipped 1 previous similar message [12796.905315] LustreError: 407752:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fc3423800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12796.910362] LustreError: 407752:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12796.966788] Lustre: Unmounted lustre-client [12797.947876] Key type lgssc unregistered [12798.069352] LNet: 407997:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12798.071638] LNetError: 407997:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12798.079646] LNet: Removed LNI 192.168.204.41@tcp [12798.309113] Key type .llcrypt unregistered [12798.310404] Key type ._llcrypt unregistered [12798.600267] Key type ._llcrypt registered [12798.601639] Key type .llcrypt registered [12798.828028] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12798.833643] alg: No test for adler32 (adler32-zlib) [12799.697373] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12799.786265] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12801.375209] Key type lgssc registered [12801.822669] Lustre: Echo OBD driver; http://www.lustre.org/ [12805.495254] Lustre: DEBUG MARKER: Iteration 35 [12805.587424] LustreError: 408829:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12805.590674] LustreError: 408828:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12805.593920] LustreError: 408829:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=5000 [12806.646352] Lustre: Mounted lustre-client [12806.647789] Lustre: Skipped 1 previous similar message [12807.095530] LustreError: 408940:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6ffc6a1800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12807.100119] LustreError: 408940:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12807.183116] Lustre: Unmounted lustre-client [12808.159063] Key type lgssc unregistered [12808.274378] LNet: 409184:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12808.277027] LNetError: 409184:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12808.283454] LNet: Removed LNI 192.168.204.41@tcp [12808.525142] Key type .llcrypt unregistered [12808.526397] Key type ._llcrypt unregistered [12808.818530] Key type ._llcrypt registered [12808.819907] Key type .llcrypt registered [12809.073218] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12809.078964] alg: No test for adler32 (adler32-zlib) [12809.959827] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12810.052792] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12811.639174] Key type lgssc registered [12812.111130] Lustre: Echo OBD driver; http://www.lustre.org/ [12816.065276] Lustre: DEBUG MARKER: Iteration 36 [12816.175951] LustreError: 410016:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12816.176050] LustreError: 410015:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12816.181252] LustreError: 410016:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12817.242121] Lustre: Mounted lustre-client [12817.243322] Lustre: Skipped 1 previous similar message [12817.716988] LustreError: 410125:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fc2c6f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12817.722182] LustreError: 410125:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12817.775395] Lustre: Unmounted lustre-client [12818.793052] Key type lgssc unregistered [12818.908454] LNet: 410368:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12818.912486] LNetError: 410368:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12818.922743] LNet: Removed LNI 192.168.204.41@tcp [12819.189144] Key type .llcrypt unregistered [12819.190535] Key type ._llcrypt unregistered [12819.501449] Key type ._llcrypt registered [12819.502669] Key type .llcrypt registered [12819.691201] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12819.697038] alg: No test for adler32 (adler32-zlib) [12820.571558] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12820.667778] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12822.255153] Key type lgssc registered [12822.714949] Lustre: Echo OBD driver; http://www.lustre.org/ [12826.812975] Lustre: DEBUG MARKER: Iteration 37 [12826.928191] LustreError: 411199:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12826.928227] LustreError: 411200:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12826.932956] LustreError: 411199:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [12828.011562] Lustre: Mounted lustre-client [12828.509091] LustreError: 411312:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fd1857000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12828.513105] LustreError: 411312:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12828.567053] Lustre: Unmounted lustre-client [12829.528023] Key type lgssc unregistered [12829.655290] LNet: 411554:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12829.658559] LNetError: 411554:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12829.666994] LNet: Removed LNI 192.168.204.41@tcp [12829.929123] Key type .llcrypt unregistered [12829.930454] Key type ._llcrypt unregistered [12830.272833] Key type ._llcrypt registered [12830.275970] Key type .llcrypt registered [12830.530517] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12830.536942] alg: No test for adler32 (adler32-zlib) [12831.411165] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12831.502650] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12833.103195] Key type lgssc registered [12833.506486] Lustre: Echo OBD driver; http://www.lustre.org/ [12837.297779] Lustre: DEBUG MARKER: Iteration 38 [12837.401841] LustreError: 412386:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12837.403217] LustreError: 412387:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12837.407548] LustreError: 412386:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12838.465225] Lustre: Mounted lustre-client [12838.466719] Lustre: Skipped 1 previous similar message [12838.968499] LustreError: 412502:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fc2d3a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12838.972761] LustreError: 412502:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12839.063043] Lustre: Unmounted lustre-client [12840.244294] Key type lgssc unregistered [12840.368642] LNet: 412747:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12840.371491] LNetError: 412747:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12840.380844] LNet: Removed LNI 192.168.204.41@tcp [12840.643232] Key type .llcrypt unregistered [12840.644664] Key type ._llcrypt unregistered [12840.951559] Key type ._llcrypt registered [12840.956669] Key type .llcrypt registered [12841.202454] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12841.209224] alg: No test for adler32 (adler32-zlib) [12842.086729] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12842.184324] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12843.783159] Key type lgssc registered [12844.238221] Lustre: Echo OBD driver; http://www.lustre.org/ [12848.550275] Lustre: DEBUG MARKER: Iteration 39 [12848.652624] LustreError: 413578:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12848.652767] LustreError: 413580:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12848.659159] LustreError: 413578:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [12849.736615] Lustre: Mounted lustre-client [12850.220661] LustreError: 413693:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fc357a000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12850.224887] LustreError: 413693:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12850.291197] Lustre: Unmounted lustre-client [12850.296310] Lustre: Skipped 1 previous similar message [12851.379789] Key type lgssc unregistered [12851.506632] LNet: 413938:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12851.509714] LNetError: 413938:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12851.526941] LNet: Removed LNI 192.168.204.41@tcp [12851.789153] Key type .llcrypt unregistered [12851.790501] Key type ._llcrypt unregistered [12852.121788] Key type ._llcrypt registered [12852.123048] Key type .llcrypt registered [12852.389873] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12852.396505] alg: No test for adler32 (adler32-zlib) [12853.262929] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12853.350175] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12854.935125] Key type lgssc registered [12855.388044] Lustre: Echo OBD driver; http://www.lustre.org/ [12859.083648] Lustre: DEBUG MARKER: Iteration 40 [12859.177787] LustreError: 414769:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12859.179208] LustreError: 414770:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12859.183224] LustreError: 414769:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12860.241181] Lustre: Mounted lustre-client [12860.742969] LustreError: 414886:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fffe11000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12860.746873] LustreError: 414886:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12860.796125] Lustre: Unmounted lustre-client [12861.810501] Key type lgssc unregistered [12861.927516] LNet: 415129:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12861.930557] LNetError: 415129:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12861.938676] LNet: Removed LNI 192.168.204.41@tcp [12862.177131] Key type .llcrypt unregistered [12862.178566] Key type ._llcrypt unregistered [12862.580664] Key type ._llcrypt registered [12862.581929] Key type .llcrypt registered [12862.668755] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12862.675601] alg: No test for adler32 (adler32-zlib) [12863.547412] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12863.646128] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12865.231167] Key type lgssc registered [12865.688702] Lustre: Echo OBD driver; http://www.lustre.org/ [12869.265984] Lustre: DEBUG MARKER: Iteration 41 [12869.368446] LustreError: 415962:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12869.369285] LustreError: 415963:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12869.374988] LustreError: 415962:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [12870.427275] Lustre: Mounted lustre-client [12871.001379] LustreError: 416070:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fc4e2b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12871.004677] LustreError: 416070:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12871.066841] Lustre: Unmounted lustre-client [12871.067981] Lustre: Skipped 1 previous similar message [12871.997386] Key type lgssc unregistered [12872.120815] LNet: 416313:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12872.123811] LNetError: 416313:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12872.135644] LNet: Removed LNI 192.168.204.41@tcp [12872.373103] Key type .llcrypt unregistered [12872.374102] Key type ._llcrypt unregistered [12872.649163] Key type ._llcrypt registered [12872.650550] Key type .llcrypt registered [12872.825080] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12872.830505] alg: No test for adler32 (adler32-zlib) [12873.695244] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12873.779654] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12875.367158] Key type lgssc registered [12875.818945] Lustre: Echo OBD driver; http://www.lustre.org/ [12879.596959] Lustre: DEBUG MARKER: Iteration 42 [12879.700852] LustreError: 417144:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12879.700899] LustreError: 417145:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12879.707089] LustreError: 417144:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12880.767275] Lustre: Mounted lustre-client [12880.768845] Lustre: Skipped 1 previous similar message [12881.263055] LustreError: 417252:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fc51d4800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12881.268187] LustreError: 417252:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12881.320413] Lustre: Unmounted lustre-client [12882.356843] Key type lgssc unregistered [12882.482525] LNet: 417496:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12882.485212] LNetError: 417496:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12882.497652] LNet: Removed LNI 192.168.204.41@tcp [12882.754127] Key type .llcrypt unregistered [12882.755264] Key type ._llcrypt unregistered [12883.073468] Key type ._llcrypt registered [12883.074832] Key type .llcrypt registered [12883.300614] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12883.306904] alg: No test for adler32 (adler32-zlib) [12884.187459] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12884.285657] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12885.879225] Key type lgssc registered [12886.329561] Lustre: Echo OBD driver; http://www.lustre.org/ [12890.111951] Lustre: DEBUG MARKER: Iteration 43 [12890.225420] LustreError: 418328:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12890.225468] LustreError: 418327:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12890.231593] LustreError: 418328:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12891.307803] Lustre: Mounted lustre-client [12891.759071] LustreError: 418443:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fc51d6000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12891.764121] LustreError: 418443:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12891.834112] Lustre: Unmounted lustre-client [12892.883365] Key type lgssc unregistered [12893.009413] LNet: 418687:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12893.012381] LNetError: 418687:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12893.022519] LNet: Removed LNI 192.168.204.41@tcp [12893.278148] Key type .llcrypt unregistered [12893.279478] Key type ._llcrypt unregistered [12893.571401] Key type ._llcrypt registered [12893.572633] Key type .llcrypt registered [12893.753908] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12893.759745] alg: No test for adler32 (adler32-zlib) [12894.636643] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12894.735574] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12896.319204] Key type lgssc registered [12896.767567] Lustre: Echo OBD driver; http://www.lustre.org/ [12900.589394] Lustre: DEBUG MARKER: Iteration 44 [12900.708839] LustreError: 419518:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12900.709127] LustreError: 419519:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12900.713771] LustreError: 419518:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [12901.772074] Lustre: Mounted lustre-client [12902.211269] LustreError: 419632:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fdfeda800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12902.216510] LustreError: 419632:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12902.297594] Lustre: Unmounted lustre-client [12903.395195] Key type lgssc unregistered [12903.521500] LNet: 419877:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12903.524677] LNetError: 419877:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12903.534722] LNet: Removed LNI 192.168.204.41@tcp [12903.806113] Key type .llcrypt unregistered [12903.807404] Key type ._llcrypt unregistered [12904.089209] Key type ._llcrypt registered [12904.090640] Key type .llcrypt registered [12904.275908] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12904.281818] alg: No test for adler32 (adler32-zlib) [12905.145152] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12905.233220] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12906.815168] Key type lgssc registered [12907.238731] Lustre: Echo OBD driver; http://www.lustre.org/ [12911.116750] Lustre: DEBUG MARKER: Iteration 45 [12911.227964] LustreError: 420708:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12911.228047] LustreError: 420709:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12911.233815] LustreError: 420708:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12912.305551] Lustre: Mounted lustre-client [12912.307483] Lustre: Skipped 1 previous similar message [12912.754857] LustreError: 420822:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fe6a7d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12912.831491] Lustre: Unmounted lustre-client [12913.923709] Key type lgssc unregistered [12914.044451] LNet: 421065:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12914.047525] LNetError: 421065:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12914.056920] LNet: Removed LNI 192.168.204.41@tcp [12914.305672] Key type .llcrypt unregistered [12914.306918] Key type ._llcrypt unregistered [12914.615837] Key type ._llcrypt registered [12914.617206] Key type .llcrypt registered [12914.802468] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12914.808740] alg: No test for adler32 (adler32-zlib) [12915.694138] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12915.786642] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12917.383197] Key type lgssc registered [12917.788377] Lustre: Echo OBD driver; http://www.lustre.org/ [12921.668501] Lustre: DEBUG MARKER: Iteration 46 [12921.765488] LustreError: 421896:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12921.765527] LustreError: 421897:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12921.771406] LustreError: 421896:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [12922.831459] Lustre: Mounted lustre-client [12923.298620] LustreError: 422011:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fcb9b9000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12923.303448] LustreError: 422011:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12923.370130] Lustre: Unmounted lustre-client [12923.371231] Lustre: Skipped 1 previous similar message [12924.369074] Key type lgssc unregistered [12924.491691] LNet: 422255:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12924.494723] LNetError: 422255:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12924.503736] LNet: Removed LNI 192.168.204.41@tcp [12924.765142] Key type .llcrypt unregistered [12924.766670] Key type ._llcrypt unregistered [12925.090228] Key type ._llcrypt registered [12925.091620] Key type .llcrypt registered [12925.223630] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12925.228303] alg: No test for adler32 (adler32-zlib) [12926.108074] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12926.192240] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12927.775161] Key type lgssc registered [12928.180355] Lustre: Echo OBD driver; http://www.lustre.org/ [12932.051148] Lustre: DEBUG MARKER: Iteration 47 [12932.159369] LustreError: 423087:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12932.160497] LustreError: 423086:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12932.165975] LustreError: 423087:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12933.242993] Lustre: Mounted lustre-client [12933.702946] LustreError: 423202:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fc703e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12933.707232] LustreError: 423202:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12933.749184] Lustre: Unmounted lustre-client [12934.731476] Key type lgssc unregistered [12934.849481] LNet: 423445:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12934.852069] LNetError: 423445:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12934.859453] LNet: Removed LNI 192.168.204.41@tcp [12935.103129] Key type .llcrypt unregistered [12935.104434] Key type ._llcrypt unregistered [12935.382483] Key type ._llcrypt registered [12935.383587] Key type .llcrypt registered [12935.556571] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12935.561407] alg: No test for adler32 (adler32-zlib) [12936.418494] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12936.497618] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12938.079203] Key type lgssc registered [12938.461081] Lustre: Echo OBD driver; http://www.lustre.org/ [12942.332593] Lustre: DEBUG MARKER: Iteration 48 [12942.455023] LustreError: 424277:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12942.455084] LustreError: 424278:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12942.461941] LustreError: 424277:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12943.532340] Lustre: Mounted lustre-client [12944.071190] LustreError: 424392:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fd0187800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12944.074511] LustreError: 424392:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12944.150822] Lustre: Unmounted lustre-client [12945.202636] Key type lgssc unregistered [12945.335477] LNet: 424637:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12945.338112] LNetError: 424637:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12945.345941] LNet: Removed LNI 192.168.204.41@tcp [12945.611254] Key type .llcrypt unregistered [12945.612449] Key type ._llcrypt unregistered [12945.932100] Key type ._llcrypt registered [12945.933659] Key type .llcrypt registered [12946.163980] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12946.171443] alg: No test for adler32 (adler32-zlib) [12947.044057] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12947.138603] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12948.735195] Key type lgssc registered [12949.138440] Lustre: Echo OBD driver; http://www.lustre.org/ [12953.007073] Lustre: DEBUG MARKER: Iteration 49 [12953.133518] LustreError: 425468:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12953.133554] LustreError: 425469:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12953.138748] LustreError: 425468:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12954.207775] Lustre: Mounted lustre-client [12954.657059] LustreError: 425584:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fc721e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12954.661353] LustreError: 425584:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12954.708404] Lustre: Unmounted lustre-client [12955.739688] Key type lgssc unregistered [12955.869524] LNet: 425828:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12955.872981] LNetError: 425828:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12955.884915] LNet: Removed LNI 192.168.204.41@tcp [12956.172129] Key type .llcrypt unregistered [12956.173554] Key type ._llcrypt unregistered [12956.416714] Key type ._llcrypt registered [12956.418230] Key type .llcrypt registered [12956.713397] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12956.731498] alg: No test for adler32 (adler32-zlib) [12957.608996] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12957.726898] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12959.311190] Key type lgssc registered [12959.741093] Lustre: Echo OBD driver; http://www.lustre.org/ [12963.869971] Lustre: DEBUG MARKER: Iteration 50 [12963.984488] LustreError: 426658:0:(llite_lib.c:1377:ll_fill_super()) cfs_race id 1417 sleeping [12963.985707] LustreError: 426659:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 waking [12963.990948] LustreError: 426658:0:(llite_lib.c:1377:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [12965.066779] Lustre: Mounted lustre-client [12965.068014] Lustre: Skipped 1 previous similar message [12965.509281] LustreError: 426774:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fd004c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [12965.513338] LustreError: 426774:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [12965.580173] Lustre: Unmounted lustre-client [12966.603181] Key type lgssc unregistered [12966.731479] LNet: 427017:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [12966.734246] LNetError: 427017:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [12966.743637] LNet: Removed LNI 192.168.204.41@tcp [12967.003101] Key type .llcrypt unregistered [12967.004266] Key type ._llcrypt unregistered [12967.321176] Key type ._llcrypt registered [12967.322891] Key type .llcrypt registered [12967.486986] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [12967.493385] alg: No test for adler32 (adler32-zlib) [12968.354164] Lustre: Lustre: Build Version: 2.17.51_1_gce01c51 [12968.441308] LNet: Added LNI 192.168.204.41@tcp [8/256/0/180] [12970.031153] Key type lgssc registered [12970.459027] Lustre: Echo OBD driver; http://www.lustre.org/ [12975.317754] Lustre: Mounted lustre-client [12977.963419] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 20:30:17 (1773621017) [12994.527171] Lustre: 428376:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773621018/real 1773621018] req@ffff8f6ff8aa0000 x1859776422094720/t0(0) o36->lustre-MDT0000-mdc-ffff8f6fc81e3000@192.168.204.141@tcp:12/10 lens 496/440 e 0 to 1 dl 1773621034 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [12994.535953] Lustre: lustre-MDT0000-mdc-ffff8f6fc81e3000: Connection to lustre-MDT0000 (at 192.168.204.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [12994.546632] Lustre: lustre-MDT0000-mdc-ffff8f6fc81e3000: Connection restored to 192.168.204.141@tcp (at 192.168.204.141@tcp) [13010.911154] Lustre: 428376:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773621035/real 1773621035] req@ffff8f6ff8aa0000 x1859776422094720/t0(0) o36->lustre-MDT0000-mdc-ffff8f6fc81e3000@192.168.204.141@tcp:12/10 lens 496/440 e 0 to 1 dl 1773621051 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [13010.920858] Lustre: lustre-MDT0000-mdc-ffff8f6fc81e3000: Connection to lustre-MDT0000 (at 192.168.204.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [13010.930634] Lustre: lustre-MDT0000-mdc-ffff8f6fc81e3000: Connection restored to 192.168.204.141@tcp (at 192.168.204.141@tcp) [13027.295152] Lustre: 428376:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773621051/real 1773621051] req@ffff8f6ff8aa0000 x1859776422094720/t0(0) o36->lustre-MDT0000-mdc-ffff8f6fc81e3000@192.168.204.141@tcp:12/10 lens 496/440 e 0 to 1 dl 1773621067 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [13027.304830] Lustre: lustre-MDT0000-mdc-ffff8f6fc81e3000: Connection to lustre-MDT0000 (at 192.168.204.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [13027.317287] Lustre: lustre-MDT0000-mdc-ffff8f6fc81e3000: Connection restored to 192.168.204.141@tcp (at 192.168.204.141@tcp) [13042.655198] Lustre: 428376:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773621067/real 1773621067] req@ffff8f6ff8aa0000 x1859776422094720/t0(0) o36->lustre-MDT0000-mdc-ffff8f6fc81e3000@192.168.204.141@tcp:12/10 lens 496/440 e 0 to 1 dl 1773621083 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [13042.669564] Lustre: lustre-MDT0000-mdc-ffff8f6fc81e3000: Connection to lustre-MDT0000 (at 192.168.204.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [13042.681745] Lustre: lustre-MDT0000-mdc-ffff8f6fc81e3000: Connection restored to 192.168.204.141@tcp (at 192.168.204.141@tcp) [13043.285391] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 20:31:23 (1773621083) [13049.033772] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 20:31:29 (1773621089) [13052.418190] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 20:31:32 (1773621092) [13054.667977] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 20:31:34 (1773621094) [13062.431550] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 20:31:42 (1773621102) [13075.174949] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 20:31:55 (1773621115) [13077.631480] Lustre: DEBUG MARKER: == sanityn test 121: trunc append race =================== 20:31:57 (1773621117) [13077.732675] LustreError: 433148:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 sleeping for 2000ms [13079.815165] LustreError: 433148:0:(lcommon_cl.c:99:cl_setattr_ost()) cfs_fail_timeout id 1436 awake [13082.038465] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 20:32:02 (1773621122) [13099.999151] Lustre: 427211:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773621124/real 1773621124] req@ffff8f7000b17100 x1859776423132672/t0(0) o4->lustre-OST0000-osc-ffff8f6fc81e3000@192.168.204.141@tcp:6/4 lens 4584/448 e 0 to 1 dl 1773621140 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [13099.999190] Lustre: lustre-OST0000-osc-ffff8f6fc81e3000: Connection to lustre-OST0000 (at 192.168.204.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [13100.006946] Lustre: 427211:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [13100.017570] Lustre: lustre-OST0000-osc-ffff8f6fc81e3000: Connection restored to 192.168.204.141@tcp (at 192.168.204.141@tcp) [13116.383172] Lustre: 427212:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773621140/real 1773621140] req@ffff8f7000b14000 x1859776423133440/t0(0) o4->lustre-OST0000-osc-ffff8f6fc81e3000@192.168.204.141@tcp:6/4 lens 4584/448 e 0 to 1 dl 1773621156 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [13116.383204] Lustre: lustre-OST0000-osc-ffff8f6fc81e3000: Connection to lustre-OST0000 (at 192.168.204.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [13116.391538] Lustre: 427212:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 4 previous similar messages [13116.403020] Lustre: lustre-OST0000-osc-ffff8f6fc81e3000: Connection restored to 192.168.204.141@tcp (at 192.168.204.141@tcp) [13148.127182] Lustre: 427210:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773621172/real 1773621172] req@ffff8f70004cc380 x1859776423132288/t0(0) o4->lustre-OST0000-osc-ffff8f6fc81e3000@192.168.204.141@tcp:6/4 lens 4584/448 e 0 to 1 dl 1773621188 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [13148.127182] Lustre: 427209:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1773621172/real 1773621172] req@ffff8f6ffe1ad180 x1859776423133056/t0(0) o4->lustre-OST0000-osc-ffff8f6fc81e3000@192.168.204.141@tcp:6/4 lens 4584/448 e 0 to 1 dl 1773621188 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [13148.127203] Lustre: 427210:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 6 previous similar messages [13148.136767] Lustre: 427209:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 6 previous similar messages [13148.136822] Lustre: lustre-OST0000-osc-ffff8f6fc81e3000: Connection to lustre-OST0000 (at 192.168.204.141@tcp) was lost; in progress operations using this service will wait for recovery to complete [13148.156014] Lustre: Skipped 1 previous similar message [13148.165690] Lustre: lustre-OST0000-osc-ffff8f6fc81e3000: Connection restored to 192.168.204.141@tcp (at 192.168.204.141@tcp) [13148.169086] Lustre: Skipped 1 previous similar message [13171.711371] Lustre: DEBUG MARKER: oleg441-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff8f6fc703f000.ost_server_uuid 50 [13172.243271] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8f6fc703f000.ost_server_uuid in IDLE state after 0 sec [13172.837245] Lustre: DEBUG MARKER: cleanup: ====================================================== [13173.504716] Lustre: DEBUG MARKER: == sanityn test complete, duration 12865 sec ============= 20:33:33 (1773621213) [13174.160736] Lustre: DEBUG MARKER: === sanityn: start cleanup 20:33:34 (1773621214) === [13255.000744] LustreError: 435201:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fc703f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13255.022801] Lustre: Unmounted lustre-client [13256.323541] Lustre: DEBUG MARKER: === sanityn: finish cleanup 20:34:56 (1773621296) === [13256.649476] LustreError: 435504:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff8f6fc81e3000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [13256.652698] LustreError: 435504:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [13256.685161] Lustre: Unmounted lustre-client [13293.847302] Key type lgssc unregistered [13293.977370] LNet: 436189:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [13293.981952] LNetError: 436189:0:(acceptor.c:252:lnet_acceptor_remove_socket()) Interface ens2 not found [13293.991387] LNet: Removed LNI 192.168.204.41@tcp [13294.244138] Key type .llcrypt unregistered [13294.245572] Key type ._llcrypt unregistered