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 majordomo@xxxxxxxxxxxxxxx
More majordomo info at http://vger.kernel.org/majordomo-info.html
