USB2 + ucom + UHCI: still not happy

Andrew Thompson thompsa at FreeBSD.org
Thu Jan 22 13:29:55 PST 2009 

On Thu, Jan 22, 2009 at 10:09:50PM +0100, Hans Petter Selasky wrote:
> On Thursday 22 January 2009, Andrew Thompson wrote:
> > On Thu, Jan 22, 2009 at 04:24:12PM +0100, Hans Petter Selasky wrote:
> > > Hi,
> > >
> > > Try these patches on a clean -current. Tested with a similar device of
> > > yours!
> > >
> > > http://perforce.freebsd.org/chv.cgi?CH=156521
> > > http://perforce.freebsd.org/chv.cgi?CH=156522
> >
> > I am still seeing the UHCI attach issue with these patches applied.
> >
> > usb2_alloc_device:1401: set address 2 failed (ignored)
> > usb2_alloc_device:1436: getting device descriptor at addr 2 failed!
> > usb2_req_re_enumerate:1366: addr=2, set address failed! (ignored)
> > usb2_req_re_enumerate:1379: getting device descriptor at addr 2 failed!
> > usb2_req_re_enumerate:1366: addr=2, set address failed! (ignored)
> > usb2_req_re_enumerate:1379: getting device descriptor at addr 2 failed!
> > ugen1.2: <> at usbus1 (disconnected)
> > uhub_reattach_port:413: could not allocate new device!
>
> You can try editing "uhci2.c" and change the "if ()" in:
> 
> static void
> uhci_set_hw_power(struct usb2_bus *bus)
> 
> to "if (1)". That will prevent the USB schedule from ever stopping.

No change, log attached.
-------------- next part --------------
uhci_set_hw_power:3335: 
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0001
uhci_device_done:1825: xfer=0xc44ed8b8, pipe=0xc44e8380, error=0
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002
uhci_device_done:1825: xfer=0xc44ed8b8, pipe=0xc44e8380, error=0
uhci_set_hw_power:3335: 
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0001
uhci_device_done:1825: xfer=0xc44ed0b8, pipe=0xc44e7380, error=0
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002
uhci_device_done:1825: xfer=0xc44ed0b8, pipe=0xc44e7380, error=0
uhci_set_hw_power:3335: 
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0001
uhci_device_done:1825: xfer=0xc44ee0b8, pipe=0xc44e6380, error=0
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002
uhci_device_done:1825: xfer=0xc44ee0b8, pipe=0xc44e6380, error=0
uhci_set_hw_power:3335: 
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0001
uhci_device_done:1825: xfer=0xc44ef0b8, pipe=0xc44e5380, error=0
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002
uhci_device_done:1825: xfer=0xc44ef0b8, pipe=0xc44e5380, error=0
uhci_set_hw_power:3335: 
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0001
uhci_device_done:1825: xfer=0xc44ed8b8, pipe=0xc44e8380, error=0
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002
uhci_device_done:1825: xfer=0xc44ed8b8, pipe=0xc44e8380, error=0
uhci_set_hw_power:3335: 
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0001
uhci_device_done:1825: xfer=0xc44ed0b8, pipe=0xc44e7380, error=0
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002
uhci_device_done:1825: xfer=0xc44ed0b8, pipe=0xc44e7380, error=0
uhci_set_hw_power:3335: 
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0001
uhci_device_done:1825: xfer=0xc44ee0b8, pipe=0xc44e6380, error=0
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002
uhci_device_done:1825: xfer=0xc44ee0b8, pipe=0xc44e6380, error=0
uhci_set_hw_power:3335: 
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0001
uhci_device_done:1825: xfer=0xc44ef0b8, pipe=0xc44e5380, error=0
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002
uhci_device_done:1825: xfer=0xc44ef0b8, pipe=0xc44e5380, error=0
uhci_device_done:1825: xfer=0xc4361cb8, pipe=0xc44e73a8, error=0
uhci_set_hw_power:3335: 
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0001
uhci_device_done:1825: xfer=0xc44ed0b8, pipe=0xc44e7380, error=0
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002
uhci_device_done:1825: xfer=0xc44ed0b8, pipe=0xc44e7380, error=0
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0x23 request=0x01 wLen=0x0000 wValue=0x0010 wIndex=0x0002
uhci_root_ctrl_done:2673: UR_CLEAR_PORT_FEATURE port=2 feature=16
uhci_device_done:1825: xfer=0xc44ed0b8, pipe=0xc44e7380, error=0
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002
uhci_device_done:1825: xfer=0xc44ed0b8, pipe=0xc44e7380, error=0
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0x23 request=0x03 wLen=0x0000 wValue=0x0004 wIndex=0x0002
uhci_portreset:2429: uhci port 2 reset, status0 = 0x0280
uhci_portreset:2442: uhci port 2 reset, status1 = 0x0093
uhci_portreset:2460: uhci port 2 iteration 0, status = 0x0097
uhci_portreset:2460: uhci port 2 iteration 1, status = 0x0095
uhci_portreset:2498: uhci port 2 reset, status2 = 0x0095
uhci_device_done:1825: xfer=0xc44ed0b8, pipe=0xc44e7380, error=0
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002
uhci_device_done:1825: xfer=0xc44ed0b8, pipe=0xc44e7380, error=0
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0x23 request=0x01 wLen=0x0000 wValue=0x0014 wIndex=0x0002
uhci_root_ctrl_done:2673: UR_CLEAR_PORT_FEATURE port=2 feature=20
uhci_device_done:1825: xfer=0xc44ed0b8, pipe=0xc44e7380, error=0
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002
uhci_device_done:1825: xfer=0xc44ed0b8, pipe=0xc44e7380, error=0
uhci_pipe_init:3184: pipe=0xc44c5380, addr=0, endpt=0, mode=0 (1)
uhci_set_hw_power:3335: 
uhci_setup_standard_chain:1662: addr=0 endpt=0 sumlen=8 speed=2
uhci_setup_standard_chain:1804: nexttog=0; data before transfer:
TD(0xe67a48d0) at 0x096578d4 = link=0x00000001 status=0x198003ff token=0x00e0002d buffer=0x01b1a168
TD(0xe67a48d0) td_next=-T td_status=-ACTIVE-IOC, errcnt=3, actlen=0 pid=2d,addr=0,endpt=0,D=0,maxlen=8
_uhci_append_qh:919: 0xc432f280 to 0xc42dab80
uhci_check_transfer:1384: xfer=0xc431a0b8 is still active
uhci_non_isoc_done:1186: xfer=0xc431a0b8 pipe=0xc44c5380 transfer done
TD(0xe67a48d0) at 0x096578d4 = link=0x00000001 status=0x01450007 token=0x00e0002d buffer=0x01b1a168
TD(0xe67a48d0) td_next=-T td_status=-CRCTO-STALLED-IOC, errcnt=0, actlen=8 pid=2d,addr=0,endpt=0,D=0,maxlen=8
uhci_non_isoc_done_sub:1173: error, addr=0, endpt=0x00, frame=0x00 status=[CRCTO][STALLED][NOT_ACTIVE][IOC]
uhci_device_done:1825: xfer=0xc431a0b8, pipe=0xc44c5380, error=22
_uhci_remove_qh:975: 0xc432f280 from 0xc432f280
ichsmb0: irq 0x02 during -1
uhci_device_done:1825: xfer=0xc431a0b8, pipe=0xc44c5380, error=5
_uhci_remove_qh:975: 0xc432f280 from 0xc42dab80
usb2_alloc_device:1401: set address 2 failed (ignored)
uhci_setup_standard_chain:1662: addr=2 endpt=0 sumlen=16 speed=2
uhci_setup_standard_chain:1804: nexttog=1; data before transfer:
TD(0xe67a48d0) at 0x096768d4 = link=0x096768a4 status=0x188003ff token=0x00e0022d buffer=0x01b1a168
TD(0xe67a48d0) td_next=-VF td_status=-ACTIVE, errcnt=3, actlen=0 pid=2d,addr=2,endpt=0,D=0,maxlen=8
TD(0xe67a48a0) at 0x096768a4 = link=0x09676874 status=0x388003ff token=0x00e80269 buffer=0x01b1a170
TD(0xe67a48a0) td_next=-VF td_status=-ACTIVE-SPD, errcnt=3, actlen=0 pid=69,addr=2,endpt=0,D=1,maxlen=8
TD(0xe67a4870) at 0x09676874 = link=0x00000001 status=0x198003ff token=0xffe802e1 buffer=0x00000000
TD(0xe67a4870) td_next=-T td_status=-ACTIVE-IOC, errcnt=3, actlen=0 pid=e1,addr=2,endpt=0,D=1,maxlen=0
_uhci_append_qh:919: 0xc432f880 to 0xc42dab80
uhci_check_transfer:1384: xfer=0xc431a0b8 is still active
uhci_non_isoc_done:1186: xfer=0xc431a0b8 pipe=0xc44c5380 transfer done
TD(0xe67a48d0) at 0x096768d4 = link=0x096768a4 status=0x00450007 token=0x00e0022d buffer=0x01b1a168
TD(0xe67a48d0) td_next=-VF td_status=-CRCTO-STALLED, errcnt=0, actlen=8 pid=2d,addr=2,endpt=0,D=0,maxlen=8
TD(0xe67a48a0) at 0x096768a4 = link=0x09676874 status=0x388003ff token=0x00e80269 buffer=0x01b1a170
TD(0xe67a48a0) td_next=-VF td_status=-ACTIVE-SPD, errcnt=3, actlen=0 pid=69,addr=2,endpt=0,D=1,maxlen=8
TD(0xe67a4870) at 0x09676874 = link=0x00000001 status=0x198003ff token=0xffe802e1 buffer=0x00000000
TD(0xe67a4870) td_next=-T td_status=-ACTIVE-IOC, errcnt=3, actlen=0 pid=e1,addr=2,endpt=0,D=1,maxlen=0
uhci_non_isoc_done_sub:1173: error, addr=2, endpt=0x80, frame=0x00 status=[CRCTO][STALLED][NOT_ACTIVE]
uhci_device_done:1825: xfer=0xc431a0b8, pipe=0xc44c5380, error=22
_uhci_remove_qh:975: 0xc432f880 from 0xc432f880
uhci_device_done:1825: xfer=0xc431a0b8, pipe=0xc44c5380, error=5
_uhci_remove_qh:975: 0xc432f880 from 0xc42dab80
usb2_alloc_device:1436: getting device descriptor at addr 2 failed!
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0x23 request=0x03 wLen=0x0000 wValue=0x0004 wIndex=0x0002
uhci_portreset:2429: uhci port 2 reset, status0 = 0x0288
uhci_portreset:2442: uhci port 2 reset, status1 = 0x009b
uhci_device_done:1825: xfer=0xc4361cb8, pipe=0xc44e73a8, error=0
uhci_portreset:2460: uhci port 2 iteration 0, status = 0x009f
uhci_portreset:2460: uhci port 2 iteration 1, status = 0x0095
uhci_portreset:2498: uhci port 2 reset, status2 = 0x0095
uhci_device_done:1825: xfer=0xc44ed0b8, pipe=0xc44e7380, error=0
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002
uhci_device_done:1825: xfer=0xc44ed0b8, pipe=0xc44e7380, error=0
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0x23 request=0x01 wLen=0x0000 wValue=0x0014 wIndex=0x0002
uhci_root_ctrl_done:2673: UR_CLEAR_PORT_FEATURE port=2 feature=20
uhci_device_done:1825: xfer=0xc44ed0b8, pipe=0xc44e7380, error=0
uhci_setup_standard_chain:1662: addr=0 endpt=0 sumlen=8 speed=2
uhci_setup_standard_chain:1804: nexttog=1; data before transfer:
TD(0xe67a48d0) at 0x011488d4 = link=0x00000001 status=0x198003ff token=0x00e0002d buffer=0x01b1a168
TD(0xe67a48d0) td_next=-T td_status=-ACTIVE-IOC, errcnt=3, actlen=0 pid=2d,addr=0,endpt=0,D=0,maxlen=8
_uhci_append_qh:919: 0xc432fe80 to 0xc42dab80
uhci_check_transfer:1384: xfer=0xc431a0b8 is still active
uhci_non_isoc_done:1186: xfer=0xc431a0b8 pipe=0xc44c5380 transfer done
TD(0xe67a48d0) at 0x011488d4 = link=0x00000001 status=0x01450007 token=0x00e0002d buffer=0x01b1a168
TD(0xe67a48d0) td_next=-T td_status=-CRCTO-STALLED-IOC, errcnt=0, actlen=8 pid=2d,addr=0,endpt=0,D=0,maxlen=8
uhci_non_isoc_done_sub:1173: error, addr=0, endpt=0x00, frame=0x00 status=[CRCTO][STALLED][NOT_ACTIVE][IOC]
uhci_device_done:1825: xfer=0xc431a0b8, pipe=0xc44c5380, error=22
_uhci_remove_qh:975: 0xc432fe80 from 0xc432fe80
uhci_device_done:1825: xfer=0xc431a0b8, pipe=0xc44c5380, error=5
_uhci_remove_qh:975: 0xc432fe80 from 0xc42dab80
usb2_req_re_enumerate:1366: addr=2, set address failed! (ignored)
uhci_setup_standard_chain:1662: addr=2 endpt=0 sumlen=16 speed=2
uhci_setup_standard_chain:1804: nexttog=1; data before transfer:
TD(0xe67a48d0) at 0x0847a8d4 = link=0x0847a8a4 status=0x188003ff token=0x00e0022d buffer=0x01b1a168
TD(0xe67a48d0) td_next=-VF td_status=-ACTIVE, errcnt=3, actlen=0 pid=2d,addr=2,endpt=0,D=0,maxlen=8
TD(0xe67a48a0) at 0x0847a8a4 = link=0x0847a874 status=0x388003ff token=0x00e80269 buffer=0x01b1a170
TD(0xe67a48a0) td_next=-VF td_status=-ACTIVE-SPD, errcnt=3, actlen=0 pid=69,addr=2,endpt=0,D=1,maxlen=8
TD(0xe67a4870) at 0x0847a874 = link=0x00000001 status=0x198003ff token=0xffe802e1 buffer=0x00000000
TD(0xe67a4870) td_next=-T td_status=-ACTIVE-IOC, errcnt=3, actlen=0 pid=e1,addr=2,endpt=0,D=1,maxlen=0
_uhci_append_qh:919: 0xc4318700 to 0xc42dab80
uhci_check_transfer:1384: xfer=0xc431a0b8 is still active
uhci_non_isoc_done:1186: xfer=0xc431a0b8 pipe=0xc44c5380 transfer done
TD(0xe67a48d0) at 0x0847a8d4 = link=0x0847a8a4 status=0x00450007 token=0x00e0022d buffer=0x01b1a168
TD(0xe67a48d0) td_next=-VF td_status=-CRCTO-STALLED, errcnt=0, actlen=8 pid=2d,addr=2,endpt=0,D=0,maxlen=8
TD(0xe67a48a0) at 0x0847a8a4 = link=0x0847a874 status=0x388003ff token=0x00e80269 buffer=0x01b1a170
TD(0xe67a48a0) td_next=-VF td_status=-ACTIVE-SPD, errcnt=3, actlen=0 pid=69,addr=2,endpt=0,D=1,maxlen=8
TD(0xe67a4870) at 0x0847a874 = link=0x00000001 status=0x198003ff token=0xffe802e1 buffer=0x00000000
TD(0xe67a4870) td_next=-T td_status=-ACTIVE-IOC, errcnt=3, actlen=0 pid=e1,addr=2,endpt=0,D=1,maxlen=0
uhci_non_isoc_done_sub:1173: error, addr=2, endpt=0x80, frame=0x00 status=[CRCTO][STALLED][NOT_ACTIVE]
uhci_device_done:1825: xfer=0xc431a0b8, pipe=0xc44c5380, error=22
_uhci_remove_qh:975: 0xc4318700 from 0xc4318700
uhci_device_done:1825: xfer=0xc431a0b8, pipe=0xc44c5380, error=5
_uhci_remove_qh:975: 0xc4318700 from 0xc42dab80
usb2_req_re_enumerate:1379: getting device descriptor at addr 2 failed!
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0x23 request=0x03 wLen=0x0000 wValue=0x0004 wIndex=0x0002
uhci_portreset:2429: uhci port 2 reset, status0 = 0x0288
uhci_set_hw_power:3335: 
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0001
uhci_device_done:1825: xfer=0xc44ed8b8, pipe=0xc44e8380, error=0
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002
uhci_device_done:1825: xfer=0xc44ed8b8, pipe=0xc44e8380, error=0
uhci_set_hw_power:3335: 
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0001
uhci_device_done:1825: xfer=0xc44ee0b8, pipe=0xc44e6380, error=0
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002
uhci_device_done:1825: xfer=0xc44ee0b8, pipe=0xc44e6380, error=0
uhci_set_hw_power:3335: 
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0001
uhci_device_done:1825: xfer=0xc44ef0b8, pipe=0xc44e5380, error=0
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002
uhci_device_done:1825: xfer=0xc44ef0b8, pipe=0xc44e5380, error=0
uhci_portreset:2442: uhci port 2 reset, status1 = 0x009b
uhci_portreset:2460: uhci port 2 iteration 0, status = 0x009f
uhci_portreset:2460: uhci port 2 iteration 1, status = 0x0095
uhci_portreset:2498: uhci port 2 reset, status2 = 0x0095
uhci_device_done:1825: xfer=0xc44ed0b8, pipe=0xc44e7380, error=0
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002
uhci_device_done:1825: xfer=0xc44ed0b8, pipe=0xc44e7380, error=0
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0x23 request=0x01 wLen=0x0000 wValue=0x0014 wIndex=0x0002
uhci_root_ctrl_done:2673: UR_CLEAR_PORT_FEATURE port=2 feature=20
uhci_device_done:1825: xfer=0xc44ed0b8, pipe=0xc44e7380, error=0
uhci_setup_standard_chain:1662: addr=0 endpt=0 sumlen=8 speed=2
uhci_setup_standard_chain:1804: nexttog=1; data before transfer:
TD(0xe67a48d0) at 0x011488d4 = link=0x00000001 status=0x198003ff token=0x00e0002d buffer=0x01b1a168
TD(0xe67a48d0) td_next=-T td_status=-ACTIVE-IOC, errcnt=3, actlen=0 pid=2d,addr=0,endpt=0,D=0,maxlen=8
_uhci_append_qh:919: 0xc432f580 to 0xc42dab80
uhci_check_transfer:1384: xfer=0xc431a0b8 is still active
uhci_non_isoc_done:1186: xfer=0xc431a0b8 pipe=0xc44c5380 transfer done
TD(0xe67a48d0) at 0x011488d4 = link=0x00000001 status=0x01450007 token=0x00e0002d buffer=0x01b1a168
TD(0xe67a48d0) td_next=-T td_status=-CRCTO-STALLED-IOC, errcnt=0, actlen=8 pid=2d,addr=0,endpt=0,D=0,maxlen=8
uhci_non_isoc_done_sub:1173: error, addr=0, endpt=0x00, frame=0x00 status=[CRCTO][STALLED][NOT_ACTIVE][IOC]
uhci_device_done:1825: xfer=0xc431a0b8, pipe=0xc44c5380, error=22
_uhci_remove_qh:975: 0xc432f580 from 0xc432f580
uhci_device_done:1825: xfer=0xc431a0b8, pipe=0xc44c5380, error=5
_uhci_remove_qh:975: 0xc432f580 from 0xc42dab80
usb2_req_re_enumerate:1366: addr=2, set address failed! (ignored)
uhci_setup_standard_chain:1662: addr=2 endpt=0 sumlen=16 speed=2
uhci_setup_standard_chain:1804: nexttog=1; data before transfer:
TD(0xe67a48d0) at 0x0847a8d4 = link=0x0847a8a4 status=0x188003ff token=0x00e0022d buffer=0x01b1a168
TD(0xe67a48d0) td_next=-VF td_status=-ACTIVE, errcnt=3, actlen=0 pid=2d,addr=2,endpt=0,D=0,maxlen=8
TD(0xe67a48a0) at 0x0847a8a4 = link=0x0847a874 status=0x388003ff token=0x00e80269 buffer=0x01b1a170
TD(0xe67a48a0) td_next=-VF td_status=-ACTIVE-SPD, errcnt=3, actlen=0 pid=69,addr=2,endpt=0,D=1,maxlen=8
TD(0xe67a4870) at 0x0847a874 = link=0x00000001 status=0x198003ff token=0xffe802e1 buffer=0x00000000
TD(0xe67a4870) td_next=-T td_status=-ACTIVE-IOC, errcnt=3, actlen=0 pid=e1,addr=2,endpt=0,D=1,maxlen=0
_uhci_append_qh:919: 0xc4330680 to 0xc42dab80
uhci_check_transfer:1384: xfer=0xc431a0b8 is still active
uhci_non_isoc_done:1186: xfer=0xc431a0b8 pipe=0xc44c5380 transfer done
TD(0xe67a48d0) at 0x0847a8d4 = link=0x0847a8a4 status=0x00450007 token=0x00e0022d buffer=0x01b1a168
TD(0xe67a48d0) td_next=-VF td_status=-CRCTO-STALLED, errcnt=0, actlen=8 pid=2d,addr=2,endpt=0,D=0,maxlen=8
TD(0xe67a48a0) at 0x0847a8a4 = link=0x0847a874 status=0x388003ff token=0x00e80269 buffer=0x01b1a170
TD(0xe67a48a0) td_next=-VF td_status=-ACTIVE-SPD, errcnt=3, actlen=0 pid=69,addr=2,endpt=0,D=1,maxlen=8
TD(0xe67a4870) at 0x0847a874 = link=0x00000001 status=0x198003ff token=0xffe802e1 buffer=0x00000000
TD(0xe67a4870) td_next=-T td_status=-ACTIVE-IOC, errcnt=3, actlen=0 pid=e1,addr=2,endpt=0,D=1,maxlen=0
uhci_non_isoc_done_sub:1173: error, addr=2, endpt=0x80, frame=0x00 status=[CRCTO][STALLED][NOT_ACTIVE]
uhci_device_done:1825: xfer=0xc431a0b8, pipe=0xc44c5380, error=22
_uhci_remove_qh:975: 0xc4330680 from 0xc4330680
uhci_device_done:1825: xfer=0xc431a0b8, pipe=0xc44c5380, error=5
_uhci_remove_qh:975: 0xc4330680 from 0xc42dab80
usb2_req_re_enumerate:1379: getting device descriptor at addr 2 failed!
ugen1.2: <> at usbus1 (disconnected)
uhub_reattach_port:413: could not allocate new device!
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0x23 request=0x01 wLen=0x0000 wValue=0x0001 wIndex=0x0002
uhci_root_ctrl_done:2673: UR_CLEAR_PORT_FEATURE port=2 feature=1
uhci_device_done:1825: xfer=0xc44ed0b8, pipe=0xc44e7380, error=0
uhci_set_hw_power:3335: 
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0001
uhci_device_done:1825: xfer=0xc44ed0b8, pipe=0xc44e7380, error=0
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002
uhci_device_done:1825: xfer=0xc44ed0b8, pipe=0xc44e7380, error=0
uhci_set_hw_power:3335: 
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0001
uhci_device_done:1825: xfer=0xc44ed8b8, pipe=0xc44e8380, error=0
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002
uhci_device_done:1825: xfer=0xc44ed8b8, pipe=0xc44e8380, error=0
uhci_set_hw_power:3335: 
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0001
uhci_device_done:1825: xfer=0xc44ed0b8, pipe=0xc44e7380, error=0
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002
uhci_device_done:1825: xfer=0xc44ed0b8, pipe=0xc44e7380, error=0
uhci_set_hw_power:3335: 
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0001
uhci_device_done:1825: xfer=0xc44ee0b8, pipe=0xc44e6380, error=0
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002
uhci_device_done:1825: xfer=0xc44ee0b8, pipe=0xc44e6380, error=0
uhci_set_hw_power:3335: 
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0001
uhci_device_done:1825: xfer=0xc44ef0b8, pipe=0xc44e5380, error=0
uhci_root_ctrl_start:2515: 
uhci_root_ctrl_done:2563: type=0xa3 request=0x00 wLen=0x0004 wValue=0x0000 wIndex=0x0002
uhci_device_done:1825: xfer=0xc44ef0b8, pipe=0xc44e5380, error=0

More information about the freebsd-current mailing list