Hi, I have an embedded board with microprocessor MPC8270 and a ISP1562 BE as USB controller; kernel is based on linux-2.6.25.14 (ehci and ohci enabled). When I plug an USB 1.1 stick (or USB 2.0 through an USB 1.1 hub), I cannot mount the disk. I've enabled all the verbose debug of USB, usb_storage and SCSI in the kernel. The first part of 'dmesg' show the behavior of the stick insertion Few seconds after the command 'MODE_SENSE', USB stick is reset and the USB protocol restart. After 2 minutes, 'dmesg' show a call trace and then USB protocol restart. I've put two snapshots of 'dmesg' at the end of this message. In most cases (90%), USB 2.0 sticks work properly but when a stick is downgraded (noisy USB cable?), I see the same behavior of USB 1.1 I don't understand if it's a software bug (kernel, drivers, ???) or an hardware issue. Can someone help me? Thanks and Best Regards Angelo Fontana FIRST PART OF DMESG ----------------------------------------- hub 2-0:1.0: state 7 ports 1 chg 0000 evt 0001 hub 2-0:1.0: overcurrent change hub 2-0:1.0: trying to enable port power on non-switchable hub hub 1-0:1.0: state 7 ports 2 chg 0000 evt 0006 ehci_hcd 0000:00:0c.2: GetStatus port 1 status 000020 sig=se0 OCC hub 1-0:1.0: over-current change on port 1 hub 1-0:1.0: enabling power on all ports ehci_hcd 0000:00:0c.2: GetStatus port 2 status 001803 POWER sig=j CSC CONNECT hub 1-0:1.0: port 2, status 0501, change 0001, 480 Mb/s hub 1-0:1.0: debounce: port 2: total 100ms stable 100ms status 0x501 ehci_hcd 0000:00:0c.2: port 2 full speed --> companion ehci_hcd 0000:00:0c.2: GetStatus port 2 status 003801 POWER OWNER sig=j CONNECT hub 1-0:1.0: port 2 not reset yet, waiting 50ms ehci_hcd 0000:00:0c.2: GetStatus port 2 status 003802 POWER OWNER sig=j CSC hub 2-0:1.0: state 7 ports 1 chg 0000 evt 0001 hub 2-0:1.0: overcurrent change hub 2-0:1.0: trying to enable port power on non-switchable hub hub 1-0:1.0: state 7 ports 2 chg 0000 evt 0004 hub 3-0:1.0: state 7 ports 1 chg 0000 evt 0002 ohci_hcd 0000:00:0c.1: GetStatus roothub.portstatus [0] = 0x00010101 CSC PPS CCS hub 3-0:1.0: port 1, status 0101, change 0001, 12 Mb/s hub 3-0:1.0: debounce: port 1: total 100ms stable 100ms status 0x101 ohci_hcd 0000:00:0c.1: GetStatus roothub.portstatus [0] = 0x00100103 PRSC PPS PES CCS usb 3-1: new full speed USB device using ohci_hcd and address 2 ohci_hcd 0000:00:0c.1: GetStatus roothub.portstatus [0] = 0x00100103 PRSC PPS PES CCS usb 3-1: default language 0x0409 usb 3-1: uevent usb 3-1: usb_probe_device usb 3-1: configuration #1 chosen from 1 choice usb 3-1: adding 3-1:1.0 (config #1, interface 0) usb 3-1:1.0: uevent usb-storage 3-1:1.0: usb_probe_interface usb-storage 3-1:1.0: usb_probe_interface - got id usb-storage: USB Mass Storage device detected usb-storage: -- associate_dev usb-storage: Vendor: 0x0ea0, Product: 0x6803, Revision: 0x0100 usb-storage: Interface Subclass: 0x06, Protocol: 0x50 usb-storage: Transport: Bulk usb-storage: Protocol: Transparent SCSI scsi0 : SCSI emulation for USB Mass Storage devices usb-storage: *** thread sleeping. usb-storage: device found at 2 usb-storage: waiting for device to settle before scanning drivers/usb/core/inode.c: creating file '002' usb 3-1: New USB device found, idVendor=0ea0, idProduct=6803 usb 3-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 usb 3-1: Product: Solid state disk usb 3-1: Manufacturer: USB usb 3-1: SerialNumber: 7777777777777777 hub 2-0:1.0: state 7 ports 1 chg 0000 evt 0001 hub 3-0:1.0: state 7 ports 1 chg 0000 evt 0002 usb-storage: usb_stor_control_msg: rq=fe rqtype=a1 value=0000 index=00 len=1 usb-storage: GetMaxLUN command result is 1, data is 0 usb-storage: queuecommand called usb-storage: *** thread awakened. usb-storage: Command INQUIRY (6 bytes) usb-storage: 12 00 00 00 24 00 usb-storage: Bulk Command S 0x43425355 T 0x1 L 36 F 128 Trg 0 LUN 0 CL 6 usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes usb-storage: Status code 0; transferred 31/31 usb-storage: -- transfer complete usb-storage: Bulk command transfer result=0 usb-storage: usb_stor_bulk_transfer_sglist: xfer 36 bytes, 1 entries usb-storage: Status code 0; transferred 36/36 usb-storage: -- transfer complete usb-storage: Bulk data transfer result 0x0 usb-storage: Attempting to get CSW... usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes usb-storage: Status code 0; transferred 13/13 usb-storage: -- transfer complete usb-storage: Bulk status result = 0 usb-storage: Bulk Status S 0x53425355 T 0x1 R 0 Stat 0x0 usb-storage: scsi cmd done, result=0x0 usb-storage: *** thread sleeping. scsi 0:0:0:0: Direct-Access OTi Ultra Floppy 1.11 PQ: 0 ANSI: 2 usb-storage: queuecommand called usb-storage: *** thread awakened. usb-storage: Command TEST_UNIT_READY (6 bytes) usb-storage: 00 00 00 00 00 00 usb-storage: Bulk Command S 0x43425355 T 0x2 L 0 F 0 Trg 0 LUN 0 CL 6 usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes usb-storage: Status code 0; transferred 31/31 usb-storage: -- transfer complete usb-storage: Bulk command transfer result=0 usb-storage: Attempting to get CSW... usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes usb-storage: Status code 0; transferred 13/13 usb-storage: -- transfer complete usb-storage: Bulk status result = 0 usb-storage: Bulk Status S 0x53425355 T 0x2 R 0 Stat 0x0 usb-storage: scsi cmd done, result=0x0 usb-storage: *** thread sleeping. usb-storage: queuecommand called usb-storage: *** thread awakened. usb-storage: Command READ_CAPACITY (10 bytes) usb-storage: 25 00 00 00 00 00 00 00 00 00 usb-storage: Bulk Command S 0x43425355 T 0x3 L 8 F 128 Trg 0 LUN 0 CL 10 usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes usb-storage: Status code 0; transferred 31/31 usb-storage: -- transfer complete usb-storage: Bulk command transfer result=0 usb-storage: usb_stor_bulk_transfer_sglist: xfer 8 bytes, 1 entries usb-storage: Status code 0; transferred 8/8 usb-storage: -- transfer complete usb-storage: Bulk data transfer result 0x0 usb-storage: Attempting to get CSW... usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes usb-storage: Status code 0; transferred 13/13 usb-storage: -- transfer complete usb-storage: Bulk status result = 0 usb-storage: Bulk Status S 0x53425355 T 0x3 R 0 Stat 0x0 usb-storage: scsi cmd done, result=0x0 usb-storage: *** thread sleeping. sd 0:0:0:0: [sda] 129024 512-byte hardware sectors (66 MB) usb-storage: queuecommand called usb-storage: *** thread awakened. usb-storage: Command MODE_SENSE (6 bytes) usb-storage: 1a 00 3f 00 c0 00 usb-storage: Bulk Command S 0x43425355 T 0x4 L 192 F 128 Trg 0 LUN 0 CL 6 usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes usb-storage: Status code 0; transferred 31/31 usb-storage: -- transfer complete usb-storage: Bulk command transfer result=0 usb-storage: usb_stor_bulk_transfer_sglist: xfer 192 bytes, 1 entries ohci_hcd 0000:00:0c.1: urb dfed0940 path 1 ep1in 91120000 cc 9 --> status -121 usb-storage: Status code -121; transferred 4/192 usb-storage: -- short read transfer usb-storage: Bulk data transfer result 0x1 usb-storage: Attempting to get CSW... usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes usb-storage: command_abort called usb-storage: usb_stor_stop_transport called usb-storage: -- cancelling URB usb-storage: Status code -104; transferred 0/13 usb-storage: -- transfer cancelled usb-storage: Bulk status result = 4 usb-storage: -- command was aborted usb-storage: storage_pre_reset hub 3-0:1.0: state 7 ports 1 chg 0000 evt 0002 ohci_hcd 0000:00:0c.1: GetStatus roothub.portstatus [0] = 0x00100103 PRSC PPS PES CCS usb 3-1: reset full speed USB device using ohci_hcd and address 2 ohci_hcd 0000:00:0c.1: GetStatus roothub.portstatus [0] = 0x00100103 PRSC PPS PES CCS usb-storage: storage_post_reset usb-storage: usb_reset_composite_device returns 0 usb-storage: scsi command aborted usb-storage: *** thread sleeping. usb-storage: queuecommand called ------------------------------------------ CALL TRACE ------------------------------------------ INFO: task usb-stor-scan:798 blocked for more than 120 seconds. "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. usb-stor-scan D 00000000 0 798 2 Call Trace: [defa7a10] [c0012178] __enqueue_entity+0xe8/0xfc (unreliable) [defa7ad0] [c000b4d4] __switch_to+0x50/0x68 [defa7af0] [c026cc3c] schedule+0x294/0x2d4 [defa7b20] [c026cdf8] schedule_timeout+0x2c/0xc4 [defa7b60] [c026c850] wait_for_common+0xf8/0x1a0 [defa7ba0] [c013a788] blk_execute_rq+0x94/0xbc [defa7c40] [c017b0f0] scsi_execute+0xe0/0x104 [defa7c70] [c017be94] scsi_execute_req+0x84/0xc0 [defa7cb0] [c017d5d0] scsi_mode_sense+0xe4/0x26c [defa7d00] [c0184f98] sd_revalidate_disk+0xd54/0xd80 [defa7d50] [c018544c] sd_probe+0x2b0/0x3b8 [defa7d80] [c016dfd0] driver_probe_device+0xe4/0x188 [defa7da0] [c016ce20] bus_for_each_drv+0x58/0xa8 [defa7dd0] [c016e138] device_attach+0x80/0xbc [defa7df0] [c016d01c] bus_attach_device+0x44/0x8c [defa7e00] [c016bbe4] device_add+0x378/0x500 [defa7e30] [c0180c70] scsi_sysfs_add_sdev+0x30/0x190 [defa7e50] [c017ee24] scsi_probe_and_add_lun+0x890/0x9bc [defa7ec0] [c017f014] __scsi_scan_target+0xc4/0x608 [defa7f50] [c017f5a4] scsi_scan_channel+0x4c/0x9c [defa7f70] [c017f6d8] scsi_scan_host_selected+0xe4/0x154 [defa7fa0] [c01c5834] usb_stor_scan_thread+0x114/0x12c [defa7fd0] [c0029d7c] kthread+0x50/0x8c [defa7ff0] [c0005af8] kernel_thread+0x44/0x60 ------------------------------------------ -- 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
