************************ crashinfo ************************* /exports/testreports/50430/testresults/racer-zfs-centos7_x86_64-centos7_x86_64/oleg146-client-vmcore (3.10.0-7.9-debug) +==========================+ | *** Crashinfo v1.3.7 *** | +==========================+ +++WARNING+++ PARTIAL DUMP with size(vmcore) < 25% size(RAM) KERNEL: /tmp/crash-anaysis.WiPoW/vmlinux [TAINTED] DUMPFILE: /exports/testreports/50430/testresults/racer-zfs-centos7_x86_64-centos7_x86_64/oleg146-client-vmcore [PARTIAL DUMP] CPUS: 4 DATE: Tue Apr 1 13:16:14 EDT 2025 UPTIME: 00:02:48 LOAD AVERAGE: 54.42, 13.96, 4.70 TASKS: 394 NODENAME: oleg146-client.virtnet RELEASE: 3.10.0-7.9-debug VERSION: #1 SMP Sat Mar 26 23:28:42 EDT 2022 MACHINE: x86_64 (2400 Mhz) MEMORY: 4 GB PANIC: "BUG: unable to handle kernel NULL pointer dereference at 000000000000012e" +--------------------------+ >------------------------| Per-cpu Stacks ('bt -a') |------------------------< +--------------------------+ -- CPU#0 -- PID=22446 CPU=0 CMD=fallocate #0 crash_nmi_callback+0x31 #1 nmi_handle+0x97 #2 do_nmi+0x12c #3 end_repeat_nmi+0x1e #-1 __pv_queued_spin_lock_slowpath+0x102, 507 bytes of data #4 __pv_queued_spin_lock_slowpath+0x102 #5 do_raw_spin_lock+0x6d #6 _raw_spin_lock+0x1e #7 try_to_wake_up+0x144 #8 default_wake_function+0x12 #9 autoremove_wake_function+0x18 #10 __wake_up_common+0x70 #11 __wake_up_common_lock+0x83 #12 __wake_up+0x13 #13 ksocknal_read_callback+0x67 #14 ksocknal_data_ready+0x44 #15 tcp_rcv_established+0x444 #16 tcp_v4_do_rcv+0x10a #17 tcp_v4_rcv+0x7ff #18 ip_local_deliver_finish+0xc8 #19 ip_local_deliver+0x60 #20 ip_rcv_finish+0x8a #21 ip_rcv+0x2bd #22 __netif_receive_skb_core+0x714 #23 __netif_receive_skb+0x18 #24 netif_receive_skb_internal+0x50 #25 napi_gro_receive+0xf8 #26 virtnet_poll+0x265 #27 net_rx_action+0x28b #28 __do_softirq+0xf7 #29 call_softirq+0x1c #30 do_softirq+0x65 #31 irq_exit+0x105 #32 smp_apic_timer_interrupt+0x42 #33 apic_timer_interrupt+0x16a, 19 bytes of data #34 apic_timer_interrupt+0x16a #-1 _raw_spin_unlock_irq+0x15, 477 bytes of data #35 __set_current_blocked+0x55 #36 sigprocmask+0x77 #37 ptlrpc_set_wait+0x26d #38 ptlrpc_queue_wait+0x86 #39 ldlm_cli_enqueue+0x424 #40 osc_enqueue_base+0x2c5 #41 osc_lock_enqueue+0x381 #42 cl_lock_enqueue+0x62 #43 lov_lock_enqueue+0x99 #44 cl_lock_enqueue+0x62 #45 cl_lock_request+0x67 #46 cl_io_lock+0xf6 #47 cl_io_loop+0x8d #48 ll_fallocate+0x575 #49 vfs_fallocate+0x13c #50 sys_fallocate+0x5b #51 system_call_fastpath+0x1f, 477 bytes of data -- CPU#1 -- PID=22346 CPU=1 CMD=lfs #0 machine_kexec+0x19e #1 __crash_kexec+0x72 #2 crash_kexec+0x30 #3 oops_end+0xa8 #4 no_context+0x144 #5 __bad_area_nosemaphore+0x112 #6 bad_area+0x49 #7 __do_page_fault+0x494 #8 trace_do_page_fault+0x56 #9 do_async_page_fault+0x22 #10 async_page_fault+0x28 #-1 osc_io_submit+0x142, 477 bytes of data #11 cl_io_submit_rw+0x72 #12 lov_io_submit+0x1b4 #13 cl_io_submit_rw+0x72 #14 ll_direct_IO_impl+0x530 #15 ll_direct_IO+0x7c #16 generic_file_aio_read+0x24e #17 vvp_io_read_start+0x779 #18 cl_io_start+0x6d #19 cl_io_loop+0x9f #20 ll_file_io_generic+0x36b #21 do_file_read_iter+0xc46 #22 ll_file_read_iter+0x2d #23 ll_file_aio_read+0x1b1 #24 ll_file_read+0x111 #25 vfs_read+0xb9 #26 sys_pread64+0x92 #27 system_call_fastpath+0x1f, 477 bytes of data -- CPU#2 -- PID=22560 CPU=2 CMD=truncate #0 crash_nmi_callback+0x31 #1 nmi_handle+0x97 #2 do_nmi+0x12c #3 end_repeat_nmi+0x1e #-1 queued_write_lock_slowpath+0x42, 507 bytes of data #4 queued_write_lock_slowpath+0x42 #5 do_raw_qwrite_lock+0x43 #6 _raw_qwrite_lock_irq+0x25 #7 tasklist_write_lock_irq+0x15 #8 do_exit+0x384 #9 do_group_exit+0x3f #10 sys_exit_group+0x14 #11 system_call_fastpath+0x1f, 477 bytes of data -- CPU#3 -- PID=22444 CPU=3 CMD=mrename #0 crash_nmi_callback+0x31 #1 nmi_handle+0x97 #2 do_nmi+0x12c #3 end_repeat_nmi+0x1e #-1 enqueue_entity+0x2ef, 507 bytes of data #4 enqueue_entity+0x2ef #5 enqueue_task_fair+0x59 #6 activate_task+0x46 #7 ttwu_do_activate+0x44 #8 try_to_wake_up+0x152 #9 default_wake_function+0x12 #10 child_wait_callback+0x5a #11 __wake_up_common+0x70 #12 __wake_up_common_lock+0x83 #13 __wake_up_sync_key+0x1d #14 __wake_up_parent+0x26 #15 do_notify_parent+0x1d9 #16 do_exit+0x77f #17 do_group_exit+0x3f #18 sys_exit_group+0x14 #19 system_call_fastpath+0x1f, 477 bytes of data +--------------------------------+ >---------------------| How This Dump Has Been Created |---------------------< +--------------------------------+ *** Dump was triggered by kernel *** BUG: 73 of 73 active objects replaced +++WARNING+++ High Load Averages: 54.42, 13.96, 4.70 +---------------+ >------------------------------| Tasks Summary |------------------------------< +---------------+ Number of Threads That Ran Recently ----------------------------------- last second 246 last 5s 253 last 60s 310 ----- Total Numbers of Threads per State ------ TASK_INTERRUPTIBLE 294 TASK_PARKED 1 TASK_RUNNING 13 TASK_UNINTERRUPTIBLE 81 TASK_WAKEKILL 2 +++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 ----- -------------- ------ ---------------------------- 22444 mrename 0 ms (no user stack) 4819 ldlm_cb01_000 0 ms (no user stack) 22446 fallocate 0 ms (no user stack) 9045 dir_create.sh 0 ms (no user stack) 9256 ldlm_cb01_002 0 ms (no user stack) +------------------------+ >-------------------------| Memory Usage (kmem -i) |-------------------------< +------------------------+ PAGES TOTAL PERCENTAGE TOTAL MEM 955079 3.6 GB ---- FREE 825676 3.1 GB 86% of TOTAL MEM USED 129403 505.5 MB 13% of TOTAL MEM SHARED 14261 55.7 MB 1% of TOTAL MEM BUFFERS 5163 20.2 MB 0% of TOTAL MEM CACHED 49711 194.2 MB 5% of TOTAL MEM SLAB 14087 55 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 739682 2.8 GB ---- COMMITTED 108734 424.7 MB 14% of TOTAL LIMIT +-------------------------------+ >----------------------| Scheduler Runqueues (per CPU) |----------------------< +-------------------------------+ ---+ CPU=0 ---- | CURRENT TASK , CMD=fallocate 1971 ptlrpcd_00_00 1.18479 1972 ptlrpcd_00_01 1.13845 22558 mkdir 0.00453 ---+ CPU=1 ---- | CURRENT TASK , CMD=lfs 22463 ln 0.00169 8952 file_link.sh 0.06844 22450 getfattr 0.00172 ---+ CPU=2 ---- | CURRENT TASK , CMD=truncate ---+ CPU=3 ---- | CURRENT TASK , CMD=mrename 8871 file_rename.sh 0.17585 9 rcu_sched 4.16574 22559 mkdir 0.00376 +------------------------+ >-------------------------| Network Status Summary |-------------------------< +------------------------+ TCP Connection Info ------------------- ESTABLISHED 6 TIME_WAIT 40 LISTEN 3 NAGLE disabled (TCP_NODELAY): 5 user_data set (NFS etc.): 4 UDP Connection Info ------------------- 2 UDP sockets, 0 in ESTABLISHED Unix Connection Info ------------------------ ESTABLISHED 26 CLOSE 18 LISTEN 8 Raw sockets info -------------------- ESTABLISHED 1 Interfaces Info --------------- How long ago (in seconds) interfaces transmitted/received? Name RX TX ---- ---------- --------- lo n/a 164.9 eth0 n/a 0.0 RSS_TOTAL=359920 pages, %mem= 2.1 +------------+ >-------------------------------| Mounted FS |-------------------------------< +------------+ MOUNT SUPERBLK TYPE DEVNAME DIRNAME ffff880138cca000 ffff880139940800 rootfs rootfs / ffff88012b29a700 ffff88012aa2c800 sysfs sysfs /sys ffff88012b29a8c0 ffff880139944000 proc proc /proc ffff88012b29aa80 ffff880137678000 devtmpfs devtmpfs /dev ffff88012b29ac40 ffff8800b6cd1000 securityfs securityfs /sys/kernel/security ffff88012b29ae00 ffff88012aa2d000 tmpfs tmpfs /dev/shm ffff88012b29afc0 ffff880137001000 devpts devpts /dev/pts ffff88012b29b180 ffff88012aa2d800 tmpfs tmpfs /run ffff88012b29b340 ffff88012aa2e000 tmpfs tmpfs /sys/fs/cgroup ffff88012b29b500 ffff88012aa2e800 cgroup cgroup /sys/fs/cgroup/systemd ffff88012b29b6c0 ffff88012aa2f000 pstore pstore /sys/fs/pstore ffff88013702c1c0 ffff88012aab8800 cgroup cgroup /sys/fs/cgroup/devices ffff88013702c380 ffff88012aab8000 cgroup cgroup /sys/fs/cgroup/perf_event ffff88013702c540 ffff88012aab9000 cgroup cgroup /sys/fs/cgroup/memory ffff88013702c700 ffff88012aab9800 cgroup cgroup /sys/fs/cgroup/pids ffff88013702c8c0 ffff88012aaba000 cgroup cgroup /sys/fs/cgroup/cpu,cpuacct ffff88013702ca80 ffff88012aaba800 cgroup cgroup /sys/fs/cgroup/hugetlb ffff88013702cc40 ffff88012aabb000 cgroup cgroup /sys/fs/cgroup/net_cls,net_prio ffff88013702ce00 ffff88012aabb800 cgroup cgroup /sys/fs/cgroup/blkio ffff88013702cfc0 ffff88012aabc000 cgroup cgroup /sys/fs/cgroup/cpuset ffff88013702d180 ffff88012aabc800 cgroup cgroup /sys/fs/cgroup/freezer ffff88013702d340 ffff88012aabf000 configfs configfs /sys/kernel/config ffff88012b29bdc0 ffff88012ab0b000 ext4 /dev/nbd0 / ffff8800b6e02000 ffff8800b6d0f000 rpc_pipefs rpc_pipefs /var/lib/nfs/rpc_pipefs ffff880137668700 ffff88012a579800 autofs systemd-1 /proc/sys/fs/binfmt_misc ffff8800b6e021c0 ffff880137311800 mqueue mqueue /dev/mqueue ffff8801376688c0 ffff880139947800 debugfs debugfs /sys/kernel/debug ffff8800b6e02380 ffff8800b63e3000 hugetlbfs hugetlbfs /dev/hugepages ffff88013702da40 ffff8800b5d20800 binfmt_misc binfmt_misc /proc/sys/fs/binfmt_misc/ ffff88013702dc00 ffff8800b5d27800 ramfs none /mnt ffff880137668c40 ffff88012a579000 tmpfs none /var/lib/stateless/writable ffff8800b6e02540 ffff8800b63e6000 squashfs /dev/vda /home/green/git/lustre-release ffff880137668e00 ffff88012a579000 tmpfs none /var/cache/man ffff8800b6e9a540 ffff88012a579000 tmpfs none /var/log ffff88013702ddc0 ffff88012a579000 tmpfs none /var/lib/dbus ffff88013702d880 ffff88012a579000 tmpfs none /tmp ffff88013702d6c0 ffff88012a579000 tmpfs none /var/lib/dhclient ffff8800b6e02700 ffff88012a579000 tmpfs none /var/tmp ffff88013702d500 ffff88012a579000 tmpfs none /var/lib/NetworkManager ffff880137668fc0 ffff88012a579000 tmpfs none /var/lib/systemd/random-seed ffff8800b6e9a700 ffff88012a579000 tmpfs none /var/spool ffff880137669180 ffff88012a579000 tmpfs none /var/lib/nfs ffff880137669340 ffff88012a579000 tmpfs none /var/lib/gssproxy ffff8800b6e028c0 ffff88012a579000 tmpfs none /var/lib/logrotate ffff880137669500 ffff88012a579000 tmpfs none /etc ffff8800b6e9a8c0 ffff88012a579000 tmpfs none /var/lib/rsyslog ffff8801376696c0 ffff88012a579000 tmpfs none /var/lib/dhclient/var/lib/dhclient ffff8800b6e02a80 ffff8800b6d0e000 nfs4 192.168.200.253:/exports/state/oleg146-client.virtnet /var/lib/stateless/state ffff8800b6de6380 ffff8800b6d0e000 nfs4 192.168.200.253:/exports/state/oleg146-client.virtnet /boot ffff8800b6e02e00 ffff8800b6d0e000 nfs4 192.168.200.253:/exports/state/oleg146-client.virtnet /etc/etc/kdump.conf ffff880137669880 ffff8800b6d0f000 rpc_pipefs sunrpc /var/lib/nfs/var/lib/nfs/rpc_pipefs ffff8800b3f63dc0 ffff8800ad82b000 nfs4 192.168.200.253://exports/testreports/50430/testresults/racer-zfs-centos7_x86_64-centos7_x86_64 /tmp/tmp/testlogs ffff88012e14e1c0 ffff8800ad82a000 tmpfs tmpfs /run/user/0 ffff8800b0a401c0 ffff8800b63e6000 squashfs /dev/vda /usr/sbin/mount.lustre ffff8800b6e9a000 ffff8800b094d800 lustre 192.168.201.146@tcp:/lustre /mnt/lustre ffff8800b6e9b6c0 ffff88012b747800 lustre 192.168.201.146@tcp:/lustre /mnt/lustre2 +-------------------------------+ >----------------------| Last 40 lines of dmesg buffer |----------------------< +-------------------------------+ [ 168.654206] CPU: 1 PID: 22346 Comm: lfs Kdump: loaded Tainted: G OE ------------ 3.10.0-7.9-debug #1 [ 168.659006] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.16.3-2.fc40 04/01/2014 [ 168.664953] task: ffff8800a7e23330 ti: ffff8800a3d14000 task.ti: ffff8800a3d14000 [ 168.673843] RIP: 0010:[] [] osc_io_submit+0x142/0x720 [osc] [ 168.681186] RSP: 0018:ffff8800a3d176c8 EFLAGS: 00010246 [ 168.684433] RAX: 0000000000000000 RBX: 0000000000000081 RCX: ffff8800a6bbafd0 [ 168.688515] RDX: 0000000000020081 RSI: ffff88012b51b760 RDI: ffffea0002841180 [ 168.693190] RBP: ffff8800a3d17768 R08: ffff880136439738 R09: ffff8800a4e75bf0 [ 168.697934] R10: 0000000000000000 R11: 0000000000000000 R12: ffff880136439738 [ 168.701447] R13: ffff8800a863c340 R14: ffff88012f6b4388 R15: 0000000000000000 [ 168.706523] FS: 00007f6b6989d740(0000) GS:ffff88013e280000(0000) knlGS:0000000000000000 [ 168.710159] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 [ 168.714243] CR2: 000000000000012e CR3: 00000000a3f9e000 CR4: 0000000000160fe0 [ 168.717933] Call Trace: [ 168.718916] [] cl_io_submit_rw+0x72/0x190 [obdclass] [ 168.723655] [] lov_io_submit+0x1b4/0x3a0 [lov] [ 168.729035] [] cl_io_submit_rw+0x72/0x190 [obdclass] [ 168.732215] [] ll_direct_IO_impl+0x530/0xef0 [lustre] [ 168.735667] [] ll_direct_IO+0x7c/0xa0 [lustre] [ 168.738074] [] generic_file_aio_read+0x24e/0x290 [ 168.741052] [] ? vvp_prep_size+0x20c/0x2f0 [lustre] [ 168.746084] [] vvp_io_read_start+0x779/0xa60 [lustre] [ 168.749008] [] ? cl_lock_request+0x67/0x210 [obdclass] [ 168.752956] [] cl_io_start+0x6d/0x150 [obdclass] [ 168.757499] [] cl_io_loop+0x9f/0x230 [obdclass] [ 168.760370] [] ll_file_io_generic+0x36b/0x1000 [lustre] [ 168.767289] [] ? do_raw_spin_unlock+0x49/0x90 [ 168.770273] [] do_file_read_iter+0xc46/0x1080 [lustre] [ 168.774850] [] ? handle_pte_fault+0x2ef/0xca0 [ 168.777825] [] ? _raw_spin_unlock+0xe/0x20 [ 168.780245] [] ll_file_read_iter+0x2d/0x40 [lustre] [ 168.782760] [] ll_file_aio_read+0x1b1/0x210 [lustre] [ 168.785700] [] ll_file_read+0x111/0x1e0 [lustre] [ 168.790959] [] vfs_read+0xb9/0x1c0 [ 168.795080] [] SyS_pread64+0x92/0xc0 [ 168.800493] [] system_call_fastpath+0x1f/0x24 [ 168.804332] Code: da 80 ca 80 41 0f b6 46 12 49 8b 7e f0 83 e0 c0 3c 80 0f 44 da e8 6f 88 89 ff 89 da 81 ca 00 00 02 00 84 c0 49 8b 46 18 0f 45 da 80 2e 01 00 00 02 0f 85 e1 01 00 00 49 8b 44 24 08 49 8d 4c [ 168.821573] RIP [] osc_io_submit+0x142/0x720 [osc] [ 168.825359] RSP [ 168.832581] CR2: 000000000000012e ****************************************************************************** ************************ A Summary Of Problems Found ************************* ****************************************************************************** -------------------- A list of all +++WARNING+++ messages -------------------- PARTIAL DUMP with size(vmcore) < 25% size(RAM) High Load Averages: 54.42, 13.96, 4.70 There are 3 threads running in their own namespaces Use 'taskinfo --ns' to get more details ------------------------------------------------------------------------------ ** Execution took 11.84s (real) 6.26s (CPU), Child processes: 5.52s