Am 21.04.2017 um 16:48 schrieb Alan Stern:
On Thu, 20 Apr 2017, Andreas Hartmann wrote:
Apr 19 19:25:39 notebook2 kernel: scsi host6: scsi scan: INQUIRY result too
short (5), using 36
But that is wrong! Even though it's for a working test. It means the
usb_stor_set_xfer_buf() subroutine isn't doing what it should.
Please apply the following debugging patch (on top of the previous one)
and let's see what it prints in the kernel log.
These are the log entries. I'm not sure if they contain what you expected:
No, they don't. But they do indicate where the problem lies.
ok 1
Apr 20 22:24:53 notebook2 kernel: usb 1-1.1: new high-speed USB device number 3
using ehci-pci
Apr 20 22:24:53 notebook2 kernel: usb 1-1.1: New USB device found,
idVendor=0cf2, idProduct=6250
Apr 20 22:24:53 notebook2 kernel: usb 1-1.1: New USB device strings: Mfr=1,
Product=2, SerialNumber=4
Apr 20 22:24:53 notebook2 kernel: usb 1-1.1: Product: UB6250
Apr 20 22:24:53 notebook2 kernel: usb 1-1.1: Manufacturer: ENE Flash
Apr 20 22:24:53 notebook2 kernel: usb 1-1.1: SerialNumber: 606569746801
Apr 20 22:24:53 notebook2 mtp-probe[2246]: checking bus 1, device 3:
"/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.1"
Apr 20 22:24:53 notebook2 mtp-probe[2246]: bus: 1, device: 3 was not an MTP
device
Apr 20 22:24:54 notebook2 kernel: usbcore: registered new interface driver
usb-storage
Apr 20 22:24:54 notebook2 kernel: usbcore: registered new interface driver uas
Apr 20 22:24:54 notebook2 kernel: ums_eneub6250 1-1.1:1.0: USB Mass Storage
device detected
Apr 20 22:24:54 notebook2 kernel: scsi host6: usb-storage 1-1.1:1.0
Apr 20 22:24:54 notebook2 kernel: usbcore: registered new interface driver
ums_eneub6250
Apr 20 22:24:55 notebook2 kernel: usb 1-1.1: direct-loading
ene-ub6250/sd_init1.bin
Apr 20 22:24:56 notebook2 kernel: usb 1-1.1: direct-loading
ene-ub6250/sd_init2.bin
Apr 20 22:24:56 notebook2 kernel: scsi 6:0:0:0: Direct-Access
PQ: 0 ANSI: 0
We should have seen a pair of "set_xfer_buf" lines before this.
ok 2
Apr 20 22:25:51 notebook2 kernel: usb 1-1.1: new high-speed USB device number 4
using ehci-pci
Apr 20 22:25:51 notebook2 kernel: usb 1-1.1: New USB device found,
idVendor=0cf2, idProduct=6250
Apr 20 22:25:51 notebook2 kernel: usb 1-1.1: New USB device strings: Mfr=1,
Product=2, SerialNumber=4
Apr 20 22:25:51 notebook2 kernel: usb 1-1.1: Product: UB6250
Apr 20 22:25:51 notebook2 kernel: usb 1-1.1: Manufacturer: ENE Flash
Apr 20 22:25:51 notebook2 kernel: usb 1-1.1: SerialNumber: 606569746801
Apr 20 22:25:51 notebook2 kernel: ums_eneub6250 1-1.1:1.0: USB Mass Storage
device detected
Apr 20 22:25:51 notebook2 kernel: scsi host6: usb-storage 1-1.1:1.0
Apr 20 22:25:51 notebook2 mtp-probe[2293]: checking bus 1, device 4:
"/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.1"
Apr 20 22:25:51 notebook2 mtp-probe[2293]: bus: 1, device: 4 was not an MTP
device
Apr 20 22:25:52 notebook2 kernel: usb 1-1.1: direct-loading
ene-ub6250/sd_init1.bin
Apr 20 22:25:53 notebook2 kernel: usb 1-1.1: direct-loading
ene-ub6250/sd_init2.bin
Apr 20 22:25:53 notebook2 kernel: scsi host6: scsi scan: INQUIRY result too
short (5), using 36
Apr 20 22:25:53 notebook2 kernel: scsi 6:0:0:0: Direct-Access
PQ: 0 ANSI: 0
Same here. So the reason usb_stor_set_xfer_buf() wasn't working is
because it never got called!
Knowing that, it's easy to see where the bug is. It's a completely
different issue from the bad DMA problem. In fact, I'm surprised that
this driver ever worked at all.
Please try the patch below. (You can remove the usb_stor_set_xfer_buf
patch.)
Sadly it doesn't work at all (I removed the SD-Card to stop the log entries
...):
Apr 21 19:35:23 notebook2 kernel: usb 1-1.1: new high-speed USB device number 4
using ehci-pci
Apr 21 19:35:23 notebook2 kernel: usb 1-1.1: New USB device found,
idVendor=0cf2, idProduct=6250
Apr 21 19:35:23 notebook2 kernel: usb 1-1.1: New USB device strings: Mfr=1,
Product=2, SerialNumber=4
Apr 21 19:35:23 notebook2 kernel: usb 1-1.1: Product: UB6250
Apr 21 19:35:23 notebook2 kernel: usb 1-1.1: Manufacturer: ENE Flash
Apr 21 19:35:23 notebook2 kernel: usb 1-1.1: SerialNumber: 606569746801
Apr 21 19:35:23 notebook2 kernel: ums_eneub6250 1-1.1:1.0: USB Mass Storage
device detected
Apr 21 19:35:23 notebook2 kernel: scsi host6: usb-storage 1-1.1:1.0
Apr 21 19:35:23 notebook2 mtp-probe[2349]: checking bus 1, device 4:
"/sys/devices/pci0000:00/0000:00:1a.0/usb1/1-1/1-1.1"
Apr 21 19:35:23 notebook2 mtp-probe[2349]: bus: 1, device: 4 was not an MTP
device
Apr 21 19:35:24 notebook2 kernel: usb 1-1.1: direct-loading
ene-ub6250/sd_init1.bin
Apr 21 19:35:24 notebook2 kernel: usb 1-1.1: direct-loading
ene-ub6250/sd_init2.bin
Apr 21 19:35:24 notebook2 kernel: scsi 6:0:0:0: Direct-Access USB2.0
CardReader 0100 PQ: 0 ANSI: 2
Apr 21 19:35:24 notebook2 kernel: sd 6:0:0:0: [sdb] 3985408 512-byte logical
blocks: (2.04 GB/1.90 GiB)
Apr 21 19:35:24 notebook2 kernel: sd 6:0:0:0: Attached scsi generic sg2 type 0
Apr 21 19:35:24 notebook2 kernel: sd 6:0:0:0: [sdb] Write Protect is off
Apr 21 19:35:24 notebook2 kernel: sd 6:0:0:0: [sdb] Mode Sense: 0b 00 00 08
Apr 21 19:35:24 notebook2 kernel: sd 6:0:0:0: [sdb] No Caching mode page found
Apr 21 19:35:24 notebook2 kernel: sd 6:0:0:0: [sdb] Assuming drive cache: write
through
Apr 21 19:35:24 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:24 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:25 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:25 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:25 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:25 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:25 notebook2 kernel: usb 1-1.1: direct-loading
ene-ub6250/sd_rdwr.bin
Apr 21 19:35:25 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:26 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:26 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:26 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:26 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:26 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:27 notebook2 kernel: sd 6:0:0:0: [sdb] tag#0 FAILED Result:
hostbyte=DID_ERROR driverbyte=DRIVER_OK
Apr 21 19:35:27 notebook2 kernel: sd 6:0:0:0: [sdb] tag#0 CDB: Read(10) 28 00
00 00 00 00 00 00 08 00
Apr 21 19:35:27 notebook2 kernel: blk_update_request: I/O error, dev sdb,
sector 0
Apr 21 19:35:27 notebook2 kernel: Buffer I/O error on dev sdb, logical block 0,
async page read
Apr 21 19:35:27 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:27 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:27 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:27 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:27 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:28 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:28 notebook2 kernel: sd 6:0:0:0: [sdb] tag#0 FAILED Result:
hostbyte=DID_ERROR driverbyte=DRIVER_OK
Apr 21 19:35:28 notebook2 kernel: sd 6:0:0:0: [sdb] tag#0 CDB: Read(10) 28 00
00 00 00 00 00 00 08 00
Apr 21 19:35:28 notebook2 kernel: blk_update_request: I/O error, dev sdb,
sector 0
Apr 21 19:35:28 notebook2 kernel: Buffer I/O error on dev sdb, logical block 0,
async page read
Apr 21 19:35:28 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:28 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:28 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:29 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:29 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:29 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:29 notebook2 kernel: sd 6:0:0:0: [sdb] tag#0 FAILED Result:
hostbyte=DID_ERROR driverbyte=DRIVER_OK
Apr 21 19:35:29 notebook2 kernel: sd 6:0:0:0: [sdb] tag#0 CDB: Read(10) 28 00
00 00 00 00 00 00 08 00
Apr 21 19:35:29 notebook2 kernel: blk_update_request: I/O error, dev sdb,
sector 0
Apr 21 19:35:29 notebook2 kernel: Buffer I/O error on dev sdb, logical block 0,
async page read
Apr 21 19:35:29 notebook2 kernel: ldm_validate_partition_table(): Disk read
failed.
Apr 21 19:35:29 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:29 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:30 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:30 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:30 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:30 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:30 notebook2 kernel: sd 6:0:0:0: [sdb] tag#0 FAILED Result:
hostbyte=DID_ERROR driverbyte=DRIVER_OK
Apr 21 19:35:30 notebook2 kernel: sd 6:0:0:0: [sdb] tag#0 CDB: Read(10) 28 00
00 00 00 00 00 00 08 00
Apr 21 19:35:30 notebook2 kernel: blk_update_request: I/O error, dev sdb,
sector 0
Apr 21 19:35:30 notebook2 kernel: Buffer I/O error on dev sdb, logical block 0,
async page read
Apr 21 19:35:30 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:31 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:31 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:31 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:31 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:31 notebook2 kernel: usb 1-1.1: reset high-speed USB device number
4 using ehci-pci
Apr 21 19:35:32 notebook2 kernel: usb 1-1.1: device not accepting address 4,
error -71
Apr 21 19:35:32 notebook2 kernel: sd 6:0:0:0: [sdb] tag#0 FAILED Result:
hostbyte=DID_ERROR driverbyte=DRIVER_OK
Apr 21 19:35:32 notebook2 kernel: sd 6:0:0:0: [sdb] tag#0 CDB: Read(10) 28 00
00 00 00 00 00 00 08 00
Apr 21 19:35:32 notebook2 kernel: blk_update_request: I/O error, dev sdb,
sector 0
Apr 21 19:35:32 notebook2 kernel: Buffer I/O error on dev sdb, logical block 0,
async page read
Apr 21 19:35:32 notebook2 kernel: usb 1-1.1: USB disconnect, device number 4
Apr 21 19:35:32 notebook2 kernel: sd 6:0:0:0: [sdb] tag#0 FAILED Result:
hostbyte=DID_NO_CONNECT driverbyte=DRIVER_OK
Apr 21 19:35:32 notebook2 kernel: sd 6:0:0:0: [sdb] tag#0 CDB: Read(10) 28 00
00 00 00 00 00 00 08 00
Apr 21 19:35:32 notebook2 kernel: blk_update_request: I/O error, dev sdb,
sector 0
Apr 21 19:35:32 notebook2 kernel: Buffer I/O error on dev sdb, logical block 0,
async page read
Apr 21 19:35:32 notebook2 kernel: sdb: unable to read partition table
Apr 21 19:35:32 notebook2 kernel: sd 6:0:0:0: [sdb] Read Capacity(10) failed:
Result: hostbyte=DID_NO_CONNECT driverbyte=DRIVER_OK
Apr 21 19:35:32 notebook2 kernel: sd 6:0:0:0: [sdb] Sense not available.
Apr 21 19:35:32 notebook2 kernel: sd 6:0:0:0: [sdb] Attached SCSI removable disk
Thanks,
Andreas
--
To unsubscribe from this list: send the line "unsubscribe linux-usb" in
the body of a message to majord...@vger.kernel.org
More majordomo info at http://vger.kernel.org/majordomo-info.html
