************************ crashinfo ************************* /exports/testreports/51275/testresults/conf-sanity3-ldiskfs-DNE-centos7_x86_64-centos7_x86_64-retry3/oleg317-server-timeout-core (3.10.0-7.9-debug) +==========================+ | *** Crashinfo v1.3.7 *** | +==========================+ +++WARNING+++ PARTIAL DUMP with size(vmcore) < 25% size(RAM) KERNEL: /tmp/crash-anaysis.hcHtP/vmlinux [TAINTED] DUMPFILE: /exports/testreports/51275/testresults/conf-sanity3-ldiskfs-DNE-centos7_x86_64-centos7_x86_64-retry3/oleg317-server-timeout-core [PARTIAL DUMP] CPUS: 4 DATE: Fri May 2 04:05:08 EDT 2025 UPTIME: 02:30:15 LOAD AVERAGE: 0.37, 0.72, 0.72 TASKS: 158 NODENAME: oleg317-server.virtnet RELEASE: 3.10.0-7.9-debug VERSION: #1 SMP Sat Mar 26 23:28:42 EDT 2022 MACHINE: x86_64 (2399 Mhz) MEMORY: 4 GB PANIC: "" +--------------------------+ >------------------------| Per-cpu Stacks ('bt -a') |------------------------< +--------------------------+ -- CPU#0 -- PID=0 CPU=0 CMD=swapper/0 #-1 native_safe_halt+0xb, 449 bytes of data #0 default_idle+0x1e #1 default_enter_idle+0x45 #2 cpuidle_enter_state+0x40 #3 cpuidle_idle_call+0xd8 #4 arch_cpu_idle+0xe #5 cpu_startup_entry+0x14a #6 rest_init+0x8e #7 start_kernel+0x456 #8 x86_64_start_reservations+0x2a #9 x86_64_start_kernel+0x152 #10 start_cpu+0x5 -- CPU#1 -- PID=0 CPU=1 CMD=swapper/1 #-1 native_safe_halt+0xb, 449 bytes of data #0 default_idle+0x1e #1 default_enter_idle+0x45 #2 cpuidle_enter_state+0x40 #3 cpuidle_idle_call+0xd8 #4 arch_cpu_idle+0xe #5 cpu_startup_entry+0x14a #6 start_secondary+0x1eb #7 start_cpu+0x5 -- CPU#2 -- PID=0 CPU=2 CMD=swapper/2 #-1 native_safe_halt+0xb, 449 bytes of data #0 default_idle+0x1e #1 default_enter_idle+0x45 #2 cpuidle_enter_state+0x40 #3 cpuidle_idle_call+0xd8 #4 arch_cpu_idle+0xe #5 cpu_startup_entry+0x14a #6 start_secondary+0x1eb #7 start_cpu+0x5 -- CPU#3 -- PID=0 CPU=3 CMD=swapper/3 #-1 native_safe_halt+0xb, 449 bytes of data #0 default_idle+0x1e #1 default_enter_idle+0x45 #2 cpuidle_enter_state+0x40 #3 cpuidle_idle_call+0xd8 #4 arch_cpu_idle+0xe #5 cpu_startup_entry+0x14a #6 start_secondary+0x1eb #7 start_cpu+0x5 +--------------------------------+ >---------------------| How This Dump Has Been Created |---------------------< +--------------------------------+ Cannot identify the specific condition that triggered vmcore +---------------+ >------------------------------| Tasks Summary |------------------------------< +---------------+ Number of Threads That Ran Recently ----------------------------------- last second 15 last 5s 24 last 60s 62 ----- Total Numbers of Threads per State ------ TASK_INTERRUPTIBLE 154 TASK_RUNNING 1 +++WARNING+++ There are 3 threads running in their own namespaces Use 'taskinfo --ns' to get more details +-----------------------+ >--------------------------| 5 Most Recent Threads |--------------------------< +-----------------------+ PID CMD Age ARGS ----- -------------- ------ ---------------------------- 34 rcuos/3 0 ms (no user stack) 32064 socknal_reaper 0 ms (no user stack) 912 in:imjournal 0 ms /usr/sbin/rsyslogd -n 32069 monitor_thread 4 ms (no user stack) 32070 lnet_discovery 58 ms (no user stack) +------------------------+ >-------------------------| Memory Usage (kmem -i) |-------------------------< +------------------------+ PAGES TOTAL PERCENTAGE TOTAL MEM 955067 3.6 GB ---- FREE 812533 3.1 GB 85% of TOTAL MEM USED 142534 556.8 MB 14% of TOTAL MEM SHARED 9074 35.4 MB 0% of TOTAL MEM BUFFERS 6695 26.2 MB 0% of TOTAL MEM CACHED 75570 295.2 MB 7% of TOTAL MEM SLAB 15998 62.5 MB 1% of TOTAL MEM TOTAL HUGE 0 0 ---- HUGE FREE 0 0 0% of TOTAL HUGE TOTAL SWAP 262143 1024 MB ---- SWAP USED 0 0 0% of TOTAL SWAP SWAP FREE 262143 1024 MB 100% of TOTAL SWAP COMMIT LIMIT 739676 2.8 GB ---- COMMITTED 62192 242.9 MB 8% of TOTAL LIMIT +-------------------------------+ >----------------------| Scheduler Runqueues (per CPU) |----------------------< +-------------------------------+ ---+ CPU=0 ---- | CURRENT TASK , CMD=swapper/0 ---+ CPU=1 ---- | CURRENT TASK , CMD=swapper/1 ---+ CPU=2 ---- | CURRENT TASK , CMD=swapper/2 ---+ CPU=3 ---- | CURRENT TASK , CMD=swapper/3 +------------------------+ >-------------------------| Network Status Summary |-------------------------< +------------------------+ TCP Connection Info ------------------- ESTABLISHED 6 LISTEN 3 NAGLE disabled (TCP_NODELAY): 5 user_data set (NFS etc.): 5 UDP Connection Info ------------------- 2 UDP sockets, 0 in ESTABLISHED Unix Connection Info ------------------------ ESTABLISHED 26 CLOSE 17 LISTEN 8 Raw sockets info -------------------- ESTABLISHED 1 Interfaces Info --------------- How long ago (in seconds) interfaces transmitted/received? Name RX TX ---- ---------- --------- lo n/a 9012.9 eth0 n/a 2.4 RSS_TOTAL=51016 pages, %mem= 0.8 +------------+ >-------------------------------| Mounted FS |-------------------------------< +------------+ MOUNT SUPERBLK TYPE DEVNAME DIRNAME ffff880138cca000 ffff880139940800 rootfs rootfs / ffff88012a62e000 ffff880137318800 sysfs sysfs /sys ffff88012a62e1c0 ffff880139944000 proc proc /proc ffff88012a62e380 ffff880137678000 devtmpfs devtmpfs /dev ffff88012a62e540 ffff8800b51f9800 securityfs securityfs /sys/kernel/security ffff88012a62e700 ffff880137319000 tmpfs tmpfs /dev/shm ffff88012a62e8c0 ffff88013771f000 devpts devpts /dev/pts ffff88012a62ea80 ffff880137319800 tmpfs tmpfs /run ffff88012a62ec40 ffff88013731a000 tmpfs tmpfs /sys/fs/cgroup ffff88012a62ee00 ffff88013731a800 cgroup cgroup /sys/fs/cgroup/systemd ffff88012a62efc0 ffff88013731b000 pstore pstore /sys/fs/pstore ffff880137012540 ffff8800b7e41800 cgroup cgroup /sys/fs/cgroup/cpu,cpuacct ffff880137012700 ffff8800b7e42000 cgroup cgroup /sys/fs/cgroup/net_cls,net_prio ffff8801370128c0 ffff8800b7e42800 cgroup cgroup /sys/fs/cgroup/cpuset ffff880137012a80 ffff8800b7e43000 cgroup cgroup /sys/fs/cgroup/pids ffff880137012c40 ffff8800b7e43800 cgroup cgroup /sys/fs/cgroup/freezer ffff880137012e00 ffff8800b7e44000 cgroup cgroup /sys/fs/cgroup/memory ffff880137012fc0 ffff8800b7e44800 cgroup cgroup /sys/fs/cgroup/perf_event ffff880137013180 ffff8800b7e45000 cgroup cgroup /sys/fs/cgroup/hugetlb ffff880137013340 ffff8800b7e45800 cgroup cgroup /sys/fs/cgroup/devices ffff880137013500 ffff8800b7e46000 cgroup cgroup /sys/fs/cgroup/blkio ffff8801376688c0 ffff8800b51fd000 configfs configfs /sys/kernel/config ffff880129f0c540 ffff8800b4030800 ext4 /dev/nbd0 / ffff88012a62f180 ffff8800b51fc800 rpc_pipefs rpc_pipefs /var/lib/nfs/rpc_pipefs ffff88012a62f880 ffff880129cb0000 autofs systemd-1 /proc/sys/fs/binfmt_misc ffff880137668700 ffff88012b278800 mqueue mqueue /dev/mqueue ffff88012a62fa40 ffff880139947800 debugfs debugfs /sys/kernel/debug ffff880129f0c700 ffff880129d0a000 hugetlbfs hugetlbfs /dev/hugepages ffff88012a62fc00 ffff880129cb3000 binfmt_misc binfmt_misc /proc/sys/fs/binfmt_misc/ ffff880137013a40 ffff880129f97000 ramfs none /mnt ffff88012a62fdc0 ffff880129f76000 squashfs /dev/vda /home/green/git/lustre-release ffff880137668c40 ffff8800b4005000 tmpfs none /var/lib/stateless/writable ffff880137668e00 ffff8800b4005000 tmpfs none /var/cache/man ffff88012a62f6c0 ffff8800b4005000 tmpfs none /var/log ffff880137668fc0 ffff8800b4005000 tmpfs none /var/lib/dbus ffff88012a62f500 ffff8800b4005000 tmpfs none /tmp ffff880137669180 ffff8800b4005000 tmpfs none /var/lib/dhclient ffff88012a62f340 ffff8800b4005000 tmpfs none /var/tmp ffff880137669340 ffff8800b4005000 tmpfs none /var/lib/NetworkManager ffff8800b4362000 ffff8800b4005000 tmpfs none /var/lib/systemd/random-seed ffff880129f0ca80 ffff8800b4005000 tmpfs none /var/spool ffff880137669500 ffff8800b4005000 tmpfs none /var/lib/nfs ffff8800b43621c0 ffff8800b4005000 tmpfs none /var/lib/gssproxy ffff8800b4362380 ffff8800b4005000 tmpfs none /var/lib/logrotate ffff880137013c00 ffff8800b4005000 tmpfs none /etc ffff8800b4362540 ffff8800b4005000 tmpfs none /var/lib/rsyslog ffff8800b4362700 ffff8800b4005000 tmpfs none /var/lib/dhclient/var/lib/dhclient ffff8800b43628c0 ffff880129f95000 nfs4 192.168.200.253:/exports/state/oleg317-server.virtnet /var/lib/stateless/state ffff8800b4362c40 ffff880129f95000 nfs4 192.168.200.253:/exports/state/oleg317-server.virtnet /boot ffff8800b4362e00 ffff880129f95000 nfs4 192.168.200.253:/exports/state/oleg317-server.virtnet /etc/etc/kdump.conf ffff8800b4362fc0 ffff8800b51fc800 rpc_pipefs sunrpc /var/lib/nfs/var/lib/nfs/rpc_pipefs ffff8800b2075500 ffff880129f76000 squashfs /dev/vda /usr/sbin/mount.lustre +-------------------------------+ >----------------------| Last 40 lines of dmesg buffer |----------------------< +-------------------------------+ [ 8901.790307] LDISKFS-fs (dm-2): mounted filesystem with ordered data mode. Opts: user_xattr,acl,no_mbcache,nodelalloc [ 8903.190019] Lustre: lustre-OST0000-osc-MDT0000: update sequence from 0x100000000 to 0x280000401 [ 8903.712442] Lustre: DEBUG MARKER: oleg317-server.virtnet: executing set_default_debug -1 all [ 8906.906251] Lustre: DEBUG MARKER: oleg317-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8908.096623] Lustre: DEBUG MARKER: oleg317-server.virtnet: executing wait_import_state FULL os[cp].lustre-OST0000-osc-MDT0000.ost_server_uuid 50 [ 8908.171693] Lustre: DEBUG MARKER: os[cp].lustre-OST0000-osc-MDT0000.ost_server_uuid in FULL state after 0 sec [ 8909.328035] Lustre: DEBUG MARKER: oleg317-server.virtnet: executing wait_import_state FULL os[cp].lustre-OST0000-osc-MDT0001.ost_server_uuid 50 [ 8909.405191] Lustre: DEBUG MARKER: os[cp].lustre-OST0000-osc-MDT0001.ost_server_uuid in FULL state after 0 sec [ 8913.175525] LustreError: lustre-OST0000-osc-MDT0001: operation ost_statfs to node 0@lo failed: rc = -107 [ 8913.182494] Lustre: lustre-OST0000-osc-MDT0001: Connection to lustre-OST0000 (at 0@lo) was lost; in progress operations using this service will wait for recovery to complete [ 8913.193278] Lustre: Skipped 1 previous similar message [ 8913.193779] Lustre: lustre-OST0000: Not available for connect from 0@lo (stopping) [ 8913.193781] Lustre: Skipped 3 previous similar messages [ 8916.105490] LustreError: 2805:0:(obd_class.h:479:obd_check_dev()) Device 19 not setup [ 8916.109310] LustreError: 2805:0:(obd_class.h:479:obd_check_dev()) Skipped 17 previous similar messages [ 8916.186553] Lustre: server umount lustre-OST0000 complete [ 8923.700540] Lustre: server umount lustre-MDT0000 complete [ 8925.078702] LustreError: 31674:0:(ldlm_lockd.c:2549:ldlm_cancel_handler()) ldlm_cancel from 0@lo arrived at 1746173017 with bad export cookie 8547495702585452890 [ 8925.081154] LustreError: MGC192.168.203.117@tcp: Connection to MGS (at 0@lo) was lost; in progress operations using this service will fail [ 8925.093155] LustreError: 31674:0:(ldlm_lockd.c:2549:ldlm_cancel_handler()) Skipped 2 previous similar messages [ 8925.262482] Lustre: server umount lustre-MDT0001 complete [ 8928.374437] LDISKFS-fs (dm-0): mounted filesystem with ordered data mode. Opts: user_xattr,errors=remount-ro,no_mbcache,nodelalloc [ 8928.556832] LustreError: 3968:0:(ldlm_lib.c:1113:target_handle_connect()) lustre-MDT0001: not available for connect from 0@lo (no target). If you are running an HA pair check that the target is mounted on the other server. [ 8929.659475] Lustre: DEBUG MARKER: oleg317-server.virtnet: executing set_default_debug -1 all [ 8932.624634] LDISKFS-fs (dm-1): mounted filesystem with ordered data mode. Opts: user_xattr,acl,no_mbcache,nodelalloc [ 8933.972564] Lustre: DEBUG MARKER: oleg317-server.virtnet: executing set_default_debug -1 all [ 8935.220294] Lustre: DEBUG MARKER: oleg317-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0000-mdc-*.mds_server_uuid [ 8936.415262] Lustre: DEBUG MARKER: oleg317-client.virtnet: executing wait_import_state_mount FULL mdc.lustre-MDT0001-mdc-*.mds_server_uuid [ 8938.962496] LDISKFS-fs (dm-2): file extents enabled, maximum tree depth=5 [ 8938.970433] LDISKFS-fs (dm-2): mounted filesystem with ordered data mode. Opts: user_xattr,acl,no_mbcache,nodelalloc [ 8940.994692] Lustre: DEBUG MARKER: oleg317-server.virtnet: executing set_default_debug -1 all [ 8942.893943] Lustre: DEBUG MARKER: oleg317-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 8962.151815] LustreError: lustre-OST0000-osc-MDT0001: operation ost_statfs to node 0@lo failed: rc = -107 [ 8962.156803] LustreError: Skipped 1 previous similar message [ 8965.724772] Lustre: server umount lustre-OST0000 complete [ 8973.296033] Lustre: server umount lustre-MDT0000 complete [ 8974.498573] LustreError: 3950:0:(ldlm_lockd.c:2549:ldlm_cancel_handler()) ldlm_cancel from 0@lo arrived at 1746173066 with bad export cookie 8547495702585453772 [ 8974.501439] LustreError: MGC192.168.203.117@tcp: Connection to MGS (at 0@lo) was lost; in progress operations using this service will fail [ 8974.511497] LustreError: 3950:0:(ldlm_lockd.c:2549:ldlm_cancel_handler()) Skipped 4 previous similar messages [ 8974.663816] Lustre: server umount lustre-MDT0001 complete ****************************************************************************** ************************ A Summary Of Problems Found ************************* ****************************************************************************** -------------------- A list of all +++WARNING+++ messages -------------------- PARTIAL DUMP with size(vmcore) < 25% size(RAM) There are 3 threads running in their own namespaces Use 'taskinfo --ns' to get more details ------------------------------------------------------------------------------ ** Execution took 12.06s (real) 6.20s (CPU), Child processes: 5.81s