usb/83863: Communication problem between opensc/openct via USB with e-gate smart-card.

Mohacsi Janos mohacsi at niif.hu
Thu Aug 4 22:30:15 GMT 2005 

The following reply was made to PR usb/83863; it has been noted by GNATS.

From: Mohacsi Janos <mohacsi at niif.hu>
To: Hans Petter Selasky <hselasky at c2i.net>
Cc: bug-followup at freebsd.org, janos.mohacsi at bsd.hu
Subject: Re: usb/83863: Communication problem between opensc/openct via USB
 with e-gate smart-card.
Date: Fri, 5 Aug 2005 00:21:41 +0200 (CEST)

 On Thu, 4 Aug 2005, Hans Petter Selasky wrote:
 
 >
 >
 > Post debugging output again.
 
 Here it is:
 debug log of ifdhandler:
 
 Aug  5 00:01:24 scone ifdhandler[930]: eg_card_status: slot=0
 Aug  5 00:01:44 scone last message repeated 20 times
 Aug  5 00:01:44 scone ifdhandler[930]: ifdhandler_process: ifdhandler_process(cmd=CT_CMD_RESET, unit=0)
 Aug  5 00:01:44 scone ifdhandler[930]: eg_card_reset: called.
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_usb_control: usb req type=x40 req=x90 val=x0000 ind=x0000 len=0
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_sysdep_usb_control: BSD: ifd_sysdep_usb_control(0x90)
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_sysdep_usb_control: BSD: CTRL bmRequestType 0x40 bRequest 0x90 wValue 0x0 wIndex 0x0 wLength 0x0
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_sysdep_usb_control: BSD: ctrl.ucr_data == NULL 
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_sysdep_usb_control: BSD: ifd_sysdep_usb_control(0xa0)
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_sysdep_usb_control: BSD: CTRL bmRequestType 0xc0 bRequest 0xa0 wValue 0x0 wIndex 0x0 wLength 0x1
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_sysdep_usb_control: BSD: CTRL SEND data  00
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_sysdep_usb_control: BSD: CTRL RECV data  00
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_usb_control: usb req type=xc0 req=xa0 val=x0000 ind=x0000 len=1
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_usb_control: recv  00
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_sysdep_usb_control: BSD: ifd_sysdep_usb_control(0x83)
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_sysdep_usb_control: BSD: CTRL bmRequestType 0xc0 bRequest 0x83 wValue 0x0 wIndex 0x0 wLength 0x23
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_sysdep_usb_control: BSD: CTRL SEND data  00 00 00 00 00 00 00 00 00 01 07 28 00 00 00 01 64 ca 56 0d f1 83 07 28 00 01 07 28 70 bf bf bf 06 00 00
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_sysdep_usb_control: BSD: CTRL RECV data  3b 95 18 40 ff 62 01 02 01 04
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_usb_control: usb req type=xc0 req=x83 val=x0000 ind=x0000 len=10
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_usb_control: recv  3b 95 18 40 ff 62 01 02 01 04
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_protocol_select: atr= 3b 95 18 40 ff 62 01 02 01 04
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_protocol_select: default T=0, supported protocols=0x1
 Aug  5 00:01:44 scone ifdhandler[930]: eg_set_protocol: proto=0
 Aug  5 00:01:44 scone ifdhandler[930]: ifdhandler_process: ifdhandler_process(cmd=CT_CMD_LOCK, unit=0)
 Aug  5 00:01:44 scone ifdhandler[930]: ifdhandler_lock: granted excl lock 0 for slot 0 by uid=4294967294
 Aug  5 00:01:44 scone ifdhandler[930]: ifdhandler_process: ifdhandler_process(cmd=CT_CMD_TRANSACT, unit=0)
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_protocol_transceive: cmd:  c0 a4 00 00 02 3f 00
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_sysdep_usb_control: BSD: ifd_sysdep_usb_control(0xa0)
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_sysdep_usb_control: BSD: CTRL bmRequestType 0xc0 bRequest 0xa0 wValue 0x0 wIndex 0x0 wLength 0x1
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_sysdep_usb_control: BSD: CTRL SEND data  00
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_sysdep_usb_control: BSD: CTRL RECV data  00
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_usb_control: usb req type=xc0 req=xa0 val=x0000 ind=x0000 len=1
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_usb_control: recv  00
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_usb_control: usb req type=x40 req=x80 val=x0000 ind=x0000 len=5
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_usb_control: send  c0 a4 00 00 02
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_sysdep_usb_control: BSD: ifd_sysdep_usb_control(0x80)
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_sysdep_usb_control: BSD: CTRL bmRequestType 0x40 bRequest 0x80 wValue 0x0 wIndex 0x0 wLength 0x5
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_sysdep_usb_control: BSD: CTRL SEND data  c0 a4 00 00 02
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_sysdep_usb_control: USB_DO_REQUEST failed: -1
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_sysdep_usb_control: trying to clear stall ...
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_sysdep_usb_control: USB_DO_REQUEST failed: -1
 Aug  5 00:01:44 scone ifdhandler[930]: ifd_protocol_transceive: transceive error: Communication error
 Aug  5 00:01:44 scone ifdhandler[930]: ifdhandler_unlock_all: released excl lock 0 for slot 0 by uid=4294967294
 Aug  5 00:01:45 scone ifdhandler[930]: eg_card_status: slot=0
 
 kernel debug messages:
 Aug  5 00:01:44 scone kernel: uhci_root_intr_start: xfer=0xc1beac00 len=32
 Aug  5 00:01:44 scone kernel: uhci_device_ctrl_start: type=0x40, request=0x90, wValue=0x0000, wIndex=0x0000 len=0, addr=126, endpt=0
 Aug  5 00:01:44 scone kernel: uhci_setup_standard_chain: addr=126 endpt=0 len=8 speed=1
 Aug  5 00:01:44 scone kernel: uhci_setup_standard_chain: nexttog=1; data before transfer:
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bc21e0) at 003161e4 = link=0x00316204 status=0x1c8003ff token=0x00e07e2d buffer=0x003161d4
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 316204<VF> 1c8003ff<ACTIVE,LS>,errcnt=3,actlen=0 pid=2d,addr=126,endpt=0,D=0,maxlen=8
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bc2200) at 00316204 = link=0x00000001 status=0x3d8003ff token=0xffe87e69 buffer=0x00000000
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1<T> 3d8003ff<ACTIVE,IOC,LS,SPD>,errcnt=3,actlen=0 pid=69,addr=126,endpt=0,D=1,maxlen=0
 Aug  5 00:01:44 scone kernel: _uhci_append_qh: 0xc1bc2260 to 0xdd6c5000
 Aug  5 00:01:44 scone kernel: uhci_non_isoc_done: xfer=0xc1bc2000 pipe=0xc1bea404 transfer done
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bc21e0) at 003161e4 = link=0x00316204 status=0x1c000007 token=0x00e07e2d buffer=0x003161d4
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 316204<VF> 1c000007<LS>,errcnt=3,actlen=8 pid=2d,addr=126,endpt=0,D=0,maxlen=8
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bc2200) at 00316204 = link=0x00000001 status=0x3d0007ff token=0xffe87e69 buffer=0x00000000
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1<T> 3d0007ff<IOC,LS,SPD>,errcnt=3,actlen=0 pid=69,addr=126,endpt=0,D=1,maxlen=0
 Aug  5 00:01:44 scone kernel: uhci_non_isoc_done: actlen=8
 Aug  5 00:01:44 scone kernel: uhci_device_done: xfer=0xc1bc2000, pipe=0xc1bea404 length=8 error=0
 Aug  5 00:01:44 scone kernel: _uhci_remove_qh: 0xc1bc2260 from 0xc1bc2260
 Aug  5 00:01:44 scone kernel: uhci_device_done: xfer=0xc1bc2000, pipe=0xc1bea404 length=8 error=5
 Aug  5 00:01:44 scone kernel: _uhci_remove_qh: 0xc1bc2260 from 0xdd6c5000
 Aug  5 00:01:44 scone kernel: uhci_device_ctrl_start: type=0xc0, request=0xa0, wValue=0x0000, wIndex=0x0000 len=1, addr=126, endpt=0
 Aug  5 00:01:44 scone kernel: uhci_setup_standard_chain: addr=126 endpt=0 len=9 speed=1
 Aug  5 00:01:44 scone kernel: uhci_setup_standard_chain: nexttog=1; data before transfer:
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bdfde0) at 1f073de4 = link=0x1f073e04 status=0x1c8003ff token=0x00e07e2d buffer=0x1f073dd4
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f073e04<VF> 1c8003ff<ACTIVE,LS>,errcnt=3,actlen=0 pid=2d,addr=126,endpt=0,D=0,maxlen=8
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bdfe00) at 1f073e04 = link=0x1f073e24 status=0x3c8003ff token=0x00087e69 buffer=0x1f073ddc
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f073e24<VF> 3c8003ff<ACTIVE,LS,SPD>,errcnt=3,actlen=0 pid=69,addr=126,endpt=0,D=1,maxlen=1
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bdfe20) at 1f073e24 = link=0x00000001 status=0x3d8003ff token=0xffe87ee1 buffer=0x00000000
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1<T> 3d8003ff<ACTIVE,IOC,LS,SPD>,errcnt=3,actlen=0 pid=e1,addr=126,endpt=0,D=1,maxlen=0
 Aug  5 00:01:44 scone kernel: _uhci_append_qh: 0xc1bdfe60 to 0xdd6c5000
 Aug  5 00:01:44 scone kernel: uhci_non_isoc_done: xfer=0xc1bdfc00 pipe=0xc1bea404 transfer done
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bdfde0) at 1f073de4 = link=0x1f073e04 status=0x1c000007 token=0x00e07e2d buffer=0x1f073dd4
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f073e04<VF> 1c000007<LS>,errcnt=3,actlen=8 pid=2d,addr=126,endpt=0,D=0,maxlen=8
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bdfe00) at 1f073e04 = link=0x1f073e24 status=0x3c000000 token=0x00087e69 buffer=0x1f073ddc
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f073e24<VF> 3c000000<LS,SPD>,errcnt=3,actlen=1 pid=69,addr=126,endpt=0,D=1,maxlen=1
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bdfe20) at 1f073e24 = link=0x00000001 status=0x3d0007ff token=0xffe87ee1 buffer=0x00000000
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1<T> 3d0007ff<IOC,LS,SPD>,errcnt=3,actlen=0 pid=e1,addr=126,endpt=0,D=1,maxlen=0
 Aug  5 00:01:44 scone kernel: uhci_non_isoc_done: actlen=9
 Aug  5 00:01:44 scone kernel: uhci_device_done: xfer=0xc1bdfc00, pipe=0xc1bea404 length=9 error=0
 Aug  5 00:01:44 scone kernel: _uhci_remove_qh: 0xc1bdfe60 from 0xc1bdfe60
 Aug  5 00:01:44 scone kernel: uhci_device_done: xfer=0xc1bdfc00, pipe=0xc1bea404 length=9 error=5
 Aug  5 00:01:44 scone kernel: _uhci_remove_qh: 0xc1bdfe60 from 0xdd6c5000
 Aug  5 00:01:44 scone kernel: uhci_device_ctrl_start: type=0xc0, request=0x83, wValue=0x0000, wIndex=0x0000 len=35, addr=126, endpt=0
 Aug  5 00:01:44 scone kernel: uhci_setup_standard_chain: addr=126 endpt=0 len=43 speed=1
 Aug  5 00:01:44 scone kernel: uhci_setup_standard_chain: nexttog=1; data before transfer:
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bdee00) at 1f072e04 = link=0x1f072e24 status=0x1c8003ff token=0x00e07e2d buffer=0x1f072dd4
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f072e24<VF> 1c8003ff<ACTIVE,LS>,errcnt=3,actlen=0 pid=2d,addr=126,endpt=0,D=0,maxlen=8
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bdee20) at 1f072e24 = link=0x1f072e44 status=0x3c8003ff token=0x00e87e69 buffer=0x1f072ddc
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f072e44<VF> 3c8003ff<ACTIVE,LS,SPD>,errcnt=3,actlen=0 pid=69,addr=126,endpt=0,D=1,maxlen=8
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bdee40) at 1f072e44 = link=0x1f072e64 status=0x3c8003ff token=0x00e07e69 buffer=0x1f072de4
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f072e64<VF> 3c8003ff<ACTIVE,LS,SPD>,errcnt=3,actlen=0 pid=69,addr=126,endpt=0,D=0,maxlen=8
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bdee60) at 1f072e64 = link=0x1f072e84 status=0x3c8003ff token=0x00e87e69 buffer=0x1f072dec
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f072e84<VF> 3c8003ff<ACTIVE,LS,SPD>,errcnt=3,actlen=0 pid=69,addr=126,endpt=0,D=1,maxlen=8
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bdee80) at 1f072e84 = link=0x1f072ea4 status=0x3c8003ff token=0x00e07e69 buffer=0x1f072df4
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f072ea4<VF> 3c8003ff<ACTIVE,LS,SPD>,errcnt=3,actlen=0 pid=69,addr=126,endpt=0,D=0,maxlen=8
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bdeea0) at 1f072ea4 = link=0x1f072ec4 status=0x3c8003ff token=0x00487e69 buffer=0x1f072dfc
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f072ec4<VF> 3c8003ff<ACTIVE,LS,SPD>,errcnt=3,actlen=0 pid=69,addr=126,endpt=0,D=1,maxlen=3
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bdeec0) at 1f072ec4 = link=0x00000001 status=0x3d8003ff token=0xffe87ee1 buffer=0x00000000
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1<T> 3d8003ff<ACTIVE,IOC,LS,SPD>,errcnt=3,actlen=0 pid=e1,addr=126,endpt=0,D=1,maxlen=0
 Aug  5 00:01:44 scone kernel: _uhci_append_qh: 0xc1bdef00 to 0xdd6c5000
 Aug  5 00:01:44 scone kernel: uhci_check_transfer: xfer=0xc1bdec00 active
 Aug  5 00:01:44 scone kernel: uhci_non_isoc_done: xfer=0xc1bdec00 pipe=0xc1bea404 transfer done
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bdee00) at 1f072e04 = link=0x1f072e24 status=0x1c000007 token=0x00e07e2d buffer=0x1f072dd4
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f072e24<VF> 1c000007<LS>,errcnt=3,actlen=8 pid=2d,addr=126,endpt=0,D=0,maxlen=8
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bdee20) at 1f072e24 = link=0x1f072e44 status=0x3c000007 token=0x00e87e69 buffer=0x1f072ddc
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f072e44<VF> 3c000007<LS,SPD>,errcnt=3,actlen=8 pid=69,addr=126,endpt=0,D=1,maxlen=8
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bdee40) at 1f072e44 = link=0x1f072e64 status=0x3c000001 token=0x00e07e69 buffer=0x1f072de4
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f072e64<VF> 3c000001<LS,SPD>,errcnt=3,actlen=2 pid=69,addr=126,endpt=0,D=0,maxlen=8
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bdee60) at 1f072e64 = link=0x1f072e84 status=0x3c8003ff token=0x00e87e69 buffer=0x1f072dec
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f072e84<VF> 3c8003ff<ACTIVE,LS,SPD>,errcnt=3,actlen=0 pid=69,addr=126,endpt=0,D=1,maxlen=8
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bdee80) at 1f072e84 = link=0x1f072ea4 status=0x3c8003ff token=0x00e07e69 buffer=0x1f072df4
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f072ea4<VF> 3c8003ff<ACTIVE,LS,SPD>,errcnt=3,actlen=0 pid=69,addr=126,endpt=0,D=0,maxlen=8
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bdeea0) at 1f072ea4 = link=0x1f072ec4 status=0x3c8003ff token=0x00487e69 buffer=0x1f072dfc
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f072ec4<VF> 3c8003ff<ACTIVE,LS,SPD>,errcnt=3,actlen=0 pid=69,addr=126,endpt=0,D=1,maxlen=3
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bdeec0) at 1f072ec4 = link=0x00000001 status=0x3d8003ff token=0xffe87ee1 buffer=0x00000000
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1<T> 3d8003ff<ACTIVE,IOC,LS,SPD>,errcnt=3,actlen=0 pid=e1,addr=126,endpt=0,D=1,maxlen=0
 Aug  5 00:01:44 scone kernel: uhci_non_isoc_done: actlen=18
 Aug  5 00:01:44 scone kernel: uhci_device_done: xfer=0xc1bdec00, pipe=0xc1bea404 length=18 error=0
 Aug  5 00:01:44 scone kernel: _uhci_remove_qh: 0xc1bdef00 from 0xc1bdef00
 Aug  5 00:01:44 scone kernel: uhci_device_done: xfer=0xc1bdec00, pipe=0xc1bea404 length=18 error=5
 Aug  5 00:01:44 scone kernel: _uhci_remove_qh: 0xc1bdef00 from 0xdd6c5000
 Aug  5 00:01:44 scone kernel: uhci_device_ctrl_start: type=0xc0, request=0xa0, wValue=0x0000, wIndex=0x0000 len=1, addr=126, endpt=0
 Aug  5 00:01:44 scone kernel: uhci_setup_standard_chain: addr=126 endpt=0 len=9 speed=1
 Aug  5 00:01:44 scone kernel: uhci_setup_standard_chain: nexttog=1; data before transfer:
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bdd1e0) at 1f0711e4 = link=0x1f071204 status=0x1c8003ff token=0x00e07e2d buffer=0x1f0711d4
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f071204<VF> 1c8003ff<ACTIVE,LS>,errcnt=3,actlen=0 pid=2d,addr=126,endpt=0,D=0,maxlen=8
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bdd200) at 1f071204 = link=0x1f071224 status=0x3c8003ff token=0x00087e69 buffer=0x1f0711dc
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f071224<VF> 3c8003ff<ACTIVE,LS,SPD>,errcnt=3,actlen=0 pid=69,addr=126,endpt=0,D=1,maxlen=1
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bdd220) at 1f071224 = link=0x00000001 status=0x3d8003ff token=0xffe87ee1 buffer=0x00000000
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1<T> 3d8003ff<ACTIVE,IOC,LS,SPD>,errcnt=3,actlen=0 pid=e1,addr=126,endpt=0,D=1,maxlen=0
 Aug  5 00:01:44 scone kernel: _uhci_append_qh: 0xc1bdd260 to 0xdd6c5000
 Aug  5 00:01:44 scone kernel: uhci_non_isoc_done: xfer=0xc1bdd000 pipe=0xc1bea404 transfer done
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bdd1e0) at 1f0711e4 = link=0x1f071204 status=0x1c000007 token=0x00e07e2d buffer=0x1f0711d4
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f071204<VF> 1c000007<LS>,errcnt=3,actlen=8 pid=2d,addr=126,endpt=0,D=0,maxlen=8
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bdd200) at 1f071204 = link=0x1f071224 status=0x3c000000 token=0x00087e69 buffer=0x1f0711dc
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f071224<VF> 3c000000<LS,SPD>,errcnt=3,actlen=1 pid=69,addr=126,endpt=0,D=1,maxlen=1
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bdd220) at 1f071224 = link=0x00000001 status=0x3d0007ff token=0xffe87ee1 buffer=0x00000000
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1<T> 3d0007ff<IOC,LS,SPD>,errcnt=3,actlen=0 pid=e1,addr=126,endpt=0,D=1,maxlen=0
 Aug  5 00:01:44 scone kernel: uhci_non_isoc_done: actlen=9
 Aug  5 00:01:44 scone kernel: uhci_device_done: xfer=0xc1bdd000, pipe=0xc1bea404 length=9 error=0
 Aug  5 00:01:44 scone kernel: _uhci_remove_qh: 0xc1bdd260 from 0xc1bdd260
 Aug  5 00:01:44 scone kernel: uhci_device_done: xfer=0xc1bdd000, pipe=0xc1bea404 length=9 error=5
 Aug  5 00:01:44 scone kernel: _uhci_remove_qh: 0xc1bdd260 from 0xdd6c5000
 Aug  5 00:01:44 scone kernel: uhci_device_ctrl_start: type=0x40, request=0x80, wValue=0x0000, wIndex=0x0000 len=5, addr=126, endpt=0
 Aug  5 00:01:44 scone kernel: uhci_setup_standard_chain: addr=126 endpt=0 len=13 speed=1
 Aug  5 00:01:44 scone kernel: uhci_setup_standard_chain: nexttog=1; data before transfer:
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bda1f0) at 1f02e1f4 = link=0x1f02e214 status=0x1c8003ff token=0x00e07e2d buffer=0x1f02e1d4
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f02e214<VF> 1c8003ff<ACTIVE,LS>,errcnt=3,actlen=0 pid=2d,addr=126,endpt=0,D=0,maxlen=8
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bda210) at 1f02e214 = link=0x1f02e234 status=0x3c8003ff token=0x00887ee1 buffer=0x1f02e1dc
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f02e234<VF> 3c8003ff<ACTIVE,LS,SPD>,errcnt=3,actlen=0 pid=e1,addr=126,endpt=0,D=1,maxlen=5
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bda230) at 1f02e234 = link=0x00000001 status=0x3d8003ff token=0xffe87e69 buffer=0x00000000
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1<T> 3d8003ff<ACTIVE,IOC,LS,SPD>,errcnt=3,actlen=0 pid=69,addr=126,endpt=0,D=1,maxlen=0
 Aug  5 00:01:44 scone kernel: _uhci_append_qh: 0xc1bda270 to 0xdd6c5000
 Aug  5 00:01:44 scone kernel: uhci_check_transfer: xfer=0xc1bda000 active
 Aug  5 00:01:44 scone kernel: uhci_non_isoc_done: xfer=0xc1bda000 pipe=0xc1bea404 transfer done
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bda1f0) at 1f02e1f4 = link=0x1f02e214 status=0x1c000007 token=0x00e07e2d buffer=0x1f02e1d4
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f02e214<VF> 1c000007<LS>,errcnt=3,actlen=8 pid=2d,addr=126,endpt=0,D=0,maxlen=8
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bda210) at 1f02e214 = link=0x1f02e234 status=0x3c400004 token=0x00887ee1 buffer=0x1f02e1dc
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f02e234<VF> 3c400004<STALLED,LS,SPD>,errcnt=3,actlen=5 pid=e1,addr=126,endpt=0,D=1,maxlen=5
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bda230) at 1f02e234 = link=0x00000001 status=0x3d8003ff token=0xffe87e69 buffer=0x00000000
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1<T> 3d8003ff<ACTIVE,IOC,LS,SPD>,errcnt=3,actlen=0 pid=69,addr=126,endpt=0,D=1,maxlen=0
 Aug  5 00:01:44 scone kernel: uhci_non_isoc_done: actlen=13
 Aug  5 00:01:44 scone kernel: uhci_non_isoc_done: error, addr=126, endpt=0x00, status 400000<STALLED>
 Aug  5 00:01:44 scone kernel: uhci_device_done: xfer=0xc1bda000, pipe=0xc1bea404 length=13 error=22
 Aug  5 00:01:44 scone kernel: _uhci_remove_qh: 0xc1bda270 from 0xc1bda270
 Aug  5 00:01:44 scone kernel: uhci_device_done: xfer=0xc1bda000, pipe=0xc1bea404 length=13 error=5
 Aug  5 00:01:44 scone kernel: _uhci_remove_qh: 0xc1bda270 from 0xdd6c5000
 Aug  5 00:01:44 scone kernel: uhci_device_ctrl_start: type=0x02, request=0x01, wValue=0x0000, wIndex=0x0000 len=0, addr=126, endpt=0
 Aug  5 00:01:44 scone kernel: uhci_setup_standard_chain: addr=126 endpt=0 len=8 speed=1
 Aug  5 00:01:44 scone kernel: uhci_setup_standard_chain: nexttog=1; data before transfer:
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bd89e0) at 002ec9e4 = link=0x002eca04 status=0x1c8003ff token=0x00e07e2d buffer=0x002ec9d4
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 2eca04<VF> 1c8003ff<ACTIVE,LS>,errcnt=3,actlen=0 pid=2d,addr=126,endpt=0,D=0,maxlen=8
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bd8a00) at 002eca04 = link=0x00000001 status=0x1d8003ff token=0xffe87e69 buffer=0x00000000
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1<T> 1d8003ff<ACTIVE,IOC,LS>,errcnt=3,actlen=0 pid=69,addr=126,endpt=0,D=1,maxlen=0
 Aug  5 00:01:44 scone kernel: _uhci_append_qh: 0xc1bd8a60 to 0xdd6c5000
 Aug  5 00:01:44 scone ifdhandler[930]: usb_do_request failed: Input/output error (5)
 Aug  5 00:01:44 scone kernel: uhci_non_isoc_done: xfer=0xc1bd8800 pipe=0xc1bea404 transfer done
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bd89e0) at 002ec9e4 = link=0x002eca04 status=0x1c000007 token=0x00e07e2d buffer=0x002ec9d4
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 2eca04<VF> 1c000007<LS>,errcnt=3,actlen=8 pid=2d,addr=126,endpt=0,D=0,maxlen=8
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1bd8a00) at 002eca04 = link=0x00000001 status=0x1d0007ff token=0xffe87e69 buffer=0x00000000
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1<T> 1d0007ff<IOC,LS>,errcnt=3,actlen=0 pid=69,addr=126,endpt=0,D=1,maxlen=0
 Aug  5 00:01:44 scone kernel: uhci_non_isoc_done: actlen=8
 Aug  5 00:01:44 scone kernel: uhci_device_done: xfer=0xc1bd8800, pipe=0xc1bea404 length=8 error=0
 Aug  5 00:01:44 scone kernel: _uhci_remove_qh: 0xc1bd8a60 from 0xc1bd8a60
 Aug  5 00:01:44 scone kernel: uhci_device_done: xfer=0xc1bd8800, pipe=0xc1bea404 length=8 error=5
 Aug  5 00:01:44 scone kernel: _uhci_remove_qh: 0xc1bd8a60 from 0xdd6c5000
 Aug  5 00:01:44 scone kernel: uhci_device_ctrl_start: type=0x40, request=0x80, wValue=0x0000, wIndex=0x0000 len=5, addr=126, endpt=0
 Aug  5 00:01:44 scone kernel: uhci_setup_standard_chain: addr=126 endpt=0 len=13 speed=1
 Aug  5 00:01:44 scone kernel: uhci_setup_standard_chain: nexttog=1; data before transfer:
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1be05f0) at 1f0745f4 = link=0x1f074614 status=0x1c8003ff token=0x00e07e2d buffer=0x1f0745d4
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f074614<VF> 1c8003ff<ACTIVE,LS>,errcnt=3,actlen=0 pid=2d,addr=126,endpt=0,D=0,maxlen=8
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1be0610) at 1f074614 = link=0x1f074634 status=0x3c8003ff token=0x00887ee1 buffer=0x1f0745dc
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f074634<VF> 3c8003ff<ACTIVE,LS,SPD>,errcnt=3,actlen=0 pid=e1,addr=126,endpt=0,D=1,maxlen=5
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1be0630) at 1f074634 = link=0x00000001 status=0x3d8003ff token=0xffe87e69 buffer=0x00000000
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1<T> 3d8003ff<ACTIVE,IOC,LS,SPD>,errcnt=3,actlen=0 pid=69,addr=126,endpt=0,D=1,maxlen=0
 Aug  5 00:01:44 scone kernel: _uhci_append_qh: 0xc1be0670 to 0xdd6c5000
 Aug  5 00:01:44 scone kernel: uhci_check_transfer: xfer=0xc1be0400 active
 Aug  5 00:01:44 scone kernel: uhci_non_isoc_done: xfer=0xc1be0400 pipe=0xc1bea404 transfer done
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1be05f0) at 1f0745f4 = link=0x1f074614 status=0x1c000007 token=0x00e07e2d buffer=0x1f0745d4
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f074614<VF> 1c000007<LS>,errcnt=3,actlen=8 pid=2d,addr=126,endpt=0,D=0,maxlen=8
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1be0610) at 1f074614 = link=0x1f074634 status=0x3c400004 token=0x00887ee1 buffer=0x1f0745dc
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1f074634<VF> 3c400004<STALLED,LS,SPD>,errcnt=3,actlen=5 pid=e1,addr=126,endpt=0,D=1,maxlen=5
 Aug  5 00:01:44 scone kernel: uhci_dump_td: TD(0xc1be0630) at 1f074634 = link=0x00000001 status=0x3d8003ff token=0xffe87e69 buffer=0x00000000
 Aug  5 00:01:44 scone kernel: uhci_dump_td: 1<T> 3d8003ff<ACTIVE,IOC,LS,SPD>,errcnt=3,actlen=0 pid=69,addr=126,endpt=0,D=1,maxlen=0
 Aug  5 00:01:44 scone kernel: uhci_non_isoc_done: actlen=13
 Aug  5 00:01:44 scone kernel: uhci_non_isoc_done: error, addr=126, endpt=0x00, status 400000<STALLED>
 Aug  5 00:01:44 scone kernel: uhci_device_done: xfer=0xc1be0400, pipe=0xc1bea404 length=13 error=22
 Aug  5 00:01:44 scone kernel: _uhci_remove_qh: 0xc1be0670 from 0xc1be0670
 Aug  5 00:01:44 scone kernel: uhci_device_done: xfer=0xc1be0400, pipe=0xc1bea404 length=13 error=5
 Aug  5 00:01:44 scone kernel: _uhci_remove_qh: 0xc1be0670 from 0xdd6c5000
 Aug  5 00:01:44 scone ifdhandler[930]: usb_do_request failed: Input/output error (5)
 Aug  5 00:01:44 scone kernel: uhci_root_intr_start: xfer=0xc1bdb000 len=32
 Aug  5 00:01:44 scone kernel: uhci_root_intr_start: xfer=0xc1be6c00 len=32
 
 ???
 
 Janos Mohacsi
 Network Engineer, Research Associate
 NIIF/HUNGARNET, HUNGARY
 Key 00F9AF98: 8645 1312 D249 471B DBAE  21A2 9F52 0D1F 00F9 AF98

More information about the freebsd-usb mailing list