2022-02-14 20:35:26

by kernel test robot

[permalink] [raw]
Subject: [scsi] fa9e41fe49: WARNING:at_drivers/scsi/scsi_lib.c:#scsi_end_request



Greeting,

FYI, we noticed the following commit (built with gcc-9):

commit: fa9e41fe49debaac13b42be5883c2c4f02cc9719 ("scsi: don't memset the entire scsi_cmnd in scsi_init_command")
git://git.infradead.org/users/hch/misc.git remove-scsi_request

in testcase: xfstests
version: xfstests-x86_64-972d710-1_20220127
with following parameters:

disk: 4HDD
fs: ext4
fs2: smbv2
test: generic-group-18
ucode: 0xe2

test-description: xfstests is a regression test suite for xfs and other files ystems.
test-url: git://git.kernel.org/pub/scm/fs/xfs/xfstests-dev.git


on test machine: 8 threads Intel(R) Core(TM) i7-6700 CPU @ 3.40GHz with 16G memory

caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):



If you fix the issue, kindly add following tag
Reported-by: kernel test robot <[email protected]>


[ 31.823618][ C3] WARNING: CPU: 3 PID: 0 at drivers/scsi/scsi_lib.c:551 scsi_end_request (drivers/scsi/scsi_lib.c:551 (discriminator 1))
[ 31.841692][ C3] Modules linked in: sd_mod t10_pi sg ipmi_devintf ipmi_msghandler intel_rapl_msr intel_rapl_common x86_pkg_temp_thermal intel_powerclamp coretemp i915 kvm_intel kvm hp_wmi irqbypass intel_gtt sparse_keymap crct10dif_pclmul crc32_pclmul ttm platform_profile crc32c_intel ghash_clmulni_intel wmi_bmof rfkill drm_kms_helper mei_wdt syscopyarea rapl sysfillrect ahci sysimgblt intel_cstate fb_sys_fops libahci drm intel_uncore mei_me libata serio_raw mei intel_pch_thermal wmi video intel_pmc_core acpi_pad tpm_infineon ip_tables
[ 31.889405][ C3] CPU: 3 PID: 0 Comm: swapper/3 Not tainted 5.17.0-rc1-00056-gfa9e41fe49de #1
[ 31.898094][ C3] Hardware name: HP HP Z240 SFF Workstation/802E, BIOS N51 Ver. 01.63 10/05/2017
[ 31.907051][ C3] RIP: 0010:scsi_end_request (drivers/scsi/scsi_lib.c:551 (discriminator 1))
[ 31.912542][ C3] Code: 48 8b 5b 18 48 8d 7b 08 48 89 fa 48 c1 ea 03 80 3c 02 00 0f 85 aa 00 00 00 48 8b 43 08 4c 89 ff e8 43 d0 16 01 e9 bb fe ff ff <0f> 0b e9 f3 fd ff ff 89 54 24 0c 89 34 24 e8 2b de 21 ff 8b 54 24
All code
========
0: 48 8b 5b 18 mov 0x18(%rbx),%rbx
4: 48 8d 7b 08 lea 0x8(%rbx),%rdi
8: 48 89 fa mov %rdi,%rdx
b: 48 c1 ea 03 shr $0x3,%rdx
f: 80 3c 02 00 cmpb $0x0,(%rdx,%rax,1)
13: 0f 85 aa 00 00 00 jne 0xc3
19: 48 8b 43 08 mov 0x8(%rbx),%rax
1d: 4c 89 ff mov %r15,%rdi
20: e8 43 d0 16 01 callq 0x116d068
25: e9 bb fe ff ff jmpq 0xfffffffffffffee5
2a:* 0f 0b ud2 <-- trapping instruction
2c: e9 f3 fd ff ff jmpq 0xfffffffffffffe24
31: 89 54 24 0c mov %edx,0xc(%rsp)
35: 89 34 24 mov %esi,(%rsp)
38: e8 2b de 21 ff callq 0xffffffffff21de68
3d: 8b .byte 0x8b
3e: 54 push %rsp
3f: 24 .byte 0x24

Code starting with the faulting instruction
===========================================
0: 0f 0b ud2
2: e9 f3 fd ff ff jmpq 0xfffffffffffffdfa
7: 89 54 24 0c mov %edx,0xc(%rsp)
b: 89 34 24 mov %esi,(%rsp)
e: e8 2b de 21 ff callq 0xffffffffff21de3e
13: 8b .byte 0x8b
14: 54 push %rsp
15: 24 .byte 0x24
[ 31.931950][ C3] RSP: 0018:ffffc900002d8e50 EFLAGS: 00010246
[ 31.937878][ C3] RAX: 0000000000000005 RBX: ffff888198800000 RCX: ffffffff8269529b
[ 31.945702][ C3] RDX: 0000000000000000 RSI: 0000000000000008 RDI: ffff88842cd32c18
[ 31.953529][ C3] RBP: ffff88842cd32c00 R08: 0000000000000001 R09: ffffed103310000d
[ 31.961359][ C3] R10: ffff888198800067 R11: ffffed103310000c R12: 0000000000000000
[ 31.969185][ C3] R13: 0000000000000000 R14: ffff8881b79a3000 R15: ffff88842cd32e6c
[ 31.971944][ C7] random: crng init done
[ 31.977004][ C3] FS: 0000000000000000(0000) GS:ffff88837f780000(0000) knlGS:0000000000000000
[ 31.981103][ C7] random: 1 urandom warning(s) missed due to ratelimiting
[ 31.989871][ C3] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 31.989880][ C3] CR2: 00007f6c38035918 CR3: 000000043d814006 CR4: 00000000003706e0
[ 32.011072][ C3] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[ 32.018893][ C3] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[ 32.026711][ C3] Call Trace:
[ 32.029855][ C3] <IRQ>
1;39mOpenBSD Sec[ 32.046101][ C3] ? scsi_unblock_requests (drivers/scsi/scsi_lib.c:951)
ure Shell server[ 32.052612][ C3] ? scsi_device_unbusy (arch/x86/include/asm/bitops.h:60 include/asm-generic/bitops/instrumented-atomic.h:29 include/linux/sbitmap.h:324 include/linux/sbitmap.h:333 drivers/scsi/scsi_lib.c:303)
[ 32.059035][ C3] blk_complete_reqs (block/blk-mq.c:998 (discriminator 3))
[ 32.064329][ C3] __do_softirq (arch/x86/include/asm/jump_label.h:27 include/linux/jump_label.h:212 include/trace/events/irq.h:142 kernel/softirq.c:559)
[ 32.068693][ C3] irq_exit_rcu (kernel/softirq.c:432 kernel/softirq.c:637 kernel/softirq.c:649)
[ 32.073052][ C3] common_interrupt (arch/x86/kernel/irq.c:240 (discriminator 14))
[ 32.077589][ C3] </IRQ>
[ 32.080388][ C3] <TASK>
[ 32.083188][ C3] asm_common_interrupt (arch/x86/include/asm/idtentry.h:629)
[ 32.088062][ C3] RIP: 0010:cpuidle_enter_state (drivers/cpuidle/cpuidle.c:259)
[ 32.093815][ C3] Code: 89 c6 0f 1f 44 00 00 31 ff e8 7d de 5f fe 80 3c 24 00 74 12 9c 58 f6 c4 02 0f 85 27 07 00 00 31 ff e8 e5 ee 74 fe fb 45 85 ed <0f> 88 97 03 00 00 49 63 ed 48 83 fd 09 0f 87 cf 08 00 00 48 8d 44
All code
========
0: 89 c6 mov %eax,%esi
2: 0f 1f 44 00 00 nopl 0x0(%rax,%rax,1)
7: 31 ff xor %edi,%edi
9: e8 7d de 5f fe callq 0xfffffffffe5fde8b
e: 80 3c 24 00 cmpb $0x0,(%rsp)
12: 74 12 je 0x26
14: 9c pushfq
15: 58 pop %rax
16: f6 c4 02 test $0x2,%ah
19: 0f 85 27 07 00 00 jne 0x746
1f: 31 ff xor %edi,%edi
21: e8 e5 ee 74 fe callq 0xfffffffffe74ef0b
26: fb sti
27: 45 85 ed test %r13d,%r13d
2a:* 0f 88 97 03 00 00 js 0x3c7 <-- trapping instruction
30: 49 63 ed movslq %r13d,%rbp
33: 48 83 fd 09 cmp $0x9,%rbp
37: 0f 87 cf 08 00 00 ja 0x90c
3d: 48 rex.W
3e: 8d .byte 0x8d
3f: 44 rex.R

Code starting with the faulting instruction
===========================================
0: 0f 88 97 03 00 00 js 0x39d
6: 49 63 ed movslq %r13d,%rbp
9: 48 83 fd 09 cmp $0x9,%rbp
d: 0f 87 cf 08 00 00 ja 0x8e2
13: 48 rex.W
14: 8d .byte 0x8d
15: 44 rex.R
[ 32.113219][ C3] RSP: 0018:ffffc9000017fd80 EFLAGS: 00000206
[ 32.119141][ C3] RAX: dffffc0000000000 RBX: ffff88837f7c2140 RCX: 000000000000001f
[ 32.126962][ C3] RDX: 1ffff1106fef7119 RSI: 00000000258f06bb RDI: ffff88837f7b88c8
[ 32.134786][ C3] RBP: 0000000000000006 R08: 0000a3bc5d9ee910 R09: ffffed106fef716e
[ 32.142614][ C3] R10: ffff88837f7b8b6b R11: ffffed106fef716d R12: ffffffff84d3fbe0
[ 32.150436][ C3] R13: 0000000000000006 R14: 0000000768d5146b R15: ffffffff84d3fe68
[ 32.158271][ C3] ? tick_nohz_stop_tick (kernel/time/tick-sched.c:872)
[ 32.163340][ C3] cpuidle_enter (drivers/cpuidle/cpuidle.c:353)
[ 32.167622][ C3] do_idle (kernel/sched/idle.c:158 kernel/sched/idle.c:239 kernel/sched/idle.c:306)
[ 32.171551][ C3] ? arch_cpu_idle_exit+0xc0/0xc0
[ 32.176439][ C3] cpu_startup_entry (kernel/sched/idle.c:402 (discriminator 1))
[ 32.181063][ C3] start_secondary (arch/x86/kernel/smpboot.c:224)
[ 32.185692][ C3] ? set_cpu_sibling_map (arch/x86/kernel/smpboot.c:224)
[ 32.191009][ C3] secondary_startup_64_no_verify (arch/x86/kernel/head_64.S:300)
[ 32.196766][ C3] </TASK>
[ 32.199658][ C3] ---[ end trace 0000000000000000 ]---
[ 32.205054][ T57] sdb: sdb1 sdb2 sdb3 sdb4
[ 32.223121][ T57] sd 0:0:0:0: [sdb] Attached SCSI disk
[ 32.226513][ T58] sd 3:0:0:0: [sda] Attached SCSI disk
[ 35.224042][ T367] Kernel tests: Boot OK!
[ 35.224051][ T367]
[ 39.293304][ T367] install debs round one: dpkg -i --force-confdef --force-depends /opt/deb/ntpdate_1%3a4.2.8p12+dfsg-4_amd64.deb
[ 39.293314][ T367]
[ 39.308123][ T367] /opt/deb/libssl1.1_1.1.1d-0+deb10u7_amd64.deb
[ 39.308129][ T367]
[ 39.317245][ T367] /opt/deb/uuid-runtime_2.33.1-0.1_amd64.deb
[ 39.317250][ T367]
[ 39.326001][ T367] /opt/deb/attr_1%3a2.4.48-4_amd64.deb
[ 39.326007][ T367]
[ 39.334211][ T367] /opt/deb/libdpkg-perl_1.19.7_all.deb
[ 39.334216][ T367]
[ 39.342503][ T367] /opt/deb/patch_2.7.6-3+deb10u1_amd64.deb
[ 39.342508][ T367]
[ 39.351081][ T367] /opt/deb/libfakeroot_1.23-1_amd64.deb
[ 39.351086][ T367]
[ 39.359343][ T367] /opt/deb/fakeroot_1.23-1_amd64.deb
[ 39.359349][ T367]
[ 39.367499][ T367] /opt/deb/libfile-which-perl_1.23-1_all.deb
[ 39.367505][ T367]
[ 39.376409][ T367] /opt/deb/libfile-homedir-perl_1.004-1_all.deb
[ 39.376415][ T367]
[ 39.385665][ T367] /opt/deb/libio-pty-perl_1%3a1.08-1.1+b5_amd64.deb
[ 39.385671][ T367]
[ 39.395188][ T367] /opt/deb/libipc-run-perl_20180523.0-1_all.deb
[ 39.395193][ T367]
[ 39.404545][ T367] /opt/deb/libclass-method-modifiers-perl_2.12-1_all.deb
[ 39.404551][ T367]
[ 39.414799][ T367] /opt/deb/libsub-exporter-progressive-perl_0.001013-1_all.deb
[ 39.414805][ T367]
[ 39.425465][ T367] /opt/deb/libdevel-globaldestruction-perl_0.14-1_all.deb
[ 39.425471][ T367]
[ 39.435651][ T367] /opt/deb/libb-hooks-op-check-perl_0.22-1+b1_amd64.deb
[ 39.435657][ T367]
[ 39.445672][ T367] /opt/deb/libdynaloader-functions-perl_0.003-1_all.deb
[ 39.445678][ T367]


To reproduce:

git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
sudo bin/lkp install job.yaml # job file is attached in this email
bin/lkp split-job --compatible job.yaml # generate the yaml file for lkp run
sudo bin/lkp run generated-yaml-file

# if come across any failure that blocks the test,
# please remove ~/.lkp and /lkp dir to run from a clean state.



---
0DAY/LKP+ Test Infrastructure Open Source Technology Center
https://lists.01.org/hyperkitty/list/[email protected] Intel Corporation

Thanks,
Oliver Sang


Attachments:
(No filename) (10.64 kB)
config-5.17.0-rc1-00056-gfa9e41fe49de (181.64 kB)
job-script (5.73 kB)
dmesg.xz (25.93 kB)
xfstests (3.20 kB)
job.yaml (4.78 kB)
reproduce (1.54 kB)
Download all attachments