On Fri, 2017-01-27 at 01:04 -0700, Jens Axboe wrote:
> The previous patch had a bug if you didn't use a scheduler, here's a
> version that should work fine in both cases. I've also updated the
> above mentioned branch, so feel free to pull that as well and merge to
> master like before.
Booting time is back to normal with commit f3a8ab7d55bc merged with
v4.10-rc5. That's a great improvement. However, running the srp-test
software triggers now a new complaint:
[ 215.600386] sd 11:0:0:0: [sdh] Attached SCSI disk
[ 215.609485] sd 11:0:0:0: alua: port group 00 state A non-preferred supports TOlUSNA
[ 215.722900] scsi 13:0:0:0: alua: Detached
[ 215.724452] general protection fault: 0000 [#1] SMP
[ 215.724484] Modules linked in: dm_service_time ib_srp scsi_transport_srp target_core_user uio target_core_pscsi target_core_file ib_srpt target_core_iblock target_core_mod brd netconsole xt_CHECKSUM iptable_mangle ipt_MASQUERADE nf_nat_masquerade_ipv4 iptable_nat nf_nat_ipv4 nf_nat libcrc32c nf_conntrack_ipv4 nf_defrag_ipv4 xt_conntrack nf_conntrack ipt_REJECT nf_reject_ipv4 xt_tcpudp tun bridge stp llc ebtable_filter ebtables ip6table_filter ip6_tables iptable_filter ip_tables x_tables af_packet ib_ipoib rdma_ucm ib_ucm ib_uverbs ib_umad rdma_cm msr configfs ib_cm iw_cm mlx4_ib ib_core sb_edac edac_core x86_pkg_temp_thermal intel_powerclamp ipmi_ssif coretemp kvm_intel hid_generic kvm usbhid irqbypass crct10dif_pclmul crc32_pclmul crc32c_intel mlx4_core ghash_clmulni_intel iTCO_wdt dcdbas pcbc tg3
[ 215.724629] iTCO_vendor_support ptp aesni_intel pps_core aes_x86_64 pcspkr crypto_simd libphy ipmi_si glue_helper cryptd ipmi_devintf tpm_tis devlink fjes ipmi_msghandler tpm_tis_core tpm mei_me lpc_ich mei mfd_core button shpchp wmi mgag200 i2c_algo_bit drm_kms_helper syscopyarea sysfillrect sysimgblt fb_sys_fops ttm drm sr_mod cdrom ehci_pci ehci_hcd usbcore usb_common sg dm_multipath dm_mod scsi_dh_rdac scsi_dh_emc scsi_dh_alua autofs4
[ 215.724719] CPU: 9 PID: 8043 Comm: multipathd Not tainted 4.10.0-rc5-dbg+ #1
[ 215.724748] Hardware name: Dell Inc. PowerEdge R430/03XKDV, BIOS 1.0.2 11/17/2014
[ 215.724775] task: ffff8801717998c0 task.stack: ffffc90002a9c000
[ 215.724804] RIP: 0010:scsi_device_put+0xb/0x30
[ 215.724829] RSP: 0018:ffffc90002a9faa0 EFLAGS: 00010246
[ 215.724855] RAX: 6b6b6b6b6b6b6b6b RBX: ffff88038bf85698 RCX: 0000000000000006
[ 215.724880] RDX: 0000000000000006 RSI: ffff88017179a108 RDI: ffff88038bf85698
[ 215.724906] RBP: ffffc90002a9faa8 R08: ffff880384786008 R09: 0000000100170007
[ 215.724932] R10: 0000000000000000 R11: 0000000000000000 R12: ffff88038bf85698
[ 215.724958] R13: ffff88038919f090 R14: dead000000000100 R15: ffff88038a41dd28
[ 215.724983] FS: 00007fbf8c6cf700(0000) GS:ffff88046f440000(0000) knlGS:0000000000000000
[ 215.725010] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 215.725035] CR2: 00007f1262ef3ee0 CR3: 000000044f6cc000 CR4: 00000000001406e0
[ 215.725060] Call Trace:
[ 215.725086] scsi_disk_put+0x2d/0x40
[ 215.725110] sd_release+0x3d/0xb0
[ 215.725137] __blkdev_put+0x29e/0x360
[ 215.725163] blkdev_put+0x49/0x170
[ 215.725192] dm_put_table_device+0x58/0xc0 [dm_mod]
[ 215.725219] dm_put_device+0x70/0xc0 [dm_mod]
[ 215.725269] free_priority_group+0x92/0xc0 [dm_multipath]
[ 215.725295] free_multipath+0x70/0xc0 [dm_multipath]
[ 215.725320] multipath_dtr+0x19/0x20 [dm_multipath]
[ 215.725348] dm_table_destroy+0x67/0x120 [dm_mod]
[ 215.725379] dev_suspend+0xde/0x240 [dm_mod]
[ 215.725434] ctl_ioctl+0x1f5/0x520 [dm_mod]
[ 215.725489] dm_ctl_ioctl+0xe/0x20 [dm_mod]
[ 215.725515] do_vfs_ioctl+0x8f/0x700
[ 215.725589] SyS_ioctl+0x3c/0x70
[ 215.725614] entry_SYSCALL_64_fastpath+0x18/0xad
[ 215.725641] RIP: 0033:0x7fbf8aca0667
[ 215.725665] RSP: 002b:00007fbf8c6cd668 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
[ 215.725692] RAX: ffffffffffffffda RBX: 0000000000000046 RCX: 00007fbf8aca0667
[ 215.725716] RDX: 00007fbf8006b940 RSI: 00000000c138fd06 RDI: 0000000000000007
[ 215.725743] RBP: 0000000000000009 R08: 00007fbf8c6cb3c0 R09: 00007fbf8b68d8d8
[ 215.725768] R10: 0000000000000075 R11: 0000000000000246 R12: 00007fbf8c6cd770
[ 215.725793] R13: 0000000000000013 R14: 00000000006168f0 R15: 0000000000f74780
[ 215.725820] Code: bc 24 b8 00 00 00 e8 55 c8 1c 00 48 83 c4 08 48 89 d8 5b 41 5c 41 5d 41 5e 41 5f 5d c3 0f 1f 00 55 48 89 e5 53 48 8b 07 48 89 fb <48> 8b 80 a8 01 00 00 48 8b 38 e8 f6 68 c5 ff 48 8d bb 38 02 00
[ 215.725903] RIP: scsi_device_put+0xb/0x30 RSP: ffffc90002a9faa0
(gdb) list *(scsi_device_put+0xb)
0xffffffff8149fc2b is in scsi_device_put (drivers/scsi/scsi.c:957).
952 * count of the underlying LLDD module. The device is freed once the last
953 * user vanishes.
954 */
955 void scsi_device_put(struct scsi_device *sdev)
956 {
957 module_put(sdev->host->hostt->module);
958 put_device(&sdev->sdev_gendev);
959 }
960 EXPORT_SYMBOL(scsi_device_put);
961
(gdb) disas scsi_device_put
Dump of assembler code for function scsi_device_put:
0xffffffff8149fc20 <+0>: push %rbp
0xffffffff8149fc21 <+1>: mov %rsp,%rbp
0xffffffff8149fc24 <+4>: push %rbx
0xffffffff8149fc25 <+5>: mov (%rdi),%rax
0xffffffff8149fc28 <+8>: mov %rdi,%rbx
0xffffffff8149fc2b <+11>: mov 0x1a8(%rax),%rax
0xffffffff8149fc32 <+18>: mov (%rax),%rdi
0xffffffff8149fc35 <+21>: callq 0xffffffff810f6530 <module_put>
0xffffffff8149fc3a <+26>: lea 0x238(%rbx),%rdi
0xffffffff8149fc41 <+33>: callq 0xffffffff814714b0 <put_device>
0xffffffff8149fc46 <+38>: pop %rbx
0xffffffff8149fc47 <+39>: pop %rbp
0xffffffff8149fc48 <+40>: retq
End of assembler dump.
(gdb) print &((struct Scsi_Host *)0)->hostt
$2 = (struct scsi_host_template **) 0x1a8 <irq_stack_union+424>
Apparently scsi_device_put() was called for a SCSI device that was already
freed (memory poisoning was enabled in my test). This is something I had
not yet seen before.
Bart.--
To unsubscribe from this list: send the line "unsubscribe linux-scsi" in
the body of a message to majordomo@xxxxxxxxxxxxxxx
More majordomo info at http://vger.kernel.org/majordomo-info.html
