[ 0.000000] Linux version 4.18.0rh8.10-debug (green@maintenance) (gcc version 8.5.0 20210514 (Red Hat 8.5.0-26) (GCC)) #2 SMP Mon Jul 14 01:24:22 EDT 2025 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:rocky8.10:ext4:ro:-p,-b4096 ro crashkernel=256M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' [ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' [ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 [ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. [ 0.000000] signal: max sigframe size: 1776 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000bffd9fff] usable [ 0.000000] BIOS-e820: [mem 0x00000000bffda000-0x00000000bfffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x0000000146dfffff] usable [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.2-1.fc38 04/01/2014 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 455007693 cycles [ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.000000] last_pfn = 0x146e00 max_arch_pfn = 0x400000000 [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.000000] last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f5b50-0x000f5b5f] [ 0.000000] RAMDISK: [mem 0xbcc64000-0xbffcffff] [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x00000000000F5970 000014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 0x00000000BFFE1BB7 000034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 0x00000000BFFE1A53 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x00000000BFFE0040 001A13 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 0x00000000BFFE0000 000040 [ 0.000000] ACPI: APIC 0x00000000BFFE1AC7 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 0x00000000BFFE1B57 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 0x00000000BFFE1B8F 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: Reserving FACP table memory at [mem 0xbffe1a53-0xbffe1ac6] [ 0.000000] ACPI: Reserving DSDT table memory at [mem 0xbffe0040-0xbffe1a52] [ 0.000000] ACPI: Reserving FACS table memory at [mem 0xbffe0000-0xbffe003f] [ 0.000000] ACPI: Reserving APIC table memory at [mem 0xbffe1ac7-0xbffe1b56] [ 0.000000] ACPI: Reserving HPET table memory at [mem 0xbffe1b57-0xbffe1b8e] [ 0.000000] ACPI: Reserving WAET table memory at [mem 0xbffe1b8f-0xbffe1bb6] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x0000000146dfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x1465a3000-0x1465cdfff] [ 0.000000] Reserving 256MB of memory at 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: 2895288K/4306400K available (18435K kernel code, 11221K rwdata, 7248K rodata, 2908K init, 18040K bss, 524580K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] ftrace: allocating 41240 entries in 162 pages [ 0.000000] ftrace: allocated 162 pages with 3 groups [ 0.000000] rcu: Hierarchical RCU implementation. [ 0.000000] rcu: RCU event tracing is enabled. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.000000] rcu: RCU callback double-/use-after-free debug enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.000000] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.000000] random: get_random_bytes called from start_kernel+0x622/0x9a8 with crng_init=0 [ 0.001000] Console: colour *CGA 80x25 [ 0.001000] printk: console [ttyS1] enabled [ 0.001000] ACPI: Core revision 20220331 [ 0.001000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.001011] APIC: Switch to symmetric I/O mode setup [ 0.002362] x2apic enabled [ 0.003007] Switched APIC routing to physical x2apic. [ 0.004014] kvm-guest: setup PV IPIs [ 0.007776] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.008000] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 0.008023] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.009014] pid_max: default: 32768 minimum: 301 [ 0.010183] LSM: Security Framework initializing [ 0.012029] Yama: becoming mindful. [ 0.013054] SELinux: Initializing. [ 0.014108] *** VALIDATE selinux *** [ 0.023128] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, vmalloc) [ 0.028700] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, vmalloc) [ 0.029179] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.030126] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, vmalloc) [ 0.031112] *** VALIDATE tmpfs *** [ 0.033407] *** VALIDATE proc *** [ 0.034240] *** VALIDATE cgroup *** [ 0.035013] *** VALIDATE cgroup2 *** [ 0.036281] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.037154] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.038012] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.039034] Spectre V2 : User space: Vulnerable [ 0.040009] Speculative Store Bypass: Vulnerable [ 0.042798] debug: unmapping init [mem 0xffffffff87459000-0xffffffff87460fff] [ 0.044881] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (family: 0x6, model: 0x3e, stepping: 0x4) [ 0.045693] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.046027] ... version: 2 [ 0.047013] ... bit width: 48 [ 0.048008] ... generic registers: 4 [ 0.049014] ... value mask: 0000ffffffffffff [ 0.050013] ... max period: 00007fffffffffff [ 0.051015] ... fixed-purpose events: 3 [ 0.052011] ... event mask: 000000070000000f [ 0.053330] rcu: Hierarchical SRCU implementation. [ 0.055497] smp: Bringing up secondary CPUs ... [ 0.056608] x86: Booting SMP configuration: [ 0.057026] .... node #0, CPUs: #1 #2 #3 [ 0.065035] smp: Brought up 1 node, 4 CPUs [ 0.067013] smpboot: Max logical packages: 1 [ 0.068014] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.131727] node 0 deferred pages initialised in 62ms [ 0.136008] devtmpfs: initialized [ 0.137217] x86/mm: Memory block size: 128MB [ 0.139843] gcov: version magic: 0x41383552 [ 0.140648] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns [ 0.144081] futex hash table entries: 1024 (order: 4, 65536 bytes, vmalloc) [ 0.146314] pinctrl core: initialized pinctrl subsystem [ 0.149181] [ 0.149797] ************************************************************* [ 0.151011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.154012] ** ** [ 0.156012] ** IOMMU DebugFS SUPPORT HAS BEEN ENABLED IN THIS KERNEL ** [ 0.159014] ** ** [ 0.161012] ** This means that this kernel is built to expose internal ** [ 0.163013] ** IOMMU data structures, which may compromise security on ** [ 0.165014] ** your system. ** [ 0.168013] ** ** [ 0.170011] ** If you see this message and you are not debugging the ** [ 0.172012] ** kernel, report this immediately to your vendor! ** [ 0.174012] ** ** [ 0.176011] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE ** [ 0.178011] ************************************************************* [ 0.181747] NET: Registered protocol family 16 [ 0.183549] DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations [ 0.187064] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.190068] DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.197094] cpuidle: using governor menu [ 0.198653] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.200466] PCI: Using configuration type 1 for base access [ 0.203134] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.210158] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages [ 0.213055] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages [ 0.217044] cryptd: max_cpu_qlen set to 1000 [ 0.220313] ACPI: Added _OSI(Module Device) [ 0.221031] ACPI: Added _OSI(Processor Device) [ 0.223028] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.225025] ACPI: Added _OSI(Processor Aggregator Device) [ 0.229528] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.236539] ACPI: Interpreter enabled [ 0.238101] ACPI: PM: (supports S0 S3 S4 S5) [ 0.239026] ACPI: Using IOAPIC for interrupt routing [ 0.241153] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.243446] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.253924] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.257079] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] [ 0.259029] acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] [ 0.263123] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.268336] acpiphp: Slot [2] registered [ 0.270208] acpiphp: Slot [3] registered [ 0.271147] acpiphp: Slot [4] registered [ 0.273091] acpiphp: Slot [5] registered [ 0.274111] acpiphp: Slot [6] registered [ 0.275095] acpiphp: Slot [7] registered [ 0.277099] acpiphp: Slot [8] registered [ 0.278107] acpiphp: Slot [9] registered [ 0.280098] acpiphp: Slot [10] registered [ 0.281115] acpiphp: Slot [11] registered [ 0.282106] acpiphp: Slot [12] registered [ 0.284103] acpiphp: Slot [13] registered [ 0.285096] acpiphp: Slot [14] registered [ 0.287102] acpiphp: Slot [15] registered [ 0.288085] acpiphp: Slot [16] registered [ 0.290142] acpiphp: Slot [17] registered [ 0.291112] acpiphp: Slot [18] registered [ 0.293116] acpiphp: Slot [19] registered [ 0.294175] acpiphp: Slot [20] registered [ 0.296103] acpiphp: Slot [21] registered [ 0.297115] acpiphp: Slot [22] registered [ 0.299127] acpiphp: Slot [23] registered [ 0.300093] acpiphp: Slot [24] registered [ 0.301105] acpiphp: Slot [25] registered [ 0.303106] acpiphp: Slot [26] registered [ 0.305101] acpiphp: Slot [27] registered [ 0.306095] acpiphp: Slot [28] registered [ 0.308116] acpiphp: Slot [29] registered [ 0.309143] acpiphp: Slot [30] registered [ 0.311116] acpiphp: Slot [31] registered [ 0.312064] PCI host bridge to bus 0000:00 [ 0.314044] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.317040] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.319041] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.322041] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.325038] pci_bus 0000:00: root bus resource [mem 0x180000000-0x1ffffffff window] [ 0.328040] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.329216] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 [ 0.333581] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 [ 0.338774] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 [ 0.345623] pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] [ 0.350066] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.353032] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.355028] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.358034] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.361063] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 [ 0.363782] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.366054] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.368593] pci 0000:00:02.0: [1af4:1000] type 00 class 0x020000 [ 0.372893] pci 0000:00:02.0: reg 0x10: [io 0xc100-0xc11f] [ 0.381893] pci 0000:00:02.0: reg 0x20: [mem 0xfebf4000-0xfebf7fff 64bit pref] [ 0.388014] pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref] [ 0.392835] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000 [ 0.398020] pci 0000:00:05.0: reg 0x10: [io 0xc000-0xc07f] [ 0.403021] pci 0000:00:05.0: reg 0x14: [mem 0xfebc0000-0xfebc0fff] [ 0.416033] pci 0000:00:05.0: reg 0x20: [mem 0xfebf8000-0xfebfbfff 64bit pref] [ 0.425057] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 [ 0.433020] pci 0000:00:06.0: reg 0x10: [io 0xc080-0xc0ff] [ 0.438017] pci 0000:00:06.0: reg 0x14: [mem 0xfebc1000-0xfebc1fff] [ 0.448016] pci 0000:00:06.0: reg 0x20: [mem 0xfebfc000-0xfebfffff 64bit pref] [ 0.461214] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 0.463476] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 0.465444] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 0.467449] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 0.470340] ACPI: PCI: Interrupt link LNKS configured for IRQ 9 [ 0.476043] iommu: Default domain type: Passthrough [ 0.477550] SCSI subsystem initialized [ 0.479138] ACPI: bus type USB registered [ 0.481167] usbcore: registered new interface driver usbfs [ 0.483104] usbcore: registered new interface driver hub [ 0.485104] usbcore: registered new device driver usb [ 0.487209] pps_core: LinuxPPS API ver. 1 registered [ 0.489012] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.493077] PTP clock support registered [ 0.495085] EDAC MC: Ver: 3.0.0 [ 0.497139] PCI: Using ACPI for IRQ routing [ 0.498553] NetLabel: Initializing [ 0.500015] NetLabel: domain hash size = 128 [ 0.501012] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.503088] NetLabel: unlabeled traffic allowed by default [ 0.505170] vgaarb: loaded [ 0.507289] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.509018] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.514006] clocksource: Switched to clocksource kvm-clock [ 0.624427] VFS: Disk quotas dquot_6.6.0 [ 0.625909] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.629345] *** VALIDATE ramfs *** [ 0.630611] *** VALIDATE hugetlbfs *** [ 0.631938] pnp: PnP ACPI init [ 0.634152] pnp: PnP ACPI: found 6 devices [ 0.653511] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 0.656315] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 0.657897] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 0.659821] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 0.662187] pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfffff window] [ 0.664683] pci_bus 0000:00: resource 8 [mem 0x180000000-0x1ffffffff window] [ 0.666892] NET: Registered protocol family 2 [ 0.669426] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, vmalloc) [ 0.674070] tcp_listen_portaddr_hash hash table entries: 4096 (order: 5, 163840 bytes, vmalloc) [ 0.677362] TCP established hash table entries: 65536 (order: 7, 524288 bytes, vmalloc) [ 0.682934] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, vmalloc) [ 0.686339] TCP: Hash tables configured (established 65536 bind 65536) [ 0.689700] MPTCP token hash table entries: 8192 (order: 6, 393216 bytes, vmalloc) [ 0.693102] UDP hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.696222] UDP-Lite hash table entries: 4096 (order: 6, 393216 bytes, vmalloc) [ 0.699367] NET: Registered protocol family 1 [ 0.701830] RPC: Registered named UNIX socket transport module. [ 0.703843] RPC: Registered udp transport module. [ 0.705742] RPC: Registered tcp transport module. [ 0.707561] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 0.709828] NET: Registered protocol family 44 [ 0.711865] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.714641] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.716569] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.718812] PCI: CLS 0 bytes, default 64 [ 0.720993] Unpacking initramfs... [ 2.154483] debug: unmapping init [mem 0xffff9cd1bcc64000-0xffff9cd1bffcffff] [ 2.158923] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.161384] software IO TLB: mapped [mem 0x00000000a8000000-0x00000000ac000000] (64MB) [ 2.164530] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x229835b7123, max_idle_ns: 440795242976 ns [ 2.671473] Initialise system trusted keyrings [ 2.673319] Key type blacklist registered [ 2.674788] workingset: timestamp_bits=36 max_order=20 bucket_order=0 [ 2.685830] zbud: loaded [ 2.688772] *** VALIDATE nfs *** [ 2.690295] *** VALIDATE nfs4 *** [ 2.691862] pstore: using deflate compression [ 2.695046] Platform Keyring initialized [ 2.793466] NET: Registered protocol family 38 [ 2.795742] Key type asymmetric registered [ 2.797403] Asymmetric key parser 'x509' registered [ 2.799541] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) [ 2.802248] io scheduler mq-deadline registered [ 2.803936] io scheduler kyber registered [ 2.805551] io scheduler bfq registered [ 2.807449] atomic64_test: passed for x86-64 platform with CX8 and with SSE [ 2.811565] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.815625] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.819796] ACPI: Power Button [PWRF] [ 2.920353] ACPI: \_SB_.LNKB: Enabled at IRQ 10 [ 3.012837] ACPI: \_SB_.LNKA: Enabled at IRQ 11 [ 3.110785] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 3.142879] 00:03: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A [ 3.176886] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 3.183065] Non-volatile memory driver v1.3 [ 3.184903] Linux agpgart interface v0.103 [ 3.218713] virtio_blk virtio1: [vda] 133840 512-byte logical blocks (68.5 MB/65.4 MiB) [ 3.222556] vda: detected capacity change from 0 to 68526080 [ 3.236989] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 3.239866] vdb: detected capacity change from 0 to 1073741824 [ 3.247351] libphy: Fixed MDIO Bus: probed [ 3.252937] usbcore: registered new interface driver usbserial_generic [ 3.255658] usbserial: USB Serial support registered for generic [ 3.257823] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.262632] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.264540] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.267054] mousedev: PS/2 mouse device common for all mice [ 3.270343] rtc_cmos 00:05: RTC can wake from S4 [ 3.272664] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.277151] rtc_cmos 00:05: registered as rtc0 [ 3.278880] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input4 [ 3.279979] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.285823] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.286561] intel_pstate: CPU model not supported [ 3.294502] hid: raw HID events driver (C) Jiri Kosina [ 3.297055] usbcore: registered new interface driver usbhid [ 3.299078] usbhid: USB HID core driver [ 3.300375] drop_monitor: Initializing network drop monitor service [ 3.303039] Initializing XFRM netlink socket [ 3.305190] NET: Registered protocol family 10 [ 3.308601] Segment Routing with IPv6 [ 3.309804] NET: Registered protocol family 17 [ 3.311332] mpls_gso: MPLS GSO support [ 3.316894] RAS: Correctable Errors collector initialized. [ 3.319064] AVX version of gcm_enc/dec engaged. [ 3.320602] AES CTR mode by8 optimization enabled [ 3.417697] sched_clock: Marking stable (3417669052, 0)->(4317282861, -899613809) [ 3.420613] registered taskstats version 1 [ 3.422795] Loading compiled-in X.509 certificates [ 3.425104] zswap: loaded using pool lzo/zbud [ 3.451680] Key type big_key registered [ 3.464820] Key type encrypted registered [ 3.466819] ima: No TPM chip found, activating TPM-bypass! [ 3.469166] ima: Allocated hash algorithm: sha1 [ 3.470953] ima: No architecture policies found [ 3.472849] evm: Initialising EVM extended attributes: [ 3.474976] evm: security.selinux [ 3.476382] evm: security.ima [ 3.477601] evm: security.capability [ 3.478891] evm: HMAC attrs: 0x1 [ 3.481280] rtc_cmos 00:05: setting system clock to 2025-11-17 03:21:45 UTC (1763349705) [ 3.487575] debug: unmapping init [mem 0xffffffff88403000-0xffffffff885fffff] [ 3.491019] debug: unmapping init [mem 0xffffffff87182000-0xffffffff87458fff] [ 3.500164] Write protecting the kernel read-only data: 28672k [ 3.503637] debug: unmapping init [mem 0xffffffff85803000-0xffffffff859fffff] [ 3.506639] debug: unmapping init [mem 0xffffffff86114000-0xffffffff861fffff] [ 3.540965] 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.549878] systemd[1]: Detected virtualization kvm. [ 3.551931] systemd[1]: Detected architecture x86-64. [ 3.554223] systemd[1]: Running in initial RAM disk. Welcome to Rocky Linux 8.10 (Green Obsidian) dracut-049-233.git20240115.el8 (Initramfs)! [ 3.581313] systemd[1]: No hostname configured. [ 3.583209] systemd[1]: Set hostname to . [ 3.585614] random: systemd: uninitialized urandom read (16 bytes read) [ 3.588379] systemd[1]: Initializing machine ID from random generator. [ 3.615279] random: ln: uninitialized urandom read (6 bytes read) [ 3.716591] random: systemd: uninitialized urandom read (16 bytes read) [ 3.719419] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 3.724549] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 3.729212] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Reached target Timers. [ OK ] Listening on udev Kernel Socket. Starting Create Volatile Files and Directories... Starting Setup Virtual Console... [ OK ] Started Memstrack Anylazing Service. [ OK ] Listening on Journal Socket (/dev/log). Starting Journal Service... [ OK ] Reached target Slices. [ OK ] Listening on udev Control Socket. [ OK ] Reached target Sockets. Starting Apply Kernel Variables... [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Reached target Initrd Root Device. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Started Create Volatile Files and Directories. [ OK ] Started Setup Virtual Console. [ OK ] Started Apply Kernel Variables. Starting dracut cmdline hook... Starting Create Static Device Nodes in /dev... [ OK ] Started Journal Service. [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 4.354443] device-mapper: uevent: version 1.0.3 [ 4.357156] 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... [ 5.034977] virtio_net virtio0 ens2: renamed from eth0 [ 5.081218] scsi host0: ata_piix [ 5.083687] scsi host1: ata_piix [ 5.085287] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 5.087752] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 [ 8.773785] dracut-initqueue[580]: RTNETLINK answers: File exists [ 9.985546] random: crng init done [ 9.986957] 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... [ 10.404465] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Started Reload Configuration from the Real Root. [ OK ] Reached target Initrd File Systems. [ OK ] Reached target Initrd Default Target. Starting dracut pre-pivot and cleanup hook... [ OK ] Started dracut pre-pivot and cleanup hook. Starting Cleaning Up and Shutting Down Daemons... [ OK ] Stopped dracut pre-pivot and cleanup hook. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). Stopping Hardware RNG Entropy Gatherer Daemon... [ OK ] Stopped target Timers. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Initrd Root Device. [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped Hardware RNG Entropy Gatherer Daemon. [ OK ] Stopped target Basic System. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped target Local Encrypted Volumes. [ OK ] Stopped Dispatch Password Requests to Console Directory Watch. [ OK ] Stopped Create Volatile Files and Directories. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped target Swap. [ OK ] Stopped target Local File Systems. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped dracut pre-trigger hook. Stopping udev Kernel Device Manager... [ OK ] Started Cleaning Up and Shutting Down Daemons. [ OK ] Stopped udev Kernel Device Manager. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta…vice nodes for the current kernel. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 11.670400] printk: systemd: 25 output lines suppressed due to ratelimiting [ 11.972540] SELinux: Disabled at runtime. [ 12.028811] 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) [ 12.037860] systemd[1]: Detected virtualization kvm. [ 12.039801] systemd[1]: Detected architecture x86-64. Welcome to Rocky Linux 8.10 (Green Obsidian)! [ 12.586566] systemd[1]: initrd-switch-root.service: Succeeded. [ 12.591726] systemd[1]: Stopped Switch Root. [ OK ] Stopped Switch Root. [ 12.604930] systemd[1]: systemd-journald.service: Service has no hold-off time (RestartSec=0), scheduling restart. [ 12.609571] systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. [ 12.613497] systemd[1]: Stopped Journal Service. [ OK ] Stopped Journal Service. [ 12.623631] systemd[1]: Starting Journal Service... Starting Journal Service... [ 12.631833] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ OK ] Created slice system-sshd\x2dkeygen.slice. [ OK ] Listening on RPCbind Server Activation Socket. Mounting Kernel Debug File System... [FAILED] Failed to set up automount Arbitrar…rmats File System Automount Point. See 'systemctl status proc-sys-fs-binfmt_misc.automount' for details. [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Reached target rpc_pipefs.target. [ OK ] Created slice system-serial\x2dgetty.slice. Starting Create list of required st…ce nodes for the current kernel... [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd Root File System. Mounting Huge Pages File System... Mounting POSIX Message Queue File System... Activating swap /dev/disk/by-label/SWAP... [ OK ] Reached target RPC Port Mapper. [ OK ] Listening on udev Kernel Socket. Starting udev Coldplug all Devices... Starting Apply Kernel Variables... [ OK ] Stopped target Initrd File Systems. [ OK ] Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Paths. [ OK ] Created slice system-getty.slice. [ 12.779275] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS Starting Remount Root and Kernel File Systems... [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on Process Core Dump Socket. [ OK ] Created slice User and Session Slice. [ OK ] Reached target Slices. [ OK ] Started Journal Service. [ OK ] Mounted Kernel Debug File System. [ OK ] Started Create list of required sta…vice nodes for the current kernel. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Activated swap /dev/disk/by-label/SWAP. [ OK ] Started Apply Kernel Variables. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. Starting Configure read-only root support... [ OK ] Reached target Swap. 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 /mnt... Mounting /home/green/git/lustre-release... Starting udev Kernel Device Manager... [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted /mnt. [ 13.144390] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ OK ] Mounted /home/green/git/lustre-release. [ OK ] Started udev Kernel Device Manager. [ 13.476312] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 13.544372] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 13.654362] RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer [ 13.670158] EDAC sbridge: Ver: 1.1.2 [ 15.002546] Key type dns_resolver registered [ 15.297561] NFS: Registering the id_resolver key type [ 15.299513] Key type id_resolver registered [ 15.300960] Key type id_legacy registered [ OK ] Started Configure read-only root support. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Load/Save Random Seed... Starting Create Volatile Files and Directories... [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Load/Save Random Seed. [ OK ] Started Create Volatile Files and Directories. Starting RPC Bind... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Started RPC Bind. [ OK ] Started Rebuild Dynamic Linker Cache. Starting Update is Completed... [ OK ] Started Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started daily update of the root trust anchor for DNSSEC. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Started dnf makecache --timer. [ OK ] Reached target Timers. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Reached target Sockets. [ OK ] Reached target Basic System. Starting Restore /run/initramfs on shutdown... Starting Login Service... [ OK ] Reached target sshd-keygen.target. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. [ OK ] Started irqbalance daemon. [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started Restore /run/initramfs on shutdown. [ OK ] Started Login Service. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting GSSAPI Proxy Daemon... Starting Dynamic System Tuning Daemon... Starting OpenSSH server daemon... Starting Network Manager Wait Online... 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 OpenSSH server daemon. [ OK ] Started Permit User Sessions. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyS1. [ OK ] Started Command Scheduler. [ OK ] Started Serial Getty on ttyS0. [ OK ] Reached target Login Prompts. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Started Network Manager Wait Online. [ OK ] Reached target Network is Online. Starting System Logging Service... Starting Crash recovery kernel arming... Starting Notify NFS peers of a restart... [ 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 oleg254-client login: [ 79.288977] libcfs: loading out-of-tree module taints kernel. [ 79.416914] Key type ._llcrypt registered [ 79.418328] Key type .llcrypt registered [ 80.515661] hrtimer: interrupt took 18134803 ns [ 80.674787] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 80.687533] alg: No test for adler32 (adler32-zlib) [ 82.705650] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 83.843750] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 85.655258] Key type lgssc registered [ 87.512256] Lustre: Echo OBD driver; http://www.lustre.org/ [ 200.414712] Lustre: Mounted lustre-client [ 204.881368] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 225.682707] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing check_logdir /tmp/testlogs/ [ 226.271808] Lustre: lustre-OST0000-osc-ffff9cd2050d8800: disconnect after 24s idle [ 230.100140] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing yml_node [ 235.315584] Lustre: DEBUG MARKER: Client: 2.16.61.42 [ 237.771535] Lustre: DEBUG MARKER: MDS: 2.16.61.42 [ 240.159096] Lustre: DEBUG MARKER: OSS: 2.16.61.42 [ 241.780675] Lustre: DEBUG MARKER: -----============= acceptance-small: sanityn ============----- Sun Nov 16 22:25:42 EST 2025 [ 258.583363] Lustre: DEBUG MARKER: excepting tests: 27 40a 102 [ 260.294221] Lustre: DEBUG MARKER: skipping tests SLOW=no: 33a [ 262.442313] Lustre: DEBUG MARKER: === sanityn: start setup 22:26:02 (1763349962) === [ 263.198194] Lustre: Mounted lustre-client [ 267.044760] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing check_config_client /mnt/lustre [ 280.897460] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 283.618530] Lustre: lustre-OST0000-osc-ffff9cd209739000: disconnect after 20s idle [ 283.624264] Lustre: Skipped 1 previous similar message [ 290.936429] Lustre: DEBUG MARKER: === sanityn: finish setup 22:26:31 (1763349991) === [ 293.629384] Lustre: DEBUG MARKER: == sanityn test 0: do_node_vp() and do_facet_vp() do the right thing ========================================================== 22:26:34 (1763349994) [ 300.548787] Lustre: DEBUG MARKER: == sanityn test 1: Check attribute updates on 2 mount points ========================================================== 22:26:41 (1763350001) [ 307.709422] Lustre: DEBUG MARKER: == sanityn test 2a: check cached attribute updates on 2 mtpt's ================================================================== 22:26:48 (1763350008) [ 316.126119] Lustre: DEBUG MARKER: == sanityn test 2b: check cached attribute updates on 2 mtpt's ================================================================== 22:26:56 (1763350016) [ 324.972465] Lustre: DEBUG MARKER: == sanityn test 2c: check cached attribute updates on 2 mtpt's root ============================================================= 22:27:05 (1763350025) [ 332.373287] Lustre: DEBUG MARKER: == sanityn test 2d: check cached attribute updates on 2 mtpt's root ============================================================= 22:27:13 (1763350033) [ 340.619813] Lustre: DEBUG MARKER: == sanityn test 2e: check chmod on root is propagated to others ========================================================== 22:27:21 (1763350041) [ 348.798698] Lustre: DEBUG MARKER: == sanityn test 2f: check attr/owner updates on DNE with 2 mtpt's ========================================================== 22:27:29 (1763350049) [ 350.690735] Lustre: DEBUG MARKER: SKIP: sanityn test_2f needs >= 2 MDTs [ 352.817844] Lustre: DEBUG MARKER: == sanityn test 2g: check blocks update on sync write ==== 22:27:33 (1763350053) [ 359.580931] Lustre: DEBUG MARKER: == sanityn test 3: symlink on one mtpt, readlink on another ===================================================================== 22:27:40 (1763350060) [ 366.140715] Lustre: DEBUG MARKER: == sanityn test 4: fstat validation on multiple mount points ==================================================================== 22:27:46 (1763350066) [ 373.526402] Lustre: DEBUG MARKER: == sanityn test 5: create a file on one mount, truncate it on the other ========================================================== 22:27:54 (1763350074) [ 375.778383] Lustre: lustre-OST0001-osc-ffff9cd2050d8800: disconnect after 22s idle [ 375.796181] Lustre: Skipped 1 previous similar message [ 380.958109] Lustre: DEBUG MARKER: == sanityn test 6: remove of open file on other node ============================================================================ 22:28:01 (1763350081) [ 387.261234] Lustre: DEBUG MARKER: == sanityn test 7: remove of open directory on other node ======================================================================= 22:28:08 (1763350088) [ 391.135372] Lustre: lustre-OST0000-osc-ffff9cd209739000: disconnect after 24s idle [ 393.043848] Lustre: DEBUG MARKER: == sanityn test 8: remove of open special file on other node ==================================================================== 22:28:13 (1763350093) [ 400.848367] Lustre: DEBUG MARKER: == sanityn test 9a: append of file with sub-page size on multiple mounts ========================================================== 22:28:21 (1763350101) [ 406.507778] Lustre: lustre-OST0001-osc-ffff9cd2050d8800: disconnect after 23s idle [ 407.722414] Lustre: DEBUG MARKER: == sanityn test 9b: append to striped sparse file ======== 22:28:28 (1763350108) [ 413.954845] Lustre: DEBUG MARKER: == sanityn test 10a: write of file with sub-page size on multiple mounts ========================================================== 22:28:34 (1763350114) [ 421.997952] Lustre: DEBUG MARKER: == sanityn test 10b: write of file with sub-page size on multiple mounts ========================================================== 22:28:42 (1763350122) [ 428.368345] Lustre: DEBUG MARKER: == sanityn test 11: execution of file opened for write should return error ============================================================== 22:28:49 (1763350129) [ 434.830484] Lustre: DEBUG MARKER: == sanityn test 12: test lock ordering (link, stat, unlink) ========================================================== 22:28:55 (1763350135) [ 435.405417] Lustre: DEBUG MARKER: start dir: /mnt/lustre/lockdir=144115205272502304 file: /mnt/lustre/lockdir/lockfile=144115205272502302 [ 585.091477] Lustre: DEBUG MARKER: == sanityn test 13: test directory page revocation ======= 22:31:26 (1763350286) [ 593.752329] Lustre: DEBUG MARKER: == sanityn test 14aa: execution of file open for write returns -ETXTBSY ========================================================== 22:31:34 (1763350294) [ 601.052823] Lustre: DEBUG MARKER: == sanityn test 14ab: open(RDWR) of executing file returns -ETXTBSY ========================================================== 22:31:41 (1763350301) [ 607.658040] Lustre: DEBUG MARKER: == sanityn test 14b: truncate of executing file returns -ETXTBSY ================================================================ 22:31:48 (1763350308) [ 614.029746] Lustre: DEBUG MARKER: == sanityn test 14c: open(O_TRUNC) of executing file return -ETXTBSY ============================================================ 22:31:54 (1763350314) [ 620.297345] Lustre: DEBUG MARKER: == sanityn test 14d: chmod of executing file is still possible ================================================================== 22:32:01 (1763350321) [ 621.920557] Lustre: DEBUG MARKER: chmod [ 628.235628] Lustre: DEBUG MARKER: == sanityn test 15: test out-of-space with multiple writers ===================================================================== 22:32:08 (1763350328) [ 660.834817] Lustre: DEBUG MARKER: SKIP: oos2 test_15 oos2.sh: 7528448KB free > 800000KB, increase MAXFREE (or reduce fs size) [ 676.647595] Lustre: DEBUG MARKER: == sanityn test 16a: 500 iterations of dual-mount fsx ==== 22:32:57 (1763350377) [ 723.196436] Lustre: DEBUG MARKER: == sanityn test 16b: 500 iterations of dual-mount fsx at small size ========================================================== 22:33:43 (1763350423) [ 751.601567] Lustre: DEBUG MARKER: == sanityn test 16c: verify data consistency on ldiskfs with cache disabled (b=17397) ========================================================== 22:34:12 (1763350452) [ 753.774168] Lustre: DEBUG MARKER: SKIP: sanityn test_16c dio on ldiskfs only [ 755.719774] Lustre: DEBUG MARKER: == sanityn test 16d: Verify DIO and buffer IO with two clients ========================================================== 22:34:16 (1763350456) [ 795.615280] Lustre: lustre-OST0000-osc-ffff9cd209739000: disconnect after 23s idle [ 797.106210] Lustre: DEBUG MARKER: == sanityn test 16e: Verify size consistency for O_DIRECT write ========================================================== 22:34:57 (1763350497) [ 803.483564] Lustre: DEBUG MARKER: == sanityn test 16f: rw sequential consistency vs drop_caches ========================================================== 22:35:04 (1763350504) [ 804.712601] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 804.795941] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 804.867983] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 804.958299] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 805.028715] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 805.117162] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 805.193532] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 805.251639] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 805.352841] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 805.451644] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 805.550616] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 805.633205] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 805.730223] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 805.799321] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 805.900728] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 805.995972] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 806.100865] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 806.197535] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 806.293734] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 806.383948] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 806.440980] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 806.524090] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 806.578401] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 806.658244] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 806.799673] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 806.883584] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 806.972191] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 807.036202] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 807.091457] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 807.196254] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 807.274475] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 807.354331] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 807.435831] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 807.485279] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 807.532049] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 807.640972] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 807.720413] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 807.774484] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 807.867553] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 807.942373] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 808.043264] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 808.130980] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 808.223082] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 808.307374] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 808.410940] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 808.490162] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 808.549048] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 808.631981] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 808.732973] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 808.806544] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 808.866625] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 808.912912] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 808.989731] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 809.041256] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 809.102671] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 809.156291] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 809.247238] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 809.295382] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 809.345252] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 809.408963] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 809.479989] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 809.530592] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 809.602809] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 809.699622] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 809.775959] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 809.839125] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 809.916755] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 809.996242] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 810.050920] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 810.123056] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 810.215076] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 810.264084] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 810.314580] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 810.361476] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 810.401975] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 810.476686] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 810.535483] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 810.590537] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 810.659620] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 810.705293] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 810.763425] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 810.825245] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 810.872736] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 810.920409] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 810.968662] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 811.037673] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 811.086557] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 811.143969] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 811.207191] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 811.263022] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 811.319553] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 811.388382] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 811.435080] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 811.495944] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 811.573991] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 811.651186] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 811.704489] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 811.744924] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 811.817727] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 811.902429] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 811.968427] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 812.030085] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 812.072204] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 812.138215] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 812.196243] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 812.248333] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 812.315667] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 812.399637] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 812.480932] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 812.553498] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 812.605731] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 812.666106] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 812.737696] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 812.798856] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 812.847922] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 812.911386] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 812.976321] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 813.035033] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 813.095669] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 813.163373] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 813.208810] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 813.251977] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 813.323806] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 813.393243] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 813.471103] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 813.560027] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 813.617543] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 813.671619] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 813.727375] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 813.799375] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 813.864907] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 813.943891] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 814.039430] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 814.121351] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 814.179305] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 814.250456] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 814.335078] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 814.402584] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 814.468423] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 814.557677] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 814.615480] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 814.671299] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 814.743567] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 814.782693] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 814.825389] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 814.872820] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 814.924347] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 814.983433] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 815.064319] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 815.147115] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 815.225030] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 815.308677] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 815.375812] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 815.429153] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 815.490122] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 815.551965] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 815.600157] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 815.653925] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 815.699965] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 815.776337] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 815.846872] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 815.906193] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 815.990078] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 816.088146] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 816.156294] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 816.222385] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 816.273941] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 816.325402] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 816.385514] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 816.487652] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 816.582806] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 816.684537] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 816.765529] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 816.836646] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 816.910723] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 816.986392] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 817.051591] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 817.096264] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 817.160802] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 817.231097] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 817.288181] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 817.355150] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 817.429393] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 817.497919] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 817.567242] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 817.643434] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 817.684647] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 817.723876] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 817.807553] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 817.867841] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 817.928761] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 817.981490] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 818.041233] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 818.099451] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 818.159200] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 818.228512] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 818.311812] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 818.365664] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 818.446876] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 818.517917] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 818.599526] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 818.650733] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 818.714199] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 818.765993] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 818.829533] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 818.886183] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 818.940116] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 818.982914] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 819.044416] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 819.102982] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 819.192625] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 819.254551] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 819.313927] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 819.387540] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 819.438202] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 819.522461] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 819.571449] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 819.642740] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 819.707519] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 819.765764] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 819.833857] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 819.883039] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 819.910669] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 819.987181] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 820.056773] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 820.127310] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 820.177293] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 820.257161] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 820.341720] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 820.436911] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 820.509740] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 820.589916] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 820.674774] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 820.755114] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 820.825383] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 820.892064] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 820.962827] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 821.020992] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 821.096660] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 821.158182] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 821.213240] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 821.217543] Lustre: lustre-OST0001-osc-ffff9cd2050d8800: disconnect after 23s idle [ 821.282373] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 821.344600] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 821.415765] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 821.490954] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 821.539714] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 821.568263] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 821.638624] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 821.714379] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 821.810546] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 821.877300] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 821.966109] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 822.052824] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 822.112548] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 822.157514] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 822.226530] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 822.290382] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 822.349286] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 822.452421] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 822.511740] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 822.612664] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 822.703116] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 822.756749] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 822.832926] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 822.910381] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 822.977232] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 823.025379] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 823.101423] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 823.162115] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 823.214550] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 823.262631] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 823.320863] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 823.401516] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 823.469674] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 823.551736] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 823.657615] rw_seq_cst_vs_d (29494): drop_caches: 3 [ 830.016787] Lustre: DEBUG MARKER: == sanityn test 16g: mmap rw sequential consistency vs drop_caches ========================================================== 22:35:31 (1763350531) [ 830.638786] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 830.720695] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 830.768585] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 830.873153] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 830.925065] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 831.019605] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 831.128490] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 831.182422] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 831.224742] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 831.405848] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 831.488767] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 831.567066] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 831.619321] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 831.664611] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 831.706708] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 831.760973] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 831.880475] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 831.996570] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 832.074703] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 832.107989] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 832.143150] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 832.330538] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 832.441179] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 832.526026] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 832.574985] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 832.710391] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 832.887403] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 832.921125] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 832.958537] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 833.190928] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 833.325848] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 833.380708] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 833.423415] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 833.452885] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 833.586887] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 833.635660] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 833.722212] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 833.842573] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 833.871499] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 834.007884] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 834.078684] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 834.117410] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 834.214036] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 834.293043] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 834.350207] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 834.560243] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 834.664402] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 834.699173] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 834.752930] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 834.818081] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 834.878356] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 835.036952] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 835.255759] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 835.341664] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 835.411402] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 835.673351] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 835.725197] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 835.785646] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 835.898339] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 835.950443] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 836.019295] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 836.104495] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 836.247117] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 836.376894] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 836.474146] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 836.515986] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 836.645628] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 836.717715] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 836.755439] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 836.790192] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 836.945826] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 836.989363] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 837.106622] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 837.167454] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 837.301603] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 837.439530] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 837.652275] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 837.778907] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 837.852389] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 838.025352] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 838.095950] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 838.122548] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 838.346703] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 838.376717] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 838.473951] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 838.506962] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 838.588489] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 838.646855] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 838.705164] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 838.748976] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 838.777817] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 838.833497] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 838.970176] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 839.017655] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 839.146379] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 839.190135] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 839.254775] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 839.393267] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 839.635091] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 839.663787] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 839.784928] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 839.898098] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 839.952135] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 839.999577] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 840.073608] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 840.173271] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 840.230511] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 840.339120] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 840.439476] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 840.578635] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 840.631420] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 840.729580] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 840.823130] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 840.908431] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 841.009433] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 841.059216] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 841.249135] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 841.330584] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 841.496668] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 841.867224] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 842.084436] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 842.149442] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 842.207535] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 842.289380] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 842.403866] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 842.463694] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 842.499606] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 842.607584] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 842.633297] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 842.654280] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 842.694018] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 842.740171] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 842.782097] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 842.930950] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 842.959408] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 843.060423] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 843.088788] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 843.152058] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 843.341324] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 843.480070] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 843.570595] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 843.697803] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 843.756980] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 843.992618] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 844.213350] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 844.387831] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 844.461435] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 844.517506] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 844.594270] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 844.811718] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 844.939445] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 845.072898] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 845.184400] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 845.252921] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 845.283543] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 845.476370] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 845.690246] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 845.802511] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 846.032798] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 846.078681] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 846.205966] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 846.265667] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 846.315980] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 846.365834] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 846.460809] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 846.577766] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 846.695827] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 846.809735] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 846.942137] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 847.050109] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 847.091356] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 847.236726] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 847.271175] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 847.492600] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 847.557668] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 847.592614] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 847.641997] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 847.699645] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 847.809625] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 847.932262] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 847.986965] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 848.019552] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 848.049196] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 848.124200] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 848.147941] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 848.372199] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 848.416799] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 848.483565] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 848.538420] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 848.740101] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 848.827491] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 848.884283] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 849.011630] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 849.044038] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 849.061740] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 849.080919] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 849.099595] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 849.116891] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 849.181565] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 849.250502] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 849.319586] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 849.392773] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 849.442685] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 849.483882] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 849.668562] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 849.780981] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 849.996103] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 850.113095] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 850.164435] rw_seq_cst_vs_d (30074): drop_caches: 3 [ 858.182973] Lustre: DEBUG MARKER: == sanityn test 16h: mmap read after truncate file ======= 22:35:58 (1763350558) [ 864.972297] Lustre: DEBUG MARKER: == sanityn test 16i: read after truncate file ============ 22:36:05 (1763350565) [ 870.771467] Lustre: DEBUG MARKER: == sanityn test 16j: race dio with buffered i/o ========== 22:36:11 (1763350571) [ 902.904961] Lustre: DEBUG MARKER: == sanityn test 16k: Parallel FSX and drop caches should not panic ========================================================== 22:36:43 (1763350603) [ 903.385462] bash (32514): drop_caches: 3 [ 906.666229] bash (32514): drop_caches: 3 [ 909.976968] bash (32514): drop_caches: 3 [ 913.167663] bash (32514): drop_caches: 3 [ 916.297778] bash (32514): drop_caches: 3 [ 919.401693] bash (32514): drop_caches: 3 [ 922.533982] bash (32514): drop_caches: 3 [ 925.621100] bash (32514): drop_caches: 3 [ 928.753641] bash (32514): drop_caches: 3 [ 931.855115] bash (32514): drop_caches: 3 [ 935.167271] bash (32514): drop_caches: 3 [ 938.305968] bash (32514): drop_caches: 3 [ 941.506317] bash (32514): drop_caches: 3 [ 944.675955] bash (32514): drop_caches: 3 [ 949.783950] Lustre: DEBUG MARKER: == sanityn test 17: resource creation/LVB creation race ========================================================================= 22:37:30 (1763350650) [ 959.333409] Lustre: DEBUG MARKER: == sanityn test 18: mmap sanity check =========================================================================================== 22:37:39 (1763350659) [ 1001.126542] Lustre: DEBUG MARKER: == sanityn test 19: test concurrent uncached read races ========================================================================= 22:38:21 (1763350701) [ 1003.576543] Lustre: DEBUG MARKER: SKIP: sanityn test_19 not cache-capable obdfilter [ 1004.990474] Lustre: DEBUG MARKER: == sanityn test 20: test extra readahead page left in cache ============================================================== 22:38:25 (1763350705) [ 1010.916492] Lustre: DEBUG MARKER: == sanityn test 21: Try to remove mountpoint on another dir ============================================================== 22:38:31 (1763350711) [ 1015.775313] Lustre: lustre-OST0001-osc-ffff9cd2050d8800: disconnect after 23s idle [ 1015.786479] Lustre: Skipped 3 previous similar messages [ 1016.964679] Lustre: DEBUG MARKER: == sanityn test 23: others should see updated atime while another read============================================================== 22:38:37 (1763350717) [ 1085.248273] Lustre: DEBUG MARKER: == sanityn test 24a: lfs df [-ih] [path] test =================================================================================== 22:39:45 (1763350785) [ 1092.719114] Lustre: DEBUG MARKER: == sanityn test 24b: lfs df should show both filesystems ========================================================================= 22:39:53 (1763350793) [ 1097.847200] Lustre: DEBUG MARKER: == sanityn test 25a: change ACL on one mountpoint be seen on another ============================================================= 22:39:58 (1763350798) [ 1102.778823] Lustre: DEBUG MARKER: == sanityn test 25b: change ACL under remote dir on one mountpoint be seen on another ========================================================== 22:40:03 (1763350803) [ 1103.770304] Lustre: DEBUG MARKER: SKIP: sanityn test_25b needs >= 2 MDTs [ 1105.350305] Lustre: DEBUG MARKER: == sanityn test 26a: allow mtime to get older ============ 22:40:06 (1763350806) [ 1111.940505] Lustre: DEBUG MARKER: == sanityn test 26b: sync mtime between ost and mds ====== 22:40:12 (1763350812) [ 1113.055384] Lustre: lustre-OST0001-osc-ffff9cd209739000: disconnect after 20s idle [ 1113.060777] Lustre: Skipped 4 previous similar messages [ 1119.977610] Lustre: DEBUG MARKER: == sanityn test 26c: set-in-past on open file is not lost on close ========================================================== 22:40:20 (1763350820) [ 1126.735838] Lustre: DEBUG MARKER: SKIP: sanityn test_27 skipping excluded test 27 [ 1128.009086] Lustre: DEBUG MARKER: == sanityn test 30: recreate file race =================== 22:40:28 (1763350828) [ 1134.640558] Lustre: DEBUG MARKER: == sanityn test 31a: voluntary cancel / blocking ast race======================================================================== 22:40:35 (1763350835) [ 1134.874359] Lustre: *** cfs_fail_loc=314, val=0*** [ 1135.903284] Lustre: *** cfs_fail_loc=314, val=0*** [ 1135.905697] Lustre: Skipped 3 previous similar messages [ 1140.723925] Lustre: DEBUG MARKER: == sanityn test 31b: voluntary OST cancel / blocking ast race======================================================================== 22:40:41 (1763350841) [ 1153.120860] Lustre: *** cfs_fail_loc=314, val=0*** [ 1154.024900] Lustre: lustre-OST0000-osc-ffff9cd209739000: Connection to lustre-OST0000 (at 192.168.202.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1154.047773] LustreError: lustre-OST0000-osc-ffff9cd209739000: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1154.077247] Lustre: 2368:0:(llite_lib.c:4226:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.202.154@tcp:/lustre/fid: [0x200000402:0x25:0x0]// may get corrupted (rc -108) [ 1154.086216] LustreError: 41792:0:(ldlm_resource.c:981:ldlm_resource_complain()) lustre-OST0000-osc-ffff9cd209739000: namespace resource [0x240000400:0x35:0x0].0x0 (ffff9cd2420e8400) refcount nonzero (1) after lock cleanup; forcing cleanup. [ 1154.109937] Lustre: lustre-OST0000-osc-ffff9cd209739000: Connection restored to 192.168.202.154@tcp (at 192.168.202.154@tcp) [ 1159.886826] Lustre: DEBUG MARKER: == sanityn test 31r: open-rename(replace) race =========== 22:41:00 (1763350860) [ 1160.186399] LustreError: 42346:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 sleeping for 3000ms [ 1163.216292] LustreError: 42346:0:(file.c:761:ll_intent_file_open()) cfs_fail_timeout id 1419 awake [ 1168.477425] Lustre: DEBUG MARKER: == sanityn test 31s: open should not revalidate invalid dentry ========================================================== 22:41:09 (1763350869) [ 1174.579396] Lustre: DEBUG MARKER: == sanityn test 31t: getattr should not revalidate invalid dentry ========================================================== 22:41:15 (1763350875) [ 1181.705939] Lustre: DEBUG MARKER: SKIP: sanityn test_33a skipping SLOW test 33a [ 1183.380957] Lustre: DEBUG MARKER: == sanityn test 33b: COS: cross create/delete, 2 clients, benchmark under remote dir ========================================================== 22:41:24 (1763350884) [ 1184.707493] Lustre: DEBUG MARKER: SKIP: sanityn test_33b Need two or more clients, have 1 [ 1186.325672] Lustre: DEBUG MARKER: == sanityn test 33c: Cancel cross-MDT lock should trigger Sync-on-Lock-Cancel ========================================================== 22:41:27 (1763350887) [ 1187.601460] Lustre: DEBUG MARKER: SKIP: sanityn test_33c needs >= 2 MDTs [ 1189.066589] Lustre: DEBUG MARKER: == sanityn test 33d: dependent transactions should trigger COS ========================================================== 22:41:29 (1763350889) [ 1190.629896] Lustre: DEBUG MARKER: SKIP: sanityn test_33d needs >= 2 MDTs [ 1192.381192] Lustre: DEBUG MARKER: == sanityn test 33e: independent transactions shouldn't trigger COS ========================================================== 22:41:32 (1763350892) [ 1193.822830] Lustre: DEBUG MARKER: SKIP: sanityn test_33e needs >= 2 MDTs [ 1195.466825] Lustre: DEBUG MARKER: == sanityn test 34: no lock timeout under IO ============= 22:41:36 (1763350896) [ 1250.191829] Lustre: lustre-OST0001-osc-ffff9cd209739000: Connection to lustre-OST0001 (at 192.168.202.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1250.205985] LustreError: lustre-OST0001-osc-ffff9cd209739000: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1250.226084] Lustre: lustre-OST0001-osc-ffff9cd209739000: Connection restored to 192.168.202.154@tcp (at 192.168.202.154@tcp) [ 1250.293952] LustreError: lustre-OST0001-osc-ffff9cd2050d8800: This client was evicted by lustre-OST0001; in progress operations using this service will fail. [ 1265.552689] Lustre: lustre-OST0000-osc-ffff9cd2050d8800: Connection to lustre-OST0000 (at 192.168.202.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1265.574175] Lustre: Skipped 1 previous similar message [ 1265.585938] LustreError: lustre-OST0000-osc-ffff9cd2050d8800: This client was evicted by lustre-OST0000; in progress operations using this service will fail. [ 1265.612914] Lustre: lustre-OST0000-osc-ffff9cd2050d8800: Connection restored to 192.168.202.154@tcp (at 192.168.202.154@tcp) [ 1265.623693] Lustre: Skipped 1 previous similar message [ 1271.776040] Lustre: lustre-OST0001-osc-ffff9cd2050d8800: disconnect after 21s idle [ 1271.778881] Lustre: Skipped 1 previous similar message [ 1284.146106] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9cd2050d8800.ost_server_uuid 50 [ 1285.699220] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9cd2050d8800.ost_server_uuid in FULL state after 0 sec [ 1289.665681] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9cd2050d8800.ost_server_uuid 50 [ 1291.115523] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9cd2050d8800.ost_server_uuid in IDLE state after 0 sec [ 1295.322272] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9cd2050d8800.ost_server_uuid 50 [ 1296.804969] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9cd2050d8800.ost_server_uuid in IDLE state after 0 sec [ 1300.815523] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9cd2050d8800.ost_server_uuid 50 [ 1302.204952] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9cd2050d8800.ost_server_uuid in IDLE state after 0 sec [ 1310.344422] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9cd2050d8800.ost_server_uuid 50 [ 1311.817713] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9cd2050d8800.ost_server_uuid in IDLE state after 0 sec [ 1314.856588] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff9cd2050d8800.ost_server_uuid 50 [ 1315.814732] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff9cd2050d8800.ost_server_uuid in IDLE state after 0 sec [ 1317.387027] Lustre: DEBUG MARKER: == sanityn test 35: -EINTR cp_ast vs. bl_ast race does not evict client ========================================================== 22:43:38 (1763351018) [ 1319.297747] Lustre: DEBUG MARKER: Race attempt 0 [ 1321.352325] Lustre: DEBUG MARKER: Wait for 48967 49048 for 60 sec... [ 1386.966360] Lustre: DEBUG MARKER: == sanityn test 36: handle ESTALE/open-unlink correctly == 22:44:47 (1763351087) [ 1394.154870] Lustre: DEBUG MARKER: start test - cycle (0) [ 1418.929926] Lustre: DEBUG MARKER: start test - cycle (1) [ 1443.840964] Lustre: DEBUG MARKER: start test - cycle (2) [ 1465.991024] Lustre: DEBUG MARKER: start test - cycle (3) [ 1489.901398] Lustre: DEBUG MARKER: start test - cycle (4) [ 1516.134493] Lustre: DEBUG MARKER: start test - cycle (5) [ 1541.114948] Lustre: DEBUG MARKER: start test - cycle (6) [ 1543.137404] Lustre: lustre-OST0001-osc-ffff9cd209739000: disconnect after 20s idle [ 1543.139709] Lustre: Skipped 6 previous similar messages [ 1566.459925] Lustre: DEBUG MARKER: start test - cycle (7) [ 1589.129663] Lustre: DEBUG MARKER: start test - cycle (8) [ 1614.239641] Lustre: DEBUG MARKER: start test - cycle (9) [ 1639.316619] Lustre: DEBUG MARKER: start test - cycle (10) [ 1669.392198] Lustre: DEBUG MARKER: == sanityn test 37: check i_size is not updated for directory on close (bug 18695) ======================================================================== 22:49:30 (1763351370) [ 1747.296634] Lustre: DEBUG MARKER: == sanityn test 39a: file mtime does not change after rename ========================================================== 22:50:48 (1763351448) [ 1753.448144] Lustre: DEBUG MARKER: == sanityn test 39b: file mtime the same on clients with/out lock ========================================================== 22:50:54 (1763351454) [ 1761.153598] Lustre: DEBUG MARKER: == sanityn test 39c: check truncate mtime update ================================================================================ 22:51:01 (1763351461) [ 1767.897646] Lustre: DEBUG MARKER: == sanityn test 39d: sync write should update mtime ====== 22:51:08 (1763351468) [ 1768.185612] Lustre: *** cfs_fail_loc=411, val=0*** [ 1773.804709] Lustre: DEBUG MARKER: SKIP: sanityn test_40a skipping ALWAYS excluded test 40a [ 1775.557996] Lustre: DEBUG MARKER: == sanityn test 40b: pdirops: open|create and others ======================================================================== 22:51:16 (1763351476) [ 1792.085119] Lustre: DEBUG MARKER: == sanityn test 40c: pdirops: link and others ======================================================================== 22:51:32 (1763351492) [ 1808.817521] Lustre: DEBUG MARKER: == sanityn test 40d: pdirops: unlink and others ======================================================================== 22:51:49 (1763351509) [ 1823.471301] Lustre: DEBUG MARKER: == sanityn test 40e: pdirops: rename and others ======================================================================== 22:52:04 (1763351524) [ 1836.789870] Lustre: DEBUG MARKER: == sanityn test 41a: pdirops: create vs mkdir ======================================================================== 22:52:17 (1763351537) [ 1847.546498] Lustre: DEBUG MARKER: == sanityn test 41b: pdirops: create vs create ======================================================================== 22:52:28 (1763351548) [ 1858.175187] Lustre: DEBUG MARKER: == sanityn test 41c: pdirops: create vs link ======================================================================== 22:52:38 (1763351558) [ 1868.541561] Lustre: DEBUG MARKER: == sanityn test 41d: pdirops: create vs unlink ======================================================================== 22:52:49 (1763351569) [ 1878.272715] Lustre: DEBUG MARKER: == sanityn test 41e: pdirops: create and rename (tgt) ======================================================================== 22:52:59 (1763351579) [ 1888.259711] Lustre: DEBUG MARKER: == sanityn test 41f: pdirops: create and rename (src) ======================================================================== 22:53:09 (1763351589) [ 1899.253356] Lustre: DEBUG MARKER: == sanityn test 41g: pdirops: create vs getattr ======================================================================== 22:53:20 (1763351600) [ 1908.845340] Lustre: DEBUG MARKER: == sanityn test 41h: pdirops: create vs readdir ======================================================================== 22:53:30 (1763351610) [ 1918.386379] Lustre: DEBUG MARKER: == sanityn test 41i: reint_open: create vs create ======== 22:53:39 (1763351619) [ 2536.415239] Lustre: lustre-OST0000-osc-ffff9cd209739000: disconnect after 22s idle [ 2536.419765] Lustre: Skipped 14 previous similar messages [ 2808.949337] Lustre: DEBUG MARKER: == sanityn test 42a: pdirops: mkdir vs mkdir ======================================================================== 23:08:30 (1763352510) [ 2816.140770] Lustre: DEBUG MARKER: == sanityn test 42b: pdirops: mkdir vs create ======================================================================== 23:08:37 (1763352517) [ 2823.256622] Lustre: DEBUG MARKER: == sanityn test 42c: pdirops: mkdir vs link ======================================================================== 23:08:44 (1763352524) [ 2829.719328] Lustre: DEBUG MARKER: == sanityn test 42d: pdirops: mkdir vs unlink ======================================================================== 23:08:51 (1763352531) [ 2836.394964] Lustre: DEBUG MARKER: == sanityn test 42e: pdirops: mkdir and rename (tgt) ======================================================================== 23:08:57 (1763352537) [ 2842.653698] Lustre: DEBUG MARKER: == sanityn test 42f: pdirops: mkdir and rename (src) ======================================================================== 23:09:04 (1763352544) [ 2848.938151] Lustre: DEBUG MARKER: == sanityn test 42g: pdirops: mkdir vs getattr ======================================================================== 23:09:10 (1763352550) [ 2854.965129] Lustre: DEBUG MARKER: == sanityn test 42h: pdirops: mkdir vs readdir ======================================================================== 23:09:16 (1763352556) [ 2861.557621] Lustre: DEBUG MARKER: == sanityn test 43a: rmdir,mkdir doesn't return -EEXIST ======================================================================== 23:09:22 (1763352562) [ 2893.286183] Lustre: DEBUG MARKER: == sanityn test 43b: pdirops: unlink vs create ======================================================================== 23:09:54 (1763352594) [ 2899.639673] Lustre: DEBUG MARKER: == sanityn test 43c: pdirops: unlink vs link ======================================================================== 23:10:01 (1763352601) [ 2906.168943] Lustre: DEBUG MARKER: == sanityn test 43d: pdirops: unlink vs unlink ======================================================================== 23:10:07 (1763352607) [ 2912.406142] Lustre: DEBUG MARKER: == sanityn test 43e: pdirops: unlink and rename (tgt) ======================================================================== 23:10:13 (1763352613) [ 2919.110180] Lustre: DEBUG MARKER: == sanityn test 43f: pdirops: unlink and rename (src) ======================================================================== 23:10:20 (1763352620) [ 2925.685423] Lustre: DEBUG MARKER: == sanityn test 43g: pdirops: unlink vs getattr ======================================================================== 23:10:27 (1763352627) [ 2932.131623] Lustre: DEBUG MARKER: == sanityn test 43h: pdirops: unlink vs readdir ======================================================================== 23:10:33 (1763352633) [ 2938.551650] Lustre: DEBUG MARKER: == sanityn test 43i: pdirops: unlink vs remote mkdir ===== 23:10:39 (1763352639) [ 2939.207157] Lustre: DEBUG MARKER: SKIP: sanityn test_43i needs >= 2 MDTs [ 2940.031822] Lustre: DEBUG MARKER: == sanityn test 43j: racy mkdir return EEXIST ======================================================================== 23:10:41 (1763352641) [ 2994.560491] Lustre: DEBUG MARKER: == sanityn test 43k: unlink vs create ==================== 23:11:35 (1763352695) [ 3140.575492] Lustre: lustre-OST0000-osc-ffff9cd2050d8800: disconnect after 21s idle [ 3140.578763] Lustre: Skipped 6 previous similar messages [ 3523.243459] Lustre: DEBUG MARKER: == sanityn test 44a: pdirops: rename tgt vs mkdir ======================================================================== 23:20:24 (1763353224) [ 3529.010370] Lustre: DEBUG MARKER: == sanityn test 44b: pdirops: rename tgt vs create ======================================================================== 23:20:30 (1763353230) [ 3534.706073] Lustre: DEBUG MARKER: == sanityn test 44c: pdirops: rename tgt vs link ======================================================================== 23:20:36 (1763353236) [ 3540.601911] Lustre: DEBUG MARKER: == sanityn test 44d: pdirops: rename tgt vs unlink ======================================================================== 23:20:41 (1763353241) [ 3547.108053] Lustre: DEBUG MARKER: == sanityn test 44e: pdirops: rename tgt and rename (tgt) ======================================================================== 23:20:48 (1763353248) [ 3553.624197] Lustre: DEBUG MARKER: == sanityn test 44f: pdirops: rename tgt and rename (src) ======================================================================== 23:20:55 (1763353255) [ 3559.987211] Lustre: DEBUG MARKER: == sanityn test 44g: pdirops: rename tgt vs getattr ======================================================================== 23:21:01 (1763353261) [ 3565.873486] Lustre: DEBUG MARKER: == sanityn test 44h: pdirops: rename tgt vs readdir ======================================================================== 23:21:07 (1763353267) [ 3571.773561] Lustre: DEBUG MARKER: == sanityn test 44i: pdirops: rename tgt vs remote mkdir ========================================================== 23:21:13 (1763353273) [ 3572.416533] Lustre: DEBUG MARKER: SKIP: sanityn test_44i needs >= 2 MDTs [ 3573.310727] Lustre: DEBUG MARKER: == sanityn test 45a: rename,mkdir doesn't return -EEXIST ======================================================================== 23:21:14 (1763353274) [ 3621.449907] Lustre: DEBUG MARKER: == sanityn test 45b: pdirops: rename src vs create ======================================================================== 23:22:02 (1763353322) [ 3628.268762] Lustre: DEBUG MARKER: == sanityn test 45c: pdirops: rename src vs link ======================================================================== 23:22:09 (1763353329) [ 3634.605446] Lustre: DEBUG MARKER: == sanityn test 45d: pdirops: rename src vs unlink ======================================================================== 23:22:16 (1763353336) [ 3640.390955] Lustre: DEBUG MARKER: == sanityn test 45e: pdirops: rename src and rename (tgt) ======================================================================== 23:22:21 (1763353341) [ 3646.639637] Lustre: DEBUG MARKER: == sanityn test 45f: pdirops: rename src and rename (src) ======================================================================== 23:22:27 (1763353347) [ 3652.879243] Lustre: DEBUG MARKER: == sanityn test 45g: pdirops: rename src vs getattr ======================================================================== 23:22:34 (1763353354) [ 3658.738647] Lustre: DEBUG MARKER: == sanityn test 45h: pdirops: unlink vs readdir ======================================================================== 23:22:40 (1763353360) [ 3664.044943] Lustre: DEBUG MARKER: == sanityn test 45i: pdirops: rename src vs remote mkdir ========================================================== 23:22:45 (1763353365) [ 3664.629991] Lustre: DEBUG MARKER: SKIP: sanityn test_45i needs >= 2 MDTs [ 3665.295155] Lustre: DEBUG MARKER: == sanityn test 45j: read vs rename ====================== 23:22:46 (1763353366) [ 3821.535409] Lustre: lustre-OST0000-osc-ffff9cd2050d8800: disconnect after 21s idle [ 3821.538549] Lustre: Skipped 3 previous similar messages [ 4133.504918] Lustre: DEBUG MARKER: == sanityn test 46a: pdirops: link vs mkdir ======================================================================== 23:30:35 (1763353835) [ 4138.777121] Lustre: DEBUG MARKER: == sanityn test 46b: pdirops: link vs create ======================================================================== 23:30:40 (1763353840) [ 4144.192494] Lustre: DEBUG MARKER: == sanityn test 46c: pdirops: link vs link ======================================================================== 23:30:45 (1763353845) [ 4149.540979] Lustre: DEBUG MARKER: == sanityn test 46d: pdirops: link vs unlink ======================================================================== 23:30:51 (1763353851) [ 4155.097413] Lustre: DEBUG MARKER: == sanityn test 46e: pdirops: link and rename (tgt) ======================================================================== 23:30:56 (1763353856) [ 4160.208373] Lustre: DEBUG MARKER: == sanityn test 46f: pdirops: link and rename (src) ======================================================================== 23:31:01 (1763353861) [ 4165.315346] Lustre: DEBUG MARKER: == sanityn test 46g: pdirops: link vs getattr ======================================================================== 23:31:06 (1763353866) [ 4170.387144] Lustre: DEBUG MARKER: == sanityn test 46h: pdirops: link vs readdir ======================================================================== 23:31:11 (1763353871) [ 4175.497583] Lustre: DEBUG MARKER: == sanityn test 46i: pdirops: link vs remote mkdir ======= 23:31:17 (1763353877) [ 4176.032070] Lustre: DEBUG MARKER: SKIP: sanityn test_46i needs >= 2 MDTs [ 4176.632606] Lustre: DEBUG MARKER: == sanityn test 47a: pdirops: remote mkdir vs mkdir ====== 23:31:18 (1763353878) [ 4177.144083] Lustre: DEBUG MARKER: SKIP: sanityn test_47a needs >= 2 MDTs [ 4177.677148] Lustre: DEBUG MARKER: == sanityn test 47b: pdirops: remote mkdir vs create ===== 23:31:19 (1763353879) [ 4178.140137] Lustre: DEBUG MARKER: SKIP: sanityn test_47b needs >= 2 MDTs [ 4178.694438] Lustre: DEBUG MARKER: == sanityn test 47c: pdirops: remote mkdir vs link ======= 23:31:20 (1763353880) [ 4179.210398] Lustre: DEBUG MARKER: SKIP: sanityn test_47c needs >= 2 MDTs [ 4179.806283] Lustre: DEBUG MARKER: == sanityn test 47d: pdirops: remote mkdir vs unlink ===== 23:31:21 (1763353881) [ 4180.335180] Lustre: DEBUG MARKER: SKIP: sanityn test_47d needs >= 2 MDTs [ 4180.876937] Lustre: DEBUG MARKER: == sanityn test 47e: pdirops: remote mkdir and rename (tgt) ========================================================== 23:31:22 (1763353882) [ 4181.332646] Lustre: DEBUG MARKER: SKIP: sanityn test_47e needs >= 2 MDTs [ 4181.860237] Lustre: DEBUG MARKER: == sanityn test 47f: pdirops: remote mkdir and rename (src) ========================================================== 23:31:23 (1763353883) [ 4182.332862] Lustre: DEBUG MARKER: SKIP: sanityn test_47f needs >= 2 MDTs [ 4182.862192] Lustre: DEBUG MARKER: == sanityn test 47g: pdirops: remote mkdir vs getattr ==== 23:31:24 (1763353884) [ 4183.327620] Lustre: DEBUG MARKER: SKIP: sanityn test_47g needs >= 2 MDTs [ 4183.855311] Lustre: DEBUG MARKER: == sanityn test 50: osc lvb attrs: enqueue vs. CP AST ======================================================================== 23:31:25 (1763353885) [ 4183.935713] LustreError: 5543:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 sleeping for 2000ms [ 4186.023149] LustreError: 5543:0:(ldlm_lockd.c:2078:ldlm_handle_cp_callback()) cfs_fail_timeout id 410 awake [ 4191.118356] Lustre: DEBUG MARKER: == sanityn test 51a: layout lock: refresh layout should work ========================================================== 23:31:32 (1763353892) [ 4195.298567] Lustre: DEBUG MARKER: == sanityn test 51b: layout lock: glimpse should be able to restart if layout changed ========================================================== 23:31:36 (1763353896) [ 4195.377440] LustreError: 216518:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4199.439163] LustreError: 216518:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 4199.446386] LustreError: 216518:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4203.503150] LustreError: 216518:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 4203.519067] LustreError: 216525:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 sleeping for 4000ms [ 4207.575113] LustreError: 216525:0:(glimpse.c:206:cl_glimpse_size0()) cfs_fail_timeout id 1404 awake [ 4209.811423] Lustre: DEBUG MARKER: == sanityn test 51c: layout lock: IT_LAYOUT blocked and correct layout can be returned ========================================================== 23:31:51 (1763353911) [ 4216.488292] Lustre: DEBUG MARKER: == sanityn test 51d: layout lock: losing layout lock should clean up memory map region ========================================================== 23:31:57 (1763353917) [ 4219.728097] Lustre: DEBUG MARKER: == sanityn test 51e: lfs getstripe does not break leases, part 2 ========================================================== 23:32:01 (1763353921) [ 4223.937086] Lustre: DEBUG MARKER: == sanityn test 54: rename locking ======================= 23:32:05 (1763353925) [ 4247.931506] Lustre: DEBUG MARKER: == sanityn test 55a: rename vs unlink target dir ========= 23:32:29 (1763353949) [ 4255.457542] Lustre: DEBUG MARKER: == sanityn test 55b: rename vs unlink source dir ========= 23:32:37 (1763353957) [ 4262.974575] Lustre: DEBUG MARKER: == sanityn test 55c: rename vs unlink orphan target dir == 23:32:44 (1763353964) [ 4275.865610] Lustre: DEBUG MARKER: == sanityn test 55d: rename file vs link ================= 23:32:57 (1763353977) [ 4285.557243] Lustre: DEBUG MARKER: == sanityn test 55e: rename race AB/BA under the same parent dir ========================================================== 23:33:07 (1763353987) [ 4286.075924] Lustre: DEBUG MARKER: SKIP: sanityn test_55e needs >= 2 MDTs [ 4286.714550] Lustre: DEBUG MARKER: == sanityn test 55f: rename: (P1/A -> P2/B) race with (P2/B -> P1/A) ========================================================== 23:33:08 (1763353988) [ 4299.713153] Lustre: DEBUG MARKER: == sanityn test 55g: rename: race with trylock =========== 23:33:21 (1763354001) [ 4313.788545] Lustre: DEBUG MARKER: == sanityn test 56a: test llverdev with single large stripe ========================================================== 23:33:35 (1763354015) [ 4338.926758] Lustre: DEBUG MARKER: == sanityn test 56b: test llverdev and partial verify of wide stripe file ========================================================== 23:34:00 (1763354040) [ 4377.603282] Lustre: DEBUG MARKER: == sanityn test 60: Verify data_version behaviour ======== 23:34:39 (1763354079) [ 4380.005964] Lustre: DEBUG MARKER: == sanityn test 70a: cd directory [ 4382.956417] Lustre: DEBUG MARKER: == sanityn test 70b: remove files after calling rm_entry ========================================================== 23:34:44 (1763354084) [ 4386.593169] Lustre: DEBUG MARKER: == sanityn test 71a: correct file map just after write operation is finished ========================================================== 23:34:48 (1763354088) [ 4387.216213] Lustre: DEBUG MARKER: SKIP: sanityn test_71a ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 4387.787841] Lustre: DEBUG MARKER: == sanityn test 71b: check fiemap support for stripecount > 1 ========================================================== 23:34:49 (1763354089) [ 4388.455202] Lustre: DEBUG MARKER: SKIP: sanityn test_71b ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 4389.095487] Lustre: DEBUG MARKER: == sanityn test 71c: check FIEMAP_EXTENT_LAST flag with different extents number ========================================================== 23:34:50 (1763354090) [ 4389.596777] Lustre: DEBUG MARKER: SKIP: sanityn test_71c support only ldiskfs ost [ 4390.137617] Lustre: DEBUG MARKER: == sanityn test 71d: fiemap corruption test with fm_extent_count=0 ========================================================== 23:34:51 (1763354091) [ 4390.646632] Lustre: DEBUG MARKER: SKIP: sanityn test_71d ORI-366/LU-1941: FIEMAP unimplemented on ZFS [ 4391.186878] Lustre: DEBUG MARKER: == sanityn test 72: getxattr/setxattr cache should be consistent between nodes ========================================================== 23:34:52 (1763354092) [ 4393.419339] Lustre: DEBUG MARKER: == sanityn test 73: getxattr should not cause xattr lock cancellation ========================================================== 23:34:55 (1763354095) [ 4395.514677] Lustre: DEBUG MARKER: == sanityn test 74: flock deadlock: different mounts ======================================================================== 23:34:57 (1763354097) [ 4398.585249] LustreError: lustre-MDT0000-mdc-ffff9cd2050d8800: operation ldlm_enqueue to node 192.168.202.154@tcp failed: rc = -35 [ 4401.600272] Lustre: DEBUG MARKER: == sanityn test 75: osc: upcall after unuse lock============================================================================= 23:35:03 (1763354103) [ 4401.735686] LustreError: 2368:0:(osc_request.c:3093:osc_enqueue_interpret()) cfs_fail_timeout id 31d sleeping for 2000ms [ 4403.815114] LustreError: 2368:0:(osc_request.c:3093:osc_enqueue_interpret()) cfs_fail_timeout id 31d awake [ 4408.797602] Lustre: DEBUG MARKER: == sanityn test 76: Verify MDT open_files listing ======== 23:35:10 (1763354110) [ 4427.580132] Lustre: DEBUG MARKER: == sanityn test 77a: check FIFO NRS policy =============== 23:35:29 (1763354129) [ 4430.634331] Lustre: DEBUG MARKER: == sanityn test 77b: check CRR-N NRS policy ============== 23:35:32 (1763354132) [ 4434.826483] Lustre: DEBUG MARKER: == sanityn test 77c: check ORR NRS policy ================ 23:35:36 (1763354136) [ 4439.938599] Lustre: DEBUG MARKER: == sanityn test 77d: check TRR nrs policy ================ 23:35:41 (1763354141) [ 4444.860251] Lustre: DEBUG MARKER: == sanityn test 77e: check TBF NID nrs policy ============ 23:35:46 (1763354146) [ 4451.296161] Lustre: lustre-OST0000-osc-ffff9cd209739000: disconnect after 23s idle [ 4451.299219] Lustre: Skipped 2 previous similar messages [ 4452.714489] Lustre: DEBUG MARKER: == sanityn test 77f: check TBF JobID nrs policy ========== 23:35:54 (1763354154) [ 4460.462426] Lustre: DEBUG MARKER: == sanityn test 77g: Change TBF type directly ============ 23:36:01 (1763354161) [ 4464.232185] Lustre: DEBUG MARKER: == sanityn test 77h: Wrong policy name should report error, not LBUG ========================================================== 23:36:05 (1763354165) [ 4468.223762] Lustre: DEBUG MARKER: == sanityn test 77i: Change rank of TBF rule ============= 23:36:09 (1763354169) [ 4475.383299] Lustre: DEBUG MARKER: == sanityn test 77j: check TBF-OPCode NRS policy ========= 23:36:16 (1763354176) [ 4520.794589] Lustre: DEBUG MARKER: == sanityn test 77ja: check TBF-UID/GID NRS policy ======= 23:37:02 (1763354222) [ 4653.743822] Lustre: DEBUG MARKER: == sanityn test 77jb: check TBF-UID/GID NRS policy on files that don't belong to us ========================================================== 23:39:15 (1763354355) [ 4786.938576] Lustre: DEBUG MARKER: == sanityn test 77k: check TBF policy with UID/GID/JobID/OPCode expression ========================================================== 23:41:28 (1763354488) [ 5086.175164] Lustre: lustre-OST0001-osc-ffff9cd2050d8800: disconnect after 22s idle [ 5086.177543] Lustre: Skipped 16 previous similar messages [ 5150.072778] Lustre: DEBUG MARKER: == sanityn test 77kb: Check different granular TBF type combination: jobid+opcode ========================================================== 23:47:31 (1763354851) [ 5187.695483] Lustre: DEBUG MARKER: == sanityn test 77kc: Check different granular TBF type combination: nid+opcode ========================================================== 23:48:09 (1763354889) [ 5228.923385] Lustre: DEBUG MARKER: == sanityn test 77kd: Check different granular TBF type combination: nid+jobid ========================================================== 23:48:50 (1763354930) [ 5260.238787] Lustre: DEBUG MARKER: == sanityn test 77ke: Check different granular TBF type combination: jobid+opcode ========================================================== 23:49:21 (1763354961) [ 5341.258155] Lustre: DEBUG MARKER: == sanityn test 77kf: Check different granular TBF type combination: uid+gid ========================================================== 23:50:42 (1763355042) [ 5407.121566] Lustre: DEBUG MARKER: == sanityn test 77kg: check different granular TBF type combination: uid+opcode ========================================================== 23:51:48 (1763355108) [ 5525.790475] Lustre: DEBUG MARKER: == sanityn test 77kh: Verify that Project ID support for NRS TBF rule ========================================================== 23:53:47 (1763355227) [ 5526.822684] LustreError: 258338:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd2050d8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5526.826764] LustreError: 258338:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 5526.839137] Lustre: Unmounted lustre-client [ 5527.606314] LustreError: 258351:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd209739000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 5527.609667] LustreError: 258351:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 5527.613300] LustreError: 258351:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 5527.615011] LustreError: 258351:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 5527.625900] Lustre: Unmounted lustre-client [ 5550.986279] Lustre: Mounted lustre-client [ 5552.492574] Lustre: Mounted lustre-client [ 5553.447414] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 5639.290268] Lustre: DEBUG MARKER: == sanityn test 77ki: Add rule with unsupported TBF type should fail for generic TBF ========================================================== 23:55:40 (1763355340) [ 5647.167141] Lustre: DEBUG MARKER: == sanityn test 77l: check the output of NRS policies for generic TBF ========================================================== 23:55:48 (1763355348) [ 5650.411666] Lustre: DEBUG MARKER: == sanityn test 77m: check NRS Delay slows write RPC processing ========================================================== 23:55:51 (1763355351) [ 5700.585377] Lustre: DEBUG MARKER: == sanityn test 77n: check wildcard support for TBF JobID NRS policy ========================================================== 23:56:42 (1763355402) [ 5726.687232] Lustre: lustre-OST0001-osc-ffff9cd20a145000: disconnect after 20s idle [ 5726.689463] Lustre: Skipped 17 previous similar messages [ 5767.323382] Lustre: DEBUG MARKER: == sanityn test 77o: Changing rank should not panic ====== 23:57:48 (1763355468) [ 5771.126671] Lustre: DEBUG MARKER: == sanityn test 77q: Parallel TBF rule definitions should not panic ========================================================== 23:57:52 (1763355472) [ 5810.504984] Lustre: DEBUG MARKER: == sanityn test 77p: Check validity of rule names for TBF policies ========================================================== 23:58:32 (1763355512) [ 5822.532628] Lustre: DEBUG MARKER: == sanityn test 77r: Change type of tbf policy at run time ========================================================== 23:58:44 (1763355524) [ 5864.691638] Lustre: DEBUG MARKER: == sanityn test 78: Enable policy and specify tunings right away ========================================================== 23:59:26 (1763355566) [ 5867.697133] Lustre: DEBUG MARKER: == sanityn test 79: xattr: intent error ================== 23:59:29 (1763355569) [ 5880.516409] Lustre: DEBUG MARKER: == sanityn test 80a: migrate directory when some children is being opened ========================================================== 23:59:42 (1763355582) [ 5881.055957] Lustre: DEBUG MARKER: SKIP: sanityn test_80a needs >= 2 MDTs [ 5881.633342] Lustre: DEBUG MARKER: == sanityn test 80b: Accessing directory during migration ========================================================== 23:59:43 (1763355583) [ 5882.170356] Lustre: DEBUG MARKER: SKIP: sanityn test_80b needs >= 2 MDTs [ 5882.765624] Lustre: DEBUG MARKER: == sanityn test 81a: rename and stat under striped directory ========================================================== 23:59:44 (1763355584) [ 5883.280976] Lustre: DEBUG MARKER: SKIP: sanityn test_81a needs >= 2 MDTs [ 5883.863208] Lustre: DEBUG MARKER: == sanityn test 81b: rename under striped directory doesn't deadlock ========================================================== 23:59:45 (1763355585) [ 5884.361715] Lustre: DEBUG MARKER: SKIP: sanityn test_81b We need at least 2 MDTs for this test [ 5884.945584] Lustre: DEBUG MARKER: == sanityn test 81c: rename revoke LOOKUP lock for remote object ========================================================== 23:59:46 (1763355586) [ 5885.493274] Lustre: DEBUG MARKER: SKIP: sanityn test_81c needs >= 4 MDTs [ 5886.066225] Lustre: DEBUG MARKER: == sanityn test 81d: parallel rename file cross-dir on same MDT ========================================================== 23:59:47 (1763355587) [ 5911.908458] Lustre: DEBUG MARKER: == sanityn test 82: fsetxattr and fgetxattr on orphan files ========================================================== 00:00:13 (1763355613) [ 5913.961805] Lustre: DEBUG MARKER: == sanityn test 83: access striped directory while it is being created/unlinked ========================================================== 00:00:15 (1763355615) [ 5914.467290] Lustre: DEBUG MARKER: SKIP: sanityn test_83 needs >= 2 MDTs [ 5915.027199] Lustre: DEBUG MARKER: == sanityn test 84: 0-nlink race in lu_object_find() ===== 00:00:16 (1763355616) [ 5922.541349] Lustre: DEBUG MARKER: == sanityn test 85: Lustre API root cache race =========== 00:00:24 (1763355624) [ 5925.382348] Lustre: DEBUG MARKER: == sanityn test 90: open/create and unlink striped directory ========================================================== 00:00:26 (1763355626) [ 5925.923274] Lustre: DEBUG MARKER: SKIP: sanityn test_90 needs >= 2 MDTs [ 5926.513778] Lustre: DEBUG MARKER: == sanityn test 91: chmod and unlink striped directory === 00:00:28 (1763355628) [ 5927.085773] Lustre: DEBUG MARKER: SKIP: sanityn test_91 needs >= 2 MDTs [ 5927.638641] Lustre: DEBUG MARKER: == sanityn test 92: create remote directory under orphan directory ========================================================== 00:00:29 (1763355629) [ 5928.128494] Lustre: DEBUG MARKER: SKIP: sanityn test_92 needs >= 2 MDTs [ 5928.662059] Lustre: DEBUG MARKER: == sanityn test 93: alloc_rr should not allocate on same ost ========================================================== 00:00:30 (1763355630) [ 5937.634821] Lustre: DEBUG MARKER: == sanityn test 94: signal vs CP callback race =========== 00:00:39 (1763355639) [ 5937.716866] Lustre: DEBUG MARKER: write [ 5937.731672] LustreError: 259750:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 sleeping for 5000ms [ 5939.736562] Lustre: DEBUG MARKER: kill 287561 [ 5939.738294] LustreError: 287561:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 sleeping for 6000ms [ 5942.831114] LustreError: 259750:0:(ldlm_request.c:1402:ldlm_cli_cancel_req()) cfs_fail_timeout id 312 awake [ 5945.775238] LustreError: 287561:0:(ldlm_request.c:1287:ldlm_cli_cancel_local()) cfs_fail_timeout id 329 awake [ 5948.038025] Lustre: DEBUG MARKER: == sanityn test 95a: Check readpage() on a page that was removed from page cache ========================================================== 00:00:49 (1763355649) [ 5950.212490] LustreError: 288165:0:(rw.c:1968:ll_readpage()) cfs_fail_timeout id 1422 sleeping for 10000ms [ 5960.303095] LustreError: 288165:0:(rw.c:1968:ll_readpage()) cfs_fail_timeout id 1422 awake [ 5962.721667] Lustre: DEBUG MARKER: == sanityn test 95b: Check readpage() on a page that is no longer uptodate ========================================================== 00:01:04 (1763355664) [ 5962.826225] LustreError: 288757:0:(rw.c:2213:ll_readpage()) cfs_fail_timeout id 1424 sleeping for 5000ms [ 5964.911143] LustreError: 288757:0:(rw.c:2213:ll_readpage()) cfs_fail_timeout interrupted [ 5971.155890] Lustre: DEBUG MARKER: == sanityn test 100a: DoM: glimpse RPCs for stat without IO lock (DoM only file) ========================================================== 00:01:12 (1763355672) [ 5971.667216] Lustre: DEBUG MARKER: SKIP: sanityn test_100a Reserved for glimpse-ahead [ 5972.193911] Lustre: DEBUG MARKER: == sanityn test 100b: DoM: no glimpse RPC for stat with IO lock (DoM only file) ========================================================== 00:01:13 (1763355673) [ 5974.426266] Lustre: DEBUG MARKER: == sanityn test 100c: DoM: write vs stat without IO lock (combined file) ========================================================== 00:01:15 (1763355675) [ 5976.748609] Lustre: DEBUG MARKER: == sanityn test 100d: DoM: write+truncate vs stat without IO lock (combined file) ========================================================== 00:01:18 (1763355678) [ 5978.899753] Lustre: DEBUG MARKER: == sanityn test 100e: DoM: read on open and file size ==== 00:01:20 (1763355680) [ 5981.164277] Lustre: DEBUG MARKER: == sanityn test 101a: Discard DoM data on unlink ========= 00:01:22 (1763355682) [ 5983.495457] Lustre: DEBUG MARKER: == sanityn test 101b: Discard DoM data on rename ========= 00:01:25 (1763355685) [ 5985.997346] Lustre: DEBUG MARKER: == sanityn test 101c: Discard DoM data on close-unlink === 00:01:27 (1763355687) [ 5989.204169] Lustre: DEBUG MARKER: SKIP: sanityn test_102 skipping ALWAYS excluded test 102 [ 5989.739395] Lustre: DEBUG MARKER: == sanityn test 103: Test size correctness with lockahead ========================================================== 00:01:31 (1763355691) [ 5990.375700] Lustre: *** cfs_fail_loc=415, val=0*** [ 5997.056021] Lustre: DEBUG MARKER: == sanityn test 104: Verify that MDS stores atime/mtime/ctime during close ========================================================== 00:01:38 (1763355698) [ 5997.550747] Lustre: DEBUG MARKER: SKIP: sanityn test_104 ldiskfs only test [ 5998.131264] Lustre: DEBUG MARKER: == sanityn test 105: Glimpse and lock cancel race ======== 00:01:39 (1763355699) [ 5998.199857] LustreError: 259750:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 sleeping for 5000ms [ 5998.203086] LustreError: 259750:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 3 previous similar messages [ 6003.295110] LustreError: 259749:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 6003.297453] LustreError: 259749:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 2 previous similar messages [ 6013.487177] LustreError: 271183:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) cfs_fail_timeout id 416 awake [ 6013.489533] LustreError: 271183:0:(osc_lock.c:407:osc_dlm_blocking_ast0()) Skipped 4 previous similar messages [ 6015.831269] Lustre: DEBUG MARKER: == sanityn test 106a: Verify the btime via statx() ======= 00:01:57 (1763355717) [ 6016.388858] Lustre: DEBUG MARKER: SKIP: sanityn test_106a Test only for ldiskfs and statx() supported [ 6017.022621] Lustre: DEBUG MARKER: == sanityn test 106b: Glimpse RPCs test for statx ======== 00:01:58 (1763355718) [ 6019.663784] Lustre: DEBUG MARKER: == sanityn test 106c: Verify statx attributes mask ======= 00:02:01 (1763355721) [ 6021.954988] Lustre: DEBUG MARKER: == sanityn test 107a: Basic grouplock conflict =========== 00:02:03 (1763355723) [ 6026.266943] Lustre: DEBUG MARKER: == sanityn test 107b: Grouplock is added to the head of waiting list ========================================================== 00:02:07 (1763355727) [ 6034.776290] Lustre: DEBUG MARKER: == sanityn test 108a: lseek: parallel updates ============ 00:02:16 (1763355736) [ 6034.937543] LustreError: 248739:0:(osc_request.c:2944:osc_build_rpc()) cfs_fail_timeout id 414 sleeping for 4000ms [ 6034.942166] LustreError: 248739:0:(osc_request.c:2944:osc_build_rpc()) Skipped 5 previous similar messages [ 6038.999147] LustreError: 248739:0:(osc_request.c:2944:osc_build_rpc()) cfs_fail_timeout id 414 awake [ 6039.001011] LustreError: 248739:0:(osc_request.c:2944:osc_build_rpc()) Skipped 1 previous similar message [ 6041.340994] Lustre: DEBUG MARKER: == sanityn test 109: Race with several mount instances on 1 node ========================================================== 00:02:22 (1763355742) [ 6041.952954] LustreError: 298698:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd20a145000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6041.957144] LustreError: 298698:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 6041.961858] LustreError: 298698:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6041.963991] LustreError: 298698:0:(obd_class.h:479:obd_check_dev()) Skipped 7 previous similar messages [ 6041.978071] Lustre: Unmounted lustre-client [ 6042.696855] Lustre: DEBUG MARKER: Iteration 1 [ 6042.796060] LustreError: 298878:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6042.796083] LustreError: 298879:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6042.801024] LustreError: 298878:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6042.839197] Lustre: Mounted lustre-client [ 6044.243957] Key type lgssc unregistered [ 6044.375625] LNet: 299226:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6044.378186] LNetError: 299226:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6044.390332] LNet: Removed LNI 192.168.202.54@tcp [ 6044.781090] Key type .llcrypt unregistered [ 6044.782179] Key type ._llcrypt unregistered [ 6045.111343] Key type ._llcrypt registered [ 6045.112487] Key type .llcrypt registered [ 6045.444840] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6045.451689] alg: No test for adler32 (adler32-zlib) [ 6046.435336] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6046.701761] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6048.327151] Key type lgssc registered [ 6048.885957] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6052.760721] Lustre: DEBUG MARKER: Iteration 2 [ 6052.866334] LustreError: 300045:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6052.866593] LustreError: 300044:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6052.870692] LustreError: 300045:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6053.920591] Lustre: Mounted lustre-client [ 6054.349694] LustreError: 300151:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd20738b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6054.354463] LustreError: 300151:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 6054.412120] Lustre: Unmounted lustre-client [ 6054.413933] Lustre: Skipped 1 previous similar message [ 6055.468373] Key type lgssc unregistered [ 6055.612511] LNet: 300391:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6055.619308] LNetError: 300391:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6055.632234] LNet: Removed LNI 192.168.202.54@tcp [ 6055.992119] Key type .llcrypt unregistered [ 6055.993587] Key type ._llcrypt unregistered [ 6056.266492] Key type ._llcrypt registered [ 6056.268758] Key type .llcrypt registered [ 6056.447948] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6056.452616] alg: No test for adler32 (adler32-zlib) [ 6057.306694] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6057.388035] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6058.967199] Key type lgssc registered [ 6059.326431] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6062.584865] Lustre: DEBUG MARKER: Iteration 3 [ 6062.695310] LustreError: 301209:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6062.695764] LustreError: 301210:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6062.702174] LustreError: 301209:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6063.745634] Lustre: Mounted lustre-client [ 6064.202777] LustreError: 301318:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd207389800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6064.205483] LustreError: 301319:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6064.205899] LustreError: 301318:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6064.227060] Lustre: Unmounted lustre-client [ 6065.242696] Key type lgssc unregistered [ 6065.369710] LNet: 301560:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6065.372037] LNetError: 301560:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6065.381597] LNet: Removed LNI 192.168.202.54@tcp [ 6065.623104] Key type .llcrypt unregistered [ 6065.624157] Key type ._llcrypt unregistered [ 6065.877375] Key type ._llcrypt registered [ 6065.878403] Key type .llcrypt registered [ 6066.070573] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6066.076844] alg: No test for adler32 (adler32-zlib) [ 6066.930719] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6067.020098] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6068.607163] Key type lgssc registered [ 6069.006302] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6072.555073] Lustre: DEBUG MARKER: Iteration 4 [ 6072.676917] LustreError: 302376:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6072.677088] LustreError: 302377:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6072.681629] LustreError: 302376:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6073.724202] Lustre: Mounted lustre-client [ 6073.726236] Lustre: Skipped 1 previous similar message [ 6074.186623] LustreError: 302484:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd227bd8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6074.189729] LustreError: 302484:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6074.193295] LustreError: 302485:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 6074.196306] LustreError: 302485:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6074.212166] Lustre: Unmounted lustre-client [ 6075.204533] Key type lgssc unregistered [ 6075.315803] LNet: 302725:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6075.318058] LNetError: 302725:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6075.328834] LNet: Removed LNI 192.168.202.54@tcp [ 6075.542104] Key type .llcrypt unregistered [ 6075.543128] Key type ._llcrypt unregistered [ 6075.783566] Key type ._llcrypt registered [ 6075.787606] Key type .llcrypt registered [ 6076.007770] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6076.013976] alg: No test for adler32 (adler32-zlib) [ 6076.864505] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6076.942795] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6078.527151] Key type lgssc registered [ 6078.971481] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6082.840541] Lustre: DEBUG MARKER: Iteration 5 [ 6082.941152] LustreError: 303541:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6082.941474] LustreError: 303543:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6082.948347] LustreError: 303541:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6083.998911] Lustre: Mounted lustre-client [ 6084.494540] LustreError: 303650:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd22d31d800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6084.496249] LustreError: 303651:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6084.498974] LustreError: 303650:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6084.511320] Lustre: Unmounted lustre-client [ 6085.535492] Key type lgssc unregistered [ 6085.663755] LNet: 303893:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6085.666146] LNetError: 303893:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6085.675305] LNet: Removed LNI 192.168.202.54@tcp [ 6085.938134] Key type .llcrypt unregistered [ 6085.939286] Key type ._llcrypt unregistered [ 6086.199073] Key type ._llcrypt registered [ 6086.211551] Key type .llcrypt registered [ 6086.402323] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6086.407092] alg: No test for adler32 (adler32-zlib) [ 6087.295060] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6087.400903] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6089.007165] Key type lgssc registered [ 6089.373523] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6092.817704] Lustre: DEBUG MARKER: Iteration 6 [ 6092.950660] LustreError: 304711:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6092.952722] LustreError: 304712:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6092.954536] LustreError: 304711:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6094.006724] Lustre: Mounted lustre-client [ 6094.587032] LustreError: 304813:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd23540b800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6094.589618] LustreError: 304812:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6094.590101] LustreError: 304813:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6094.615882] Lustre: Unmounted lustre-client [ 6095.660416] Key type lgssc unregistered [ 6095.790746] LNet: 305055:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6095.793844] LNetError: 305055:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6095.802403] LNet: Removed LNI 192.168.202.54@tcp [ 6096.064261] Key type .llcrypt unregistered [ 6096.065784] Key type ._llcrypt unregistered [ 6096.310881] Key type ._llcrypt registered [ 6096.311800] Key type .llcrypt registered [ 6096.611344] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6096.623809] alg: No test for adler32 (adler32-zlib) [ 6097.502120] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6097.581543] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6099.167159] Key type lgssc registered [ 6099.630200] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6102.974328] Lustre: DEBUG MARKER: Iteration 7 [ 6103.072753] LustreError: 305872:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6103.073240] LustreError: 305873:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6103.078777] LustreError: 305872:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6104.122137] Lustre: Mounted lustre-client [ 6104.124265] Lustre: Skipped 1 previous similar message [ 6104.585967] LustreError: 305974:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd21233d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6104.589534] LustreError: 305974:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6104.593175] LustreError: 305975:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6104.594954] LustreError: 305975:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6104.615055] Lustre: Unmounted lustre-client [ 6105.601964] Key type lgssc unregistered [ 6105.727526] LNet: 306216:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6105.731293] LNetError: 306216:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6105.744378] LNet: Removed LNI 192.168.202.54@tcp [ 6106.001148] Key type .llcrypt unregistered [ 6106.003056] Key type ._llcrypt unregistered [ 6106.316274] Key type ._llcrypt registered [ 6106.319370] Key type .llcrypt registered [ 6106.560571] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6106.565788] alg: No test for adler32 (adler32-zlib) [ 6107.439479] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6107.538339] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6109.127269] Key type lgssc registered [ 6109.562783] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6113.207162] Lustre: DEBUG MARKER: Iteration 8 [ 6113.304065] LustreError: 307033:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6113.306972] LustreError: 307034:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6113.309385] LustreError: 307033:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6114.364288] Lustre: Mounted lustre-client [ 6114.365318] Lustre: Skipped 1 previous similar message [ 6114.957067] LustreError: 307142:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd21ed71000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6114.960405] LustreError: 307143:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 6114.962464] LustreError: 307142:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6114.979691] Lustre: Unmounted lustre-client [ 6116.054767] Key type lgssc unregistered [ 6116.170537] LNet: 307383:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6116.173405] LNetError: 307383:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6116.181660] LNet: Removed LNI 192.168.202.54@tcp [ 6116.400083] Key type .llcrypt unregistered [ 6116.401764] Key type ._llcrypt unregistered [ 6116.760521] Key type ._llcrypt registered [ 6116.761621] Key type .llcrypt registered [ 6117.026818] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6117.032319] alg: No test for adler32 (adler32-zlib) [ 6117.907763] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6118.002307] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6119.591209] Key type lgssc registered [ 6120.032747] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6123.527376] Lustre: DEBUG MARKER: Iteration 9 [ 6123.635120] LustreError: 308200:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6123.637755] LustreError: 308201:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6123.640905] LustreError: 308200:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6124.702107] Lustre: Mounted lustre-client [ 6124.703103] Lustre: Skipped 1 previous similar message [ 6125.271290] LustreError: 308303:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd202897000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6125.275718] LustreError: 308302:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6125.277331] LustreError: 308303:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6125.291805] Lustre: Unmounted lustre-client [ 6126.320671] Key type lgssc unregistered [ 6126.433330] LNet: 308544:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6126.435591] LNetError: 308544:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6126.442390] LNet: Removed LNI 192.168.202.54@tcp [ 6126.672136] Key type .llcrypt unregistered [ 6126.673168] Key type ._llcrypt unregistered [ 6126.902332] Key type ._llcrypt registered [ 6126.905201] Key type .llcrypt registered [ 6127.084798] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6127.091420] alg: No test for adler32 (adler32-zlib) [ 6127.938351] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6128.015646] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6129.591199] Key type lgssc registered [ 6130.046739] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6133.553128] Lustre: DEBUG MARKER: Iteration 10 [ 6133.645772] LustreError: 309363:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6133.646099] LustreError: 309364:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6133.649959] LustreError: 309363:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6134.696828] Lustre: Mounted lustre-client [ 6135.262920] LustreError: 309470:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd232c1c800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6135.266146] LustreError: 309470:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6135.269511] LustreError: 309472:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6135.271506] LustreError: 309472:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6135.290194] Lustre: Unmounted lustre-client [ 6136.349652] Key type lgssc unregistered [ 6136.471592] LNet: 309712:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6136.474299] LNetError: 309712:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6136.481807] LNet: Removed LNI 192.168.202.54@tcp [ 6136.752099] Key type .llcrypt unregistered [ 6136.753552] Key type ._llcrypt unregistered [ 6137.087393] Key type ._llcrypt registered [ 6137.088780] Key type .llcrypt registered [ 6137.348196] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6137.354160] alg: No test for adler32 (adler32-zlib) [ 6138.216500] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6138.305188] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6139.895098] Key type lgssc registered [ 6140.298100] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6143.733328] Lustre: DEBUG MARKER: Iteration 11 [ 6143.838338] LustreError: 310530:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6143.838665] LustreError: 310531:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6143.843834] LustreError: 310530:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6144.895988] Lustre: Mounted lustre-client [ 6144.897371] Lustre: Skipped 1 previous similar message [ 6145.361093] LustreError: 310639:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd2087fc800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6145.364455] LustreError: 310638:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6145.364603] LustreError: 310639:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6145.385118] Lustre: Unmounted lustre-client [ 6146.455360] Key type lgssc unregistered [ 6146.579402] LNet: 310881:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6146.581754] LNetError: 310881:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6146.589789] LNet: Removed LNI 192.168.202.54@tcp [ 6146.840160] Key type .llcrypt unregistered [ 6146.841211] Key type ._llcrypt unregistered [ 6147.108576] Key type ._llcrypt registered [ 6147.109587] Key type .llcrypt registered [ 6147.297924] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6147.312034] alg: No test for adler32 (adler32-zlib) [ 6148.159586] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6148.252371] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6149.839190] Key type lgssc registered [ 6150.240142] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6154.140384] Lustre: DEBUG MARKER: Iteration 12 [ 6154.259416] LustreError: 311698:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6154.259470] LustreError: 311699:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6154.264097] LustreError: 311698:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6155.319124] Lustre: Mounted lustre-client [ 6155.869359] LustreError: 311807:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd232c1f800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6155.872430] LustreError: 311807:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6155.873365] LustreError: 311806:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6155.894572] Lustre: Unmounted lustre-client [ 6156.964746] Key type lgssc unregistered [ 6157.103926] LNet: 312048:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6157.109933] LNetError: 312048:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6157.120927] LNet: Removed LNI 192.168.202.54@tcp [ 6157.443197] Key type .llcrypt unregistered [ 6157.446366] Key type ._llcrypt unregistered [ 6157.804115] Key type ._llcrypt registered [ 6157.804995] Key type .llcrypt registered [ 6158.031567] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6158.036366] alg: No test for adler32 (adler32-zlib) [ 6158.934192] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6159.041046] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6160.639139] Key type lgssc registered [ 6161.078681] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6164.770818] Lustre: DEBUG MARKER: Iteration 13 [ 6164.915257] LustreError: 312865:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6164.917027] LustreError: 312866:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6164.920041] LustreError: 312865:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6165.969377] Lustre: Mounted lustre-client [ 6165.970513] Lustre: Skipped 1 previous similar message [ 6166.422462] LustreError: 312974:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd20299e000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6166.426374] LustreError: 312974:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6166.430025] LustreError: 312973:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6166.431837] LustreError: 312973:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6166.457409] Lustre: Unmounted lustre-client [ 6167.471758] Key type lgssc unregistered [ 6167.581494] LNet: 313216:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6167.584272] LNetError: 313216:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6167.591767] LNet: Removed LNI 192.168.202.54@tcp [ 6167.824205] Key type .llcrypt unregistered [ 6167.826443] Key type ._llcrypt unregistered [ 6168.099037] Key type ._llcrypt registered [ 6168.101812] Key type .llcrypt registered [ 6168.294085] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6168.299855] alg: No test for adler32 (adler32-zlib) [ 6169.153686] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6169.237853] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6170.815206] Key type lgssc registered [ 6171.318690] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6174.979992] Lustre: DEBUG MARKER: Iteration 14 [ 6175.106131] LustreError: 314034:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6175.106433] LustreError: 314035:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6175.110331] LustreError: 314034:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6176.156195] Lustre: Mounted lustre-client [ 6176.706679] LustreError: 314137:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd2118e0000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6176.709496] LustreError: 314138:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6176.710163] LustreError: 314137:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6176.726914] Lustre: Unmounted lustre-client [ 6178.064343] Key type lgssc unregistered [ 6178.193674] LNet: 314379:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6178.196211] LNetError: 314379:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6178.205720] LNet: Removed LNI 192.168.202.54@tcp [ 6178.561105] Key type .llcrypt unregistered [ 6178.562313] Key type ._llcrypt unregistered [ 6178.809709] Key type ._llcrypt registered [ 6178.811159] Key type .llcrypt registered [ 6179.043036] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6179.047914] alg: No test for adler32 (adler32-zlib) [ 6179.912115] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6179.994605] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6181.575248] Key type lgssc registered [ 6181.980485] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6185.264930] Lustre: DEBUG MARKER: Iteration 15 [ 6185.392085] LustreError: 315195:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6185.392160] LustreError: 315197:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6185.398386] LustreError: 315195:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6186.456463] Lustre: Mounted lustre-client [ 6187.058572] LustreError: 315303:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd2107a7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6187.063670] LustreError: 315303:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6187.084057] Lustre: Unmounted lustre-client [ 6188.061973] Key type lgssc unregistered [ 6188.181498] LNet: 315545:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6188.184939] LNetError: 315545:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6188.194840] LNet: Removed LNI 192.168.202.54@tcp [ 6188.436144] Key type .llcrypt unregistered [ 6188.437112] Key type ._llcrypt unregistered [ 6188.663213] Key type ._llcrypt registered [ 6188.664536] Key type .llcrypt registered [ 6188.895142] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6188.903071] alg: No test for adler32 (adler32-zlib) [ 6189.777916] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6189.876306] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6191.471104] Key type lgssc registered [ 6191.846455] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6195.110711] Lustre: DEBUG MARKER: Iteration 16 [ 6195.231120] LustreError: 316363:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6195.231486] LustreError: 316364:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6195.236168] LustreError: 316363:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6196.292716] Lustre: Mounted lustre-client [ 6196.294365] Lustre: Skipped 1 previous similar message [ 6196.745698] LustreError: 316468:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd202ca1000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6196.749029] LustreError: 316468:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6196.749613] LustreError: 316469:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6196.752699] LustreError: 316469:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6196.768085] Lustre: Unmounted lustre-client [ 6197.645943] Key type lgssc unregistered [ 6197.760643] LNet: 316709:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6197.763144] LNetError: 316709:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6197.771784] LNet: Removed LNI 192.168.202.54@tcp [ 6198.023118] Key type .llcrypt unregistered [ 6198.024815] Key type ._llcrypt unregistered [ 6198.321317] Key type ._llcrypt registered [ 6198.322301] Key type .llcrypt registered [ 6198.562472] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6198.587170] alg: No test for adler32 (adler32-zlib) [ 6199.447724] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6199.528368] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6201.119144] Key type lgssc registered [ 6201.502299] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6204.878365] Lustre: DEBUG MARKER: Iteration 17 [ 6204.998185] LustreError: 317526:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6204.998233] LustreError: 317527:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6205.003694] LustreError: 317526:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6206.055908] Lustre: Mounted lustre-client [ 6206.622397] LustreError: 317634:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd2050d8800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6206.626362] LustreError: 317635:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6206.628076] LustreError: 317634:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6206.640495] Lustre: Unmounted lustre-client [ 6207.688406] Key type lgssc unregistered [ 6207.811771] LNet: 317876:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6207.813934] LNetError: 317876:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6207.822877] LNet: Removed LNI 192.168.202.54@tcp [ 6208.063156] Key type .llcrypt unregistered [ 6208.064832] Key type ._llcrypt unregistered [ 6208.345324] Key type ._llcrypt registered [ 6208.346850] Key type .llcrypt registered [ 6208.636180] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6208.645184] alg: No test for adler32 (adler32-zlib) [ 6209.491828] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6209.571337] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6211.151135] Key type lgssc registered [ 6211.594052] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6215.282209] Lustre: DEBUG MARKER: Iteration 18 [ 6215.400652] LustreError: 318693:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6215.400733] LustreError: 318694:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6215.406086] LustreError: 318693:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6216.455832] Lustre: Mounted lustre-client [ 6216.457756] Lustre: Skipped 1 previous similar message [ 6216.909070] LustreError: 318796:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd2087fe000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6216.911588] LustreError: 318795:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6216.912140] LustreError: 318796:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6216.941081] Lustre: Unmounted lustre-client [ 6217.829077] Key type lgssc unregistered [ 6217.948568] LNet: 319037:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6217.952831] LNetError: 319037:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6217.963775] LNet: Removed LNI 192.168.202.54@tcp [ 6218.200092] Key type .llcrypt unregistered [ 6218.201148] Key type ._llcrypt unregistered [ 6218.508485] Key type ._llcrypt registered [ 6218.509497] Key type .llcrypt registered [ 6218.717879] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6218.724728] alg: No test for adler32 (adler32-zlib) [ 6219.589309] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6219.678299] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6221.263143] Key type lgssc registered [ 6221.646061] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6225.212436] Lustre: DEBUG MARKER: Iteration 19 [ 6225.320144] LustreError: 319856:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6225.321427] LustreError: 319855:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6225.325653] LustreError: 319856:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6226.379542] Lustre: Mounted lustre-client [ 6226.381149] Lustre: Skipped 1 previous similar message [ 6226.808144] LustreError: 319957:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd2107a7000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6226.811626] LustreError: 319957:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6226.813340] LustreError: 319958:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6226.835790] Lustre: Unmounted lustre-client [ 6227.746290] Key type lgssc unregistered [ 6227.871437] LNet: 320199:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6227.874097] LNetError: 320199:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6227.881825] LNet: Removed LNI 192.168.202.54@tcp [ 6228.114097] Key type .llcrypt unregistered [ 6228.115212] Key type ._llcrypt unregistered [ 6228.343733] Key type ._llcrypt registered [ 6228.344806] Key type .llcrypt registered [ 6228.541926] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6228.555961] alg: No test for adler32 (adler32-zlib) [ 6229.398695] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6229.476367] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6231.063148] Key type lgssc registered [ 6231.446823] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6235.073648] Lustre: DEBUG MARKER: Iteration 20 [ 6235.199201] LustreError: 321016:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6235.199252] LustreError: 321017:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6235.203307] LustreError: 321016:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6236.258620] Lustre: Mounted lustre-client [ 6236.700417] LustreError: 321122:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd207389000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6236.707496] LustreError: 321122:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6236.732282] Lustre: Unmounted lustre-client [ 6236.734116] Lustre: Skipped 1 previous similar message [ 6237.600767] Key type lgssc unregistered [ 6237.723429] LNet: 321366:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6237.725866] LNetError: 321366:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6237.732969] LNet: Removed LNI 192.168.202.54@tcp [ 6237.979129] Key type .llcrypt unregistered [ 6237.980738] Key type ._llcrypt unregistered [ 6238.263333] Key type ._llcrypt registered [ 6238.264650] Key type .llcrypt registered [ 6238.475987] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6238.481565] alg: No test for adler32 (adler32-zlib) [ 6239.336649] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6239.429834] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6241.015181] Key type lgssc registered [ 6241.383479] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6244.611622] Lustre: DEBUG MARKER: Iteration 21 [ 6244.718925] LustreError: 322183:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6244.718993] LustreError: 322184:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6244.722740] LustreError: 322183:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6245.769578] Lustre: Mounted lustre-client [ 6245.770476] Lustre: Skipped 1 previous similar message [ 6246.202436] LustreError: 322291:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd202ca0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6246.206249] LustreError: 322291:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6246.206380] LustreError: 322292:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6246.209826] LustreError: 322292:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6246.232100] Lustre: Unmounted lustre-client [ 6247.100578] Key type lgssc unregistered [ 6247.208508] LNet: 322534:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6247.210885] LNetError: 322534:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6247.221332] LNet: Removed LNI 192.168.202.54@tcp [ 6247.479143] Key type .llcrypt unregistered [ 6247.480139] Key type ._llcrypt unregistered [ 6247.757563] Key type ._llcrypt registered [ 6247.758571] Key type .llcrypt registered [ 6247.964788] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6247.969678] alg: No test for adler32 (adler32-zlib) [ 6248.826592] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6248.903170] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6250.479176] Key type lgssc registered [ 6250.821506] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6254.366991] Lustre: DEBUG MARKER: Iteration 22 [ 6254.484985] LustreError: 323351:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6254.485126] LustreError: 323352:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6254.490658] LustreError: 323351:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6255.541145] Lustre: Mounted lustre-client [ 6255.543357] Lustre: Skipped 1 previous similar message [ 6255.994766] LustreError: 323452:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd2107a2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6255.997950] LustreError: 323452:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6255.998566] LustreError: 323453:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6256.002024] LustreError: 323453:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6256.015071] Lustre: Unmounted lustre-client [ 6256.849755] Key type lgssc unregistered [ 6256.958412] LNet: 323694:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6256.960750] LNetError: 323694:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6256.968689] LNet: Removed LNI 192.168.202.54@tcp [ 6257.192095] Key type .llcrypt unregistered [ 6257.193069] Key type ._llcrypt unregistered [ 6257.490476] Key type ._llcrypt registered [ 6257.491411] Key type .llcrypt registered [ 6257.654193] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6257.659852] alg: No test for adler32 (adler32-zlib) [ 6258.515628] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6258.591957] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6260.167130] Key type lgssc registered [ 6260.520813] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6263.715817] Lustre: DEBUG MARKER: Iteration 23 [ 6263.816287] LustreError: 324511:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6263.816347] LustreError: 324512:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6263.821055] LustreError: 324511:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6264.869473] Lustre: Mounted lustre-client [ 6265.303763] LustreError: 324621:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd2087f9800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6265.305801] LustreError: 324620:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6265.306794] LustreError: 324621:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6265.310901] LustreError: 324620:0:(obd_class.h:479:obd_check_dev()) Skipped 4 previous similar messages [ 6265.319517] Lustre: Unmounted lustre-client [ 6266.206031] Key type lgssc unregistered [ 6266.320448] LNet: 324863:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6266.322712] LNetError: 324863:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6266.332703] LNet: Removed LNI 192.168.202.54@tcp [ 6266.551092] Key type .llcrypt unregistered [ 6266.552247] Key type ._llcrypt unregistered [ 6266.785482] Key type ._llcrypt registered [ 6266.787768] Key type .llcrypt registered [ 6266.986701] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6266.992736] alg: No test for adler32 (adler32-zlib) [ 6267.862870] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6267.957950] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6269.543132] Key type lgssc registered [ 6269.939747] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6273.153059] Lustre: DEBUG MARKER: Iteration 24 [ 6273.264532] LustreError: 325681:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6273.264578] LustreError: 325682:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6273.268484] LustreError: 325681:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6274.318934] Lustre: Mounted lustre-client [ 6274.320046] Lustre: Skipped 1 previous similar message [ 6274.891894] LustreError: 325789:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd227bdb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6274.896494] LustreError: 325789:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6274.936461] Lustre: Unmounted lustre-client [ 6274.937498] Lustre: Skipped 1 previous similar message [ 6275.929118] Key type lgssc unregistered [ 6276.065907] LNet: 326031:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6276.068105] LNetError: 326031:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6276.075732] LNet: Removed LNI 192.168.202.54@tcp [ 6276.329148] Key type .llcrypt unregistered [ 6276.330921] Key type ._llcrypt unregistered [ 6276.657189] Key type ._llcrypt registered [ 6276.658088] Key type .llcrypt registered [ 6276.900617] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6276.911679] alg: No test for adler32 (adler32-zlib) [ 6277.793403] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6277.884722] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6279.471135] Key type lgssc registered [ 6279.835857] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6283.474299] Lustre: DEBUG MARKER: Iteration 25 [ 6283.575636] LustreError: 326849:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6283.575676] LustreError: 326848:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6283.582771] LustreError: 326849:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6284.631620] Lustre: Mounted lustre-client [ 6285.081990] LustreError: 326955:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd23dbef800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6285.087297] LustreError: 326956:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6285.090462] LustreError: 326956:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6285.100085] Lustre: Unmounted lustre-client [ 6285.976430] Key type lgssc unregistered [ 6286.083528] LNet: 327197:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6286.086532] LNetError: 327197:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6286.097675] LNet: Removed LNI 192.168.202.54@tcp [ 6286.316112] Key type .llcrypt unregistered [ 6286.317564] Key type ._llcrypt unregistered [ 6286.598204] Key type ._llcrypt registered [ 6286.602844] Key type .llcrypt registered [ 6286.772176] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6286.777307] alg: No test for adler32 (adler32-zlib) [ 6287.637583] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6287.730677] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6289.319126] Key type lgssc registered [ 6289.713669] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6292.945131] Lustre: DEBUG MARKER: Iteration 26 [ 6293.066348] LustreError: 328014:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6293.066423] LustreError: 328015:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6293.070913] LustreError: 328014:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6294.124976] Lustre: Mounted lustre-client [ 6294.127075] Lustre: Skipped 1 previous similar message [ 6294.599680] LustreError: 328118:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd232c1c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6294.603148] LustreError: 328117:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6294.605674] LustreError: 328118:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6294.620083] Lustre: Unmounted lustre-client [ 6295.588103] Key type lgssc unregistered [ 6295.709659] LNet: 328359:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6295.712720] LNetError: 328359:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6295.722353] LNet: Removed LNI 192.168.202.54@tcp [ 6295.961122] Key type .llcrypt unregistered [ 6295.962747] Key type ._llcrypt unregistered [ 6296.234464] Key type ._llcrypt registered [ 6296.235529] Key type .llcrypt registered [ 6296.419043] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6296.423606] alg: No test for adler32 (adler32-zlib) [ 6297.279830] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6297.358610] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6298.935184] Key type lgssc registered [ 6299.297489] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6302.651451] Lustre: DEBUG MARKER: Iteration 27 [ 6302.767301] LustreError: 329177:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6302.767357] LustreError: 329178:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6302.771434] LustreError: 329177:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6303.820625] Lustre: Mounted lustre-client [ 6304.276190] LustreError: 329285:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd20a879000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6304.278994] LustreError: 329285:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6304.279913] LustreError: 329284:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6304.326509] Lustre: Unmounted lustre-client [ 6305.294541] Key type lgssc unregistered [ 6305.423756] LNet: 329526:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6305.426384] LNetError: 329526:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6305.433654] LNet: Removed LNI 192.168.202.54@tcp [ 6305.679133] Key type .llcrypt unregistered [ 6305.680075] Key type ._llcrypt unregistered [ 6305.993410] Key type ._llcrypt registered [ 6305.994460] Key type .llcrypt registered [ 6306.250310] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6306.261045] alg: No test for adler32 (adler32-zlib) [ 6307.125216] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6307.214675] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6308.807160] Key type lgssc registered [ 6309.251469] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6313.123317] Lustre: DEBUG MARKER: Iteration 28 [ 6313.251076] LustreError: 330350:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6313.251851] LustreError: 330349:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6313.257331] LustreError: 330350:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6314.317215] Lustre: Mounted lustre-client [ 6314.857350] LustreError: 330456:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd2087fa000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6314.860962] LustreError: 330456:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6314.861148] LustreError: 330457:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6314.864580] LustreError: 330457:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6314.880666] Lustre: Unmounted lustre-client [ 6315.871035] Key type lgssc unregistered [ 6315.978766] LNet: 330698:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6315.982131] LNetError: 330698:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6315.989106] LNet: Removed LNI 192.168.202.54@tcp [ 6316.258145] Key type .llcrypt unregistered [ 6316.259143] Key type ._llcrypt unregistered [ 6316.585836] Key type ._llcrypt registered [ 6316.588538] Key type .llcrypt registered [ 6316.811230] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6316.816212] alg: No test for adler32 (adler32-zlib) [ 6317.744055] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6317.873645] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6319.479139] Key type lgssc registered [ 6319.855783] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6323.171727] Lustre: DEBUG MARKER: Iteration 29 [ 6323.274809] LustreError: 331515:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6323.274849] LustreError: 331516:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6323.279414] LustreError: 331515:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6324.324727] Lustre: Mounted lustre-client [ 6324.778045] LustreError: 331622:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd227bdb000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6324.781482] LustreError: 331623:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 6324.783107] LustreError: 331622:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6324.788788] LustreError: 331623:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6324.804942] Lustre: Unmounted lustre-client [ 6325.708667] Key type lgssc unregistered [ 6325.838414] LNet: 331864:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6325.840607] LNetError: 331864:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6325.852418] LNet: Removed LNI 192.168.202.54@tcp [ 6326.076132] Key type .llcrypt unregistered [ 6326.078045] Key type ._llcrypt unregistered [ 6326.320645] Key type ._llcrypt registered [ 6326.322916] Key type .llcrypt registered [ 6326.517897] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6326.522494] alg: No test for adler32 (adler32-zlib) [ 6327.372202] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6327.457064] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6329.039129] Key type lgssc registered [ 6329.409170] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6332.703674] Lustre: DEBUG MARKER: Iteration 30 [ 6332.817229] LustreError: 332681:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6332.817305] LustreError: 332682:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6332.821328] LustreError: 332681:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6333.867241] Lustre: Mounted lustre-client [ 6333.868207] Lustre: Skipped 1 previous similar message [ 6334.410494] LustreError: 332784:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd20a87e800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6334.413291] LustreError: 332784:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6334.414413] LustreError: 332785:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6334.446133] Lustre: Unmounted lustre-client [ 6335.471371] Key type lgssc unregistered [ 6335.588780] LNet: 333026:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6335.591000] LNetError: 333026:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6335.598763] LNet: Removed LNI 192.168.202.54@tcp [ 6335.859109] Key type .llcrypt unregistered [ 6335.860310] Key type ._llcrypt unregistered [ 6336.200254] Key type ._llcrypt registered [ 6336.201577] Key type .llcrypt registered [ 6336.437467] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6336.446948] alg: No test for adler32 (adler32-zlib) [ 6337.312606] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6337.407274] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6338.991158] Key type lgssc registered [ 6339.397156] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6342.706385] Lustre: DEBUG MARKER: Iteration 31 [ 6342.834985] LustreError: 333845:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6342.836360] LustreError: 333846:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6342.838949] LustreError: 333845:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6343.893088] Lustre: Mounted lustre-client [ 6344.492899] LustreError: 333948:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd20a143000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6344.495669] LustreError: 333949:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6344.496388] LustreError: 333948:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6344.515108] Lustre: Unmounted lustre-client [ 6345.573482] Key type lgssc unregistered [ 6345.692928] LNet: 334190:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6345.695450] LNetError: 334190:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6345.703442] LNet: Removed LNI 192.168.202.54@tcp [ 6345.961121] Key type .llcrypt unregistered [ 6345.962888] Key type ._llcrypt unregistered [ 6346.282068] Key type ._llcrypt registered [ 6346.283058] Key type .llcrypt registered [ 6346.530766] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6346.537116] alg: No test for adler32 (adler32-zlib) [ 6347.408184] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6347.492639] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6349.079197] Key type lgssc registered [ 6349.492859] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6352.776163] Lustre: DEBUG MARKER: Iteration 32 [ 6352.885784] LustreError: 335007:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6352.886206] LustreError: 335008:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6352.892581] LustreError: 335007:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6353.941624] Lustre: Mounted lustre-client [ 6353.943050] Lustre: Skipped 1 previous similar message [ 6354.424098] LustreError: 335114:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd207389800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6354.428102] LustreError: 335114:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6354.428591] LustreError: 335115:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6354.474079] Lustre: Unmounted lustre-client [ 6355.488935] Key type lgssc unregistered [ 6355.611768] LNet: 335357:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6355.614212] LNetError: 335357:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6355.622376] LNet: Removed LNI 192.168.202.54@tcp [ 6355.868142] Key type .llcrypt unregistered [ 6355.869200] Key type ._llcrypt unregistered [ 6356.218270] Key type ._llcrypt registered [ 6356.219469] Key type .llcrypt registered [ 6356.481035] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6356.486257] alg: No test for adler32 (adler32-zlib) [ 6357.355081] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6357.441764] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6359.023180] Key type lgssc registered [ 6359.451568] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6362.692722] Lustre: DEBUG MARKER: Iteration 33 [ 6362.787255] LustreError: 336174:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6362.787284] LustreError: 336175:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6362.790831] LustreError: 336174:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6363.832440] Lustre: Mounted lustre-client [ 6364.259583] LustreError: 336280:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd204b01800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6364.262282] LustreError: 336281:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6364.263041] LustreError: 336280:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6364.289139] Lustre: Unmounted lustre-client [ 6364.290139] Lustre: Skipped 1 previous similar message [ 6365.253875] Key type lgssc unregistered [ 6365.368599] LNet: 336521:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6365.372513] LNetError: 336521:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6365.381749] LNet: Removed LNI 192.168.202.54@tcp [ 6365.601094] Key type .llcrypt unregistered [ 6365.602080] Key type ._llcrypt unregistered [ 6365.805935] Key type ._llcrypt registered [ 6365.807175] Key type .llcrypt registered [ 6366.030148] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6366.034931] alg: No test for adler32 (adler32-zlib) [ 6366.898675] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6366.980050] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6368.567115] Key type lgssc registered [ 6368.924258] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6372.128691] Lustre: DEBUG MARKER: Iteration 34 [ 6372.229775] LustreError: 337338:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6372.231227] LustreError: 337339:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6372.235122] LustreError: 337338:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6373.286256] Lustre: Mounted lustre-client [ 6373.833630] LustreError: 337445:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd2118e0800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6373.836443] LustreError: 337445:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6373.836906] LustreError: 337446:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6373.880894] Lustre: Unmounted lustre-client [ 6373.881815] Lustre: Skipped 1 previous similar message [ 6374.845378] Key type lgssc unregistered [ 6374.957498] LNet: 337687:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6374.959634] LNetError: 337687:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6374.967734] LNet: Removed LNI 192.168.202.54@tcp [ 6375.183094] Key type .llcrypt unregistered [ 6375.184123] Key type ._llcrypt unregistered [ 6375.413435] Key type ._llcrypt registered [ 6375.414483] Key type .llcrypt registered [ 6375.601515] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6375.605890] alg: No test for adler32 (adler32-zlib) [ 6376.459600] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6376.534706] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6378.111112] Key type lgssc registered [ 6378.431943] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6381.613080] Lustre: DEBUG MARKER: Iteration 35 [ 6381.706980] LustreError: 338504:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6381.708973] LustreError: 338506:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6381.713396] LustreError: 338504:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6382.752753] Lustre: Mounted lustre-client [ 6383.216610] LustreError: 338610:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd2107a2800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6383.218300] LustreError: 338611:0:(obd_class.h:479:obd_check_dev()) Device 6 not setup [ 6383.219238] LustreError: 338610:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6383.237089] Lustre: Unmounted lustre-client [ 6384.081922] Key type lgssc unregistered [ 6384.180507] LNet: 338853:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6384.184750] LNetError: 338853:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6384.195617] LNet: Removed LNI 192.168.202.54@tcp [ 6384.405089] Key type .llcrypt unregistered [ 6384.406082] Key type ._llcrypt unregistered [ 6384.602225] Key type ._llcrypt registered [ 6384.603165] Key type .llcrypt registered [ 6384.831331] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6384.835951] alg: No test for adler32 (adler32-zlib) [ 6385.690719] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6385.771982] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6387.351107] Key type lgssc registered [ 6387.688047] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6390.836957] Lustre: DEBUG MARKER: Iteration 36 [ 6390.963633] LustreError: 339670:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6390.963722] LustreError: 339671:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6390.967204] LustreError: 339670:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6392.028635] Lustre: Mounted lustre-client [ 6392.568903] LustreError: 339778:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd2087f8000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6392.570857] LustreError: 339777:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6392.572952] LustreError: 339778:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6392.585208] Lustre: Unmounted lustre-client [ 6393.657733] Key type lgssc unregistered [ 6393.776783] LNet: 340019:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6393.779232] LNetError: 340019:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6393.785870] LNet: Removed LNI 192.168.202.54@tcp [ 6394.055133] Key type .llcrypt unregistered [ 6394.056627] Key type ._llcrypt unregistered [ 6394.330503] Key type ._llcrypt registered [ 6394.331995] Key type .llcrypt registered [ 6394.560722] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6394.571870] alg: No test for adler32 (adler32-zlib) [ 6395.438248] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6395.525664] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6397.111174] Key type lgssc registered [ 6397.455227] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6400.770303] Lustre: DEBUG MARKER: Iteration 37 [ 6400.899251] LustreError: 340848:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6400.899307] LustreError: 340849:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6400.903107] LustreError: 340848:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6401.963469] Lustre: Mounted lustre-client [ 6401.964787] Lustre: Skipped 1 previous similar message [ 6402.524722] LustreError: 340955:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd232c1f000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6402.530051] LustreError: 340955:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6402.531884] LustreError: 340955:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6402.552078] Lustre: Unmounted lustre-client [ 6403.712148] Key type lgssc unregistered [ 6403.851605] LNet: 341195:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6403.856352] LNetError: 341195:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6403.865629] LNet: Removed LNI 192.168.202.54@tcp [ 6404.202101] Key type .llcrypt unregistered [ 6404.203177] Key type ._llcrypt unregistered [ 6404.473888] Key type ._llcrypt registered [ 6404.474868] Key type .llcrypt registered [ 6404.659246] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6404.663941] alg: No test for adler32 (adler32-zlib) [ 6405.514547] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6405.597723] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6407.175118] Key type lgssc registered [ 6407.537598] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6410.683990] Lustre: DEBUG MARKER: Iteration 38 [ 6410.806073] LustreError: 342012:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6410.806260] LustreError: 342013:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6410.810497] LustreError: 342012:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6411.858584] Lustre: Mounted lustre-client [ 6411.860071] Lustre: Skipped 1 previous similar message [ 6412.295363] LustreError: 342119:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd219650000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6412.297908] LustreError: 342120:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 6412.298718] LustreError: 342119:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6412.303030] LustreError: 342120:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6412.314070] Lustre: Unmounted lustre-client [ 6413.205095] Key type lgssc unregistered [ 6413.314520] LNet: 342361:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6413.316597] LNetError: 342361:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6413.325681] LNet: Removed LNI 192.168.202.54@tcp [ 6413.530095] Key type .llcrypt unregistered [ 6413.531097] Key type ._llcrypt unregistered [ 6413.780369] Key type ._llcrypt registered [ 6413.782397] Key type .llcrypt registered [ 6413.974288] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6413.979409] alg: No test for adler32 (adler32-zlib) [ 6414.828523] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6414.910683] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6416.495135] Key type lgssc registered [ 6416.870097] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6420.048264] Lustre: DEBUG MARKER: Iteration 39 [ 6420.189842] LustreError: 343180:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6420.190248] LustreError: 343179:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6420.197077] LustreError: 343180:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6421.247969] Lustre: Mounted lustre-client [ 6421.249883] Lustre: Skipped 1 previous similar message [ 6421.683060] LustreError: 343285:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd20a143000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6421.686569] LustreError: 343284:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6421.687678] LustreError: 343285:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6421.703115] Lustre: Unmounted lustre-client [ 6422.482957] Key type lgssc unregistered [ 6422.591488] LNet: 343526:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6422.593706] LNetError: 343526:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6422.602675] LNet: Removed LNI 192.168.202.54@tcp [ 6422.816095] Key type .llcrypt unregistered [ 6422.817119] Key type ._llcrypt unregistered [ 6423.030677] Key type ._llcrypt registered [ 6423.037507] Key type .llcrypt registered [ 6423.249653] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6423.254150] alg: No test for adler32 (adler32-zlib) [ 6424.112336] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6424.195500] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6425.775163] Key type lgssc registered [ 6426.136027] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6429.276055] Lustre: DEBUG MARKER: Iteration 40 [ 6429.401816] LustreError: 344343:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6429.401907] LustreError: 344345:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6429.407630] LustreError: 344343:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6430.455908] Lustre: Mounted lustre-client [ 6430.456886] Lustre: Skipped 1 previous similar message [ 6430.971822] LustreError: 344446:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd219656800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6430.978020] LustreError: 344447:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6430.980570] LustreError: 344447:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6430.991104] Lustre: Unmounted lustre-client [ 6431.804430] Key type lgssc unregistered [ 6431.905509] LNet: 344687:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6431.907649] LNetError: 344687:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6431.916664] LNet: Removed LNI 192.168.202.54@tcp [ 6432.116094] Key type .llcrypt unregistered [ 6432.117082] Key type ._llcrypt unregistered [ 6432.319472] Key type ._llcrypt registered [ 6432.320560] Key type .llcrypt registered [ 6432.528218] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6432.532343] alg: No test for adler32 (adler32-zlib) [ 6433.373338] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6433.445637] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6435.023108] Key type lgssc registered [ 6435.342564] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6438.390284] Lustre: DEBUG MARKER: Iteration 41 [ 6438.512771] LustreError: 345506:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6438.513160] LustreError: 345507:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6438.516678] LustreError: 345506:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6439.567718] Lustre: Mounted lustre-client [ 6440.027941] LustreError: 345613:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd20299a800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6440.031489] LustreError: 345613:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6440.031504] LustreError: 345614:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6440.055646] Lustre: Unmounted lustre-client [ 6441.049233] Key type lgssc unregistered [ 6441.159811] LNet: 345855:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6441.162083] LNetError: 345855:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6441.170536] LNet: Removed LNI 192.168.202.54@tcp [ 6441.405107] Key type .llcrypt unregistered [ 6441.406039] Key type ._llcrypt unregistered [ 6441.689468] Key type ._llcrypt registered [ 6441.695134] Key type .llcrypt registered [ 6441.925596] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6441.930044] alg: No test for adler32 (adler32-zlib) [ 6442.781343] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6442.856858] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6444.439120] Key type lgssc registered [ 6444.806068] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6448.356715] Lustre: DEBUG MARKER: Iteration 42 [ 6448.483726] LustreError: 346672:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6448.484133] LustreError: 346673:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6448.487681] LustreError: 346672:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6449.538829] Lustre: Mounted lustre-client [ 6449.539842] Lustre: Skipped 1 previous similar message [ 6450.066880] LustreError: 346777:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd232c1d000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6450.072368] LustreError: 346777:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6450.074755] LustreError: 346777:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6450.094107] Lustre: Unmounted lustre-client [ 6450.973783] Key type lgssc unregistered [ 6451.084721] LNet: 347020:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6451.087471] LNetError: 347020:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6451.094386] LNet: Removed LNI 192.168.202.54@tcp [ 6451.317097] Key type .llcrypt unregistered [ 6451.318028] Key type ._llcrypt unregistered [ 6451.593716] Key type ._llcrypt registered [ 6451.596269] Key type .llcrypt registered [ 6451.822135] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6451.830547] alg: No test for adler32 (adler32-zlib) [ 6452.692678] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6452.783059] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6454.367176] Key type lgssc registered [ 6454.775732] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6458.053717] Lustre: DEBUG MARKER: Iteration 43 [ 6458.156482] LustreError: 347838:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6458.156791] LustreError: 347839:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6458.163136] LustreError: 347838:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6459.214151] Lustre: Mounted lustre-client [ 6459.215083] Lustre: Skipped 1 previous similar message [ 6459.644602] LustreError: 347940:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd2107a6800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6459.647592] LustreError: 347940:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6459.648026] LustreError: 347939:0:(obd_class.h:479:obd_check_dev()) Device 8 not setup [ 6459.651250] LustreError: 347939:0:(obd_class.h:479:obd_check_dev()) Skipped 2 previous similar messages [ 6459.671117] Lustre: Unmounted lustre-client [ 6460.618278] Key type lgssc unregistered [ 6460.738797] LNet: 348181:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6460.741150] LNetError: 348181:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6460.748441] LNet: Removed LNI 192.168.202.54@tcp [ 6460.983142] Key type .llcrypt unregistered [ 6460.984152] Key type ._llcrypt unregistered [ 6461.222945] Key type ._llcrypt registered [ 6461.224315] Key type .llcrypt registered [ 6461.509502] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6461.514808] alg: No test for adler32 (adler32-zlib) [ 6462.390831] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6462.483996] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6464.063133] Key type lgssc registered [ 6464.470809] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6468.125061] Lustre: DEBUG MARKER: Iteration 44 [ 6468.248251] LustreError: 349000:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6468.248295] LustreError: 348999:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6468.252537] LustreError: 349000:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6469.310989] Lustre: Mounted lustre-client [ 6469.795267] LustreError: 349105:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd2087fe800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6469.798617] LustreError: 349105:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6469.799329] LustreError: 349106:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6469.819898] Lustre: Unmounted lustre-client [ 6469.820905] Lustre: Skipped 1 previous similar message [ 6470.812698] Key type lgssc unregistered [ 6470.920778] LNet: 349347:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6470.923581] LNetError: 349347:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6470.935351] LNet: Removed LNI 192.168.202.54@tcp [ 6471.166104] Key type .llcrypt unregistered [ 6471.167177] Key type ._llcrypt unregistered [ 6471.472983] Key type ._llcrypt registered [ 6471.478402] Key type .llcrypt registered [ 6471.710121] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6471.715852] alg: No test for adler32 (adler32-zlib) [ 6472.577771] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6472.668842] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6474.255163] Key type lgssc registered [ 6474.639655] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6478.152457] Lustre: DEBUG MARKER: Iteration 45 [ 6478.272307] LustreError: 350165:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6478.273185] LustreError: 350166:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6478.276035] LustreError: 350165:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6479.329246] Lustre: Mounted lustre-client [ 6479.331045] Lustre: Skipped 1 previous similar message [ 6479.770706] LustreError: 350271:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd227bdb800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6479.772630] LustreError: 350272:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6479.774027] LustreError: 350271:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6479.786486] Lustre: Unmounted lustre-client [ 6480.647431] Key type lgssc unregistered [ 6480.754472] LNet: 350514:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6480.756653] LNetError: 350514:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6480.762717] LNet: Removed LNI 192.168.202.54@tcp [ 6480.973087] Key type .llcrypt unregistered [ 6480.974046] Key type ._llcrypt unregistered [ 6481.239189] Key type ._llcrypt registered [ 6481.240987] Key type .llcrypt registered [ 6481.428039] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6481.432642] alg: No test for adler32 (adler32-zlib) [ 6482.282771] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6482.355950] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6483.935149] Key type lgssc registered [ 6484.274600] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6487.679578] Lustre: DEBUG MARKER: Iteration 46 [ 6487.778957] LustreError: 351333:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6487.778985] LustreError: 351334:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6487.782575] LustreError: 351333:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4998 [ 6488.827597] Lustre: Mounted lustre-client [ 6489.287088] LustreError: 351440:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd2309a5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6489.289627] LustreError: 351439:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6489.290459] LustreError: 351440:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6489.320212] Lustre: Unmounted lustre-client [ 6490.206737] Key type lgssc unregistered [ 6490.325635] LNet: 351682:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6490.327653] LNetError: 351682:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6490.336687] LNet: Removed LNI 192.168.202.54@tcp [ 6490.564092] Key type .llcrypt unregistered [ 6490.565077] Key type ._llcrypt unregistered [ 6490.803171] Key type ._llcrypt registered [ 6490.804743] Key type .llcrypt registered [ 6491.079502] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6491.084099] alg: No test for adler32 (adler32-zlib) [ 6491.940964] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6492.027957] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6493.607119] Key type lgssc registered [ 6493.999024] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6497.363846] Lustre: DEBUG MARKER: Iteration 47 [ 6497.472129] LustreError: 352501:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6497.472372] LustreError: 352500:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6497.475897] LustreError: 352501:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6498.527863] Lustre: Mounted lustre-client [ 6498.529719] Lustre: Skipped 1 previous similar message [ 6498.961051] LustreError: 352607:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd2066cf800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6498.965644] LustreError: 352607:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6498.987035] Lustre: Unmounted lustre-client [ 6499.869790] Key type lgssc unregistered [ 6499.971448] LNet: 352850:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6499.973570] LNetError: 352850:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6499.982711] LNet: Removed LNI 192.168.202.54@tcp [ 6500.222096] Key type .llcrypt unregistered [ 6500.223761] Key type ._llcrypt unregistered [ 6500.508774] Key type ._llcrypt registered [ 6500.510575] Key type .llcrypt registered [ 6500.688457] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6500.693094] alg: No test for adler32 (adler32-zlib) [ 6501.549099] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6501.623874] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6503.207154] Key type lgssc registered [ 6503.547708] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6506.921715] Lustre: DEBUG MARKER: Iteration 48 [ 6507.046818] LustreError: 353668:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6507.047161] LustreError: 353669:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6507.053872] LustreError: 353668:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4997 [ 6508.120314] Lustre: Mounted lustre-client [ 6508.121246] Lustre: Skipped 1 previous similar message [ 6508.669182] LustreError: 353777:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd202ca5000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6508.674024] LustreError: 353777:0:(obd_class.h:479:obd_check_dev()) Device 9 not setup [ 6508.691921] Lustre: Unmounted lustre-client [ 6509.674147] Key type lgssc unregistered [ 6509.777750] LNet: 354019:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6509.779681] LNetError: 354019:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6509.787495] LNet: Removed LNI 192.168.202.54@tcp [ 6510.007098] Key type .llcrypt unregistered [ 6510.007977] Key type ._llcrypt unregistered [ 6510.311545] Key type ._llcrypt registered [ 6510.312489] Key type .llcrypt registered [ 6510.566671] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6510.573659] alg: No test for adler32 (adler32-zlib) [ 6511.434946] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6511.524524] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6513.111116] Key type lgssc registered [ 6513.446675] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6516.887418] Lustre: DEBUG MARKER: Iteration 49 [ 6517.013280] LustreError: 354836:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6517.013377] LustreError: 354837:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6517.021227] LustreError: 354836:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4996 [ 6518.084535] Lustre: Mounted lustre-client [ 6518.661577] LustreError: 354941:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd218c3c000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6518.663561] LustreError: 354943:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6518.664465] LustreError: 354941:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6518.680684] Lustre: Unmounted lustre-client [ 6519.645902] Key type lgssc unregistered [ 6519.756857] LNet: 355184:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6519.758967] LNetError: 355184:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6519.769419] LNet: Removed LNI 192.168.202.54@tcp [ 6520.002104] Key type .llcrypt unregistered [ 6520.003077] Key type ._llcrypt unregistered [ 6520.322214] Key type ._llcrypt registered [ 6520.323574] Key type .llcrypt registered [ 6520.548690] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6520.553528] alg: No test for adler32 (adler32-zlib) [ 6521.419949] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6521.509592] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6523.095127] Key type lgssc registered [ 6523.509846] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6527.110319] Lustre: DEBUG MARKER: Iteration 50 [ 6527.222036] LustreError: 356004:0:(llite_lib.c:1393:ll_fill_super()) cfs_race id 1417 sleeping [ 6527.222086] LustreError: 356003:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 waking [ 6527.226950] LustreError: 356004:0:(llite_lib.c:1393:ll_fill_super()) cfs_fail_race id 1417 awake: rc=4999 [ 6528.272053] Lustre: Mounted lustre-client [ 6528.718760] LustreError: 356105:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd207389800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6528.722354] LustreError: 356106:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6528.723359] LustreError: 356105:0:(lov_obd.c:783:lov_cleanup()) Skipped 2 previous similar messages [ 6528.738527] Lustre: Unmounted lustre-client [ 6529.735068] Key type lgssc unregistered [ 6529.856801] LNet: 356347:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6529.860743] LNetError: 356347:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6529.871312] LNet: Removed LNI 192.168.202.54@tcp [ 6530.121118] Key type .llcrypt unregistered [ 6530.122067] Key type ._llcrypt unregistered [ 6530.457076] Key type ._llcrypt registered [ 6530.458049] Key type .llcrypt registered [ 6530.682350] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 6530.687017] alg: No test for adler32 (adler32-zlib) [ 6531.543807] Lustre: Lustre: Build Version: 2.16.61_42_gb9e4aef [ 6531.634110] LNet: Added LNI 192.168.202.54@tcp [8/256/0/180] [ 6533.215117] Key type lgssc registered [ 6533.568300] Lustre: Echo OBD driver; http://www.lustre.org/ [ 6537.639040] Lustre: Mounted lustre-client [ 6539.939542] Lustre: DEBUG MARKER: == sanityn test 110: do not grant another lock on resend ========================================================== 00:10:41 (1763356241) [ 6556.639188] Lustre: 357678:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763356242/real 1763356242] req@ffff9cd2381fb100 x1849013025383168/t0(0) o36->lustre-MDT0000-mdc-ffff9cd2107a5800@192.168.202.154@tcp:12/10 lens 496/440 e 0 to 1 dl 1763356258 ref 2 fl Rpc:XQr/200/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6556.650927] Lustre: lustre-MDT0000-mdc-ffff9cd2107a5800: Connection to lustre-MDT0000 (at 192.168.202.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6556.663422] Lustre: lustre-MDT0000-mdc-ffff9cd2107a5800: Connection restored to 192.168.202.154@tcp (at 192.168.202.154@tcp) [ 6571.999106] Lustre: 357678:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763356258/real 1763356258] req@ffff9cd2381fb100 x1849013025383168/t0(0) o36->lustre-MDT0000-mdc-ffff9cd2107a5800@192.168.202.154@tcp:12/10 lens 496/440 e 0 to 1 dl 1763356274 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6572.006509] Lustre: lustre-MDT0000-mdc-ffff9cd2107a5800: Connection to lustre-MDT0000 (at 192.168.202.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6572.014354] Lustre: lustre-MDT0000-mdc-ffff9cd2107a5800: Connection restored to 192.168.202.154@tcp (at 192.168.202.154@tcp) [ 6588.383151] Lustre: 357678:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763356274/real 1763356274] req@ffff9cd2381fb100 x1849013025383168/t0(0) o36->lustre-MDT0000-mdc-ffff9cd2107a5800@192.168.202.154@tcp:12/10 lens 496/440 e 0 to 1 dl 1763356290 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6588.395983] Lustre: lustre-MDT0000-mdc-ffff9cd2107a5800: Connection to lustre-MDT0000 (at 192.168.202.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6588.404742] Lustre: lustre-MDT0000-mdc-ffff9cd2107a5800: Connection restored to 192.168.202.154@tcp (at 192.168.202.154@tcp) [ 6604.767145] Lustre: 357678:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763356290/real 1763356290] req@ffff9cd2381fb100 x1849013025383168/t0(0) o36->lustre-MDT0000-mdc-ffff9cd2107a5800@192.168.202.154@tcp:12/10 lens 496/440 e 0 to 1 dl 1763356306 ref 2 fl Rpc:XQr/202/ffffffff rc 0/-1 job:'ln.0' uid:0 gid:0 projid:4294967295 [ 6604.780314] Lustre: lustre-MDT0000-mdc-ffff9cd2107a5800: Connection to lustre-MDT0000 (at 192.168.202.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6604.792809] Lustre: lustre-MDT0000-mdc-ffff9cd2107a5800: Connection restored to 192.168.202.154@tcp (at 192.168.202.154@tcp) [ 6605.455632] Lustre: DEBUG MARKER: == sanityn test 111: A racy rename/link an open file should not cause fs corruption ========================================================== 00:11:46 (1763356306) [ 6606.151563] Lustre: DEBUG MARKER: SKIP: sanityn test_111 needs >= 2 MDTs [ 6606.860650] Lustre: DEBUG MARKER: == sanityn test 112: update max-inherit in default LMV === 00:11:48 (1763356308) [ 6607.515722] Lustre: DEBUG MARKER: SKIP: sanityn test_112 We need at least 2 MDTs for this test [ 6608.167189] Lustre: DEBUG MARKER: == sanityn test 113: check servers of specified fs ======= 00:11:49 (1763356309) [ 6610.262464] Lustre: DEBUG MARKER: == sanityn test 114: implicit default LMV inherit ======== 00:11:51 (1763356311) [ 6610.712560] Lustre: DEBUG MARKER: SKIP: sanityn test_114 We need at least 2 MDTs for this test [ 6611.194464] Lustre: DEBUG MARKER: == sanityn test 115: ldiskfs doesn't check direntry for uniqueness ========================================================== 00:11:52 (1763356312) [ 6611.639259] Lustre: DEBUG MARKER: SKIP: sanityn test_115 ldiskfs only test [ 6612.137395] Lustre: DEBUG MARKER: == sanityn test 116: DNE: Set default LMV layout from a remote client ========================================================== 00:11:53 (1763356313) [ 6612.615678] Lustre: DEBUG MARKER: SKIP: sanityn test_116 needs >= 2 MDTs [ 6613.126787] Lustre: DEBUG MARKER: == sanityn test 200: service remains healthy while able to process request ========================================================== 00:11:54 (1763356314) [ 6631.391135] Lustre: 356537:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763356317/real 1763356317] req@ffff9cd210609500 x1849013025409280/t0(0) o4->lustre-OST0000-osc-ffff9cd2107a5800@192.168.202.154@tcp:6/4 lens 4584/448 e 0 to 1 dl 1763356333 ref 2 fl Rpc:XQr/600/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 6631.391175] Lustre: lustre-OST0000-osc-ffff9cd2107a5800: Connection to lustre-OST0000 (at 192.168.202.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6631.391966] Lustre: 356537:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 3 previous similar messages [ 6647.775206] Lustre: 356536:0:(client.c:2478:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1763356333/real 1763356333] req@ffff9cd2274e0a80 x1849013025409792/t0(0) o4->lustre-OST0000-osc-ffff9cd2107a5800@192.168.202.154@tcp:6/4 lens 4584/448 e 0 to 1 dl 1763356349 ref 2 fl Rpc:XQr/602/ffffffff rc 0/-1 job:'dd.0' uid:0 gid:0 projid:0 [ 6647.775259] Lustre: lustre-OST0000-osc-ffff9cd2107a5800: Connection to lustre-OST0000 (at 192.168.202.154@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 6647.789312] Lustre: 356536:0:(client.c:2478:ptlrpc_expire_one_request()) Skipped 1 previous similar message [ 6647.808428] Lustre: lustre-OST0000-osc-ffff9cd2107a5800: Connection restored to 192.168.202.154@tcp (at 192.168.202.154@tcp) [ 6663.137554] Lustre: lustre-OST0000-osc-ffff9cd2107a5800: Connection restored to 192.168.202.154@tcp (at 192.168.202.154@tcp) [ 6678.287510] Lustre: DEBUG MARKER: oleg254-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0000-osc-ffff9cd20a142000.ost_server_uuid 50 [ 6678.771610] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff9cd20a142000.ost_server_uuid in IDLE state after 0 sec [ 6679.291710] Lustre: DEBUG MARKER: cleanup: ====================================================== [ 6679.921461] Lustre: DEBUG MARKER: == sanityn test complete, duration 6437 sec ============== 00:13:01 (1763356381) [ 6680.446516] Lustre: DEBUG MARKER: === sanityn: start cleanup 00:13:01 (1763356381) === [ 6724.174065] LustreError: 361742:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd20a142000: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6724.179469] LustreError: 361742:0:(obd_class.h:479:obd_check_dev()) Device 10 not setup [ 6724.191080] Lustre: Unmounted lustre-client [ 6725.339990] Lustre: DEBUG MARKER: === sanityn: finish cleanup 00:13:46 (1763356426) === [ 6725.667920] LustreError: 362042:0:(lov_obd.c:783:lov_cleanup()) lustre-clilov-ffff9cd2107a5800: lov tgt 0 not cleaned! deathrow=0, lovrc=1 [ 6725.671908] LustreError: 362042:0:(lov_obd.c:783:lov_cleanup()) Skipped 1 previous similar message [ 6725.677980] LustreError: 362042:0:(obd_class.h:479:obd_check_dev()) Device 5 not setup [ 6725.679614] LustreError: 362042:0:(obd_class.h:479:obd_check_dev()) Skipped 5 previous similar messages [ 6725.692091] Lustre: Unmounted lustre-client [ 6737.133550] Key type lgssc unregistered [ 6737.251578] LNet: 362523:0:(lib-ptl.c:967:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 6737.253749] LNetError: 362523:0:(acceptor.c:264:lnet_acceptor_remove_socket()) Interface ens2 not found [ 6737.263505] LNet: Removed LNI 192.168.202.54@tcp [ 6737.510099] Key type .llcrypt unregistered [ 6737.511747] Key type ._llcrypt unregistered