************************ crashinfo ************************* /exports/testreports/42551/testresults/conf-sanity2-ldiskfs-DNE-centos7_x86_64-centos7_x86_64/oleg336-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.SRw4e/vmlinux [TAINTED] DUMPFILE: /exports/testreports/42551/testresults/conf-sanity2-ldiskfs-DNE-centos7_x86_64-centos7_x86_64/oleg336-server-timeout-core [PARTIAL DUMP] CPUS: 4 DATE: Wed May 8 19:19:34 EDT 2024 UPTIME: 01:18:46 LOAD AVERAGE: 0.00, 0.01, 0.05 TASKS: 230 NODENAME: oleg336-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 26 last 5s 55 last 60s 63 ----- Total Numbers of Threads per State ------ TASK_INTERRUPTIBLE 226 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 ----- -------------- ------ ---------------------------- 809 gssproxy 0 ms /usr/sbin/gssproxy -D 9774 socknal_sd00_02 0 ms (no user stack) 9 rcu_sched 0 ms (no user stack) 9776 monitor_thread 89 ms (no user stack) 9773 socknal_sd00_01 94 ms (no user stack) +------------------------+ >-------------------------| Memory Usage (kmem -i) |-------------------------< +------------------------+ PAGES TOTAL PERCENTAGE TOTAL MEM 955067 3.6 GB ---- FREE 727596 2.8 GB 76% of TOTAL MEM USED 227471 888.6 MB 23% of TOTAL MEM SHARED 10160 39.7 MB 1% of TOTAL MEM BUFFERS 8392 32.8 MB 0% of TOTAL MEM CACHED 83546 326.4 MB 8% of TOTAL MEM SLAB 15208 59.4 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 75722 295.8 MB 10% 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 9 LISTEN 3 NAGLE disabled (TCP_NODELAY): 8 user_data set (NFS etc.): 8 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 4723.9 eth0 n/a 2.5 RSS_TOTAL=51664 pages, %mem= 0.8 +------------+ >-------------------------------| Mounted FS |-------------------------------< +------------+ MOUNT SUPERBLK TYPE DEVNAME DIRNAME ffff880138cca000 ffff880139940800 rootfs rootfs / ffff880137668540 ffff8800b5202800 sysfs sysfs /sys ffff880137668700 ffff880139944000 proc proc /proc ffff8801376688c0 ffff880137678000 devtmpfs devtmpfs /dev ffff880137668a80 ffff8800b5202000 securityfs securityfs /sys/kernel/security ffff880137668c40 ffff8800b5203000 tmpfs tmpfs /dev/shm ffff880137668e00 ffff8801372f6000 devpts devpts /dev/pts ffff880137668fc0 ffff8800b5203800 tmpfs tmpfs /run ffff880137669180 ffff8800b5204000 tmpfs tmpfs /sys/fs/cgroup ffff880137669340 ffff8800b5204800 cgroup cgroup /sys/fs/cgroup/systemd ffff880137669500 ffff8800b5205000 pstore pstore /sys/fs/pstore ffff8801376696c0 ffff8800b5207000 cgroup cgroup /sys/fs/cgroup/devices ffff880137669880 ffff8800b5206800 cgroup cgroup /sys/fs/cgroup/cpu,cpuacct ffff880137669a40 ffff8800b5206000 cgroup cgroup /sys/fs/cgroup/hugetlb ffff880137669c00 ffff8800b5205800 cgroup cgroup /sys/fs/cgroup/perf_event ffff880137669dc0 ffff8800b5207800 cgroup cgroup /sys/fs/cgroup/cpuset ffff88012a30c000 ffff88012a378000 cgroup cgroup /sys/fs/cgroup/memory ffff88012a30c1c0 ffff88012a378800 cgroup cgroup /sys/fs/cgroup/freezer ffff88012a30c380 ffff88012a379000 cgroup cgroup /sys/fs/cgroup/blkio ffff88012a30c540 ffff88012a379800 cgroup cgroup /sys/fs/cgroup/pids ffff88012a30c700 ffff88012a37a000 cgroup cgroup /sys/fs/cgroup/net_cls,net_prio ffff88012b266a80 ffff88012b2b1000 configfs configfs /sys/kernel/config ffff880138ccb180 ffff8800b51b3800 ext4 /dev/nbd0 / ffff880138ccb340 ffff8800b5222000 rpc_pipefs rpc_pipefs /var/lib/nfs/rpc_pipefs ffff88012b2668c0 ffff88012b2b2800 autofs systemd-1 /proc/sys/fs/binfmt_misc ffff88012a30c8c0 ffff8800b4a7c000 hugetlbfs hugetlbfs /dev/hugepages ffff88012a30ca80 ffff880139947800 debugfs debugfs /sys/kernel/debug ffff88012a30cc40 ffff8801372f7800 mqueue mqueue /dev/mqueue ffff88012b266e00 ffff88012b2b5800 binfmt_misc binfmt_misc /proc/sys/fs/binfmt_misc/ ffff88012a30ce00 ffff8800b49bc000 ramfs none /mnt ffff8800b48cb6c0 ffff8800b4b30800 squashfs /dev/vda /home/green/git/lustre-release ffff88012a30cfc0 ffff88012b3b4000 tmpfs none /var/lib/stateless/writable ffff8800b48cba40 ffff88012b3b4000 tmpfs none /var/cache/man ffff88012a30d180 ffff88012b3b4000 tmpfs none /var/log ffff88012b267180 ffff88012b3b4000 tmpfs none /var/lib/dbus ffff88012b267340 ffff88012b3b4000 tmpfs none /tmp ffff88012a30d340 ffff88012b3b4000 tmpfs none /var/lib/dhclient ffff880138ccb500 ffff88012b3b4000 tmpfs none /var/tmp ffff88012a30d500 ffff88012b3b4000 tmpfs none /var/lib/NetworkManager ffff8800b48cbc00 ffff88012b3b4000 tmpfs none /var/lib/systemd/random-seed ffff88012a30d6c0 ffff88012b3b4000 tmpfs none /var/spool ffff880138ccb6c0 ffff88012b3b4000 tmpfs none /var/lib/nfs ffff880138ccb880 ffff88012b3b4000 tmpfs none /var/lib/gssproxy ffff88012a30d880 ffff88012b3b4000 tmpfs none /var/lib/logrotate ffff88012a30da40 ffff88012b3b4000 tmpfs none /etc ffff8800b48cbdc0 ffff88012b3b4000 tmpfs none /var/lib/rsyslog ffff88012a30dc00 ffff88012b3b4000 tmpfs none /var/lib/dhclient/var/lib/dhclient ffff88012a30ddc0 ffff880129d87000 nfs4 192.168.200.253:/exports/state/oleg336-server.virtnet /var/lib/stateless/state ffff8800b48cb500 ffff880129d87000 nfs4 192.168.200.253:/exports/state/oleg336-server.virtnet /boot ffff8800b48cb340 ffff880129d87000 nfs4 192.168.200.253:/exports/state/oleg336-server.virtnet /etc/etc/kdump.conf ffff8800b48ca000 ffff8800b5222000 rpc_pipefs sunrpc /var/lib/nfs/var/lib/nfs/rpc_pipefs ffff8800b1e221c0 ffff8800b4b30800 squashfs /dev/vda /usr/sbin/mount.lustre ffff8800b1d11c00 ffff88013272f800 lustre /dev/mapper/mds1_flakey /mnt/lustre-mds1 ffff8800b1d10c40 ffff88009a1b6000 lustre /dev/mapper/mds2_flakey /mnt/lustre-mds2 ffff8800b1d10540 ffff88009ccb8000 lustre /dev/mapper/ost1_flakey /mnt/lustre-ost1 ffff8800b48d1880 ffff8800b4b31000 lustre /dev/mapper/mds1_flakey /mnt/lustre-mds1/ +-------------------------------+ >----------------------| Last 40 lines of dmesg buffer |----------------------< +-------------------------------+ [ 1142.183258] LDISKFS-fs (dm-0): mounted filesystem with ordered data mode. Opts: errors=remount-ro [ 1142.198476] LDISKFS-fs (dm-0): mounted filesystem with ordered data mode. Opts: user_xattr,errors=remount-ro,no_mbcache,nodelalloc [ 1142.894152] Lustre: DEBUG MARKER: oleg336-server.virtnet: executing set_default_debug -1 all [ 1146.357613] LDISKFS-fs (dm-1): mounted filesystem with ordered data mode. Opts: errors=remount-ro [ 1146.376459] LDISKFS-fs (dm-1): mounted filesystem with ordered data mode. Opts: user_xattr,acl,no_mbcache,nodelalloc [ 1146.443454] Lustre: Setting parameter lustre-MDT0001.mdt.identity_upcall in log lustre-MDT0001 [ 1146.448237] Lustre: 20470:0:(mgc_request_server.c:553:mgc_llog_local_copy()) MGC192.168.203.136@tcp: no remote llog for lustre-sptlrpc, check MGS config [ 1146.450870] Lustre: 20470:0:(mgc_request_server.c:597:mgc_process_server_cfg_log()) MGC192.168.203.136@tcp: local log lustre-sptlrpc are not valid and/or remote logs are not accessbile rc = -2 [ 1146.462962] Lustre: srv-lustre-MDT0001: No data found on store. Initialize space. [ 1146.499823] Lustre: lustre-MDT0001: new disk, initializing [ 1146.516894] Lustre: lustre-MDT0001: Imperative Recovery not enabled, recovery window 60-180 [ 1146.523251] Lustre: srv-lustre-MDT0001: Waiting to contact MDT0000 to allocate super-sequence: rc = -115 [ 1146.526541] Lustre: 20506:0:(lod_sub_object.c:966:lod_sub_prep_llog()) lustre-MDT0001-mdtlov: can't create new llog: rc = -115 [ 1146.529030] LustreError: 20506:0:(lod_dev.c:525:lod_sub_recovery_thread()) lustre-MDT0001-osd: get update log duration 0, retries 0, failed: rc = -115 [ 1147.277485] Lustre: DEBUG MARKER: oleg336-server.virtnet: executing set_default_debug -1 all [ 1151.709070] LDISKFS-fs (dm-2): file extents enabled, maximum tree depth=5 [ 1151.714325] LDISKFS-fs (dm-2): mounted filesystem with ordered data mode. Opts: errors=remount-ro [ 1151.734884] LDISKFS-fs (dm-2): file extents enabled, maximum tree depth=5 [ 1151.739172] LDISKFS-fs (dm-2): mounted filesystem with ordered data mode. Opts: user_xattr,acl,no_mbcache,nodelalloc [ 1151.823059] Lustre: lustre-OST0000: new disk, initializing [ 1151.825775] Lustre: srv-lustre-OST0000: No data found on store. Initialize space. [ 1151.846933] Lustre: lustre-OST0000: Imperative Recovery not enabled, recovery window 60-180 [ 1153.194688] Lustre: DEBUG MARKER: oleg336-server.virtnet: executing set_default_debug -1 all [ 1155.518266] Lustre: DEBUG MARKER: oleg336-client.virtnet: executing wait_import_state_mount (FULL|IDLE) osc.lustre-OST0000-osc-[-0-9a-f]*.ost_server_uuid [ 1157.529491] LustreError: 166-1: MGC192.168.203.136@tcp: Connection to MGS (at 0@lo) was lost; in progress operations using this service will fail [ 1157.533049] Lustre: MGS: Client cae8c432-51a8-4336-8170-e0904a5689bc (at 0@lo) reconnecting [ 1157.549977] Lustre: Modifying parameter lustre-MDT0000.mdt.identity_upcall in log lustre-MDT0000 [ 1157.555805] LustreError: 19640:0:(mgs_handler.c:264:mgs_revoke_lock()) MGS: can't take cfg lock for 0x65727473756c/0x0 : rc = -11 [ 1157.572071] Lustre: ctl-lustre-MDT0000: No data found on store. Initialize space. [ 1157.620254] Lustre: lustre-MDT0000: new disk, initializing [ 1157.658496] Lustre: ctl-lustre-MDT0000: super-sequence allocation rc = 0 [0x0000000200000400-0x0000000240000400]:0:mdt [ 1158.570388] Lustre: DEBUG MARKER: oleg336-server.virtnet: executing set_default_debug -1 all [ 1158.664058] Lustre: 22552:0:(client.c:1513:after_reply()) @@@ resending request on EINPROGRESS req@ffff88012b828e00 x1798524602697344/t0(0) o700->lustre-MDT0001-osp-MDT0000@0@lo:30/10 lens 264/248 e 0 to 0 dl 1715206822 ref 2 fl Rpc:RQU/202/0 rc 0/-115 job:'' uid:0 gid:0 [ 1159.071773] Lustre: ctl-lustre-MDT0000: super-sequence allocation rc = 0 [0x0000000240000400-0x0000000280000400]:0:ost [ 1159.077483] Lustre: cli-lustre-OST0000-super: Allocated super-sequence [0x0000000240000400-0x0000000280000400]:0:ost] [ 1160.678313] Lustre: ctl-lustre-MDT0000: super-sequence allocation rc = 0 [0x0000000280000400-0x00000002c0000400]:1:mdt [ 1160.680448] Lustre: cli-ctl-lustre-MDT0001: Allocated super-sequence [0x0000000280000400-0x00000002c0000400]:1:mdt] [ 1160.684521] Lustre: lustre-OST0000-osc-MDT0000: update sequence from 0x100000000 to 0x240000401 [ 1202.718847] Lustre: lustre-OST0000: haven't heard from client 43a4b9e3-ff81-4008-a880-3fdd8fa0460e (at 192.168.203.36@tcp) in 33 seconds. I think it's dead, and I am evicting it. exp ffff88012d5e1000, cur 1715206850 expire 1715206820 last 1715206817 [ 1204.144683] Lustre: lustre-MDT0000: haven't heard from client 43a4b9e3-ff81-4008-a880-3fdd8fa0460e (at 192.168.203.36@tcp) in 34 seconds. I think it's dead, and I am evicting it. exp ffff88013050e800, cur 1715206851 expire 1715206821 last 1715206817 ****************************************************************************** ************************ 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 11.92s (real) 6.25s (CPU), Child processes: 5.64s