[ 0.000000] Initializing cgroup subsys cpuset [ 0.000000] Initializing cgroup subsys cpu [ 0.000000] Initializing cgroup subsys cpuacct [ 0.000000] Linux version 3.10.0-7.9-debug (green@centos7-base) (gcc version 4.8.5 20150623 (Red Hat 4.8.5-44) (GCC) ) #1 SMP Sat Mar 26 23:28:42 EDT 2022 [ 0.000000] Command line: rd.shell root=nbd:192.168.200.253:centos7:ext4:ro:-p,-b4096 ro crashkernel=128M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] e820: 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-0x000000013edfffff] 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] e820: last_pfn = 0x13ee00 max_arch_pfn = 0x400000000 [ 0.000000] PAT configuration [0-7]: WB WC UC- UC WB WP UC- UC [ 0.000000] e820: last_pfn = 0xbffda max_arch_pfn = 0x400000000 [ 0.000000] found SMP MP-table at [mem 0x000f5b50-0x000f5b5f] mapped at [ffffffffff200b50] [ 0.000000] Using GB pages for direct mapping [ 0.000000] RAMDISK: [mem 0xbc2f2000-0xbffcffff] [ 0.000000] Early table checksum verification disabled [ 0.000000] ACPI: RSDP 00000000000f5970 00014 (v00 BOCHS ) [ 0.000000] ACPI: RSDT 00000000bffe1bb7 00034 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACP 00000000bffe1a53 00074 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 00000000bffe0040 01A13 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: FACS 00000000bffe0000 00040 [ 0.000000] ACPI: APIC 00000000bffe1ac7 00090 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: HPET 00000000bffe1b57 00038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: WAET 00000000bffe1b8f 00028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x000000013edfffff] [ 0.000000] NODE_DATA(0) allocated [mem 0x13e5e3000-0x13e609fff] [ 0.000000] Reserving 128MB of memory at 768MB for crashkernel (System RAM: 4077MB) [ 0.000000] kvm-clock: cpu 0, msr 1:3e592001, primary cpu clock [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: using sched offset of 262448991 cycles [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x00001000-0x00ffffff] [ 0.000000] DMA32 [mem 0x01000000-0xffffffff] [ 0.000000] Normal [mem 0x100000000-0x13edfffff] [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x00001000-0x0009efff] [ 0.000000] node 0: [mem 0x00100000-0xbffd9fff] [ 0.000000] node 0: [mem 0x100000000-0x13edfffff] [ 0.000000] Initmem setup node 0 [mem 0x00001000-0x13edfffff] [ 0.000000] ACPI: PM-Timer IO Port: 0x608 [ 0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x01] enabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x02] lapic_id[0x02] enabled) [ 0.000000] ACPI: LAPIC (acpi_id[0x03] lapic_id[0x03] enabled) [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.000000] ACPI: IOAPIC (id[0x00] address[0xfec00000] gsi_base[0]) [ 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] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 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] e820: [mem 0xc0000000-0xfeffbfff] available for PCI devices [ 0.000000] Booting paravirtualized kernel on KVM [ 0.000000] setup_percpu: NR_CPUS:5120 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.000000] percpu: Embedded 38 pages/cpu s115176 r8192 d32280 u524288 [ 0.000000] KVM setup async PF for cpu 0 [ 0.000000] kvm-stealtime: cpu 0, msr 13e2135c0 [ 0.000000] PV qspinlock hash table entries: 256 (order: 0, 4096 bytes) [ 0.000000] Built 1 zonelists in Node order, mobility grouping on. Total pages: 1027499 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: rd.shell root=nbd:192.168.200.253:centos7:ext4:ro:-p,-b4096 ro crashkernel=128M panic=1 nomodeset ipmtu=9000 ip=dhcp rd.neednet=1 init_on_free=off mitigations=off console=ttyS1,115200 audit=0 [ 0.000000] audit: disabled (until reboot) [ 0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes) [ 0.000000] x86/fpu: xstate_offset[2]: 0240, xstate_sizes[2]: 0100 [ 0.000000] xsave: enabled xstate_bv 0x7, cntxt size 0x340 using standard form [ 0.000000] Memory: 3820316k/5224448k available (8172k kernel code, 1049120k absent, 355012k reserved, 5773k data, 2532k init) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] Hierarchical RCU implementation. [ 0.000000] RCU restricting CPUs from NR_CPUS=5120 to nr_cpu_ids=4. [ 0.000000] Offload RCU callbacks from all CPUs [ 0.000000] Offload RCU callbacks from CPUs: 0-3. [ 0.000000] NR_IRQS:327936 nr_irqs:456 0 [ 0.000000] Console: colour *CGA 80x25 [ 0.000000] console [ttyS1] enabled [ 0.000000] allocated 25165824 bytes of page_cgroup [ 0.000000] please try 'cgroup_disable=memory' option if you don't want memory cgroups [ 0.000000] kmemleak: Kernel memory leak detector disabled [ 0.000000] tsc: Detected 2399.998 MHz processor [ 0.474138] Calibrating delay loop (skipped) preset value.. 4799.99 BogoMIPS (lpj=2399998) [ 0.476779] pid_max: default: 32768 minimum: 301 [ 0.478578] Security Framework initialized [ 0.479893] SELinux: Initializing. [ 0.482537] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes) [ 0.486950] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes) [ 0.491386] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes) [ 0.493363] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes) [ 0.495893] Initializing cgroup subsys memory [ 0.497227] Initializing cgroup subsys devices [ 0.498496] Initializing cgroup subsys freezer [ 0.499747] Initializing cgroup subsys net_cls [ 0.500920] Initializing cgroup subsys blkio [ 0.502165] Initializing cgroup subsys perf_event [ 0.503622] Initializing cgroup subsys hugetlb [ 0.504850] Initializing cgroup subsys pids [ 0.506105] Initializing cgroup subsys net_prio [ 0.507610] x86/cpu: User Mode Instruction Prevention (UMIP) activated [ 0.510460] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.511989] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.513466] tlb_flushall_shift: 6 [ 0.514682] FEATURE SPEC_CTRL Present [ 0.515645] FEATURE IBPB_SUPPORT Present [ 0.516779] Spectre V2 : Enabling Indirect Branch Prediction Barrier [ 0.518558] Spectre V2 : Vulnerable [ 0.519532] Speculative Store Bypass: Vulnerable [ 0.521811] debug: unmapping init [mem 0xffffffff82019000-0xffffffff8201ffff] [ 0.532132] ACPI: Core revision 20130517 [ 0.535692] ACPI: All ACPI Tables successfully acquired [ 0.537463] ftrace: allocating 30294 entries in 119 pages [ 0.597078] Enabling x2apic [ 0.597984] Enabled x2apic [ 0.599447] Switched APIC routing to physical x2apic. [ 0.602855] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.604957] smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2695 v2 @ 2.40GHz (fam: 06, model: 3e, stepping: 04) [ 0.608235] Performance Events: IvyBridge events, full-width counters, Intel PMU driver. [ 0.610899] ... version: 2 [ 0.612064] ... bit width: 48 [ 0.613139] ... generic registers: 4 [ 0.614203] ... value mask: 0000ffffffffffff [ 0.615620] ... max period: 00007fffffffffff [ 0.616934] ... fixed-purpose events: 3 [ 0.617989] ... event mask: 000000070000000f [ 0.619567] KVM setup paravirtual spinlock [ 0.623314] smpboot: Booting Node 0, Processors #1[ 0.625300] kvm-clock: cpu 1, msr 1:3e592041, secondary cpu clock [ 0.630454] KVM setup async PF for cpu 1 [ 0.632567] kvm-stealtime: cpu 1, msr 13e2935c0 #2[ 0.636499] kvm-clock: cpu 2, msr 1:3e592081, secondary cpu clock [ 0.640996] KVM setup async PF for cpu 2 #3 OK [ 0.642575] kvm-stealtime: cpu 2, msr 13e3135c0 [ 0.644374] kvm-clock: cpu 3, msr 1:3e5920c1, secondary cpu clock [ 0.650706] Brought up 4 CPUs [ 0.651392] KVM setup async PF for cpu 3 [ 0.651399] kvm-stealtime: cpu 3, msr 13e3935c0 [ 0.655227] smpboot: Max logical packages: 1 [ 0.656491] smpboot: Total of 4 processors activated (19199.98 BogoMIPS) [ 0.664502] devtmpfs: initialized [ 0.665905] x86/mm: Memory block size: 128MB [ 0.672147] EVM: security.selinux [ 0.673550] EVM: security.ima [ 0.674653] EVM: security.capability [ 0.678680] atomic64 test passed for x86-64 platform with CX8 and with SSE [ 0.681378] NET: Registered protocol family 16 [ 0.683560] cpuidle: using governor haltpoll [ 0.686789] ACPI: bus type PCI registered [ 0.688794] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.692854] PCI: Using configuration type 1 for base access [ 0.696198] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on [ 0.710531] ACPI: Added _OSI(Module Device) [ 0.712290] ACPI: Added _OSI(Processor Device) [ 0.713980] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.716015] ACPI: Added _OSI(Processor Aggregator Device) [ 0.717963] ACPI: Added _OSI(Linux-Dell-Video) [ 0.723434] ACPI: Interpreter enabled [ 0.724824] ACPI: (supports S0 S3 S4 S5) [ 0.726294] ACPI: Using IOAPIC for interrupt routing [ 0.728558] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.732384] ACPI: Enabled 2 GPEs in block 00 to 0F [ 0.743032] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.747267] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI] [ 0.749961] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM [ 0.752588] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. [ 0.757548] acpiphp: Slot [2] registered [ 0.758821] acpiphp: Slot [3] registered [ 0.760548] acpiphp: Slot [4] registered [ 0.762240] acpiphp: Slot [5] registered [ 0.763435] acpiphp: Slot [6] registered [ 0.764697] acpiphp: Slot [7] registered [ 0.766022] acpiphp: Slot [8] registered [ 0.767426] acpiphp: Slot [9] registered [ 0.771261] acpiphp: Slot [10] registered [ 0.773133] acpiphp: Slot [11] registered [ 0.774698] acpiphp: Slot [12] registered [ 0.777352] acpiphp: Slot [13] registered [ 0.778191] acpiphp: Slot [14] registered [ 0.779665] acpiphp: Slot [15] registered [ 0.781096] acpiphp: Slot [16] registered [ 0.782512] acpiphp: Slot [17] registered [ 0.783581] acpiphp: Slot [18] registered [ 0.784917] acpiphp: Slot [19] registered [ 0.785921] acpiphp: Slot [20] registered [ 0.787371] acpiphp: Slot [21] registered [ 0.788415] acpiphp: Slot [22] registered [ 0.789822] acpiphp: Slot [23] registered [ 0.791056] acpiphp: Slot [24] registered [ 0.792230] acpiphp: Slot [25] registered [ 0.793679] acpiphp: Slot [26] registered [ 0.794923] acpiphp: Slot [27] registered [ 0.796072] acpiphp: Slot [28] registered [ 0.797587] acpiphp: Slot [29] registered [ 0.799056] acpiphp: Slot [30] registered [ 0.800512] acpiphp: Slot [31] registered [ 0.801889] PCI host bridge to bus 0000:00 [ 0.803245] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.805002] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.806821] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.809103] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.811404] pci_bus 0000:00: root bus resource [mem 0x140000000-0x1bfffffff window] [ 0.813451] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.823538] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.825041] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.826553] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.829489] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.832062] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.833463] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.903914] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) [ 0.906173] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) [ 0.909266] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) [ 0.911450] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) [ 0.913744] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) [ 0.917452] vgaarb: loaded [ 0.918536] SCSI subsystem initialized [ 0.919742] ACPI: bus type USB registered [ 0.921104] usbcore: registered new interface driver usbfs [ 0.922961] usbcore: registered new interface driver hub [ 0.924526] usbcore: registered new device driver usb [ 0.926276] PCI: Using ACPI for IRQ routing [ 0.928713] NetLabel: Initializing [ 0.930353] NetLabel: domain hash size = 128 [ 0.931899] NetLabel: protocols = UNLABELED CIPSOv4 [ 0.933734] NetLabel: unlabeled traffic allowed by default [ 0.936180] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 0.938157] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 0.944120] amd_nb: Cannot enumerate AMD northbridges [ 0.946486] Switched to clocksource kvm-clock [ 0.963942] pnp: PnP ACPI init [ 0.964547] ACPI: bus type PNP registered [ 0.965920] pnp: PnP ACPI: found 6 devices [ 0.966955] ACPI: bus type PNP unregistered [ 0.978108] NET: Registered protocol family 2 [ 0.980276] TCP established hash table entries: 32768 (order: 6, 262144 bytes) [ 0.983110] TCP bind hash table entries: 32768 (order: 8, 1048576 bytes) [ 0.985968] TCP: Hash tables configured (established 32768 bind 32768) [ 0.988232] TCP: reno registered [ 0.990295] UDP hash table entries: 2048 (order: 5, 196608 bytes) [ 0.992035] UDP-Lite hash table entries: 2048 (order: 5, 196608 bytes) [ 0.994259] NET: Registered protocol family 1 [ 0.996021] RPC: Registered named UNIX socket transport module. [ 0.997727] RPC: Registered udp transport module. [ 0.999069] RPC: Registered tcp transport module. [ 1.000328] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 1.002231] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 1.003682] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 1.005085] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 1.006900] Unpacking initramfs... [ 2.505351] debug: unmapping init [mem 0xffff8800bc2f2000-0xffff8800bffcffff] [ 2.509224] PCI-DMA: Using software bounce buffering for IO (SWIOTLB) [ 2.511109] software IO TLB [mem 0xb82f2000-0xbc2f2000] (64MB) mapped at [ffff8800b82f2000-ffff8800bc2f1fff] [ 2.513889] RAPL PMU: API unit is 2^-32 Joules, 3 fixed counters, 10737418240 ms ovfl timer [ 2.516045] RAPL PMU: hw unit of domain pp0-core 2^-0 Joules [ 2.517670] RAPL PMU: hw unit of domain package 2^-0 Joules [ 2.519141] RAPL PMU: hw unit of domain dram 2^-0 Joules [ 2.558882] futex hash table entries: 1024 (order: 4, 65536 bytes) [ 2.560748] Initialise system trusted keyring [ 2.561234] cryptomgr_test (51) used greatest stack depth: 14480 bytes left [ 2.600067] HugeTLB registered 1 GB page size, pre-allocated 0 pages [ 2.601912] HugeTLB registered 2 MB page size, pre-allocated 0 pages [ 2.610671] zpool: loaded [ 2.611320] zbud: loaded [ 2.612335] VFS: Disk quotas dquot_6.6.0 [ 2.613433] Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 2.616023] NFS: Registering the id_resolver key type [ 2.617495] Key type id_resolver registered [ 2.618606] Key type id_legacy registered [ 2.619610] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 2.621621] Key type big_key registered [ 2.625400] cryptomgr_test (57) used greatest stack depth: 14048 bytes left [ 2.627108] cryptomgr_test (58) used greatest stack depth: 13968 bytes left [ 2.629497] NET: Registered protocol family 38 [ 2.630892] Key type asymmetric registered [ 2.632895] Asymmetric key parser 'x509' registered [ 2.635249] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) [ 2.638019] io scheduler noop registered [ 2.639970] io scheduler deadline registered (default) [ 2.641892] io scheduler cfq registered [ 2.643283] io scheduler mq-deadline registered [ 2.644946] io scheduler kyber registered [ 2.647922] pci_hotplug: PCI Hot Plug PCI Core version: 0.5 [ 2.649932] pciehp: PCI Express Hot Plug Controller Driver version: 0.4 [ 2.652512] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 [ 2.654702] ACPI: Power Button [PWRF] [ 2.655893] GHES: HEST is not enabled! [ 2.717635] ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 10 [ 2.782755] ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 11 [ 2.857130] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.894835] 00:03: ttyS1 at I/O 0x2f8 (irq = 3) is a 16550A [ 2.923409] 00:04: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A [ 2.926797] Non-volatile memory driver v1.3 [ 2.928509] Linux agpgart interface v0.103 [ 2.930036] crash memory driver: version 1.1 [ 2.931972] nbd: registered device at major 43 [ 2.946253] virtio_blk virtio1: [vda] 53432 512-byte logical blocks (27.3 MB/26.0 MiB) [ 2.967036] virtio_blk virtio2: [vdb] 2097152 512-byte logical blocks (1.07 GB/1.00 GiB) [ 2.973364] rdac: device handler registered [ 2.975233] hp_sw: device handler registered [ 2.976281] emc: device handler registered [ 2.977995] libphy: Fixed MDIO Bus: probed [ 2.987455] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver [ 2.991379] ehci-pci: EHCI PCI platform driver [ 2.993512] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver [ 2.996354] ohci-pci: OHCI PCI platform driver [ 2.998287] uhci_hcd: USB Universal Host Controller Interface driver [ 3.001011] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 3.005027] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 3.007342] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 3.010124] mousedev: PS/2 mouse device common for all mice [ 3.012711] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 [ 3.015861] rtc_cmos 00:05: RTC can wake from S4 [ 3.017783] rtc_cmos 00:05: rtc core: registered rtc_cmos as rtc0 [ 3.019423] rtc_cmos 00:05: alarms up to one day, y3k, 242 bytes nvram, hpet irqs [ 3.025507] hidraw: raw HID events driver (C) Jiri Kosina [ 3.031128] usbcore: registered new interface driver usbhid [ 3.033018] usbhid: USB HID core driver [ 3.034557] drop_monitor: Initializing network drop monitor service [ 3.036755] Netfilter messages via NETLINK v0.30. [ 3.041160] TCP: cubic registered [ 3.042250] Initializing XFRM netlink socket [ 3.044981] NET: Registered protocol family 10 [ 3.049139] NET: Registered protocol family 17 [ 3.050749] Key type dns_resolver registered [ 3.053120] mce: Using 10 MCE banks [ 3.054973] Loading compiled-in X.509 certificates [ 3.057659] Loaded X.509 cert 'Magrathea: Glacier signing key: e34d0e1b7fcf5b414cce75d36d8482945c781ed6' [ 3.060240] registered taskstats version 1 [ 3.065871] modprobe (71) used greatest stack depth: 13456 bytes left [ 3.073795] Key type trusted registered [ 3.086607] Key type encrypted registered [ 3.087977] IMA: No TPM chip found, activating TPM-bypass! (rc=-19) [ 3.092076] BERT: Boot Error Record Table support is disabled. Enable it by using bert_enable as kernel parameter. [ 3.096712] rtc_cmos 00:05: setting system clock to 2025-01-15 23:46:11 UTC (1736984771) [ 3.101345] debug: unmapping init [mem 0xffffffff81da0000-0xffffffff82018fff] [ 3.104276] Write protecting the kernel read-only data: 12288k [ 3.106992] debug: unmapping init [mem 0xffff8800017fe000-0xffff8800017fffff] [ 3.109869] debug: unmapping init [mem 0xffff880001b9b000-0xffff880001bfffff] [ 3.120322] random: systemd: uninitialized urandom read (16 bytes read) [ 3.126109] random: systemd: uninitialized urandom read (16 bytes read) [ 3.128964] random: systemd: uninitialized urandom read (16 bytes read) [ 3.134035] systemd[1]: systemd 219 running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 -SECCOMP +BLKID +ELFUTILS +KMOD +IDN) [ 3.140059] systemd[1]: Detected virtualization kvm. [ 3.141736] systemd[1]: Detected architecture x86-64. [ 3.144767] systemd[1]: Running in initial RAM disk. Welcome to CentOS Linux 7 (Core) dracut-033-572.el7 (Initramfs)! [ 3.153624] systemd[1]: No hostname configured. [ 3.154735] systemd[1]: Set hostname to . [ 3.156875] random: systemd: uninitialized urandom read (16 bytes read) [ 3.158908] systemd[1]: Initializing machine ID from random generator. [ 3.221966] dracut-rootfs-g (86) used greatest stack depth: 13264 bytes left [ 3.225386] random: systemd: uninitialized urandom read (16 bytes read) [ 3.227894] random: systemd: uninitialized urandom read (16 bytes read) [ 3.230149] random: systemd: uninitialized urandom read (16 bytes read) [ 3.232170] random: systemd: uninitialized urandom read (16 bytes read) [ 3.237043] random: systemd: uninitialized urandom read (16 bytes read) [ 3.245874] random: systemd: uninitialized urandom read (16 bytes read) [ 3.262612] systemd[1]: Reached target Swap. [ OK ] Reached target Swap. [ 3.267559] systemd[1]: Created slice Root Slice. [ OK ] Created slice Root Slice. [ 3.272908] systemd[1]: Listening on udev Kernel Socket. [ OK ] Listening on udev Kernel Socket. [ 3.278050] systemd[1]: Listening on udev Control Socket. [ OK ] Listening on udev Control Socket. [ 3.284023] systemd[1]: Listening on Journal Socket. [ OK ] Listening on Journal Socket. [ 3.291304] systemd[1]: Reached target Sockets. [ OK ] Reached target Sockets. [ 3.297671] systemd[1]: Created slice System Slice. [ OK ] Created slice System Slice. [ 3.306792] systemd[1]: Starting Create list of required static device nodes for the current kernel... Starting Create list of required st... nodes for the current kernel... [ 3.312880] systemd[1]: Starting Setup Virtual Console... Starting Setup Virtual Console... [ 3.316862] systemd[1]: Starting Journal Service... Starting Journal Service... [ 3.319263] systemd[1]: Reached target Slices. [ OK ] Reached target Slices. [ 3.349867] systemd[1]: Starting Load Kernel Modules... Starting Load Kernel Modules... [ 3.361742] systemd[1]: Starting dracut cmdline hook... Starting dracut cmdline hook... [ 3.367885] systemd[1]: Reached target Timers. [ OK ] Reached target Timers. [ 3.376371] systemd[1]: Reached target Local File Systems. [ OK ] Reached target Local File Systems. [ 3.383884] systemd[1]: Started Journal Service. [ OK ] Started Journal Service. [ OK ] Started Create list of required sta...ce nodes for the current kernel. [ OK ] Started Setup Virtual Console. [ OK ] Started Load Kernel Modules. Starting Apply Kernel Variables... Starting Create Static Device Nodes in /dev... [ OK ] Started Apply Kernel Variables. [ OK ] Started Create Static Device Nodes in /dev. [ 3.522515] tsc: Refined TSC clocksource calibration: 2399.985 MHz [ OK ] Started dracut cmdline hook. Starting dracut pre-udev hook... [ 3.870408] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input2 [ OK ] Started dracut pre-udev hook. Starting udev Kernel Device Manager... [[ 4.087479] random: fast init done  OK ] Started udev Kernel Device Manager. Starting dracut pre-trigger hook... [ OK ] Started dracut pre-trigger hook. Starting udev Coldplug all Devices... Mounting Configuration File System... [ OK ] Mounted Configuration File System. [ OK ] Started udev Coldplug all Devices. Starting dracut initqueue hook... Starting Show Plymouth Boot Screen... [ OK ] Reached target System Initialization. [ OK ] Started Show Plymouth Boot Scr[ 4.317379] scsi host0: ata_piix een. [ OK ] Started Forward Password Requests to Plymouth Directory Watch. [ OK ] Reached target Paths. [ OK ] Reached target Basic System. [ 4.331067] scsi host1: ata_piix [ 4.334891] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 [ 4.338417] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 %G[ 4.544925] ip (322) used greatest stack depth: 13080 bytes left [ 4.619055] ip (345) used greatest stack depth: 12336 bytes left [ 6.338596] dracut-initqueue[277]: RTNETLINK answers: File exists [ 7.121021] dracut-initqueue[277]: bs=4096, sz=32212254720 bytes [ OK ] Started dracut initqueue hook. Mounting /sysroot... [ OK ] Reached target Initrd Root File System. Starting Reload Configuration from the Real Root... [ OK ] Reached target Remote File Systems (Pre). [ OK ] Reached target Remote File Systems. [ 7.888617] EXT4-fs (nbd0): mounted filesystem with ordered data mode. Opts: (null) [ OK ] Mounted /sysroot. [ 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. Starting Plymouth switch root service... [ OK ] Stopped target Timers. [ OK ] Stopped target Remote File Systems. [ OK ] Stopped target Remote File Systems (Pre). [ OK ] Stopped dracut initqueue hook. [ OK ] Stopped target Initrd Default Target. [ OK ] Stopped target Basic System. [ OK ] Stopped target Slices. [ OK ] Stopped target System Initialization. [ OK ] Stopped Apply Kernel Variables. [ OK ] Stopped Load Kernel Modules. [ OK ] Stopped udev Coldplug all Devices. [ OK ] Stopped target Local File Systems. [ OK ] Stopped target Swap. [ OK ] Stopped target Paths. [ OK ] Stopped target Sockets. [ 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 ] Started Plymouth switch root service. [ OK ] Stopped dracut pre-udev hook. [ OK ] Stopped dracut cmdline hook. [ OK ] Stopped Create Static Device Nodes in /dev. [ OK ] Stopped Create list of required sta...ce nodes for the current kernel. [ OK ] Closed udev Kernel Socket. [ OK ] Closed udev Control Socket. Starting Cleanup udevd DB... [ OK ] Started Cleanup udevd DB. [ OK ] Reached target Switch Root. Starting Switch Root... [ 8.483093] systemd-journald[102]: Received SIGTERM from PID 1 (systemd). [ 8.651319] systemd-cgroups (548) used greatest stack depth: 12272 bytes left [ 8.787956] SELinux: Disabled at runtime. [ 8.888472] ip_tables: (C) 2000-2006 Netfilter Core Team [ 8.892453] systemd[1]: Inserted module 'ip_tables' Welcome to CentOS Linux 7 (Core)! [ OK ] Stopped Switch Root. [ OK ] Stopped Journal Service. Starting Journal Service... [ OK ] Listening on /dev/initctl Compatibility Named Pipe. Starting Create list of required st... nodes for the current kernel... [ OK ] Listening on udev Kernel Socket. Mounting POSIX Message Queue File System... [ OK ] Created slice User and Session Slice. [ OK ] Created slice system-serial\x2dgetty.slice. [ OK ] Created slice system-selinux\x2dpol...grate\x2dlocal\x2dchanges.slice. Starting Remount Root and Kernel File Systems... Starting Read and set NIS domainname from /etc/sysconfig/network... [ OK ] Listening on udev Control Socket. Starting udev Coldplug all Devices... Mounting Debug File System... Starting Load Kernel Modules... [ OK ] Created slice system-getty.slice. [ OK ] Reached target rpc_pipefs.target. [ OK ] Set up automount Arbitrary Executab...ats File System Automount Point. Starting Set Up Additional Binary Formats... [ OK ] Reached target Slices. [ OK ] Reached target Local Encrypted Volumes. Mounting Huge Pages File System... [ OK ] Started Forward Password Requests to Wall Directory Watch. [ OK ] Stopped target Switch Root. [ OK ] Stopped target Initrd File Systems. [ OK ] Stopped target Initrd Root File System. [ OK ] Listening on Delayed Shutdown Socket. [ OK ] Started Create list of required sta...ce nodes for the current kernel. Starting Create Static Device Nodes in /dev... [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Debug File System. [ OK ] Mounted Huge Pages File System. [ OK ] Started Load Kernel Modules. Mounting Arbitrary Executable File Formats File System... Starting Apply Kernel Variables... [ OK ] Started Read and set NIS domainname from /etc/sysconfig/network. [ OK ] Started Journal Service. [ OK ] Started udev Coldplug all Devices. [ OK ] Mounted Arbitrary Executable File Formats File System. [ OK ] Started Apply Kernel Variables. [ 9.589302] mount.nfs (582) used greatest stack depth: 11824 bytes left [ OK ] Started Create Static Device Nodes in /dev. [ OK ] Started Set Up Additional Binary Formats. [FAILED] Failed to start Remount Root and Kernel File Systems. See 'systemctl status systemd-remount-fs.service' for details. Starting Flush Journal to Persistent Storage... Starting Configure read-only root support... Starting udev Kernel Device Manager... [ OK ] Reached target Local File Systems (Pre). Mounting /mnt... [ OK ] Mounted /mnt. [ 9.645433] systemd-journald[568]: Received request to flush runtime journal from PID 1 [ OK ] Started Flush Journal to Persistent Storage. [ OK ] Started udev Kernel Device Manager. [ OK ] Found device /dev/ttyS0. [ OK ] Found device /dev/ttyS1. [ 9.915823] input: PC Speaker as /devices/platform/pcspkr/input/input3 [ 9.920537] cryptd: max_cpu_qlen set to 1000 [ 9.952277] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 [ 9.978531] AVX version of gcm_enc/dec engaged. [ 9.980649] AES CTR mode by8 optimization enabled [ OK ] Found device /dev/vda. Mounting /home/green/git/lustre-release... [ OK ] Found device /dev/disk/by-label/SWAP. Activating swap /dev/disk/by-label/SWAP... [ 10.129450] Adding 1048572k swap on /dev/vdb. Priority:-2 extents:1 across:1048572k FS [ OK ] [ 10.147310] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 10.147884] alg: No test for __gcm-aes-aesni (__driver-gcm-aes-aesni) [ 10.150045] alg: No test for __generic-gcm-aes-aesni (__driver-generic-gcm-aes-aesni) Activated swap /dev/disk/by-label/SWAP. [ OK ] Reached target Swap. %G[ OK ] Mounted /home/green/git/lustre-release. [ 10.297039] EDAC MC: Ver: 3.0.0 [ 10.312661] EDAC sbridge: Ver: 1.1.2 [ 14.055259] mount.nfs (768) used greatest stack depth: 10704 bytes left [ OK ] Started Configure read-only root support. Starting Load/Save Random Seed... [ OK ] Reached target Local File Systems. Starting Rebuild Journal Catalog... Starting Create Volatile Files and Directories... Starting Tell Plymouth [ 14.204895] hrtimer: interrupt took 3211784 ns To Write Out Runtime Data... Starting Mark the need to relabel after reboot... Starting Preprocess NFS configuration... [ OK ] Started Load/Save Random Seed. [FAILED] Failed to start Create Volatile Files and Directories. See 'systemctl status systemd-tmpfiles-setup.service' for details. [FAILED] Failed to start Rebuild Journal Catalog. See 'systemctl status systemd-journal-catalog-update.service' for details. [ OK ] Started Mark the need to relabel after reboot. [ OK ] Started Tell Plymouth To Write Out Runtime Data. Starting Update is Completed... Starting Update UTMP about System Boot/Shutdown... [ OK ] Started Preprocess NFS configuration. [ OK ] Started Update is Completed. [ OK ] Started Update UTMP about System Boot/Shutdown. [ OK ] Reached target System Initialization. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target Sockets. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Timers. [ OK ] Started Flexible branding. [ OK ] Reached target Paths. [ OK ] Reached target Basic System. Starting Login Service... Starting GSSAPI Proxy Daemon... Starting Dump dmesg to /var/log/dmesg... [ OK ] Started D-Bus System Message Bus. Starting Network Manager... [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Started Dump dmesg to /var/log/dmesg. [ 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 Login Service. [ OK ] Started Permit User Sessions. [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting /etc/rc.d/rc.local Compatibility... Starting OpenSSH server daemon... Starting Network Manager Wait Online... Starting Hostname Service... [ OK ] Started OpenSSH server daemon. [ OK ] Started /etc/rc.d/rc.local Compatibility. [ OK ] Started Hostname Service. Starting Network Manager Script Dispatcher Service... Starting Wait for Plymouth Boot Screen to Quit... Starting Terminate Plymouth Boot Screen... CentOS Linux 7 (Core) Kernel 3.10.0-7.9-debug on an x86_64 oleg256-client login: [ 28.108895] libcfs: loading out-of-tree module taints kernel. [ 28.113135] libcfs: module verification failed: signature and/or required key missing - tainting kernel [ 28.137562] alg: No test for adler32 (adler32-zlib) [ 28.975169] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 29.136641] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 29.320366] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 29.322449] LNet: Accept secure, port 988 [ 30.880507] Key type lgssc registered [ 31.280156] Lustre: Echo OBD driver; http://www.lustre.org/ [ 68.082166] Lustre: Mounted lustre-client [ 70.001143] Lustre: DEBUG MARKER: Using TIMEOUT=20 [ 78.959675] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing check_logdir /tmp/testlogs/ [ 79.901336] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing yml_node [ 80.841339] Lustre: DEBUG MARKER: Client: 2.15.6.7 [ 81.437740] Lustre: DEBUG MARKER: MDS: 2.15.6.7 [ 82.157747] Lustre: DEBUG MARKER: OSS: 2.15.6.7 [ 82.639667] Lustre: DEBUG MARKER: -----============= acceptance-small: conf-sanity ============----- Wed Jan 15 18:47:30 EST 2025 [ 84.504171] Lustre: DEBUG MARKER: excepting tests: 55 63 67 32newtarball 110 [ 84.963861] Lustre: DEBUG MARKER: skipping tests SLOW=no: 45 69 106 111 [ 85.275766] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 95.304340] Lustre: Unmounted lustre-client [ 144.874836] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 154.871731] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 175.655735] Lustre: DEBUG MARKER: == conf-sanity test 33a: Mount ost with a large index number ========================================================== 18:49:03 (1736984943) [ 177.808450] random: crng init done [ 195.677928] Lustre: Mounted test-33a-client [ 195.843919] Lustre: Unmounted test-33a-client [ 214.659497] Key type lgssc unregistered [ 214.739309] LNet: 11743:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 215.745016] LNet: Removed LNI 192.168.202.56@tcp [ 223.034767] alg: No test for adler32 (adler32-zlib) [ 224.512811] Lustre: DEBUG MARKER: == conf-sanity test 33b: Drop cancel during umount ======= 18:49:51 (1736984991) [ 225.282273] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 225.422787] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 225.508231] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 225.511134] LNet: Accept secure, port 988 [ 227.078498] Key type lgssc registered [ 227.512525] Lustre: Echo OBD driver; http://www.lustre.org/ [ 240.919710] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 249.269755] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 255.569232] Lustre: Mounted lustre-client [ 257.611285] Lustre: Unmounted lustre-client [ 274.958887] Key type lgssc unregistered [ 275.039532] LNet: 14844:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 276.044492] LNet: Removed LNI 192.168.202.56@tcp [ 281.689028] alg: No test for adler32 (adler32-zlib) [ 282.941597] Lustre: DEBUG MARKER: == conf-sanity test 34a: umount with opened file should be fail ========================================================== 18:50:50 (1736985050) [ 283.751231] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 283.934829] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 284.017348] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 284.019715] LNet: Accept secure, port 988 [ 285.576490] Key type lgssc registered [ 285.989962] Lustre: Echo OBD driver; http://www.lustre.org/ [ 301.359990] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 308.199128] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 314.417762] Lustre: Mounted lustre-client [ 317.422535] Lustre: Unmounted lustre-client [ 336.265292] Key type lgssc unregistered [ 336.366029] LNet: 17872:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 337.369360] LNet: Removed LNI 192.168.202.56@tcp [ 342.011194] alg: No test for adler32 (adler32-zlib) [ 343.263180] Lustre: DEBUG MARKER: == conf-sanity test 34b: force umount with failed mds should be normal ========================================================== 18:51:50 (1736985110) [ 343.986432] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 344.233531] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 344.301755] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 344.304045] LNet: Accept secure, port 988 [ 345.874530] Key type lgssc registered [ 346.334671] Lustre: Echo OBD driver; http://www.lustre.org/ [ 360.894171] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 366.970571] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 373.208072] Lustre: Mounted lustre-client [ 378.559359] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 385.220275] Lustre: 18848:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1736985146/real 1736985146] req@ffff88013711b900 x1821360900802688/t0(0) o400->MGC192.168.202.156@tcp@192.168.202.156@tcp:26/25 lens 224/224 e 0 to 1 dl 1736985153 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 385.239355] LustreError: 166-1: MGC192.168.202.156@tcp: Connection to MGS (at 192.168.202.156@tcp) was lost; in progress operations using this service will fail [ 388.607651] Lustre: Unmounted lustre-client [ 394.208961] Key type lgssc unregistered [ 394.299445] LNet: 20907:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 395.306457] LNet: Removed LNI 192.168.202.56@tcp [ 399.665788] alg: No test for adler32 (adler32-zlib) [ 400.984485] Lustre: DEBUG MARKER: == conf-sanity test 34c: force umount with failed ost should be normal ========================================================== 18:52:48 (1736985168) [ 401.629110] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 401.774343] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 401.846183] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 401.849290] LNet: Accept secure, port 988 [ 403.404495] Key type lgssc registered [ 403.843201] Lustre: Echo OBD driver; http://www.lustre.org/ [ 418.299446] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 425.464409] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 431.804578] Lustre: Mounted lustre-client [ 436.811666] Lustre: lustre-OST0000-osc-ffff8800b6120000: Connection to lustre-OST0000 (at 192.168.202.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 441.258021] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 451.300562] Lustre: Unmounted lustre-client [ 467.504735] Key type lgssc unregistered [ 467.587841] LNet: 23952:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 468.592103] LNet: Removed LNI 192.168.202.56@tcp [ 474.810137] alg: No test for adler32 (adler32-zlib) [ 476.185037] Lustre: DEBUG MARKER: == conf-sanity test 35a: Reconnect to the last active server first ========================================================== 18:54:03 (1736985243) [ 477.169372] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 477.292591] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 477.388268] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 477.391140] LNet: Accept secure, port 988 [ 478.974525] Key type lgssc registered [ 479.484506] Lustre: Echo OBD driver; http://www.lustre.org/ [ 494.159294] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 500.149011] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 506.392374] Lustre: Mounted lustre-client [ 508.281074] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 509.473000] Lustre: DEBUG MARKER: Wait for RECONNECT_INTERVAL seconds (10s) [ 515.634133] LNetError: 120-3: Refusing connection from 192.168.202.56 for 127.0.0.2@tcp: No matching NI [ 515.638386] LNetError: 24917:0:(socklnd_cb.c:1771:ksocknal_recv_hello()) Error -104 reading HELLO from 127.0.0.2 [ 515.644235] LNetError: 11b-b: Connection to 127.0.0.2@tcp at host 127.0.0.2:988 was reset: is it running a compatible version of Lustre and is 127.0.0.2@tcp one of its NIDs? [ 519.987336] Lustre: DEBUG MARKER: conf-sanity.sh test_35a 2025-01-1518h54m47s [ 520.514342] Lustre: DEBUG MARKER: Stopping the MDT: lustre-MDT0000 [ 521.419078] Lustre: lustre-MDT0000-mdc-ffff8800a9c19800: Connection to lustre-MDT0000 (at 192.168.202.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 521.439761] LNetError: 120-3: Refusing connection from 192.168.202.56 for 127.0.0.2@tcp: No matching NI [ 521.442733] LNetError: 24918:0:(socklnd_cb.c:1771:ksocknal_recv_hello()) Error -104 reading HELLO from 127.0.0.2 [ 521.446292] LNetError: 11b-b: Connection to 127.0.0.2@tcp at host 127.0.0.2:988 was reset: is it running a compatible version of Lustre and is 127.0.0.2@tcp one of its NIDs? [ 526.467096] LNetError: 120-3: Refusing connection from 192.168.202.56 for 127.0.0.2@tcp: No matching NI [ 526.474340] LNetError: 24919:0:(socklnd_cb.c:1771:ksocknal_recv_hello()) Error -104 reading HELLO from 127.0.0.2 [ 526.479429] LNetError: 11b-b: Connection to 127.0.0.2@tcp at host 127.0.0.2:988 was reset: is it running a compatible version of Lustre and is 127.0.0.2@tcp one of its NIDs? [ 528.743037] Lustre: DEBUG MARKER: Restarting the MDT: lustre-MDT0000 [ 531.482545] LustreError: 166-1: MGC192.168.202.156@tcp: Connection to MGS (at 192.168.202.156@tcp) was lost; in progress operations using this service will fail [ 531.491486] Lustre: Evicted from MGS (at 192.168.202.156@tcp) after server handle changed from 0xb2b561317f37ca3b to 0xb2b561317f37ccb1 [ 531.503662] Lustre: MGC192.168.202.156@tcp: Connection restored to (at 192.168.202.156@tcp) [ 531.510109] LustreError: 167-0: lustre-MDT0000-mdc-ffff8800a9c19800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 531.521105] LustreError: 27272:0:(statahead.c:1376:is_first_dirent()) lustre: reading dir [0x200000007:0x1:0x0] at 0 opendir_pid = 27272 : rc = -5 [ 533.981461] Lustre: DEBUG MARKER: Wait for df (27272) ... [ 534.564822] Lustre: DEBUG MARKER: done [ 534.950597] Lustre: Unmounted lustre-client [ 553.373253] Key type lgssc unregistered [ 553.450060] LNet: 28250:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 554.453979] LNet: Removed LNI 192.168.202.56@tcp [ 568.118560] alg: No test for adler32 (adler32-zlib) [ 568.921747] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 569.011169] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 569.072879] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 569.074892] LNet: Accept secure, port 988 [ 570.616494] Key type lgssc registered [ 570.969553] Lustre: Echo OBD driver; http://www.lustre.org/ [ 594.879249] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 602.837829] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 620.808250] Lustre: DEBUG MARKER: == conf-sanity test 35b: Continue reconnection retries, if the active server is busy ========================================================== 18:56:28 (1736985388) [ 626.862306] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 632.579339] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 643.800259] Lustre: Mounted lustre-client [ 645.982828] Lustre: DEBUG MARKER: conf-sanity.sh test_35b 2025-01-1518h56m53s [ 646.625945] Lustre: DEBUG MARKER: Set up a fake failnode for the MDS [ 649.417505] Lustre: DEBUG MARKER: Injecting EBUSY on MDS [ 649.446027] LNetError: 120-3: Refusing connection from 192.168.202.56 for 127.0.0.2@tcp: No matching NI [ 649.451477] LNetError: 28984:0:(socklnd_cb.c:1771:ksocknal_recv_hello()) Error -104 reading HELLO from 127.0.0.2 [ 649.457873] LNetError: 11b-b: Connection to 127.0.0.2@tcp at host 127.0.0.2:988 was reset: is it running a compatible version of Lustre and is 127.0.0.2@tcp one of its NIDs? [ 650.322444] Lustre: DEBUG MARKER: Creating a test file and stat it [ 670.397598] Lustre: 1712:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1736985418/real 1736985418] req@ffff880137309c80 x1821361136732800/t0(0) o101->lustre-MDT0000-mdc-ffff8800b3d75800@192.168.202.156@tcp:12/10 lens 576/1024 e 0 to 1 dl 1736985438 ref 2 fl Rpc:XQr/0/ffffffff rc 0/-1 job:'' [ 670.413026] Lustre: lustre-MDT0000-mdc-ffff8800b3d75800: Connection to lustre-MDT0000 (at 192.168.202.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 670.435175] Lustre: lustre-MDT0000-mdc-ffff8800b3d75800: Connection restored to (at 192.168.202.156@tcp) [ 690.439525] Lustre: 1712:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1736985438/real 1736985438] req@ffff880137309c80 x1821361136732800/t0(0) o101->lustre-MDT0000-mdc-ffff8800b3d75800@192.168.202.156@tcp:12/10 lens 576/1024 e 0 to 1 dl 1736985458 ref 2 fl Rpc:XQr/2/ffffffff rc 0/-1 job:'' [ 690.451658] Lustre: lustre-MDT0000-mdc-ffff8800b3d75800: Connection to lustre-MDT0000 (at 192.168.202.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 690.464985] Lustre: lustre-MDT0000-mdc-ffff8800b3d75800: Connection restored to (at 192.168.202.156@tcp) [ 690.922489] Lustre: DEBUG MARKER: Stop injecting EBUSY on MDS [ 691.692182] Lustre: DEBUG MARKER: done [ 692.387897] Lustre: Unmounted lustre-client [ 708.287260] Key type lgssc unregistered [ 708.364077] LNet: 2586:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 709.370365] LNet: Removed LNI 192.168.202.56@tcp [ 722.708883] alg: No test for adler32 (adler32-zlib) [ 723.531949] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 723.624129] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 723.680821] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 723.683464] LNet: Accept secure, port 988 [ 725.232669] Key type lgssc registered [ 725.541892] Lustre: Echo OBD driver; http://www.lustre.org/ [ 748.032120] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 755.770379] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 774.732998] Lustre: DEBUG MARKER: == conf-sanity test 36: df report consistency on OSTs with different block size ========================================================== 18:59:02 (1736985542) [ 775.287443] Lustre: DEBUG MARKER: SKIP: conf-sanity test_36 remote OST [ 775.889587] Lustre: DEBUG MARKER: == conf-sanity test 37: verify set tunables works for symlink device ========================================================== 18:59:03 (1736985543) [ 776.373054] Lustre: DEBUG MARKER: SKIP: conf-sanity test_37 ldiskfs only test [ 776.971060] Lustre: DEBUG MARKER: == conf-sanity test 38: MDS recreates missing lov_objid file from OST data ========================================================== 18:59:04 (1736985544) [ 782.250093] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 787.945516] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 799.159308] Lustre: Mounted lustre-client [ 800.977721] Lustre: DEBUG MARKER: copying 10 files to /mnt/lustre/d38.conf-sanity [ 801.997372] Lustre: Unmounted lustre-client [ 806.410584] Lustre: DEBUG MARKER: delete lov_objid file on MDS [ 816.684544] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 816.874903] Lustre: Mounted lustre-client [ 817.805330] Lustre: Unmounted lustre-client [ 833.540196] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 833.696696] Lustre: Mounted lustre-client [ 834.452538] Lustre: Unmounted lustre-client [ 845.360205] Lustre: DEBUG MARKER: files compared the same [ 847.875656] Key type lgssc unregistered [ 847.943752] LNet: 10821:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 848.946740] LNet: Removed LNI 192.168.202.56@tcp [ 851.898040] alg: No test for adler32 (adler32-zlib) [ 853.002705] Lustre: DEBUG MARKER: == conf-sanity test 39: leak_finder recognizes both LUSTRE and LNET malloc messages ========================================================== 19:00:20 (1736985620) [ 853.463160] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 853.533496] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 853.577848] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 853.580031] LNet: Accept secure, port 988 [ 855.114578] Key type lgssc registered [ 855.351477] Lustre: Echo OBD driver; http://www.lustre.org/ [ 866.631767] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 871.562201] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 882.740271] Lustre: Mounted lustre-client [ 884.303643] Lustre: Unmounted lustre-client [ 899.799187] Key type lgssc unregistered [ 899.861732] LNet: 13809:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 900.864747] LNet: Removed LNI 192.168.202.56@tcp [ 904.292621] alg: No test for adler32 (adler32-zlib) [ 905.430241] Lustre: DEBUG MARKER: == conf-sanity test 40: race during service thread startup ========================================================== 19:01:12 (1736985672) [ 905.990357] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 906.064580] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 906.113815] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 906.116032] LNet: Accept secure, port 988 [ 907.649834] Key type lgssc registered [ 907.938585] Lustre: Echo OBD driver; http://www.lustre.org/ [ 995.368121] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1017.298883] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1032.638623] Key type lgssc unregistered [ 1032.706415] LNet: 16759:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1032.711004] LNet: Removed LNI 192.168.202.56@tcp [ 1036.055752] alg: No test for adler32 (adler32-zlib) [ 1037.186493] Lustre: DEBUG MARKER: == conf-sanity test 41a: mount mds with --nosvc and --nomgs ========================================================== 19:03:24 (1736985804) [ 1037.835834] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1037.906958] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 1037.956035] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 1037.958400] LNet: Accept secure, port 988 [ 1039.495559] Key type lgssc registered [ 1039.802191] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1055.579602] Lustre: Mounted lustre-client [ 1060.835691] Lustre: Unmounted lustre-client [ 1078.056871] Lustre: DEBUG MARKER: == conf-sanity test 41b: mount mds with --nosvc and --nomgs on first mount ========================================================== 19:04:06 (1736985846) [ 1104.707273] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1109.228148] Lustre: Mounted lustre-client [ 1114.490147] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1114.493586] LustreError: 22271:0:(file.c:259:ll_close_inode_openhandle()) lustre-clilmv-ffff8800ab1f8800: inode [0x200000401:0x1:0x0] mdc close failed: rc = -108 [ 1114.501192] Lustre: 17753:0:(llite_lib.c:3590:ll_dirty_page_discard_warn()) lustre: dirty page discard: 192.168.202.156@tcp:/lustre/fid: [0x200000401:0x1:0x0]/ may get corrupted (rc -108) [ 1124.528482] Lustre: Unmounted lustre-client [ 1139.468256] Lustre: DEBUG MARKER: == conf-sanity test 41c: concurrent mounts of MDT/OST should all fail but one ========================================================== 19:05:07 (1736985907) [ 1141.785170] Key type lgssc unregistered [ 1141.850740] LNet: 23421:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1142.853950] LNet: Removed LNI 192.168.202.56@tcp [ 1145.150225] alg: No test for adler32 (adler32-zlib) [ 1145.947710] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1146.013774] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 1146.067294] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 1146.069304] LNet: Accept secure, port 988 [ 1147.599790] Key type lgssc registered [ 1147.823685] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1158.159497] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1162.516379] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1163.652461] Lustre: Mounted lustre-client [ 1165.076511] Lustre: Unmounted lustre-client [ 1179.866840] Key type lgssc unregistered [ 1179.927840] LNet: 25786:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1180.930854] LNet: Removed LNI 192.168.202.56@tcp [ 1182.738152] alg: No test for adler32 (adler32-zlib) [ 1183.528414] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1183.591009] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 1183.637281] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 1183.638867] LNet: Accept secure, port 988 [ 1185.169501] Key type lgssc registered [ 1185.380922] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1201.142183] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1205.103420] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1206.223520] Lustre: Mounted lustre-client [ 1206.474518] Lustre: Unmounted lustre-client [ 1219.115068] Key type lgssc unregistered [ 1219.169737] LNet: 28364:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1220.172749] LNet: Removed LNI 192.168.202.56@tcp [ 1222.941630] alg: No test for adler32 (adler32-zlib) [ 1224.014135] Lustre: DEBUG MARKER: == conf-sanity test 42: allow client/server mount/unmount with invalid config param ========================================================== 19:06:31 (1736985991) [ 1224.446359] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1224.515503] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 1224.559580] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 1224.561698] LNet: Accept secure, port 988 [ 1226.089491] Key type lgssc registered [ 1226.294453] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1235.790561] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1239.724019] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1240.843093] Lustre: Mounted lustre-client [ 1242.621496] Lustre: Unmounted lustre-client [ 1242.716715] Lustre: Mounted lustre-client [ 1243.164335] Lustre: Unmounted lustre-client [ 1258.039847] Key type lgssc unregistered [ 1258.096836] LNet: 31434:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1259.100765] LNet: Removed LNI 192.168.202.56@tcp [ 1261.279828] alg: No test for adler32 (adler32-zlib) [ 1262.076060] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1262.140317] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 1262.185930] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 1262.187471] LNet: Accept secure, port 988 [ 1263.714486] Key type lgssc registered [ 1263.912655] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1273.280492] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1277.441727] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1278.563565] Lustre: Mounted lustre-client [ 1280.080511] Lustre: Unmounted lustre-client [ 1290.940190] Key type lgssc unregistered [ 1291.000221] LNet: 1392:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1292.003807] LNet: Removed LNI 192.168.202.56@tcp [ 1294.941454] alg: No test for adler32 (adler32-zlib) [ 1296.012330] Lustre: DEBUG MARKER: == conf-sanity test 43a: check root_squash and nosquash_nids ========================================================== 19:07:43 (1736986063) [ 1296.467599] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1296.532914] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 1296.580129] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 1296.582051] LNet: Accept secure, port 988 [ 1298.110578] Key type lgssc registered [ 1298.337709] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1307.775407] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1311.801142] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1312.912044] Lustre: Mounted lustre-client [ 1318.027994] Lustre: lustre: nosquash_nids is cleared [ 1318.032457] Lustre: lustre: root_squash is set to 500:500 [ 1323.763953] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.202.56@tcp 192.168.0.[2,10]@tcp [ 1330.283878] Lustre: Unmounted lustre-client [ 1344.990719] Key type lgssc unregistered [ 1345.052733] LNet: 4672:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1346.055737] LNet: Removed LNI 192.168.202.56@tcp [ 1348.903024] alg: No test for adler32 (adler32-zlib) [ 1349.943134] Lustre: DEBUG MARKER: == conf-sanity test 43b: parse nosquash_nids with commas in expr_list ========================================================== 19:08:37 (1736986117) [ 1350.239207] lustre: Unknown symbol range_unlock (err 0) [ 1350.241109] lustre: Unknown symbol lprocfs_wr_nosquash_nids (err 0) [ 1350.243462] lustre: Unknown symbol cl_io_end (err 0) [ 1350.245239] lustre: Unknown symbol cl_page_list_fini (err 0) [ 1350.247038] lustre: Unknown symbol req_capsule_field_present (err 0) [ 1350.249304] lustre: Unknown symbol cl_object_attr_get (err 0) [ 1350.251341] lustre: Unknown symbol cl_page_assume (err 0) [ 1350.252985] lustre: Unknown symbol cl_page_get (err 0) [ 1350.254491] lustre: Unknown symbol class_manual_cleanup (err 0) [ 1350.256831] lustre: Unknown symbol range_lock_init (err 0) [ 1350.258780] lustre: Unknown symbol cl_object_maxbytes (err 0) [ 1350.260899] lustre: Unknown symbol lu_object_header_print (err 0) [ 1350.262891] lustre: Unknown symbol RMF_EADATA (err 0) [ 1350.264527] lustre: Unknown symbol RMF_MDT_MD (err 0) [ 1350.266053] lustre: Unknown symbol lustre_swab_lmv_user_md (err 0) [ 1350.267986] lustre: Unknown symbol cl_site_init (err 0) [ 1350.269644] lustre: Unknown symbol _ldlm_lock_debug (err 0) [ 1350.271013] lustre: Unknown symbol cl_cache_init (err 0) [ 1350.272528] lustre: Unknown symbol lu_object_add (err 0) [ 1350.273935] lustre: Unknown symbol lprocfs_stats_header (err 0) [ 1350.275573] lustre: Unknown symbol cl_page_print (err 0) [ 1350.276905] lustre: Unknown symbol cl_object_attr_lock (err 0) [ 1350.278871] lustre: Unknown symbol cl_object_kill (err 0) [ 1350.280697] lustre: Unknown symbol RMF_NIOBUF_INLINE (err 0) [ 1350.282704] lustre: Unknown symbol server_fill_super (err 0) [ 1350.284475] lustre: Unknown symbol lustre_start_mgc (err 0) [ 1350.285841] lustre: Unknown symbol cfs_cpt_tab (err 0) [ 1350.287291] lustre: Unknown symbol cl_2queue_init (err 0) [ 1350.288599] lustre: Unknown symbol cl_env_alloc (err 0) [ 1350.290072] lustre: Unknown symbol obd_ioctl_getdata (err 0) [ 1350.291631] lustre: Unknown symbol cl_page_list_add (err 0) [ 1350.293229] lustre: Unknown symbol lu_context_key_get (err 0) [ 1350.294624] lustre: Unknown symbol lu_device_type_init (err 0) [ 1350.296358] lustre: Unknown symbol ldlm_cli_convert (err 0) [ 1350.297566] lustre: Unknown symbol ptlrpc_request_addref (err 0) [ 1350.299003] lustre: Unknown symbol cl_page_clip (err 0) [ 1350.300416] lustre: Unknown symbol lprocfs_wr_root_squash (err 0) [ 1350.302237] lustre: Unknown symbol LNetGetId (err 0) [ 1350.303626] lustre: Unknown symbol lu_device_type_fini (err 0) [ 1350.305710] lustre: Unknown symbol cl_type_setup (err 0) [ 1350.307143] lustre: Unknown symbol cl_page_export (err 0) [ 1350.308765] lustre: Unknown symbol obd_heat_get (err 0) [ 1350.310371] lustre: Unknown symbol ldlm_flock_completion_ast (err 0) [ 1350.311762] lustre: Unknown symbol linkea_init_with_rec (err 0) [ 1350.313809] lustre: Unknown symbol lprocfs_counter_add (err 0) [ 1350.315793] lustre: Unknown symbol cl_page_at (err 0) [ 1350.317291] lustre: Unknown symbol cl_vmpage_page (err 0) [ 1350.318850] lustre: Unknown symbol ldlm_lockname (err 0) [ 1350.320670] lustre: Unknown symbol cl_site_fini (err 0) [ 1350.322228] lustre: Unknown symbol lprocfs_clear_stats (err 0) [ 1350.323992] lustre: Unknown symbol linkea_entry_unpack (err 0) [ 1350.325723] lustre: Unknown symbol cl_2queue_discard (err 0) [ 1350.327208] lustre: Unknown symbol ldebugfs_stats_seq_fops (err 0) [ 1350.328765] lustre: Unknown symbol lustre_swab_lov_user_md (err 0) [ 1350.330357] lustre: Unknown symbol cl_io_rw_init (err 0) [ 1350.331571] lustre: Unknown symbol cl_object_attr_update (err 0) [ 1350.333123] lustre: Unknown symbol obd_heat_add (err 0) [ 1350.334671] lustre: Unknown symbol sysfs_memparse (err 0) [ 1350.336651] lustre: Unknown symbol lu_object_fini (err 0) [ 1350.338207] lustre: Unknown symbol lustre_sysfs_ops (err 0) [ 1350.339952] lustre: Unknown symbol ldlm_completion_ast (err 0) [ 1350.341750] lustre: Unknown symbol range_lock_tree_init (err 0) [ 1350.343447] lustre: Unknown symbol lprocfs_find_named_value (err 0) [ 1350.344894] lustre: Unknown symbol lu_buf_free (err 0) [ 1350.346405] lustre: Unknown symbol class_put_profile (err 0) [ 1350.348380] lustre: Unknown symbol cl_page_find (err 0) [ 1350.349739] lustre: Unknown symbol obdo_from_inode (err 0) [ 1350.351769] lustre: Unknown symbol cl_page_delete (err 0) [ 1350.353762] lustre: Unknown symbol cl_lock_release (err 0) [ 1350.355350] lustre: Unknown symbol cl_sub_dio_free (err 0) [ 1350.356713] lustre: Unknown symbol cl_object_layout_get (err 0) [ 1350.358382] lustre: Unknown symbol cfs_cpt_cpumask (err 0) [ 1350.359823] lustre: Unknown symbol cl_sync_io_note (err 0) [ 1350.361634] lustre: Unknown symbol cl_page_own (err 0) [ 1350.363319] lustre: Unknown symbol obd_memory (err 0) [ 1350.364743] lustre: Unknown symbol cl_io_loop (err 0) [ 1350.366756] lustre: Unknown symbol __ldlm_handle2lock (err 0) [ 1350.368204] lustre: Unknown symbol lustre_process_log (err 0) [ 1350.369888] lustre: Unknown symbol lustre_get_jobid (err 0) [ 1350.371821] lustre: Unknown symbol RMF_EAVALS_LENS (err 0) [ 1350.373462] lustre: Unknown symbol obdo_set_o_projid (err 0) [ 1350.375117] lustre: Unknown symbol class_get_profile (err 0) [ 1350.376839] lustre: Unknown symbol lu_site_wq_from_fid (err 0) [ 1350.378604] lustre: Unknown symbol cl_page_header_print (err 0) [ 1350.380401] lustre: Unknown symbol cl_page_is_owned (err 0) [ 1350.382277] lustre: Unknown symbol cfs_free_nidlist (err 0) [ 1350.384216] lustre: Unknown symbol cl_sync_io_wait_recycle (err 0) [ 1350.386115] lustre: Unknown symbol cl_object_put (err 0) [ 1350.387933] lustre: Unknown symbol debugfs_lustre_root (err 0) [ 1350.389717] lustre: Unknown symbol obdo_set_parent_fid (err 0) [ 1350.391867] lustre: Unknown symbol cl_page_touch (err 0) [ 1350.394121] lustre: Unknown symbol lu_object_locate (err 0) [ 1350.395909] lustre: Unknown symbol lu_object_add_top (err 0) [ 1350.397995] lustre: Unknown symbol cl_index (err 0) [ 1350.399207] lustre: Unknown symbol lprocfs_alloc_stats (err 0) [ 1350.400613] lustre: Unknown symbol cl_object_getstripe (err 0) [ 1350.401841] lustre: Unknown symbol cl_offset (err 0) [ 1350.403572] lustre: Unknown symbol obd_zombie_barrier (err 0) [ 1350.405424] lustre: Unknown symbol lu_cdebug_printer (err 0) [ 1350.407108] lustre: Unknown symbol ldlm_cli_cancel (err 0) [ 1350.409364] lustre: Unknown symbol lu_context_key_degister_many (err 0) [ 1350.411879] lustre: Unknown symbol cl_page_list_disown (err 0) [ 1350.413877] lustre: Unknown symbol lov_read_and_clear_async_rc (err 0) [ 1350.416104] lustre: Unknown symbol cl_page_slice_add (err 0) [ 1350.417809] lustre: Unknown symbol cl_env_put (err 0) [ 1350.419098] lustre: Unknown symbol lu_device_get (err 0) [ 1350.420505] lustre: Unknown symbol it_open_error (err 0) [ 1350.422080] lustre: Unknown symbol cl_object_attr_unlock (err 0) [ 1350.424360] lustre: Unknown symbol ldlm_lock_put (err 0) [ 1350.426323] lustre: Unknown symbol cl_2queue_fini (err 0) [ 1350.428323] lustre: Unknown symbol lu_context_key_register_many (err 0) [ 1350.430964] lustre: Unknown symbol RMF_FILE_ENCCTX (err 0) [ 1350.433059] lustre: Unknown symbol cl_page_put (err 0) [ 1350.435178] lustre: Unknown symbol cfs_print_nidlist (err 0) [ 1350.437412] lustre: Unknown symbol cl_sync_io_wait (err 0) [ 1350.439117] lustre: Unknown symbol lu_device_init (err 0) [ 1350.440750] lustre: Unknown symbol range_lock (err 0) [ 1350.442477] lustre: Unknown symbol cl_conf_set (err 0) [ 1350.443930] lustre: Unknown symbol cl_sub_dio_alloc (err 0) [ 1350.445736] lustre: Unknown symbol lu_device_fini (err 0) [ 1350.447367] lustre: Unknown symbol lu_site_init_finish (err 0) [ 1350.449345] lustre: Unknown symbol ldlm_lock_allow_match (err 0) [ 1350.451215] lustre: Unknown symbol cl_cache_decref (err 0) [ 1350.452981] lustre: Unknown symbol req_capsule_server_get (err 0) [ 1350.454836] lustre: Unknown symbol class_devices_in_group (err 0) [ 1350.456660] lustre: Unknown symbol ll_release_user_pages (err 0) [ 1350.458539] lustre: Unknown symbol ldlm_lock_decref_and_cancel (err 0) [ 1350.460557] lustre: Unknown symbol lprocfs_stats_collector (err 0) [ 1350.462378] lustre: Unknown symbol unlock_res_and_lock (err 0) [ 1350.464225] lustre: Unknown symbol cl_io_fini (err 0) [ 1350.465656] lustre: Unknown symbol cl_object_fiemap (err 0) [ 1350.467558] lustre: Unknown symbol lock_res_and_lock (err 0) [ 1350.469158] lustre: Unknown symbol lprocfs_free_stats (err 0) [ 1350.470883] lustre: Unknown symbol cl_site_stats_print (err 0) [ 1350.472650] lustre: Unknown symbol cl_io_top (err 0) [ 1350.474229] lustre: Unknown symbol lu_context_key_revive_many (err 0) [ 1350.476759] lustre: Unknown symbol cl_page_flush (err 0) [ 1350.478417] lustre: Unknown symbol cl_page_unassume (err 0) [ 1350.480356] lustre: Unknown symbol lu_object_get_first (err 0) [ 1350.482059] lustre: Unknown symbol ldebugfs_add_vars (err 0) [ 1350.484300] lustre: Unknown symbol cl_2queue_add (err 0) [ 1350.486275] lustre: Unknown symbol ldlm_lock2handle (err 0) [ 1350.488424] lustre: Unknown symbol cl_io_slice_add (err 0) [ 1350.490255] lustre: Unknown symbol cl_env_percpu_get (err 0) [ 1350.492504] lustre: Unknown symbol cl_page_discard (err 0) [ 1350.494242] lustre: Unknown symbol lu_object_init (err 0) [ 1350.496126] lustre: Unknown symbol cl_lock_request (err 0) [ 1350.497580] lustre: Unknown symbol cl_env_get (err 0) [ 1350.498645] lustre: Unknown symbol lprocfs_counter_init (err 0) [ 1350.500314] lustre: Unknown symbol req_capsule_server_sized_get (err 0) [ 1350.501997] lustre: Unknown symbol cl_page_list_splice (err 0) [ 1350.503645] lustre: Unknown symbol RMF_MDT_BODY (err 0) [ 1350.505219] lustre: Unknown symbol cl_page_disown (err 0) [ 1350.506719] lustre: Unknown symbol cl_object_header_init (err 0) [ 1350.508184] lustre: Unknown symbol RMF_FILE_SECCTX (err 0) [ 1350.509681] lustre: Unknown symbol cl_page_list_init (err 0) [ 1350.511236] lustre: Unknown symbol class_del_profile (err 0) [ 1350.512849] lustre: Unknown symbol cl_stack_fini (err 0) [ 1350.514395] lustre: Unknown symbol lu_kmem_fini (err 0) [ 1350.516249] lustre: Unknown symbol class_name2obd (err 0) [ 1350.518298] lustre: Unknown symbol cl_env_percpu_put (err 0) [ 1350.520701] lustre: Unknown symbol cl_object_find (err 0) [ 1350.522640] lustre: Unknown symbol lustre_end_log (err 0) [ 1350.524620] lustre: Unknown symbol cfs_cpt_spread_node (err 0) [ 1350.526622] lustre: Unknown symbol lu_kmem_init (err 0) [ 1350.528289] lustre: Unknown symbol cl_2queue_disown (err 0) [ 1350.530045] lustre: Unknown symbol cl_io_commit_async (err 0) [ 1350.531461] lustre: Unknown symbol cl_object_flush (err 0) [ 1350.533148] lustre: Unknown symbol ldlm_it2str (err 0) [ 1350.534313] lustre: Unknown symbol cl_io_lock_add (err 0) [ 1350.535482] lustre: Unknown symbol cl_io_init (err 0) [ 1350.536622] lustre: Unknown symbol cl_page_list_discard (err 0) [ 1350.538224] lustre: Unknown symbol cl_io_lru_reserve (err 0) [ 1350.539867] lustre: Unknown symbol lprocfs_counter_sub (err 0) [ 1350.541498] lustre: Unknown symbol cfs_cpt_weight (err 0) [ 1350.542654] lustre: Unknown symbol cl_page_list_del (err 0) [ 1350.544059] lustre: Unknown symbol lprocfs_oh_clear (err 0) [ 1350.545303] lustre: Unknown symbol cl_io_submit_rw (err 0) [ 1350.546627] lustre: Unknown symbol lustre_common_put_super (err 0) [ 1350.547942] lustre: Unknown symbol cl_sync_io_init_notify (err 0) [ 1350.549174] lustre: Unknown symbol cl_io_submit_sync (err 0) [ 1350.550585] lustre: Unknown symbol req_capsule_get_size (err 0) [ 1350.552161] lustre: Unknown symbol lu_object_header_fini (err 0) [ 1350.553732] lustre: Unknown symbol cl_dio_aio_alloc (err 0) [ 1350.555109] lustre: Unknown symbol cl_page_size (err 0) [ 1350.556510] lustre: Unknown symbol lmv_free_memmd (err 0) [ 1350.558103] lustre: Unknown symbol lu_context_key_quiesce_many (err 0) [ 1350.559872] lustre: Unknown symbol obd_heat_clear (err 0) [ 1350.561212] lustre: Unknown symbol cl_io_lock_alloc_add (err 0) [ 1350.563237] lustre: Unknown symbol linkea_data_new (err 0) [ 1350.565204] lustre: Unknown symbol obd_connect_flags2str (err 0) [ 1350.567077] lustre: Unknown symbol RMF_EAVALS (err 0) [ 1350.568782] lustre: Unknown symbol cl_io_read_ahead (err 0) [ 1350.570221] lustre: Unknown symbol cl_env_cache_purge (err 0) [ 1350.571671] lustre: Unknown symbol cl_io_start (err 0) [ 1350.573007] lustre: Unknown symbol lustre_init_lsi (err 0) [ 1350.574350] lustre: Unknown symbol class_exp2obd (err 0) [ 1350.575897] lustre: Unknown symbol lustre_put_lsi (err 0) [ 1350.577678] lustre: Unknown symbol ptlrpc_req_finished (err 0) [ 1350.579633] lustre: Unknown symbol cfs_match_nid (err 0) [ 1350.581869] lustre: Unknown symbol statfs_unpack (err 0) [ 1350.583315] lustre: Unknown symbol ldlm_lock_decref (err 0) [ 1350.585293] lustre: Unknown symbol cl_dio_aio_free (err 0) [ 1350.587031] lustre: Unknown symbol lmd_parse (err 0) [ 1350.588542] lustre: Unknown symbol class_config_llog_handler (err 0) [ 1350.590672] lustre: Unknown symbol lustre_kset (err 0) [ 1350.592053] lustre: Unknown symbol LU_BUF_NULL (err 0) [ 1352.855880] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1352.913777] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 1352.959215] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 1352.960897] LNet: Accept secure, port 988 [ 1354.485513] Key type lgssc registered [ 1354.703379] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1371.938893] Key type lgssc unregistered [ 1372.000657] LNet: 7054:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1372.003075] LNet: Removed LNI 192.168.202.56@tcp [ 1374.931258] alg: No test for adler32 (adler32-zlib) [ 1375.988478] Lustre: DEBUG MARKER: == conf-sanity test 44: mounted client proc entry exists ========================================================== 19:09:03 (1736986143) [ 1376.416543] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1376.479465] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 1376.524058] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 1376.525751] LNet: Accept secure, port 988 [ 1378.053972] Key type lgssc registered [ 1378.271090] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1387.360351] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1391.245630] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1391.330073] Lustre: lustre: root_squash is set to 500:500 [ 1391.335153] Lustre: lustre: nosquash_nids set to 2@gni 0@lo 192.168.202.56@tcp 192.168.0.[2,10]@tcp [ 1392.347154] Lustre: Mounted lustre-client [ 1394.087621] Lustre: Unmounted lustre-client [ 1404.692524] Key type lgssc unregistered [ 1404.751695] LNet: 10094:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1405.754753] LNet: Removed LNI 192.168.202.56@tcp [ 1408.740784] alg: No test for adler32 (adler32-zlib) [ 1409.823471] Lustre: DEBUG MARKER: SKIP: conf-sanity test_45 skipping SLOW test 45 [ 1410.197939] Lustre: DEBUG MARKER: == conf-sanity test 46a: handle ost additional - wide striped file ========================================================== 19:09:38 (1736986178) [ 1414.770372] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1414.835633] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 1414.881574] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 1414.883365] LNet: Accept secure, port 988 [ 1416.419534] Key type lgssc registered [ 1416.636459] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1434.687631] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1440.003801] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1458.782654] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1462.782496] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1464.864871] Lustre: Mounted lustre-client [ 1471.126135] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8800b6365000.ost_server_uuid 40 [ 1471.460414] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8800b6365000.ost_server_uuid in FULL state after 0 sec [ 1471.526231] Lustre: Mounted lustre-client [ 1472.014536] Lustre: Unmounted lustre-client [ 1495.164535] Key type lgssc unregistered [ 1495.220696] LNet: 16390:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1496.222768] LNet: Removed LNI 192.168.202.56@tcp [ 1504.872749] alg: No test for adler32 (adler32-zlib) [ 1505.668859] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 1505.736840] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 1505.786342] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 1505.788141] LNet: Accept secure, port 988 [ 1507.316498] Key type lgssc registered [ 1507.511170] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1525.146847] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1530.377144] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1546.847042] Lustre: DEBUG MARKER: == conf-sanity test 47: server restart does not make client loss lru_resize settings ========================================================== 19:11:54 (1736986314) [ 1563.628402] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1568.608224] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1569.696635] Lustre: Mounted lustre-client [ 1581.714518] Lustre: 17139:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1736986343/real 1736986343] req@ffff8800a84ff6c0 x1821362119248192/t0(0) o400->lustre-OST0000-osc-ffff8800b5caf800@192.168.202.156@tcp:28/4 lens 224/224 e 0 to 1 dl 1736986350 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 1581.721504] Lustre: lustre-OST0000-osc-ffff8800b5caf800: Connection to lustre-OST0000 (at 192.168.202.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1586.725518] Lustre: 17138:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1736986348/real 1736986348] req@ffff8800a84f97c0 x1821362119248384/t0(0) o400->lustre-OST0000-osc-ffff8800b5caf800@192.168.202.156@tcp:28/4 lens 224/224 e 0 to 1 dl 1736986355 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 1593.738491] Lustre: 17139:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1736986355/real 1736986355] req@ffff8800a9d06880 x1821362119248896/t0(0) o400->lustre-MDT0000-mdc-ffff8800b5caf800@192.168.202.156@tcp:12/10 lens 224/224 e 0 to 1 dl 1736986362 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 1593.746694] Lustre: lustre-MDT0000-mdc-ffff8800b5caf800: Connection to lustre-MDT0000 (at 192.168.202.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1593.752154] LustreError: 166-1: MGC192.168.202.156@tcp: Connection to MGS (at 192.168.202.156@tcp) was lost; in progress operations using this service will fail [ 1599.756486] Lustre: 17139:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1736986361/real 1736986361] req@ffff8800a9d03900 x1821362119249280/t0(0) o400->lustre-MDT0000-mdc-ffff8800b5caf800@192.168.202.156@tcp:12/10 lens 224/224 e 0 to 1 dl 1736986368 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 1599.758491] Lustre: Evicted from MGS (at 192.168.202.156@tcp) after server handle changed from 0x7547ec912c755289 to 0x7547ec912c7554ab [ 1599.758938] Lustre: MGC192.168.202.156@tcp: Connection restored to 192.168.202.156@tcp (at 192.168.202.156@tcp) [ 1599.770617] Lustre: 17139:0:(client.c:2295:ptlrpc_expire_one_request()) Skipped 2 previous similar messages [ 1603.233144] Lustre: lustre-OST0000-osc-ffff8800b5caf800: Connection restored to 192.168.202.156@tcp (at 192.168.202.156@tcp) [ 1603.236156] Lustre: Skipped 1 previous similar message [ 1603.388430] Lustre: Unmounted lustre-client [ 1617.960860] Key type lgssc unregistered [ 1618.010770] LNet: 23992:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1619.013766] LNet: Removed LNI 192.168.202.56@tcp [ 1621.697294] alg: No test for adler32 (adler32-zlib) [ 1622.731491] Lustre: DEBUG MARKER: == conf-sanity test 48: too many acls on file ============ 19:13:10 (1736986390) [ 1623.166954] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1623.219582] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 1623.262226] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 1623.263801] LNet: Accept secure, port 988 [ 1624.789516] Key type lgssc registered [ 1624.977845] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1633.961748] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1637.762099] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1638.859932] Lustre: Mounted lustre-client [ 1705.970476] Lustre: 24983:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1736986467/real 1736986467] req@ffff8800a9caf200 x1821362243365312/t0(0) o400->lustre-MDT0000-mdc-ffff88012e5a7000@192.168.202.156@tcp:12/10 lens 224/224 e 0 to 1 dl 1736986474 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 1705.971553] LustreError: 166-1: MGC192.168.202.156@tcp: Connection to MGS (at 192.168.202.156@tcp) was lost; in progress operations using this service will fail [ 1705.981782] Lustre: 24983:0:(client.c:2295:ptlrpc_expire_one_request()) Skipped 1 previous similar message [ 1705.984465] Lustre: lustre-MDT0000-mdc-ffff88012e5a7000: Connection to lustre-MDT0000 (at 192.168.202.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1710.989507] Lustre: 24983:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1736986472/real 1736986472] req@ffff8800a9cae880 x1821362243365504/t0(0) o400->lustre-MDT0000-mdc-ffff88012e5a7000@192.168.202.156@tcp:12/10 lens 224/224 e 0 to 1 dl 1736986479 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 1711.991464] Lustre: Evicted from MGS (at 192.168.202.156@tcp) after server handle changed from 0x7d2088541f505269 to 0x7d2088541f52baf5 [ 1711.994873] Lustre: MGC192.168.202.156@tcp: Connection restored to (at 192.168.202.156@tcp) [ 1712.971509] Lustre: 24980:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1736986474/real 1736986474] req@ffff8800a9175f00 x1821362243365696/t0(0) o400->lustre-MDT0000-mdc-ffff88012e5a7000@192.168.202.156@tcp:12/10 lens 224/224 e 0 to 1 dl 1736986481 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 1723.012079] Lustre: lustre-MDT0000-mdc-ffff88012e5a7000: Connection restored to 192.168.202.156@tcp (at 192.168.202.156@tcp) [ 1724.337317] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1724.738493] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 1726.103650] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 1736.131494] Lustre: Unmounted lustre-client [ 1768.665171] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1774.169929] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1790.548607] Lustre: DEBUG MARKER: == conf-sanity test 49a: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 19:15:58 (1736986558) [ 1806.981178] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1812.240045] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1812.339161] Lustre: Mounted lustre-client [ 1813.007937] Lustre: Unmounted lustre-client [ 1828.507592] Lustre: DEBUG MARKER: == conf-sanity test 49b: check PARAM_SYS_LDLM_TIMEOUT option of mkfs.lustre ========================================================== 19:16:36 (1736986596) [ 1845.576843] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1850.863089] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1850.963423] Lustre: Mounted lustre-client [ 1851.682295] Lustre: Unmounted lustre-client [ 1866.479680] Key type lgssc unregistered [ 1866.537771] LNet: 9508:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1867.540815] LNet: Removed LNI 192.168.202.56@tcp [ 1870.523857] alg: No test for adler32 (adler32-zlib) [ 1871.577623] Lustre: DEBUG MARKER: == conf-sanity test 50a: lazystatfs all servers available ========================================================== 19:17:18 (1736986638) [ 1871.951749] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1872.026879] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 1872.065939] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 1872.067738] LNet: Accept secure, port 988 [ 1873.594548] Key type lgssc registered [ 1873.798663] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1883.142572] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1887.145321] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1893.269819] Lustre: Mounted lustre-client [ 1903.699504] Lustre: Unmounted lustre-client [ 1916.349906] Key type lgssc unregistered [ 1916.410762] LNet: 12507:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 1917.412846] LNet: Removed LNI 192.168.202.56@tcp [ 1920.177859] alg: No test for adler32 (adler32-zlib) [ 1921.236208] Lustre: DEBUG MARKER: == conf-sanity test 50b: lazystatfs all servers down ===== 19:18:08 (1736986688) [ 1921.658806] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 1921.718982] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 1921.764736] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 1921.766586] LNet: Accept secure, port 988 [ 1923.294528] Key type lgssc registered [ 1923.494617] Lustre: Echo OBD driver; http://www.lustre.org/ [ 1932.644701] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1936.383680] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1937.482006] Lustre: Mounted lustre-client [ 1942.500159] Lustre: lustre-OST0000-osc-ffff8800b62ea800: Connection to lustre-OST0000 (at 192.168.202.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 1947.102291] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state DISCONN osc.lustre-OST0000-osc-ffff8800b62ea800.ost_server_uuid 40 [ 1947.439300] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-ffff8800b62ea800.ost_server_uuid in DISCONN state after 0 sec [ 1947.770146] Lustre: DEBUG MARKER: OSCs should all be DISCONN [ 1956.949504] Lustre: Unmounted lustre-client [ 1971.485442] Lustre: DEBUG MARKER: == conf-sanity test 50c: lazystatfs one server down ====== 19:18:59 (1736986739) [ 1975.463138] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 1979.234881] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1984.676654] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 1984.793195] Lustre: Mounted lustre-client [ 1989.796520] Lustre: lustre-OST0000-osc-ffff8800a9c59800: Connection to lustre-OST0000 (at 192.168.202.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2002.104767] Lustre: Unmounted lustre-client [ 2037.347571] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2042.877197] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2059.372102] Lustre: DEBUG MARKER: == conf-sanity test 50d: lazystatfs client/server conn race ========================================================== 19:20:27 (1736986827) [ 2063.214276] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2067.234700] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2072.528872] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 2072.631566] Lustre: Mounted lustre-client [ 2077.636178] Lustre: lustre-OST0000-osc-ffff8800b5cdb800: Connection to lustre-OST0000 (at 192.168.202.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2088.876417] Lustre: Unmounted lustre-client [ 2123.303708] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2128.613030] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2145.112804] Lustre: DEBUG MARKER: == conf-sanity test 50e: normal statfs all servers down == 19:21:53 (1736986913) [ 2162.488726] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2167.549000] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2187.917023] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2191.717291] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2202.619756] Lustre: Mounted lustre-client [ 2227.414782] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2233.882871] Lustre: DEBUG MARKER: osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 6 sec [ 2234.067047] Lustre: Unmounted lustre-client [ 2245.956695] Lustre: DEBUG MARKER: == conf-sanity test 50f: normal statfs one server in down ========================================================== 19:23:34 (1736987014) [ 2250.178480] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2254.282943] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2260.803570] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 2269.980057] Lustre: Mounted lustre-client [ 2294.619160] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 2295.954061] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 1 sec [ 2299.009904] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2309.033881] Lustre: Unmounted lustre-client [ 2345.879717] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2351.334885] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2368.021661] Lustre: DEBUG MARKER: == conf-sanity test 50g: deactivated OST should not cause panic ========================================================== 19:25:36 (1736987136) [ 2372.288135] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2376.314247] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2376.420027] Lustre: Mounted lustre-client [ 2382.899137] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 2387.325451] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 4 sec [ 2389.686224] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state (FULL|IDLE) osc.lustre-OST0001-osc-ffff8800a9c50000.ost_server_uuid 40 [ 2390.051714] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-ffff8800a9c50000.ost_server_uuid in FULL state after 0 sec [ 2390.488001] Lustre: Unmounted lustre-client [ 2390.547927] Lustre: setting import lustre-OST0001_UUID INACTIVE by administrator request [ 2390.550347] Lustre: Skipped 2 previous similar messages [ 2390.847966] LustreError: 8540:0:(sec.c:411:import_sec_validate_get()) import ffff8800a9c50000 (NEW) with no sec [ 2428.609769] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2433.871108] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2450.351200] Lustre: DEBUG MARKER: == conf-sanity test 50h: LU-642: activate deactivated OST ========================================================== 19:26:58 (1736987218) [ 2455.052342] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2458.907263] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2464.132020] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 2464.224980] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 2464.247102] Lustre: Mounted lustre-client [ 2464.248441] Lustre: Skipped 1 previous similar message [ 2469.428659] LustreError: 13870:0:(obd_config.c:1999:class_config_llog_handler()) MGC192.168.202.156@tcp: cfg command failed: rc = -114 [ 2469.434118] Lustre: cmd=cf00f 0:lustre-OST0000-osc 1:osc.active=1 [ 2469.434118] [ 2469.437620] LustreError: 13788:0:(mgc_request.c:612:do_requeue()) failed processing log: -114 [ 2471.201638] Lustre: Unmounted lustre-client [ 2471.203483] Lustre: Skipped 1 previous similar message [ 2492.919048] Key type lgssc unregistered [ 2492.976797] LNet: 14314:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2493.978881] LNet: Removed LNI 192.168.202.56@tcp [ 2496.785292] alg: No test for adler32 (adler32-zlib) [ 2497.839098] Lustre: DEBUG MARKER: == conf-sanity test 50i: activate deactivated MDT ======== 19:27:45 (1736987265) [ 2498.149736] Lustre: DEBUG MARKER: SKIP: conf-sanity test_50i needs >= 2 MDTs [ 2498.517343] Lustre: DEBUG MARKER: == conf-sanity test 51: Verify that mdt_reint handles RMF_MDT_MD correctly when an OST is added ========================================================== 19:27:46 (1736987266) [ 2502.791584] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2502.850415] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 2502.897195] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 2502.898589] LNet: Accept secure, port 988 [ 2504.424528] Key type lgssc registered [ 2504.637248] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2521.726289] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2527.045752] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2533.159162] Lustre: Mounted lustre-client [ 2540.806580] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 2542.202756] Lustre: DEBUG MARKER: osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid in FULL state after 1 sec [ 2556.516703] Lustre: lustre-OST0001-osc-ffff8800b6e92000: Connection to lustre-OST0001 (at 192.168.202.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 2560.723817] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 2570.756598] Lustre: Unmounted lustre-client [ 2583.781714] Key type lgssc unregistered [ 2583.855043] LNet: 19449:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2584.857774] LNet: Removed LNI 192.168.202.56@tcp [ 2595.142217] alg: No test for adler32 (adler32-zlib) [ 2595.938021] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2595.990315] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 2596.032818] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 2596.035021] LNet: Accept secure, port 988 [ 2597.561563] Key type lgssc registered [ 2597.875851] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2620.049638] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2626.038185] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2646.946492] Lustre: DEBUG MARKER: == conf-sanity test 52: check recovering objects from lost+found ========================================================== 19:30:15 (1736987415) [ 2647.272759] Lustre: DEBUG MARKER: SKIP: conf-sanity test_52 ldiskfs only test [ 2647.767322] Lustre: DEBUG MARKER: == conf-sanity test 53a: check OSS thread count params === 19:30:15 (1736987415) [ 2652.683646] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2656.910849] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2658.022665] Lustre: Mounted lustre-client [ 2663.043691] Lustre: Unmounted lustre-client [ 2675.660644] Key type lgssc unregistered [ 2675.710772] LNet: 25331:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2676.712806] LNet: Removed LNI 192.168.202.56@tcp [ 2678.829357] alg: No test for adler32 (adler32-zlib) [ 2679.620004] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2679.676257] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 2679.720859] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 2679.723525] LNet: Accept secure, port 988 [ 2681.249522] Key type lgssc registered [ 2681.465735] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2690.610494] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2695.501029] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2696.658960] Lustre: Mounted lustre-client [ 2698.929503] Lustre: Unmounted lustre-client [ 2713.865672] Key type lgssc unregistered [ 2713.919863] LNet: 27746:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2714.924883] LNet: Removed LNI 192.168.202.56@tcp [ 2717.185471] alg: No test for adler32 (adler32-zlib) [ 2717.975148] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2718.035566] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 2718.097605] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 2718.100293] LNet: Accept secure, port 988 [ 2719.638616] Key type lgssc registered [ 2719.856851] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2729.485219] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2734.121504] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2735.223517] Lustre: Mounted lustre-client [ 2736.706535] Lustre: Unmounted lustre-client [ 2751.278456] Key type lgssc unregistered [ 2751.344726] LNet: 30111:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2752.346771] LNet: Removed LNI 192.168.202.56@tcp [ 2755.067071] alg: No test for adler32 (adler32-zlib) [ 2756.105951] Lustre: DEBUG MARKER: == conf-sanity test 53b: check MDS thread count params === 19:32:03 (1736987523) [ 2756.506855] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2756.567826] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 2756.612422] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 2756.613881] LNet: Accept secure, port 988 [ 2758.139529] Key type lgssc registered [ 2758.331184] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2767.444545] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2771.334833] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2772.428957] Lustre: Mounted lustre-client [ 2777.906494] Lustre: Unmounted lustre-client [ 2788.577767] Key type lgssc unregistered [ 2788.635748] LNet: 828:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2789.638758] LNet: Removed LNI 192.168.202.56@tcp [ 2791.592656] alg: No test for adler32 (adler32-zlib) [ 2792.381831] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2792.440707] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 2792.485609] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 2792.487084] LNet: Accept secure, port 988 [ 2794.014498] Key type lgssc registered [ 2794.218745] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2803.341988] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2807.187442] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2808.283863] Lustre: Mounted lustre-client [ 2810.447770] Lustre: Unmounted lustre-client [ 2825.075845] Key type lgssc unregistered [ 2825.128787] LNet: 3256:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2826.130807] LNet: Removed LNI 192.168.202.56@tcp [ 2828.174079] alg: No test for adler32 (adler32-zlib) [ 2828.963983] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 2829.023073] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 2829.066139] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 2829.068592] LNet: Accept secure, port 988 [ 2830.594478] Key type lgssc registered [ 2830.785293] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2840.110837] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2844.081941] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2845.198488] Lustre: Mounted lustre-client [ 2846.579318] Lustre: Unmounted lustre-client [ 2857.304474] Key type lgssc unregistered [ 2857.365758] LNet: 5616:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 2858.368790] LNet: Removed LNI 192.168.202.56@tcp [ 2861.121193] alg: No test for adler32 (adler32-zlib) [ 2862.166265] Lustre: DEBUG MARKER: == conf-sanity test 54a: test llverdev and partial verify of device ========================================================== 19:33:49 (1736987629) [ 2862.491177] Lustre: DEBUG MARKER: SKIP: conf-sanity test_54a ldiskfs only test [ 2862.854881] Lustre: DEBUG MARKER: == conf-sanity test 54b: test llverfs and partial verify of filesystem ========================================================== 19:33:50 (1736987630) [ 2863.179940] Lustre: DEBUG MARKER: SKIP: conf-sanity test_54b ldiskfs only test [ 2863.528724] Lustre: DEBUG MARKER: SKIP: conf-sanity test_55 skipping excluded test 55 [ 2863.894849] Lustre: DEBUG MARKER: == conf-sanity test 56a: check big OST indexes and out-of-index-order start ========================================================== 19:33:51 (1736987631) [ 2868.205491] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 2868.261173] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 2868.304887] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 2868.306692] LNet: Accept secure, port 988 [ 2869.836538] Key type lgssc registered [ 2870.066944] Lustre: Echo OBD driver; http://www.lustre.org/ [ 2890.946560] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2896.057406] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST2710-osc-[-0-9a-f]*.ost_server_uuid [ 2900.231529] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST03e8-osc-[-0-9a-f]*.ost_server_uuid [ 2901.333312] Lustre: Mounted lustre-client [ 2905.216549] Lustre: Unmounted lustre-client [ 2934.110646] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2939.729745] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 2956.380689] Lustre: DEBUG MARKER: == conf-sanity test 56b: test target_obd correctness with nonconsecutive MDTs ========================================================== 19:35:24 (1736987724) [ 2956.730013] Lustre: DEBUG MARKER: SKIP: conf-sanity test_56b needs >= 3 MDTs [ 2957.088116] Lustre: DEBUG MARKER: == conf-sanity test 57a: initial registration from failnode should fail (should return errs) ========================================================== 19:35:25 (1736987725) [ 2980.637959] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 2985.935189] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3007.960319] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3017.794322] Key type lgssc unregistered [ 3017.856036] LNet: 19003:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3018.863100] LNet: Removed LNI 192.168.202.56@tcp [ 3021.934023] alg: No test for adler32 (adler32-zlib) [ 3023.001626] Lustre: DEBUG MARKER: == conf-sanity test 57b: initial registration from servicenode should not fail ========================================================== 19:36:30 (1736987790) [ 3033.275985] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3033.336944] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 3033.380081] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 3033.381507] LNet: Accept secure, port 988 [ 3034.907498] Key type lgssc registered [ 3035.114727] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3050.631441] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3055.946614] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3075.840944] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3079.749454] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3096.458667] Key type lgssc unregistered [ 3096.520821] LNet: 24354:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3096.522998] LNet: Removed LNI 192.168.202.56@tcp [ 3099.349605] alg: No test for adler32 (adler32-zlib) [ 3100.387856] Lustre: DEBUG MARKER: == conf-sanity test 58: missing llog files must not prevent MDT from mounting ========================================================== 19:37:47 (1736987867) [ 3100.892066] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3100.952363] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 3101.003657] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 3101.005273] LNet: Accept secure, port 988 [ 3102.535545] Key type lgssc registered [ 3102.726815] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3112.063449] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3115.908460] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3117.010894] Lustre: Mounted lustre-client [ 3122.020540] Lustre: lustre-MDT0000-mdc-ffff880136437800: Connection to lustre-MDT0000 (at 192.168.202.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3131.893320] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3132.036377] LustreError: 166-1: MGC192.168.202.156@tcp: Connection to MGS (at 192.168.202.156@tcp) was lost; in progress operations using this service will fail [ 3132.043987] Lustre: Evicted from MGS (at 192.168.202.156@tcp) after server handle changed from 0x91954f30d1a04645 to 0x91954f30d1a063cd [ 3132.047502] Lustre: MGC192.168.202.156@tcp: Connection restored to 192.168.202.156@tcp (at 192.168.202.156@tcp) [ 3138.051719] LustreError: 167-0: lustre-MDT0000-mdc-ffff880136437800: This client was evicted by lustre-MDT0000; in progress operations using this service will fail. [ 3138.065060] Lustre: lustre-MDT0000-mdc-ffff880136437800: Connection restored to 192.168.202.156@tcp (at 192.168.202.156@tcp) [ 3139.364572] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 7 sec [ 3139.748360] Lustre: Unmounted lustre-client [ 3154.464285] Key type lgssc unregistered [ 3154.523769] LNet: 28215:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3155.526826] LNet: Removed LNI 192.168.202.56@tcp [ 3158.295180] alg: No test for adler32 (adler32-zlib) [ 3159.353285] Lustre: DEBUG MARKER: == conf-sanity test 59: writeconf mount option =========== 19:38:46 (1736987926) [ 3159.775498] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3159.837439] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 3159.885518] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 3159.887029] LNet: Accept secure, port 988 [ 3161.415540] Key type lgssc registered [ 3161.619944] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3170.803610] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3183.655417] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3189.014781] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3194.618303] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3210.582024] Key type lgssc unregistered [ 3210.640636] LNet: 32454:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3210.642969] LNet: Removed LNI 192.168.202.56@tcp [ 3219.433523] alg: No test for adler32 (adler32-zlib) [ 3220.225183] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3220.279798] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 3220.325658] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 3220.327166] LNet: Accept secure, port 988 [ 3221.851544] Key type lgssc registered [ 3222.061172] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3239.140108] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3244.468661] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3261.990637] Lustre: DEBUG MARKER: == conf-sanity test 60: check mkfs.lustre --mkfsoptions -E -O options setting ========================================================== 19:40:30 (1736988030) [ 3262.313931] Lustre: DEBUG MARKER: SKIP: conf-sanity test_60 ldiskfs only test [ 3262.712613] Lustre: DEBUG MARKER: == conf-sanity test 61: large xattr ====================== 19:40:30 (1736988030) [ 3266.703067] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3270.774139] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3271.882178] Lustre: Mounted lustre-client [ 3274.709482] Lustre: DEBUG MARKER: save large xattr of 65536 bytes on trusted.big on /mnt/lustre/f61.conf-sanity [ 3275.173956] Lustre: DEBUG MARKER: shrink value of trusted.big on /mnt/lustre/f61.conf-sanity [ 3275.552656] Lustre: DEBUG MARKER: grow value of trusted.big on /mnt/lustre/f61.conf-sanity [ 3275.918852] Lustre: DEBUG MARKER: check value of trusted.big on /mnt/lustre/f61.conf-sanity after remounting MDS [ 3283.906504] Lustre: 742:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1736988045/real 1736988045] req@ffff8800a80c7200 x1821363916508800/t0(0) o400->MGC192.168.202.156@tcp@192.168.202.156@tcp:26/25 lens 224/224 e 0 to 1 dl 1736988052 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 3283.906586] Lustre: lustre-MDT0000-mdc-ffff8800a84d3800: Connection to lustre-MDT0000 (at 192.168.202.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3283.915681] Lustre: 742:0:(client.c:2295:ptlrpc_expire_one_request()) Skipped 1 previous similar message [ 3283.917598] LustreError: 166-1: MGC192.168.202.156@tcp: Connection to MGS (at 192.168.202.156@tcp) was lost; in progress operations using this service will fail [ 3288.920529] Lustre: 742:0:(client.c:2295:ptlrpc_expire_one_request()) @@@ Request sent has timed out for slow reply: [sent 1736988050/real 1736988050] req@ffff8800a80c4280 x1821363916509056/t0(0) o400->lustre-MDT0000-mdc-ffff8800a84d3800@192.168.202.156@tcp:12/10 lens 224/224 e 0 to 1 dl 1736988057 ref 1 fl Rpc:XNQr/0/ffffffff rc 0/-1 job:'' [ 3289.922831] Lustre: Evicted from MGS (at 192.168.202.156@tcp) after server handle changed from 0xb395ce2dba4b54ed to 0xb395ce2dba4b5628 [ 3289.925983] Lustre: MGC192.168.202.156@tcp: Connection restored to (at 192.168.202.156@tcp) [ 3300.937882] Lustre: lustre-MDT0000-mdc-ffff8800a84d3800: Connection restored to 192.168.202.156@tcp (at 192.168.202.156@tcp) [ 3302.274230] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3302.650328] Lustre: DEBUG MARKER: mdc.lustre-MDT0000-mdc-*.mds_server_uuid in FULL state after 0 sec [ 3304.262301] Lustre: DEBUG MARKER: remove large xattr trusted.big from /mnt/lustre/f61.conf-sanity [ 3304.426495] Lustre: Unmounted lustre-client [ 3319.102773] Key type lgssc unregistered [ 3319.163750] LNet: 7489:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3320.167358] LNet: Removed LNI 192.168.202.56@tcp [ 3323.128111] alg: No test for adler32 (adler32-zlib) [ 3324.182010] Lustre: DEBUG MARKER: == conf-sanity test 62: start with disabled journal ====== 19:41:31 (1736988091) [ 3324.515677] Lustre: DEBUG MARKER: SKIP: conf-sanity test_62 ldiskfs only test [ 3324.895902] Lustre: DEBUG MARKER: SKIP: conf-sanity test_63 skipping excluded test 63 [ 3325.359481] Lustre: DEBUG MARKER: == conf-sanity test 64: check lfs df --lazy ============== 19:41:33 (1736988093) [ 3326.003219] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3326.060057] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 3326.113690] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 3326.116300] LNet: Accept secure, port 988 [ 3327.659531] Key type lgssc registered [ 3327.883490] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3338.205899] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3342.652274] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3347.958529] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0001-osc-[-0-9a-f]*.ost_server_uuid [ 3349.055110] Lustre: Mounted lustre-client [ 3354.069409] Lustre: lustre-OST0001-osc-ffff8800a843a800: Connection to lustre-OST0001 (at 192.168.202.156@tcp) was lost; in progress operations using this service will wait for recovery to complete [ 3356.359274] Lustre: setting import lustre-MDT0000_UUID INACTIVE by administrator request [ 3366.389567] Lustre: Unmounted lustre-client [ 3387.472200] Key type lgssc unregistered [ 3387.558927] LNet: 11456:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3388.563224] LNet: Removed LNI 192.168.202.56@tcp [ 3399.562701] alg: No test for adler32 (adler32-zlib) [ 3400.360318] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3400.419085] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 3400.468966] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 3400.471006] LNet: Accept secure, port 988 [ 3401.998633] Key type lgssc registered [ 3402.218831] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3422.872276] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3429.167063] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3447.540206] Lustre: DEBUG MARKER: == conf-sanity test 65: re-create the lost last_rcvd file when server mount ========================================================== 19:43:35 (1736988215) [ 3448.050098] Lustre: DEBUG MARKER: SKIP: conf-sanity test_65 ldiskfs only test [ 3448.440948] Lustre: DEBUG MARKER: == conf-sanity test 66: replace nids ===================== 19:43:36 (1736988216) [ 3453.320136] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3458.252996] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3459.404034] Lustre: Mounted lustre-client [ 3464.584782] Lustre: setting import lustre-OST0000_UUID INACTIVE by administrator request [ 3468.263275] Lustre: Unmounted lustre-client [ 3502.305169] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3512.333989] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3517.432927] Lustre: Mounted lustre-client [ 3517.756216] Lustre: Unmounted lustre-client [ 3535.015706] Key type lgssc unregistered [ 3535.076778] LNet: 18814:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3536.079275] LNet: Removed LNI 192.168.202.56@tcp [ 3542.942267] alg: No test for adler32 (adler32-zlib) [ 3543.735636] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 2 [ 3543.797054] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 3543.850868] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 3543.853216] LNet: Accept secure, port 988 [ 3545.396662] Key type lgssc registered [ 3545.706235] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3565.378705] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3571.298875] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3589.748273] Lustre: DEBUG MARKER: SKIP: conf-sanity test_67 skipping excluded test 67 [ 3590.233389] Lustre: DEBUG MARKER: == conf-sanity test 68: be able to reserve specific sequences in FLDB ========================================================== 19:45:58 (1736988358) [ 3596.360627] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3602.398452] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3609.661778] Lustre: Mounted lustre-client [ 3610.161493] Lustre: Unmounted lustre-client [ 3625.435530] Key type lgssc unregistered [ 3625.486629] LNet: 24199:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3626.488928] LNet: Removed LNI 192.168.202.56@tcp [ 3629.232691] alg: No test for adler32 (adler32-zlib) [ 3630.309943] Lustre: DEBUG MARKER: SKIP: conf-sanity test_69 skipping SLOW test 69 [ 3630.690893] Lustre: DEBUG MARKER: == conf-sanity test 70a: start MDT0, then OST, then MDT1 ========================================================== 19:46:38 (1736988398) [ 3631.010884] Lustre: DEBUG MARKER: SKIP: conf-sanity test_70a needs >= 2 MDTs [ 3631.379073] Lustre: DEBUG MARKER: == conf-sanity test 70b: start OST, MDT1, MDT0 =========== 19:46:39 (1736988399) [ 3631.705774] Lustre: DEBUG MARKER: SKIP: conf-sanity test_70b needs >= 2 MDTs [ 3632.089152] Lustre: DEBUG MARKER: == conf-sanity test 70c: stop MDT0, mkdir fail, create remote dir fail ========================================================== 19:46:40 (1736988400) [ 3632.431754] Lustre: DEBUG MARKER: SKIP: conf-sanity test_70c needs >= 2 MDTs [ 3632.781758] Lustre: DEBUG MARKER: == conf-sanity test 70d: stop MDT1, mkdir succeed, create remote dir fail ========================================================== 19:46:40 (1736988400) [ 3633.105108] Lustre: DEBUG MARKER: SKIP: conf-sanity test_70d needs >= 2 MDTs [ 3633.480214] Lustre: DEBUG MARKER: == conf-sanity test 70e: Sync-on-Cancel will be enabled by default on DNE ========================================================== 19:46:41 (1736988401) [ 3633.782563] Lustre: DEBUG MARKER: SKIP: conf-sanity test_70e needs >= 2 MDTs [ 3634.166127] Lustre: DEBUG MARKER: == conf-sanity test 71a: start MDT0 OST0, MDT1, OST1 ===== 19:46:42 (1736988402) [ 3634.479746] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71a needs >= 2 MDTs [ 3634.842773] Lustre: DEBUG MARKER: == conf-sanity test 71b: start MDT1, OST0, MDT0, OST1 ==== 19:46:42 (1736988402) [ 3635.167588] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71b needs >= 2 MDTs [ 3635.567748] Lustre: DEBUG MARKER: == conf-sanity test 71c: start OST0, OST1, MDT1, MDT0 ==== 19:46:43 (1736988403) [ 3635.894631] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71c needs >= 2 MDTs [ 3636.275915] Lustre: DEBUG MARKER: == conf-sanity test 71d: start OST0, MDT1, MDT0, OST1 ==== 19:46:44 (1736988404) [ 3636.610932] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71d needs >= 2 MDTs [ 3636.995194] Lustre: DEBUG MARKER: == conf-sanity test 71e: start OST0, MDT1, OST1, MDT0 ==== 19:46:45 (1736988405) [ 3637.311287] Lustre: DEBUG MARKER: SKIP: conf-sanity test_71e needs >= 2 MDTs [ 3637.689123] Lustre: DEBUG MARKER: == conf-sanity test 72: test fast symlink with extents flag enabled ========================================================== 19:46:45 (1736988405) [ 3638.007947] Lustre: DEBUG MARKER: SKIP: conf-sanity test_72 ldiskfs only test [ 3638.388056] Lustre: DEBUG MARKER: == conf-sanity test 73: failnode to update from mountdata properly ========================================================== 19:46:46 (1736988406) [ 3639.515122] libcfs: HW NUMA nodes: 1, HW CPU cores: 4, npartitions: 1 [ 3639.569355] Lustre: Lustre: Build Version: 2.15.6_7_gfc23ddf [ 3639.617274] LNet: Added LNI 192.168.202.56@tcp [8/256/0/180] [ 3639.618626] LNet: Accept secure, port 988 [ 3641.145484] Key type lgssc registered [ 3641.333567] Lustre: Echo OBD driver; http://www.lustre.org/ [ 3650.724766] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3654.615536] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3655.767658] Lustre: Mounted lustre-client [ 3655.936482] Lustre: Unmounted lustre-client [ 3683.740932] Lustre: DEBUG MARKER: == conf-sanity test 75: The order of --index should be irrelevant ========================================================== 19:47:31 (1736988451) [ 3711.230975] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 3718.348185] Lustre: DEBUG MARKER: oleg256-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 3753.036790] Lustre: DEBUG MARKER: == conf-sanity test complete, duration 3669 sec ========== 19:48:40 (1736988520) [ 3756.688387] Key type lgssc unregistered [ 3756.755697] LNet: 5144:0:(lib-ptl.c:992:lnet_clear_lazy_portal()) Active lazy portal 0 on exit [ 3782.760092] LNet: Removed LNI 192.168.202.56@tcp