Sunday, January 30, 2022

umass1 takes unusually long time to detach

OpenBSD 7.0 on Thinkpad T480 (thunderbolt disabled)
When a device in storage mode (Pocketbook X) is connected, it's not
functioning properly and takes a long time to detach.
It also blocks all other devices to be processed as detached or
attached making usb ports basically unusable.
Devices that are still connected are working normally though.

/sys/arch/amd64/conf/GENERIC.MP:
# $OpenBSD: GENERIC.MP,v 1.16 2021/02/09 14:06:19 patrick Exp $

include "arch/amd64/conf/GENERIC"

option MULTIPROCESSOR
option EHCI_DEBUG
option UHCI_DEBUG
option XHCI_DEBUG
option OHCI_DEBUG
option USB_DEBUG
option UMASS_DEBUG
option UHUB_DEBUG
#option MP_LOCKDEBUG
#option WITNESS

cpu* at mainbus?

usbdevs -vvv:
addr 01: 8086:0000 Intel, xHCI root hub
super speed, self powered, config 1, rev 1.00
driver: uhub0
port 01: 0000.0503 connect enabled recovery
port 02: 0000.02a0 power Rx.detect
port 03: 0000.02a0 power Rx.detect
port 04: 0000.02a0 power Rx.detect
port 05: 0000.02a0 power Rx.detect
port 06: 0000.0503 connect enabled recovery
port 07: 0000.02a0 power Rx.detect
port 08: 0000.0503 connect enabled recovery
port 09: 0000.0103 connect enabled recovery
port 10: 0000.02a0 power Rx.detect
port 11: 0000.02a0 power Rx.detect
port 12: 0000.02a0 power Rx.detect
port 13: 0020.02a0 power Rx.detect
port 14: 0020.02a0 power Rx.detect
port 15: 0000.0203 connect enabled power U0
port 16: 0020.02a0 power Rx.detect
addr 02: 2cb7:0210 FIBOCOM, L830-EB-00
high speed, self powered, config 1, rev 3.33, iSerial 004999010640000
driver: umb0
driver: umodem0
addr 03: 13d3:56a6 Azurewave, Integrated Camera
high speed, power 500 mA, config 1, rev 17.11, iSerial 0001
driver: uvideo0
addr 04: 06cb:009a Synaptics, product 0x009a
full speed, power 100 mA, config 1, rev 1.64, iSerial 7e75a880e5bf
driver: ugen0
addr 05: 0bda:0316 Generic, USB3.0-CRW
super speed, power 200 mA, config 1, rev 2.04, iSerial
20120501030900000
driver: umass0
addr 06: 18d1:0001 Obreey
, PocketBook1040
high speed, self powered, config 1, rev 1.01, iSerial 20080411

dmesg:
xhci0: xhci_cmd_configure_ep dev 5
xhci0: xhci_cmd_slot_control
xhci0: short xfer 0xfffffd8441d0c690 for 129
xhci0: port=1 change=0x02
uhub0: intr status=0
uhub0: port 1 status=0x01e1 change=0x0001
xhci0: port=1 change=0x02
uhub0: intr status=0
uhub0: port 1 status=0x0503 change=0x0000
xhci0: xhci_cmd_slot_control
xhci0: dev 6, input=0xfffffd80a7d9f000 slot=0xfffffd80a7d9f020
ep0=0xfffffd80a7d9f040
xhci0: dev 6, setting DCBAA to 0x00000000a7d94000
xhci_pipe_init: pipe=0xffff80000154f000 addr=0 depth=1 port=1 speed=3
dev 6 dci 1 (epAddr=0x0)
xhci0: xhci_cmd_set_address BSR=1
xhci0: xhci_cmd_set_address BSR=0
xhci0: dev 6 addr 6
umass1 at uhub0 port 1 configuration 1 interface 0 "Obreey
PocketBook1040" rev 2.00/1.01 addr 6
umass1: using SCSI over Bulk-Only
xhci_pipe_init: pipe=0xffff80000154b000 addr=6 depth=1 port=1 speed=3
dev 6 dci 2 (epAddr=0x1)
xhci0: xhci_cmd_configure_ep dev 6
xhci_pipe_init: pipe=0xffff80000154a000 addr=6 depth=1 port=1 speed=3
dev 6 dci 3 (epAddr=0x81)
xhci0: xhci_cmd_configure_ep dev 6
scsibus5 at umass1: 2 targets, initiator 0
xhci0: port=1 change=0x02
uhub0: intr status=0
xhci0: txerr? code 4
xhci0: txerr? code 4
xhci_abort_xfer: xfer=0xfffffd839ea36478 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=47
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225010 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd839ea36360 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=49
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225020 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd8441d0c690 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=31 idx=3
xhci0: xhci_cmd_stop_ep dev 6 dci 2
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225030 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd839ea36590 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=51
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225040 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci0: port=2 change=0x04
uhub0: intr status=0
xhci_abort_xfer: xfer=0xfffffd839ea36478 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=53
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225050 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd839ea36360 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=55
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225060 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd8441d0c690 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=31 idx=4
xhci0: xhci_cmd_stop_ep dev 6 dci 2
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225070 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd839ea36590 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=57
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225080 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd839ea36478 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=59
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225090 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd839ea36360 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=61
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f2250a0 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd8441d0c690 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=31 idx=5
xhci0: xhci_cmd_stop_ep dev 6 dci 2
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f2250b0 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd839ea36590 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=63
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f2250c0 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd839ea36478 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=65
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f2250d0 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd839ea36360 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=67
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f2250e0 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd8441d0c690 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=31 idx=6
xhci0: xhci_cmd_stop_ep dev 6 dci 2
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225000 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd839ea36590 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=69
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225010 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd839ea36478 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=71
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225020 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd839ea36360 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=73
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225030 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd8441d0c690 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=31 idx=7
xhci0: xhci_cmd_stop_ep dev 6 dci 2
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225040 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd839ea36590 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=75
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225050 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd839ea36478 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=77
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225060 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd839ea36360 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=79
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225070 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd8441d0c690 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=31 idx=8
xhci0: xhci_cmd_stop_ep dev 6 dci 2
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225080 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd839ea36590 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=81
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225090 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd839ea36478 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=83
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f2250a0 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd839ea36360 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=85
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f2250b0 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd8441d0c690 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=31 idx=9
xhci0: xhci_cmd_stop_ep dev 6 dci 2
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f2250c0 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd839ea36590 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=87
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f2250d0 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd839ea36478 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=89
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f2250e0 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd839ea36360 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=91
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225000 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd8441d0c690 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=31 idx=10
xhci0: xhci_cmd_stop_ep dev 6 dci 2
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225010 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd839ea36590 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=93
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225020 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd839ea36478 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=95
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225030 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd839ea36360 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=97
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225040 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd8441d0c690 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=31 idx=11
xhci0: xhci_cmd_stop_ep dev 6 dci 2
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225050 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd839ea36590 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=99
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225060 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd839ea36478 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=101
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225070 0x13000000 0x6008400)
xhci0: error stopping endpoint
xhci_abort_xfer: xfer=0xfffffd839ea36360 status=IN_PROGRESS
err=TIMEOUT actlen=0 len=0 idx=103
xhci0: xhci_cmd_stop_ep dev 6 dci 1
xhci0: event error code=19, result=33
trb=0xffff800022f45378 (0x000000006f225080 0x13000000 0x6008400)
xhci0: error stopping endpoint
uhub0: port 2 status=0x02a0 change=0x0001
uhub0: port 1 status=0x02a0 change=0x0001
scsibus5 detached
xhci0: xhci_cmd_configure_ep dev 6
xhci0: xhci_cmd_configure_ep dev 6
umass1 detached

No comments:

Post a Comment