Sarah Sharp <sarah.a.sharp@...> writes: > Did you grab the master branch, or another branch? (Also, please CC me > if you're asking questions about the xHCI driver, otherwise it gets a > little lost in my mail filter rules.) I used the following command: git clone git://git.kernel.org/pub/scm/linux/kernel/git/sarah/xhci.git > > > Now, the device can get the "INQUIRY" command. But during the data stage, > > the uasp driver submit the urb by "usb_fill_bulk_urb" and "usb_submit_urb" > > after evaluating, it return the valid return value. > > Did it set urb->stream_id after calling usb_fill_bulk_urb()? That > function won't set the stream ID for you. > Yes, I verified the stream_id before prepare_transfer. It is valid. > It looks like your device didn't respond to the device descriptor > requests and doesn't advertise an alternate setting 0. That could lead > to some issues. What device is this? Has it passed PIL certification, > or is it a prototype? > That's a pretty busted looking device. It is a card reader device which has passed "PIL" certification. And we are on the further debugging. :) Following is the new debug message. It seems to load the default usb-storage 2.0 driver. But when I rmmod the default usb-storage driver and insmod the uasp-storage driver for USB3.0 device. The probe function seems can not be recalled, which can be recalled before. May 19 09:44:27 realsil-desktop kernel: [ 579.476043] xhci_hcd 0000:01:00.0: Can't reset device (slot ID 1) in enabled/disabled state May 19 09:44:27 realsil-desktop kernel: [ 579.476048] xhci_hcd 0000:01:00.0: Not freeing device rings. May 19 09:44:27 realsil-desktop kernel: [ 579.476059] usb 9-2: new full speed USB device using xhci_hcd and address 0 May 19 09:44:27 realsil-desktop kernel: [ 579.476107] usb 9-2: Device not responding to set address. May 19 09:44:27 realsil-desktop kernel: [ 579.680075] usb 9-2: Device not responding to set address. May 19 09:44:27 realsil-desktop kernel: [ 579.884019] usb 9-2: device not accepting address 0, error -71 May 19 09:44:27 realsil-desktop kernel: [ 579.996038] xhci_hcd 0000:01:00.0: Can't reset device (slot ID 2) in enabled/disabled state May 19 09:44:27 realsil-desktop kernel: [ 579.996042] xhci_hcd 0000:01:00.0: Not freeing device rings. May 19 09:44:27 realsil-desktop kernel: [ 579.996052] usb 9-2: new full speed USB device using xhci_hcd and address 0 May 19 09:44:27 realsil-desktop kernel: [ 579.996100] usb 9-2: Device not responding to set address. May 19 09:44:27 realsil-desktop kernel: [ 580.200063] usb 9-2: Device not responding to set address. May 19 09:44:28 realsil-desktop kernel: [ 580.404025] usb 9-2: device not accepting address 0, error -71 May 19 09:44:28 realsil-desktop kernel: [ 580.516058] xhci_hcd 0000:01:00.0: Can't reset device (slot ID 3) in enabled/disabled state May 19 09:44:28 realsil-desktop kernel: [ 580.516063] xhci_hcd 0000:01:00.0: Not freeing device rings. May 19 09:44:28 realsil-desktop kernel: [ 580.516077] usb 9-2: new full speed USB device using xhci_hcd and address 0 May 19 09:44:28 realsil-desktop kernel: [ 580.516119] usb 9-2: Device not responding to set address. May 19 09:44:28 realsil-desktop kernel: [ 580.720105] usb 9-2: Device not responding to set address. May 19 09:44:28 realsil-desktop kernel: [ 580.924016] usb 9-2: device not accepting address 0, error -71 May 19 09:44:28 realsil-desktop kernel: [ 581.036049] xhci_hcd 0000:01:00.0: Can't reset device (slot ID 4) in enabled/disabled state May 19 09:44:28 realsil-desktop kernel: [ 581.036054] xhci_hcd 0000:01:00.0: Not freeing device rings. May 19 09:44:28 realsil-desktop kernel: [ 581.036064] usb 9-2: new full speed USB device using xhci_hcd and address 0 May 19 09:44:28 realsil-desktop kernel: [ 581.036111] usb 9-2: Device not responding to set address. May 19 09:44:28 realsil-desktop kernel: [ 581.240061] usb 9-2: Device not responding to set address. May 19 09:44:29 realsil-desktop kernel: [ 581.444025] usb 9-2: device not accepting address 0, error -71 May 19 09:44:29 realsil-desktop kernel: [ 581.444049] hub 9-0:1.0: unable to enumerate USB device on port 2 May 19 09:44:29 realsil-desktop kernel: [ 581.668172] usb 9-1: new SuperSpeed USB device using xhci_hcd and address 2 May 19 09:44:29 realsil-desktop kernel: [ 581.686859] usb 9-1: config 1 interface 0 has no altsetting 0 May 19 09:44:29 realsil-desktop kernel: [ 581.687005] xhci_hcd 0000:01:00.0: WARN: short transfer on control ep May 19 09:44:29 realsil-desktop kernel: [ 581.687349] xhci_hcd 0000:01:00.0: WARN: short transfer on control ep May 19 09:44:29 realsil-desktop kernel: [ 581.687664] xhci_hcd 0000:01:00.0: WARN: short transfer on control ep May 19 09:44:29 realsil-desktop kernel: [ 581.688208] xhci_hcd 0000:01:00.0: WARN: short transfer on control ep May 19 09:44:29 realsil-desktop kernel: [ 581.688627] Did not find alt setting 0 for intf 0, config 1 May 19 09:44:29 realsil-desktop kernel: [ 581.689802] xhci_hcd 0000:01:00.0: WARN: short transfer on control ep May 19 09:44:29 realsil-desktop kernel: [ 581.690583] xhci_hcd 0000:01:00.0: WARN: short transfer on control ep May 19 09:47:17 realsil-desktop kernel: [ 749.732037] usb 9-1: USB disconnect, address 2 May 19 09:47:44 realsil-desktop kernel: [ 776.912041] xhci_hcd 0000:01:00.0: Can't reset device (slot ID 2) in enabled/disabled state May 19 09:47:44 realsil-desktop kernel: [ 776.912046] xhci_hcd 0000:01:00.0: Not freeing device rings. May 19 09:47:44 realsil-desktop kernel: [ 776.912057] usb 9-2: new full speed USB device using xhci_hcd and address 0 May 19 09:47:44 realsil-desktop kernel: [ 776.912126] usb 9-2: Device not responding to set address. May 19 09:47:44 realsil-desktop kernel: [ 777.116100] usb 9-2: Device not responding to set address. May 19 09:47:44 realsil-desktop kernel: [ 777.320031] usb 9-2: device not accepting address 0, error -71 May 19 09:47:45 realsil-desktop kernel: [ 777.432041] xhci_hcd 0000:01:00.0: Can't reset device (slot ID 3) in enabled/disabled state May 19 09:47:45 realsil-desktop kernel: [ 777.432045] xhci_hcd 0000:01:00.0: Not freeing device rings. May 19 09:47:45 realsil-desktop kernel: [ 777.432055] usb 9-2: new full speed USB device using xhci_hcd and address 0 May 19 09:47:45 realsil-desktop kernel: [ 777.432130] usb 9-2: Device not responding to set address. May 19 09:47:45 realsil-desktop kernel: [ 777.636103] usb 9-2: Device not responding to set address. May 19 09:47:45 realsil-desktop kernel: [ 777.840033] usb 9-2: device not accepting address 0, error -71 May 19 09:47:45 realsil-desktop kernel: [ 777.952051] xhci_hcd 0000:01:00.0: Can't reset device (slot ID 4) in enabled/disabled state May 19 09:47:45 realsil-desktop kernel: [ 777.952056] xhci_hcd 0000:01:00.0: Not freeing device rings. May 19 09:47:45 realsil-desktop kernel: [ 777.952067] usb 9-2: new full speed USB device using xhci_hcd and address 0 May 19 09:47:45 realsil-desktop kernel: [ 777.952135] usb 9-2: Device not responding to set address. May 19 09:47:45 realsil-desktop kernel: [ 778.156087] usb 9-2: Device not responding to set address. May 19 09:47:45 realsil-desktop kernel: [ 778.360015] usb 9-2: device not accepting address 0, error -71 May 19 09:47:46 realsil-desktop kernel: [ 778.472048] xhci_hcd 0000:01:00.0: Can't reset device (slot ID 1) in enabled/disabled state May 19 09:47:46 realsil-desktop kernel: [ 778.472054] xhci_hcd 0000:01:00.0: Not freeing device rings. May 19 09:47:46 realsil-desktop kernel: [ 778.472067] usb 9-2: new full speed USB device using xhci_hcd and address 0 May 19 09:47:46 realsil-desktop kernel: [ 778.472142] usb 9-2: Device not responding to set address. May 19 09:47:46 realsil-desktop kernel: [ 778.676092] usb 9-2: Device not responding to set address. May 19 09:47:46 realsil-desktop kernel: [ 778.880027] usb 9-2: device not accepting address 0, error -71 May 19 09:47:46 realsil-desktop kernel: [ 778.880051] hub 9-0:1.0: unable to enumerate USB device on port 2 May 19 09:47:46 realsil-desktop kernel: [ 779.008151] usb 9-1: new SuperSpeed USB device using xhci_hcd and address 3 May 19 09:47:46 realsil-desktop kernel: [ 779.027869] xhci_hcd 0000:01:00.0: WARN: short transfer on control ep May 19 09:47:46 realsil-desktop kernel: [ 779.028213] xhci_hcd 0000:01:00.0: WARN: short transfer on control ep May 19 09:47:46 realsil-desktop kernel: [ 779.028538] xhci_hcd 0000:01:00.0: WARN: short transfer on control ep May 19 09:47:46 realsil-desktop kernel: [ 779.029085] xhci_hcd 0000:01:00.0: WARN: short transfer on control ep May 19 09:47:46 realsil-desktop kernel: [ 779.030262] xhci_hcd 0000:01:00.0: WARN: short transfer on control ep May 19 09:47:46 realsil-desktop kernel: [ 779.031048] xhci_hcd 0000:01:00.0: WARN: short transfer on control ep May 19 09:47:46 realsil-desktop kernel: [ 779.074033] Initializing USB Mass Storage driver... May 19 09:47:46 realsil-desktop kernel: [ 779.074123] usb-storage: USB Mass Storage device detected May 19 09:47:46 realsil-desktop kernel: [ 779.074185] usb-storage: -- associate_dev May 19 09:47:46 realsil-desktop kernel: [ 779.074189] usb-storage: Vendor: 0x0bda, Product: 0x0301, Revision: 0x0103 May 19 09:47:46 realsil-desktop kernel: [ 779.074193] usb-storage: Interface Subclass: 0x06, Protocol: 0x50 May 19 09:47:46 realsil-desktop kernel: [ 779.074203] usb-storage: Transport: Bulk May 19 09:47:46 realsil-desktop kernel: [ 779.074207] usb-storage: Protocol: Transparent SCSI May 19 09:47:46 realsil-desktop kernel: [ 779.074238] scsi6 : usb-storage 9- 1:1.0 May 19 09:47:46 realsil-desktop kernel: [ 779.074427] usb-storage: *** thread sleeping. May 19 09:47:46 realsil-desktop kernel: [ 779.074464] usbcore: registered new interface driver usb-storage May 19 09:47:46 realsil-desktop kernel: [ 779.074467] USB Mass Storage support registered. May 19 09:47:46 realsil-desktop kernel: [ 779.076298] usb-storage 9-1:1.0: device found May 19 09:47:46 realsil-desktop kernel: [ 779.076303] usb-storage 9-1:1.0: waiting for device to settle before scanning May 19 09:47:47 realsil-desktop kernel: [ 780.072022] usb-storage: usb_stor_control_msg: rq=fe rqtype=a1 value=0000 index=00 len=1 May 19 09:47:47 realsil-desktop kernel: [ 780.072119] usb-storage: GetMaxLUN command result is 1, data is 4 May 19 09:47:47 realsil-desktop kernel: [ 780.072173] usb-storage 9-1:1.0: scan complete May 19 09:47:47 realsil-desktop kernel: [ 780.072234] usb-storage: queuecommand called May 19 09:47:47 realsil-desktop kernel: [ 780.072245] usb-storage: *** thread awakened. May 19 09:47:47 realsil-desktop kernel: [ 780.072250] usb-storage: Command INQUIRY (6 bytes) May 19 09:47:47 realsil-desktop kernel: [ 780.072253] usb-storage: 12 00 00 00 24 00 May 19 09:47:47 realsil-desktop kernel: [ 780.072265] usb-storage: Bulk Command S 0x43425355 T 0x1 L 36 F 128 Trg 0 LUN 0 CL 6 May 19 09:47:47 realsil-desktop kernel: [ 780.072269] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes May 19 09:47:47 realsil-desktop kernel: [ 780.072308] usb-storage: Status code 0; transferred 31/31 May 19 09:47:47 realsil-desktop kernel: [ 780.072311] usb-storage: -- transfer complete May 19 09:47:47 realsil-desktop kernel: [ 780.072313] usb-storage: Bulk command transfer result=0 May 19 09:47:47 realsil-desktop kernel: [ 780.072317] usb-storage: usb_stor_bulk_transfer_sglist: xfer 36 bytes, 1 entries May 19 09:47:47 realsil-desktop kernel: [ 780.072915] usb-storage: Status code 0; transferred 36/36 May 19 09:47:47 realsil-desktop kernel: [ 780.072919] usb-storage: -- transfer complete May 19 09:47:47 realsil-desktop kernel: [ 780.072923] usb-storage: Bulk data transfer result 0x0 May 19 09:47:47 realsil-desktop kernel: [ 780.072927] usb-storage: Attempting to get CSW... May 19 09:47:47 realsil-desktop kernel: [ 780.072931] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes May 19 09:47:47 realsil-desktop kernel: [ 780.072972] usb-storage: Status code 0; transferred 13/13 May 19 09:47:47 realsil-desktop kernel: [ 780.072976] usb-storage: -- transfer complete May 19 09:47:47 realsil-desktop kernel: [ 780.072980] usb-storage: Bulk status result = 0 May 19 09:47:47 realsil-desktop kernel: [ 780.072984] usb-storage: Bulk Status S 0x53425355 T 0x1 R 0 Stat 0x0 May 19 09:47:47 realsil-desktop kernel: [ 780.072989] usb-storage: scsi cmd done, result=0x0 May 19 09:47:47 realsil-desktop kernel: [ 780.072996] usb-storage: *** thread sleeping. May 19 09:47:47 realsil-desktop kernel: [ 780.073046] scsi 6:0:0:0: Direct- Access Generic- Compact Flash 1.00 PQ: 0 ANSI: 0 CCS May 19 09:47:47 realsil-desktop kernel: [ 780.073088] usb-storage: queuecommand called May 19 09:47:47 realsil-desktop kernel: [ 780.073097] usb-storage: *** thread awakened. May 19 09:47:47 realsil-desktop kernel: [ 780.073102] usb-storage: Command INQUIRY (6 bytes) May 19 09:47:47 realsil-desktop kernel: [ 780.073107] usb-storage: 12 00 00 00 24 00 May 19 09:47:47 realsil-desktop kernel: [ 780.073121] usb-storage: Bulk Command S 0x43425355 T 0x2 L 36 F 128 Trg 0 LUN 1 CL 6 May 19 09:47:47 realsil-desktop kernel: [ 780.073126] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes May 19 09:47:47 realsil-desktop kernel: [ 780.073158] usb-storage: Status code 0; transferred 31/31 May 19 09:47:47 realsil-desktop kernel: [ 780.073161] usb-storage: -- transfer complete May 19 09:47:47 realsil-desktop kernel: [ 780.073164] usb-storage: Bulk command transfer result=0 May 19 09:47:47 realsil-desktop kernel: [ 780.073169] usb-storage: usb_stor_bulk_transfer_sglist: xfer 36 bytes, 1 entries May 19 09:47:47 realsil-desktop kernel: [ 780.073675] usb-storage: Status code 0; transferred 36/36 May 19 09:47:47 realsil-desktop kernel: [ 780.073679] usb-storage: -- transfer complete May 19 09:47:47 realsil-desktop kernel: [ 780.073682] usb-storage: Bulk data transfer result 0x0 May 19 09:47:47 realsil-desktop kernel: [ 780.073686] usb-storage: Attempting to get CSW... May 19 09:47:47 realsil-desktop kernel: [ 780.073690] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes May 19 09:47:47 realsil-desktop kernel: [ 780.073718] usb-storage: Status code 0; transferred 13/13 May 19 09:47:47 realsil-desktop kernel: [ 780.073720] usb-storage: -- transfer complete May 19 09:47:47 realsil-desktop kernel: [ 780.073724] usb-storage: Bulk status result = 0 May 19 09:47:47 realsil-desktop kernel: [ 780.073728] usb-storage: Bulk Status S 0x53425355 T 0x2 R 0 Stat 0x0 May 19 09:47:47 realsil-desktop kernel: [ 780.073733] usb-storage: scsi cmd done, result=0x0 May 19 09:47:47 realsil-desktop kernel: [ 780.073739] usb-storage: *** thread sleeping. May 19 09:47:47 realsil-desktop kernel: [ 780.073779] scsi 6:0:0:1: Direct- Access Generic- SM/xD-Picture 1.00 PQ: 0 ANSI: 0 CCS May 19 09:47:47 realsil-desktop kernel: [ 780.073815] usb-storage: queuecommand called May 19 09:47:47 realsil-desktop kernel: [ 780.073823] usb-storage: *** thread awakened. May 19 09:47:47 realsil-desktop kernel: [ 780.073828] usb-storage: Command INQUIRY (6 bytes) May 19 09:47:47 realsil-desktop kernel: [ 780.073833] usb-storage: 12 00 00 00 24 00 May 19 09:47:47 realsil-desktop kernel: [ 780.073846] usb-storage: Bulk Command S 0x43425355 T 0x3 L 36 F 128 Trg 0 LUN 2 CL 6 May 19 09:47:47 realsil-desktop kernel: [ 780.073851] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes May 19 09:47:47 realsil-desktop kernel: [ 780.073888] usb-storage: Status code 0; transferred 31/31 May 19 09:47:47 realsil-desktop kernel: [ 780.073892] usb-storage: -- transfer complete May 19 09:47:47 realsil-desktop kernel: [ 780.073896] usb-storage: Bulk command transfer result=0 May 19 09:47:47 realsil-desktop kernel: [ 780.073901] usb-storage: usb_stor_bulk_transfer_sglist: xfer 36 bytes, 1 entries May 19 09:47:47 realsil-desktop kernel: [ 780.074387] usb-storage: Status code 0; transferred 36/36 May 19 09:47:47 realsil-desktop kernel: [ 780.074390] usb-storage: -- transfer complete May 19 09:47:47 realsil-desktop kernel: [ 780.074393] usb-storage: Bulk data transfer result 0x0 May 19 09:47:47 realsil-desktop kernel: [ 780.074397] usb-storage: Attempting to get CSW... May 19 09:47:47 realsil-desktop kernel: [ 780.074400] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes May 19 09:47:47 realsil-desktop kernel: [ 780.074440] usb-storage: Status code 0; transferred 13/13 May 19 09:47:47 realsil-desktop kernel: [ 780.074444] usb-storage: -- transfer complete May 19 09:47:47 realsil-desktop kernel: [ 780.074448] usb-storage: Bulk status result = 0 May 19 09:47:47 realsil-desktop kernel: [ 780.074453] usb-storage: Bulk Status S 0x53425355 T 0x3 R 0 Stat 0x0 May 19 09:47:47 realsil-desktop kernel: [ 780.074457] usb-storage: scsi cmd done, result=0x0 May 19 09:47:47 realsil-desktop kernel: [ 780.074463] usb-storage: *** thread sleeping. May 19 09:47:47 realsil-desktop kernel: [ 780.074503] scsi 6:0:0:2: Direct- Access Generic- SD/MMC 1.00 PQ: 0 ANSI: 0 CCS May 19 09:47:47 realsil-desktop kernel: [ 780.074538] usb-storage: queuecommand called May 19 09:47:47 realsil-desktop kernel: [ 780.074546] usb-storage: *** thread awakened. May 19 09:47:47 realsil-desktop kernel: [ 780.074550] usb-storage: Command INQUIRY (6 bytes) May 19 09:47:47 realsil-desktop kernel: [ 780.074555] usb-storage: 12 00 00 00 24 00 May 19 09:47:47 realsil-desktop kernel: [ 780.074569] usb-storage: Bulk Command S 0x43425355 T 0x4 L 36 F 128 Trg 0 LUN 3 CL 6 May 19 09:47:47 realsil-desktop kernel: [ 780.074574] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes May 19 09:47:47 realsil-desktop kernel: [ 780.074603] usb-storage: Status code 0; transferred 31/31 May 19 09:47:47 realsil-desktop kernel: [ 780.074606] usb-storage: -- transfer complete May 19 09:47:47 realsil-desktop kernel: [ 780.074610] usb-storage: Bulk command transfer result=0 May 19 09:47:47 realsil-desktop kernel: [ 780.074614] usb-storage: usb_stor_bulk_transfer_sglist: xfer 36 bytes, 1 entries May 19 09:47:47 realsil-desktop kernel: [ 780.075123] usb-storage: Status code 0; transferred 36/36 May 19 09:47:47 realsil-desktop kernel: [ 780.075126] usb-storage: -- transfer complete May 19 09:47:47 realsil-desktop kernel: [ 780.075130] usb-storage: Bulk data transfer result 0x0 May 19 09:47:47 realsil-desktop kernel: [ 780.075133] usb-storage: Attempting to get CSW... May 19 09:47:47 realsil-desktop kernel: [ 780.075138] usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes May 19 09:47:47 realsil-desktop kernel: [ 780.075166] usb-storage: Status code 0; transferred 13/13 May 19 09:47:47 realsil-desktop kernel: [ 780.075168] usb-storage: -- transfer complete May 19 09:47:47 realsil-desktop kernel: [ 780.075172] usb-storage: Bulk status result = 0 May 19 09:47:47 realsil-desktop kernel: [ 780.075177] usb-storage: Bulk Status S 0x53425355 T 0x4 R 0 Stat 0x0 May 19 09:47:47 realsil-desktop kernel: [ 780.075181] usb-storage: scsi cmd done, result=0x0 May 19 09:47:47 realsil-desktop kernel: [ 780.075187] usb-storage: *** thread sleeping. May 19 09:47:47 realsil-desktop kernel: [ 780.075226] scsi 6:0:0:3: Direct- Access Generic- MS/MS-Pro/HG 1.00 PQ: 0 ANSI: 0 CCS May 19 09:47:47 realsil-desktop kernel: [ 780.075259] usb-storage: queuecommand called May 19 09:47:47 realsil-desktop kernel: [ 780.075267] usb-storage: *** thread awakened. May 19 09:47:47 realsil-desktop kernel: [ 780.075272] usb-storage: Command INQUIRY (6 bytes) May 19 09:47:47 realsil-desktop kernel: [ 780.075277] usb-storage: 12 00 00 00 24 00 May 19 09:47:47 realsil-desktop kernel: [ 780.075290] usb-storage: Bulk Command S 0x43425355 T 0x5 L 36 F 128 Trg 0 LUN 4 CL 6 May 19 09:47:47 realsil-desktop kernel: [ 780.075295] usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes May 19 09:47:47 realsil-desktop kernel: [ 780.075332] usb-storage: Status code 0; transferred 31/31 May 19 09:47:47 realsil-desktop kernel: [ 780.075335] usb-storage: -- transfer complete May 19 09:47:47 realsil-desktop kernel: [ 780.075339] usb-storage: Bulk command transfer result=0 May 19 09:47:47 realsil-desktop kernel: [ 780.075344] usb-storage: usb_stor_bulk_transfer_sglist: xfer 36 bytes, 1 entries -- 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
