On 2016-02-18 16:12, Mathias Nyman wrote: > On 16.02.2016 23:58, main.ha...@googlemail.com wrote: >> >> >> On 2016-02-08 15:31, Mathias Nyman wrote: >>> Hi >>> >>> On 06.02.2016 19:08, Mike Murdoch wrote: >>>> Bug ID: 111251 >>>> >>>> Hello, >>>> >>>> I have a NEC uPD720200 USB3.0 controller in a Thinkpad W520 laptop on >>>> kernel 4.4.1-gentoo. >>>> >>>> 0e:00.0 USB controller: NEC Corporation uPD720200 USB 3.0 Host >>>> Controller (rev 04) (prog-if 30 [XHCI]) >>>> Subsystem: Lenovo uPD720200 USB 3.0 Host Controller >>>> >>>> When runtime power control for this controller is disabled >>>> (/sys/bus/pci/devices/0000:0e:00.0/power/control = on), the controller >>>> works fine and reaches over 120MB/s transfer rates. >>>> >>>> When runtime power control for this controller is enabled >>>> (/sys/bus/pci/devices/0000:0e:00.0/power/control = auto), two effects >>>> can be observed: >>>> >>>> - Transfer rates are much lower at around 30MB/s >>>> - During transfers, the controller dies after a couple of seconds: >>>> >>>> xhci_hcd 0000:0e:00.0: xHCI host not responding to stop endpoint >>>> command. >>>> xhci_hcd 0000:0e:00.0: Assuming host is dying, halting host. >>>> xhci_hcd 0000:0e:00.0: Host not halted after 16000 microseconds. >>>> xhci_hcd 0000:0e:00.0: Non-responsive xHCI host is not halting. >>>> xhci_hcd 0000:0e:00.0: Completing active URBs anyway. >>>> xhci_hcd 0000:0e:00.0: HC died; cleaning up >>>> sd 9:0:0:0: [sdc] tag#0 FAILED Result: hostbyte=DID_ERROR >>>> driverbyte=DRIVER_OK >>>> sd 9:0:0:0: [sdc] tag#0 CDB: Read(10) 28 00 00 19 a9 00 00 00 f0 00 >>>> blk_update_request: I/O error, dev sdc, sector 1681664 >>>> xhci_hcd 0000:0e:00.0: Stopped the command ring failed, maybe the host >>>> is dead >>>> xhci_hcd 0000:0e:00.0: Host not halted after 16000 microseconds. >>>> xhci_hcd 0000:0e:00.0: Abort command ring failed >>>> xhci_hcd 0000:0e:00.0: HC died; cleaning up >>>> >>>> At this point, a reboot is required to reactivate the controller, >>>> unloading and reloading the xhci_* modules does not work. >>>> >>> >>> With 120MB/s I assume it was a USB3 device. >>> Was there any USB 2 device connected as well? >>> Does this occur with only a USB2 device connected to xhci? >>> >>> xhci handles suspend/resume a bit differently for USB2 and USB3 >>> roothubs. >>> >>> Does this happen on older kernels as well? 4.3 or 4.2 based? >>> >>> For more xhci debugging, do: >>> echo -n 'module xhci_hcd =p' > /sys/kernel/debug/dynamic_debug/control >>> and check dmesg for more xhci info. >>> >>> If reloading the module did not help it is more likely that the >>> controller is in some >>> unexpected state. >>> If however, it would instead be just bad timeout timer handling we >>> could just return immediately >>> in the timeout handler, and check if the usb device(s) continue to >>> work normally. >>> >>> This could be done by editing drivers/usb/hosts/xhci-ring.c >>> >>> +++ b/drivers/usb/host/xhci-ring.c >>> @@ -831,6 +831,7 @@ void xhci_stop_endpoint_command_watchdog(unsigned >>> long arg) >>> struct xhci_virt_ep *ep; >>> int ret, i, j; >>> unsigned long flags; >>> + return; >>> >>> -Mathias >>> >>> >> Hello Mat, >> >> thanks for your response. I have experimented with your suggestions. >> >> As for your questions: No, there was only one USB3 stick connected to >> the host controller during the tests. USB2 devices work fine too. >> >> Yes, I encountered this problem on a 4.1 series kernel aswell as the 4.4 >> series. >> >> I have enabled the debug controls and attached the results to this mail, >> along with some commentary. I am hoping this works in the mailing list. >> >> I've also tried your suggested modification, and it does seem to work! >> With it, the controller does not die, but it still sacrifices a lot of >> speed (as I had mentioned in the first mail of this thread) >> >> >> I hope this is helpful! >> > > Thanks, it is helpful > > Looks like when the USB3 device is inserted it is first detected as a > USB2 device, > then immediately afterwars as a USB3 device, the usb2 device stops > responding so 5 > seconds later we timeout, and kill everything. > > selected parts of the log: > > inserting usb3 storage device > 20:03:33 xhci_hcd 0000:0e:00.0: xhci_resume: starting port polling. > 20:03:33 xhci_hcd 0000:0e:00.0: Port Status Change Event for port 3 > 20:03:33 xhci_hcd 0000:0e:00.0: get port status, actual port 0 status > = 0x202e1 /* PORT 0 > 20:03:33 xhci_hcd 0000:0e:00.0: get port status, actual port 1 status > = 0x2a0 /* PORT 1 > 20:03:33 usb 1-1: new high-speed USB device number 2 using xhci_hcd > 20:03:33 xhci_hcd 0000:0e:00.0: Slot ID 1 Input Context: /* > Found a HS device > 20:03:33 xhci_hcd 0000:0e:00.0: IN Endpoint 00 Context (ep_index 00): > 20:03:33 xhci_hcd 0000:0e:00.0: @ffff8805fc8a5048 (virt) @ffffa048 > (dma) 0xfffdf001 - deq > 20:03:33 xhci_hcd 0000:0e:00.0: Successful setup context command > * now we have a device at SLOT 1 with control endpoint 0 buffer at > address 0xfffdf000 > 20:03:33 xhci_hcd 0000:0e:00.0: Slot ID 2 Input Context: > 20:03:33 xhci_hcd 0000:0e:00.0: IN Endpoint 00 Context (ep_index 00): > 20:03:33 xhci_hcd 0000:0e:00.0: @ffff8800b68d7048 (virt) @ffff2048 > (dma) 0xfffe1001 - deq > * now we have another device at SLOT 2 with control endpoint buffer > at 0xfffe1000 > 20:03:33 usb 2-1: new SuperSpeed USB device number 3 using > xhci_hcd /* found SS device > 20:03:33 usb 2-1: New USB device found, idVendor=0951, idProduct=1666 > 20:03:33 usb 2-1: Product: DataTraveler 3.0 > 20:03:33 usb 2-1: Manufacturer: Kingston > 20:03:33 usb 2-1: SerialNumber: AC220B280C8FBFA1F96CA020 > 20:03:33 usb-storage 2-1:1.0: USB Mass Storage device detected > 20:03:34 sd 7:0:0:0: [sdc] Attached SCSI removable disk > 20:03:38 xhci_hcd 0000:0e:00.0: Cancel URB ffff8805fc825600, dev 1, ep > 0x0, starting at offset 0xfffdf000 > * URB placed is the HS device control endpoint ring is canceled > after a 5 second timeout. > > We try to remove the cancelled URB from the control endpoint ring of > the USB2 HS device, > we fail in stopping the ring, (probably because there is no real USB2 > device running anymore) > and then kill everything. > > I need to look at this in more detail, check if the speed changes on > port reset, is there > some race in resume code or somewhere else, or if we don't we give the > link training enough time > on USB3 side before starting USB2 device initialization. > > What does the log look like when attaching the USB3 storage device > with runtime power disabled? > I'd guess there is no HS device detected at all. > > -Mathias > Hi,
I'm attaching a log with power management disabled. Cheers, - Mike
Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Port Status Change Event for port 2 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: resume root hub Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: handle_port_status: starting port polling. Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: get port status, actual port 0 status = 0x2a0 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Get port status returned 0x2a0 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: get port status, actual port 1 status = 0x21203 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Get port status returned 0x10203 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: clear port connect change, actual port 1 status = 0x1203 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: get port status, actual port 1 status = 0x1203 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Get port status returned 0x203 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: // Ding dong! Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Slot 1 output ctx = 0xfffff000 (dma) Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Slot 1 input ctx = 0xffffe000 (dma) Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Set slot id 1 dcbaa entry ffff88061e225008 to 0xfffff000 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: get port status, actual port 1 status = 0x1203 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Get port status returned 0x203 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: set port reset, actual port 1 status = 0x1311 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Port Status Change Event for port 2 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: handle_port_status: starting port polling. Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: get port status, actual port 1 status = 0x201203 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Get port status returned 0x100203 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: clear port reset change, actual port 1 status = 0x1203 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: clear port warm(BH) reset change, actual port 1 status = 0x1203 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: clear port link state change, actual port 1 status = 0x1203 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: clear port connect change, actual port 1 status = 0x1203 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: get port status, actual port 1 status = 0x1203 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Get port status returned 0x203 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Set root hub portnum to 2 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Set fake root hub portnum to 2 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: udev->tt = (null) Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: udev->ttport = 0x0 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Slot ID 1 Input Context: Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334000 (virt) @ffffe000 (dma) 0x000000 - drop flags Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334004 (virt) @ffffe004 (dma) 0x000003 - add flags Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334008 (virt) @ffffe008 (dma) 0x000000 - rsvd2[0] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac33400c (virt) @ffffe00c (dma) 0x000000 - rsvd2[1] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334010 (virt) @ffffe010 (dma) 0x000000 - rsvd2[2] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334014 (virt) @ffffe014 (dma) 0x000000 - rsvd2[3] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334018 (virt) @ffffe018 (dma) 0x000000 - rsvd2[4] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac33401c (virt) @ffffe01c (dma) 0x000000 - rsvd2[5] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Slot Context: Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334020 (virt) @ffffe020 (dma) 0x8400000 - dev_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334024 (virt) @ffffe024 (dma) 0x020000 - dev_info2 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334028 (virt) @ffffe028 (dma) 0x000000 - tt_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac33402c (virt) @ffffe02c (dma) 0x000000 - dev_state Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334030 (virt) @ffffe030 (dma) 0x000000 - rsvd[0] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334034 (virt) @ffffe034 (dma) 0x000000 - rsvd[1] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334038 (virt) @ffffe038 (dma) 0x000000 - rsvd[2] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac33403c (virt) @ffffe03c (dma) 0x000000 - rsvd[3] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: IN Endpoint 00 Context (ep_index 00): Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334040 (virt) @ffffe040 (dma) 0x000000 - ep_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334044 (virt) @ffffe044 (dma) 0x2000026 - ep_info2 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334048 (virt) @ffffe048 (dma) 0xffffd001 - deq Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334050 (virt) @ffffe050 (dma) 0x000000 - tx_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334054 (virt) @ffffe054 (dma) 0x000000 - rsvd[0] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334058 (virt) @ffffe058 (dma) 0x000000 - rsvd[1] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac33405c (virt) @ffffe05c (dma) 0x000000 - rsvd[2] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: OUT Endpoint 01 Context (ep_index 01): Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334060 (virt) @ffffe060 (dma) 0x000000 - ep_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334064 (virt) @ffffe064 (dma) 0x000000 - ep_info2 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334068 (virt) @ffffe068 (dma) 0x000000 - deq Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334070 (virt) @ffffe070 (dma) 0x000000 - tx_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334074 (virt) @ffffe074 (dma) 0x000000 - rsvd[0] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334078 (virt) @ffffe078 (dma) 0x000000 - rsvd[1] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac33407c (virt) @ffffe07c (dma) 0x000000 - rsvd[2] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: IN Endpoint 01 Context (ep_index 02): Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334080 (virt) @ffffe080 (dma) 0x000000 - ep_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334084 (virt) @ffffe084 (dma) 0x000000 - ep_info2 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334088 (virt) @ffffe088 (dma) 0x000000 - deq Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334090 (virt) @ffffe090 (dma) 0x000000 - tx_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334094 (virt) @ffffe094 (dma) 0x000000 - rsvd[0] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334098 (virt) @ffffe098 (dma) 0x000000 - rsvd[1] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac33409c (virt) @ffffe09c (dma) 0x000000 - rsvd[2] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: // Ding dong! Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Successful setup address command Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Op regs DCBAA ptr = 0x000000ffffa000 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Slot ID 1 dcbaa entry @ffff88061e225008 = 0x000000fffff000 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Output Context DMA address = 0xfffff000 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Slot ID 1 Input Context: Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334000 (virt) @ffffe000 (dma) 0x000000 - drop flags Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334004 (virt) @ffffe004 (dma) 0x000003 - add flags Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334008 (virt) @ffffe008 (dma) 0x000000 - rsvd2[0] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac33400c (virt) @ffffe00c (dma) 0x000000 - rsvd2[1] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334010 (virt) @ffffe010 (dma) 0x000000 - rsvd2[2] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334014 (virt) @ffffe014 (dma) 0x000000 - rsvd2[3] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334018 (virt) @ffffe018 (dma) 0x000000 - rsvd2[4] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac33401c (virt) @ffffe01c (dma) 0x000000 - rsvd2[5] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Slot Context: Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334020 (virt) @ffffe020 (dma) 0x8400000 - dev_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334024 (virt) @ffffe024 (dma) 0x020000 - dev_info2 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334028 (virt) @ffffe028 (dma) 0x000000 - tt_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac33402c (virt) @ffffe02c (dma) 0x000000 - dev_state Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334030 (virt) @ffffe030 (dma) 0x000000 - rsvd[0] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334034 (virt) @ffffe034 (dma) 0x000000 - rsvd[1] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334038 (virt) @ffffe038 (dma) 0x000000 - rsvd[2] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac33403c (virt) @ffffe03c (dma) 0x000000 - rsvd[3] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: IN Endpoint 00 Context (ep_index 00): Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334040 (virt) @ffffe040 (dma) 0x000000 - ep_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334044 (virt) @ffffe044 (dma) 0x2000026 - ep_info2 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334048 (virt) @ffffe048 (dma) 0xffffd001 - deq Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334050 (virt) @ffffe050 (dma) 0x000000 - tx_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334054 (virt) @ffffe054 (dma) 0x000000 - rsvd[0] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334058 (virt) @ffffe058 (dma) 0x000000 - rsvd[1] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac33405c (virt) @ffffe05c (dma) 0x000000 - rsvd[2] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: OUT Endpoint 01 Context (ep_index 01): Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334060 (virt) @ffffe060 (dma) 0x000000 - ep_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334064 (virt) @ffffe064 (dma) 0x000000 - ep_info2 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334068 (virt) @ffffe068 (dma) 0x000000 - deq Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334070 (virt) @ffffe070 (dma) 0x000000 - tx_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334074 (virt) @ffffe074 (dma) 0x000000 - rsvd[0] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334078 (virt) @ffffe078 (dma) 0x000000 - rsvd[1] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac33407c (virt) @ffffe07c (dma) 0x000000 - rsvd[2] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: IN Endpoint 01 Context (ep_index 02): Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334080 (virt) @ffffe080 (dma) 0x000000 - ep_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334084 (virt) @ffffe084 (dma) 0x000000 - ep_info2 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334088 (virt) @ffffe088 (dma) 0x000000 - deq Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334090 (virt) @ffffe090 (dma) 0x000000 - tx_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334094 (virt) @ffffe094 (dma) 0x000000 - rsvd[0] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334098 (virt) @ffffe098 (dma) 0x000000 - rsvd[1] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac33409c (virt) @ffffe09c (dma) 0x000000 - rsvd[2] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Slot ID 1 Output Context: Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Slot Context: Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb000 (virt) @fffff000 (dma) 0x8400000 - dev_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb004 (virt) @fffff004 (dma) 0x020000 - dev_info2 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb008 (virt) @fffff008 (dma) 0x000000 - tt_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb00c (virt) @fffff00c (dma) 0x10000001 - dev_state Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb010 (virt) @fffff010 (dma) 0x000000 - rsvd[0] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb014 (virt) @fffff014 (dma) 0x000000 - rsvd[1] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb018 (virt) @fffff018 (dma) 0x000000 - rsvd[2] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb01c (virt) @fffff01c (dma) 0x000000 - rsvd[3] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: IN Endpoint 00 Context (ep_index 00): Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb020 (virt) @fffff020 (dma) 0x000001 - ep_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb024 (virt) @fffff024 (dma) 0x2000026 - ep_info2 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb028 (virt) @fffff028 (dma) 0xffffd001 - deq Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb030 (virt) @fffff030 (dma) 0x000000 - tx_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb034 (virt) @fffff034 (dma) 0x000000 - rsvd[0] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb038 (virt) @fffff038 (dma) 0x000000 - rsvd[1] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb03c (virt) @fffff03c (dma) 0x000000 - rsvd[2] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: OUT Endpoint 01 Context (ep_index 01): Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb040 (virt) @fffff040 (dma) 0x000000 - ep_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb044 (virt) @fffff044 (dma) 0x000000 - ep_info2 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb048 (virt) @fffff048 (dma) 0x000000 - deq Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb050 (virt) @fffff050 (dma) 0x000000 - tx_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb054 (virt) @fffff054 (dma) 0x000000 - rsvd[0] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb058 (virt) @fffff058 (dma) 0x000000 - rsvd[1] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb05c (virt) @fffff05c (dma) 0x000000 - rsvd[2] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: IN Endpoint 01 Context (ep_index 02): Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb060 (virt) @fffff060 (dma) 0x000000 - ep_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb064 (virt) @fffff064 (dma) 0x000000 - ep_info2 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb068 (virt) @fffff068 (dma) 0x000000 - deq Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb070 (virt) @fffff070 (dma) 0x000000 - tx_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb074 (virt) @fffff074 (dma) 0x000000 - rsvd[0] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb078 (virt) @fffff078 (dma) 0x000000 - rsvd[1] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb07c (virt) @fffff07c (dma) 0x000000 - rsvd[2] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Internal device address = 1 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Endpoint 0x0 ep reset callback called Feb 18 17:25:58 usb 2-2: new SuperSpeed USB device number 2 using xhci_hcd Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Waiting for status stage event Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Waiting for status stage event Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Waiting for status stage event Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Waiting for status stage event Feb 18 17:25:58 usb 2-2: New USB device found, idVendor=0951, idProduct=1666 Feb 18 17:25:58 usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 18 17:25:58 usb 2-2: Product: DataTraveler 3.0 Feb 18 17:25:58 usb 2-2: Manufacturer: Kingston Feb 18 17:25:58 usb 2-2: SerialNumber: ..... Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: add ep 0x81, slot id 1, new drop flags = 0x0, new add flags = 0x8 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: add ep 0x2, slot id 1, new drop flags = 0x0, new add flags = 0x18 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: xhci_check_bandwidth called for udev ffff8800aca8e800 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: New Input Control Context: Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334000 (virt) @ffffe000 (dma) 0x000000 - drop flags Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334004 (virt) @ffffe004 (dma) 0x000019 - add flags Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334008 (virt) @ffffe008 (dma) 0x000000 - rsvd2[0] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac33400c (virt) @ffffe00c (dma) 0x000000 - rsvd2[1] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334010 (virt) @ffffe010 (dma) 0x000000 - rsvd2[2] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334014 (virt) @ffffe014 (dma) 0x000000 - rsvd2[3] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334018 (virt) @ffffe018 (dma) 0x000000 - rsvd2[4] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac33401c (virt) @ffffe01c (dma) 0x000000 - rsvd2[5] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Slot Context: Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334020 (virt) @ffffe020 (dma) 0x20400000 - dev_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334024 (virt) @ffffe024 (dma) 0x020000 - dev_info2 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334028 (virt) @ffffe028 (dma) 0x000000 - tt_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac33402c (virt) @ffffe02c (dma) 0x000000 - dev_state Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334030 (virt) @ffffe030 (dma) 0x000000 - rsvd[0] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334034 (virt) @ffffe034 (dma) 0x000000 - rsvd[1] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334038 (virt) @ffffe038 (dma) 0x000000 - rsvd[2] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac33403c (virt) @ffffe03c (dma) 0x000000 - rsvd[3] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: IN Endpoint 00 Context (ep_index 00): Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334040 (virt) @ffffe040 (dma) 0x000000 - ep_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334044 (virt) @ffffe044 (dma) 0x2000026 - ep_info2 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334048 (virt) @ffffe048 (dma) 0xffffd001 - deq Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334050 (virt) @ffffe050 (dma) 0x000000 - tx_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334054 (virt) @ffffe054 (dma) 0x000000 - rsvd[0] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334058 (virt) @ffffe058 (dma) 0x000000 - rsvd[1] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac33405c (virt) @ffffe05c (dma) 0x000000 - rsvd[2] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: OUT Endpoint 01 Context (ep_index 01): Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334060 (virt) @ffffe060 (dma) 0x000000 - ep_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334064 (virt) @ffffe064 (dma) 0x000000 - ep_info2 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334068 (virt) @ffffe068 (dma) 0x000000 - deq Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334070 (virt) @ffffe070 (dma) 0x000000 - tx_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334074 (virt) @ffffe074 (dma) 0x000000 - rsvd[0] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334078 (virt) @ffffe078 (dma) 0x000000 - rsvd[1] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac33407c (virt) @ffffe07c (dma) 0x000000 - rsvd[2] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: IN Endpoint 01 Context (ep_index 02): Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334080 (virt) @ffffe080 (dma) 0x000000 - ep_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334084 (virt) @ffffe084 (dma) 0x4000336 - ep_info2 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334088 (virt) @ffffe088 (dma) 0xfffe2001 - deq Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334090 (virt) @ffffe090 (dma) 0x000000 - tx_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334094 (virt) @ffffe094 (dma) 0x000000 - rsvd[0] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac334098 (virt) @ffffe098 (dma) 0x000000 - rsvd[1] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac33409c (virt) @ffffe09c (dma) 0x000000 - rsvd[2] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: OUT Endpoint 02 Context (ep_index 03): Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac3340a0 (virt) @ffffe0a0 (dma) 0x000000 - ep_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac3340a4 (virt) @ffffe0a4 (dma) 0x4000316 - ep_info2 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac3340a8 (virt) @ffffe0a8 (dma) 0xfffe0001 - deq Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac3340b0 (virt) @ffffe0b0 (dma) 0x000000 - tx_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac3340b4 (virt) @ffffe0b4 (dma) 0x000000 - rsvd[0] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac3340b8 (virt) @ffffe0b8 (dma) 0x000000 - rsvd[1] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff8805ac3340bc (virt) @ffffe0bc (dma) 0x000000 - rsvd[2] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: // Ding dong! Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Successful Endpoint Configure command Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Output context after successful config ep cmd: Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Slot Context: Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb000 (virt) @fffff000 (dma) 0x20400000 - dev_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb004 (virt) @fffff004 (dma) 0x020000 - dev_info2 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb008 (virt) @fffff008 (dma) 0x000000 - tt_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb00c (virt) @fffff00c (dma) 0x18000001 - dev_state Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb010 (virt) @fffff010 (dma) 0x000000 - rsvd[0] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb014 (virt) @fffff014 (dma) 0x000000 - rsvd[1] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb018 (virt) @fffff018 (dma) 0x000000 - rsvd[2] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb01c (virt) @fffff01c (dma) 0x000000 - rsvd[3] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: IN Endpoint 00 Context (ep_index 00): Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb020 (virt) @fffff020 (dma) 0x000001 - ep_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb024 (virt) @fffff024 (dma) 0x2000026 - ep_info2 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb028 (virt) @fffff028 (dma) 0xffffd001 - deq Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb030 (virt) @fffff030 (dma) 0x000000 - tx_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb034 (virt) @fffff034 (dma) 0x000000 - rsvd[0] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb038 (virt) @fffff038 (dma) 0x000000 - rsvd[1] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb03c (virt) @fffff03c (dma) 0x000000 - rsvd[2] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: OUT Endpoint 01 Context (ep_index 01): Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb040 (virt) @fffff040 (dma) 0x000000 - ep_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb044 (virt) @fffff044 (dma) 0x000000 - ep_info2 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb048 (virt) @fffff048 (dma) 0x000000 - deq Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb050 (virt) @fffff050 (dma) 0x000000 - tx_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb054 (virt) @fffff054 (dma) 0x000000 - rsvd[0] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb058 (virt) @fffff058 (dma) 0x000000 - rsvd[1] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb05c (virt) @fffff05c (dma) 0x000000 - rsvd[2] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: IN Endpoint 01 Context (ep_index 02): Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb060 (virt) @fffff060 (dma) 0x000001 - ep_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb064 (virt) @fffff064 (dma) 0x4000336 - ep_info2 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb068 (virt) @fffff068 (dma) 0xfffe2001 - deq Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb070 (virt) @fffff070 (dma) 0x000000 - tx_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb074 (virt) @fffff074 (dma) 0x000000 - rsvd[0] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb078 (virt) @fffff078 (dma) 0x000000 - rsvd[1] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb07c (virt) @fffff07c (dma) 0x000000 - rsvd[2] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: OUT Endpoint 02 Context (ep_index 03): Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb080 (virt) @fffff080 (dma) 0x000001 - ep_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb084 (virt) @fffff084 (dma) 0x4000316 - ep_info2 Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb088 (virt) @fffff088 (dma) 0xfffe0001 - deq Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb090 (virt) @fffff090 (dma) 0x000000 - tx_info Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb094 (virt) @fffff094 (dma) 0x000000 - rsvd[0] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb098 (virt) @fffff098 (dma) 0x000000 - rsvd[1] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: @ffff880526eeb09c (virt) @fffff09c (dma) 0x000000 - rsvd[2] Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Endpoint 0x81 ep reset callback called Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: Endpoint 0x2 ep reset callback called Feb 18 17:25:58 usb-storage 2-2:1.0: USB Mass Storage device detected Feb 18 17:25:58 scsi host6: usb-storage 2-2:1.0 Feb 18 17:25:58 usbcore: registered new interface driver usb-storage Feb 18 17:25:58 xhci_hcd 0000:0e:00.0: xhci_hub_status_data: stopping port polling. Feb 18 17:25:59 scsi 6:0:0:0: Direct-Access Kingston DataTraveler 3.0 PMAP PQ: 0 ANSI: 6 Feb 18 17:25:59 sd 6:0:0:0: Attached scsi generic sg2 type 0 Feb 18 17:25:59 sd 6:0:0:0: [sdc] 121110528 512-byte logical blocks: (62.0 GB/57.7 GiB) Feb 18 17:25:59 xhci_hcd 0000:0e:00.0: ep 0x81 - asked for 192 bytes, 122 bytes untransferred Feb 18 17:25:59 xhci_hcd 0000:0e:00.0: Giveback URB ffff8805fee3d840, len = 70, expected = 192, status = -121 Feb 18 17:25:59 sd 6:0:0:0: [sdc] Write Protect is off Feb 18 17:25:59 sd 6:0:0:0: [sdc] Mode Sense: 45 00 00 00 Feb 18 17:25:59 xhci_hcd 0000:0e:00.0: ep 0x81 - asked for 192 bytes, 122 bytes untransferred Feb 18 17:25:59 xhci_hcd 0000:0e:00.0: Giveback URB ffff8805fee3d840, len = 70, expected = 192, status = -121 Feb 18 17:25:59 sd 6:0:0:0: [sdc] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Feb 18 17:25:59 xhci_hcd 0000:0e:00.0: ep 0x81 - asked for 96 bytes, 78 bytes untransferred Feb 18 17:25:59 xhci_hcd 0000:0e:00.0: Giveback URB ffff88056b451600, len = 18, expected = 96, status = -121 Feb 18 17:25:59 xhci_hcd 0000:0e:00.0: ep 0x81 - asked for 192 bytes, 122 bytes untransferred Feb 18 17:25:59 xhci_hcd 0000:0e:00.0: Giveback URB ffff8805ac217b40, len = 70, expected = 192, status = -121 Feb 18 17:25:59 xhci_hcd 0000:0e:00.0: ep 0x81 - asked for 192 bytes, 122 bytes untransferred Feb 18 17:25:59 xhci_hcd 0000:0e:00.0: Giveback URB ffff8805ac217b40, len = 70, expected = 192, status = -121 Feb 18 17:25:59 sdc: sdc1 Feb 18 17:25:59 xhci_hcd 0000:0e:00.0: ep 0x81 - asked for 192 bytes, 122 bytes untransferred Feb 18 17:25:59 xhci_hcd 0000:0e:00.0: Giveback URB ffff8800b9e00900, len = 70, expected = 192, status = -121 Feb 18 17:25:59 xhci_hcd 0000:0e:00.0: ep 0x81 - asked for 192 bytes, 122 bytes untransferred Feb 18 17:25:59 xhci_hcd 0000:0e:00.0: Giveback URB ffff8805ac217b40, len = 70, expected = 192, status = -121 Feb 18 17:25:59 sd 6:0:0:0: [sdc] Attached SCSI removable disk
