Date: Fri, 29 Nov 2002 21:46:14 +0100 From: Oliver Enzmann <oliver@cosec.org> To: freebsd-mobile@freebsd.org Subject: [Fwd: USB wierdness on Vaio] Message-ID: <3DE7D216.1010508@cosec.org>
next in thread | raw e-mail | index | archive | help
Hi, I've turned the following debugging options on, in the hope that someone can shed some light on this: hw.usb.uhci.debug: 50 hw.usb.uhub.debug: 50 hw.usb.umass.debug: 50 hw.usb.debug: 50 The following is logged when I attach the Y-E DATA USB Floppy Drive to one of the USB ports: Nov 29 21:38:15 vaio kernel: usb0: uhci_intr1 Nov 29 21:38:15 vaio kernel: usb0 regs: cmd=0081, sts=0000, intr=000f, frnum=02ca, flbase=00787b28, sof=0040, portsc1=0080, portsc2=0080 Nov 29 21:38:15 vaio kernel: uhci_intr: real interrupt Nov 29 21:38:15 vaio kernel: usb1: uhci_intr1 Nov 29 21:38:15 vaio kernel: usb1 regs: cmd=0081, sts=0000, intr=000f, frnum=009a, flbase=0075e268, sof=0040, portsc1=0095, portsc2=0080 Nov 29 21:38:15 vaio kernel: uhci_poll_hub Nov 29 21:38:19 vaio last message repeated 33 times Nov 29 21:38:19 vaio kernel: usb_event_thread: woke up Nov 29 21:38:19 vaio kernel: usb_discover Nov 29 21:38:20 vaio kernel: uhci_poll_hub Nov 29 21:38:30 vaio last message repeated 82 times Nov 29 21:38:30 vaio kernel: usb_transfer_complete: pipe=0xc15dc200 xfer=0xc1571900 status=0 actlen=1 Nov 29 21:38:30 vaio kernel: usb_transfer_complete: repeat=1 new head=0xc1571900 Nov 29 21:38:30 vaio kernel: uhub_intr: sc=0xc151ceb0 Nov 29 21:38:30 vaio kernel: usb_needs_explore Nov 29 21:38:30 vaio kernel: uhci_poll_hub Nov 29 21:38:30 vaio kernel: usb_event_thread: woke up Nov 29 21:38:30 vaio kernel: usb_discover Nov 29 21:38:30 vaio kernel: uhub_explore dev=0xc15dbd80 addr=1 Nov 29 21:38:30 vaio kernel: usbd_alloc_xfer() = 0xc1571800 Nov 29 21:38:30 vaio kernel: usbd_transfer: xfer=0xc1571800, flags=2, pipe=0xc15dc080, running=0 Nov 29 21:38:30 vaio kernel: usbd_dump_queue: pipe=0xc15dc080 Nov 29 21:38:30 vaio kernel: usb_insert_transfer: pipe=0xc15dc080 running=0 timeout=5000 Nov 29 21:38:30 vaio kernel: uhci_root_ctrl_control type=0xa3 request=00 Nov 29 21:38:30 vaio kernel: usb_transfer_complete: pipe=0xc15dc080 xfer=0xc1571800 status=0 actlen=4 Nov 29 21:38:30 vaio kernel: usb_transfer_complete: repeat=0 new head=0 Nov 29 21:38:30 vaio kernel: usbd_start_next: pipe=0xc15dc080, xfer=0 Nov 29 21:38:30 vaio kernel: usbd_free_xfer: 0xc1571800 Nov 29 21:38:30 vaio kernel: uhub_explore: uhub0 port 1 status 0x0100 0x0000 Nov 29 21:38:30 vaio kernel: uhub_explore: port=1 !C_CONNECT_STATUS Nov 29 21:38:30 vaio kernel: usbd_alloc_xfer() = 0xc1571800 Nov 29 21:38:30 vaio kernel: usbd_transfer: xfer=0xc1571800, flags=2, pipe=0xc15dc080, running=0 Nov 29 21:38:30 vaio kernel: usbd_dump_queue: pipe=0xc15dc080 Nov 29 21:38:30 vaio kernel: usb_insert_transfer: pipe=0xc15dc080 running=0 timeout=5000 Nov 29 21:38:30 vaio kernel: uhci_root_ctrl_control type=0xa3 request=00 Nov 29 21:38:30 vaio kernel: usb_transfer_complete: pipe=0xc15dc080 xfer=0xc1571800 status=0 actlen=4 Nov 29 21:38:30 vaio kernel: usb_transfer_complete: repeat=0 new head=0 Nov 29 21:38:30 vaio kernel: usbd_start_next: pipe=0xc15dc080, xfer=0 Nov 29 21:38:30 vaio kernel: usbd_free_xfer: 0xc1571800 Nov 29 21:38:30 vaio kernel: uhub_explore: uhub0 port 2 status 0x0101 0x0001 Nov 29 21:38:30 vaio kernel: uhub_explore: status change hub=1 port=2 Nov 29 21:38:30 vaio kernel: usbd_alloc_xfer() = 0xc1571800 Nov 29 21:38:30 vaio kernel: usbd_transfer: xfer=0xc1571800, flags=2, pipe=0xc15dc080, running=0 Nov 29 21:38:30 vaio kernel: usbd_dump_queue: pipe=0xc15dc080 Nov 29 21:38:30 vaio kernel: usb_insert_transfer: pipe=0xc15dc080 running=0 timeout=5000 Nov 29 21:38:30 vaio kernel: uhci_root_ctrl_control type=0x23 request=01 Nov 29 21:38:30 vaio kernel: uhci_root_ctrl_control: UR_CLEAR_PORT_FEATURE port=2 feature=16 Nov 29 21:38:30 vaio kernel: usb_transfer_complete: pipe=0xc15dc080 xfer=0xc1571800 status=0 actlen=0 Nov 29 21:38:30 vaio kernel: usb_transfer_complete: repeat=0 new head=0 Nov 29 21:38:30 vaio kernel: usbd_start_next: pipe=0xc15dc080, xfer=0 Nov 29 21:38:30 vaio kernel: usbd_free_xfer: 0xc1571800 Nov 29 21:38:30 vaio kernel: uhci_intr: real interrupt Nov 29 21:38:30 vaio kernel: usb0: uhci_intr1 Nov 29 21:38:30 vaio kernel: usb0 regs: cmd=0081, sts=0000, intr=000f, frnum=03c3, flbase=00787f0c, sof=0040, portsc1=0080, portsc2=0091 Nov 29 21:38:30 vaio kernel: uhci_intr: real interrupt Nov 29 21:38:30 vaio kernel: usb1: uhci_intr1 Nov 29 21:38:30 vaio kernel: usb1 regs: cmd=0081, sts=0000, intr=000f, frnum=0194, flbase=0075e650, sof=0040, portsc1=0095, portsc2=0080 Nov 29 21:38:30 vaio kernel: uhci_poll_hub Nov 29 21:38:30 vaio kernel: uhci_poll_hub Nov 29 21:38:30 vaio kernel: usbd_alloc_xfer() = 0xc1571800 Nov 29 21:38:30 vaio kernel: usbd_transfer: xfer=0xc1571800, flags=2, pipe=0xc15dc080, running=0 Nov 29 21:38:30 vaio kernel: usbd_dump_queue: pipe=0xc15dc080 Nov 29 21:38:30 vaio kernel: usb_insert_transfer: pipe=0xc15dc080 running=0 timeout=5000 Nov 29 21:38:30 vaio kernel: uhci_root_ctrl_control type=0x23 request=03 Nov 29 21:38:30 vaio kernel: uhci_poll_hub Nov 29 21:38:30 vaio kernel: uhci_poll_hub Nov 29 21:38:30 vaio kernel: uhci port 2 reset, status = 0x0095 Nov 29 21:38:30 vaio kernel: usb_transfer_complete: pipe=0xc15dc080 xfer=0xc1571800 status=0 actlen=0 Nov 29 21:38:30 vaio kernel: usb_transfer_complete: repeat=0 new head=0 Nov 29 21:38:30 vaio kernel: usbd_start_next: pipe=0xc15dc080, xfer=0 Nov 29 21:38:30 vaio kernel: usbd_free_xfer: 0xc1571800 Nov 29 21:38:30 vaio kernel: usbd_reset_port: port 2 reset done, error=NORMAL_COMPLETION Nov 29 21:38:31 vaio kernel: usbd_alloc_xfer() = 0xc1571800 Nov 29 21:38:31 vaio kernel: usbd_transfer: xfer=0xc1571800, flags=2, pipe=0xc15dc080, running=0 Nov 29 21:38:31 vaio kernel: usbd_dump_queue: pipe=0xc15dc080 Nov 29 21:38:31 vaio kernel: usb_insert_transfer: pipe=0xc15dc080 running=0 timeout=5000 Nov 29 21:38:31 vaio kernel: uhci_root_ctrl_control type=0xa3 request=00 Nov 29 21:38:31 vaio kernel: usb_transfer_complete: pipe=0xc15dc080 xfer=0xc1571800 status=0 actlen=4 Nov 29 21:38:31 vaio kernel: usb_transfer_complete: repeat=0 new head=0 Nov 29 21:38:31 vaio kernel: usbd_start_next: pipe=0xc15dc080, xfer=0 Nov 29 21:38:31 vaio kernel: usbd_free_xfer: 0xc1571800 Nov 29 21:38:31 vaio kernel: usbd_alloc_xfer() = 0xc1571800 Nov 29 21:38:31 vaio kernel: usbd_transfer: xfer=0xc1571800, flags=2, pipe=0xc15dc080, running=0 Nov 29 21:38:31 vaio kernel: usbd_dump_queue: pipe=0xc15dc080 Nov 29 21:38:31 vaio kernel: usb_insert_transfer: pipe=0xc15dc080 running=0 timeout=5000 Nov 29 21:38:31 vaio kernel: uhci_root_ctrl_control type=0x23 request=01 Nov 29 21:38:31 vaio kernel: uhci_root_ctrl_control: UR_CLEAR_PORT_FEATURE port=2 feature=20 Nov 29 21:38:31 vaio kernel: usb_transfer_complete: pipe=0xc15dc080 xfer=0xc1571800 status=0 actlen=0 Nov 29 21:38:31 vaio kernel: usb_transfer_complete: repeat=0 new head=0 Nov 29 21:38:31 vaio kernel: usbd_start_next: pipe=0xc15dc080, xfer=0 Nov 29 21:38:31 vaio kernel: usbd_free_xfer: 0xc1571800 Nov 29 21:38:31 vaio kernel: uhci_poll_hub Nov 29 21:38:31 vaio kernel: uhci_poll_hub Nov 29 21:38:31 vaio kernel: usbd_alloc_xfer() = 0xc1571800 Nov 29 21:38:31 vaio kernel: usbd_transfer: xfer=0xc1571800, flags=2, pipe=0xc15dc080, running=0 Nov 29 21:38:31 vaio kernel: usbd_dump_queue: pipe=0xc15dc080 Nov 29 21:38:31 vaio kernel: usb_insert_transfer: pipe=0xc15dc080 running=0 timeout=5000 Nov 29 21:38:31 vaio kernel: uhci_root_ctrl_control type=0xa3 request=00 Nov 29 21:38:31 vaio kernel: usb_transfer_complete: pipe=0xc15dc080 xfer=0xc1571800 status=0 actlen=4 Nov 29 21:38:31 vaio kernel: usb_transfer_complete: repeat=0 new head=0 Nov 29 21:38:31 vaio kernel: usbd_start_next: pipe=0xc15dc080, xfer=0 Nov 29 21:38:31 vaio kernel: usbd_free_xfer: 0xc1571800 Nov 29 21:38:31 vaio kernel: usbd_new_device bus=0xc16b4000 port=2 depth=1 speed=2 Nov 29 21:38:31 vaio kernel: usbd_setup_pipe: dev=0xc1b1ee00 iface=0 ep=0xc1b1ee24 pipe=0xc1b1ee04 Nov 29 21:38:31 vaio kernel: uhci_open: pipe=0xc1b1e500, addr=0, endpt=0 (1) Nov 29 21:38:31 vaio kernel: usbd_get_desc: type=1, index=0, len=8 Nov 29 21:38:31 vaio kernel: usbd_alloc_xfer() = 0xc1571800 Nov 29 21:38:31 vaio kernel: usbd_transfer: xfer=0xc1571800, flags=2, pipe=0xc1b1e500, running=0 Nov 29 21:38:31 vaio kernel: usbd_dump_queue: pipe=0xc1b1e500 Nov 29 21:38:31 vaio kernel: usb_insert_transfer: pipe=0xc1b1e500 running=0 timeout=5000 Nov 29 21:38:31 vaio kernel: uhci_device_control type=0x80, request=0x06, wValue=0x0100, wIndex=0x0000 len=8, addr=0, endpt=0 Nov 29 21:38:31 vaio kernel: uhci_alloc_std_chain: addr=0 endpt=0 len=8 speed=2 flags=0x2 Nov 29 21:38:31 vaio kernel: uhci_alloc_std_chain: maxp=8 ntd=1 Nov 29 21:38:31 vaio kernel: uhci_alloc_std_chain: nexttog=0 Nov 29 21:38:31 vaio kernel: uhci_device_request: before transfer Nov 29 21:38:31 vaio kernel: TD(0xc16aff80) at 00763f80 = link=0x00763f44 status=0x18800000 token=0x00e0002d buffer=0x007725f0 Nov 29 21:38:31 vaio kernel: 763f44<VF> 18800000<ACTIVE>,errcnt=3,actlen=1 pid=2d,addr=0,endpt=0,D=0,maxlen=8 Nov 29 21:38:31 vaio kernel: TD(0xc16aff40) at 00763f40 = link=0x00763f64 status=0x188003ff token=0x00e80069 buffer=0x007720e0 Nov 29 21:38:31 vaio kernel: 763f64<VF> 188003ff<ACTIVE>,errcnt=3,actlen=0 pid=69,addr=0,endpt=0,D=1,maxlen=8 Nov 29 21:38:31 vaio kernel: TD(0xc16aff60) at 00763f60 = link=0x00000001 status=0x19800000 token=0xffe800e1 buffer=0x00000000 Nov 29 21:38:31 vaio kernel: 1<T> 19800000<ACTIVE,IOC>,errcnt=3,actlen=1 pid=e1,addr=0,endpt=0,D=1,maxlen=0 Nov 29 21:38:31 vaio kernel: uhci_add_ctrl: sqh=0xc16b0f40 Nov 29 21:38:31 vaio kernel: uhci_enter_ctl_q: follow from [0] Nov 29 21:38:31 vaio kernel: TD(0xc16b2fc0) at 00746fc0 = link=0x00785f62 status=0x02000000 token=0x00000000 buffer=0x00000000 Nov 29 21:38:31 vaio kernel: 785f62<Q> 2000000<ISO>,errcnt=0,actlen=1 pid=00,addr=0,endpt=0,D=0,maxlen=1 Nov 29 21:38:31 vaio kernel: QH(0xc16b1f60) at 00785f60: hlink=00785f82 elink=00000001 Nov 29 21:38:31 vaio kernel: QH(0xc16b1f60) at 00785f60: hlink=00785f82 elink=00000001 Nov 29 21:38:31 vaio kernel: QH(0xc16b1f80) at 00785f80: hlink=00785fa2 elink=00000001 Nov 29 21:38:31 vaio kernel: QH(0xc16b1fc0) at 00785fc0: hlink=00785fe2 elink=00000001 Nov 29 21:38:31 vaio kernel: QH(0xc16b1fe0) at 00785fe0: hlink=00000001 elink=00746fe0 Nov 29 21:38:31 vaio kernel: Enqueued QH: Nov 29 21:38:31 vaio kernel: QH(0xc16b0f40) at 00764f40: hlink=00785fc2 elink=00763f80 Nov 29 21:38:31 vaio kernel: TD(0xc16aff80) at 00763f80 = link=0x00763f44 status=0x18800000 token=0x00e0002d buffer=0x007725f0 Nov 29 21:38:31 vaio kernel: 763f44<VF> 18800000<ACTIVE>,errcnt=3,actlen=1 pid=2d,addr=0,endpt=0,D=0,maxlen=8 Nov 29 21:38:31 vaio kernel: TD(0xc16aff40) at 00763f40 = link=0x00763f64 status=0x188003ff token=0x00e80069 buffer=0x007720e0 Nov 29 21:38:31 vaio kernel: 763f64<VF> 188003ff<ACTIVE>,errcnt=3,actlen=0 pid=69,addr=0,endpt=0,D=1,maxlen=8 Nov 29 21:38:31 vaio kernel: TD(0xc16aff60) at 00763f60 = link=0x00000001 status=0x19800000 token=0xffe800e1 buffer=0x00000000 Nov 29 21:38:31 vaio kernel: 1<T> 19800000<ACTIVE,IOC>,errcnt=3,actlen=1 pid=e1,addr=0,endpt=0,D=1,maxlen=0 Nov 29 21:38:31 vaio kernel: uhci_poll_hub Nov 29 21:38:35 vaio last message repeated 37 times Nov 29 21:38:35 vaio kernel: uhci_intr: real interrupt Nov 29 21:38:35 vaio kernel: usb0: uhci_intr1 Nov 29 21:38:35 vaio kernel: usb0 regs: cmd=0081, sts=0001, intr=000f, frnum=012c, flbase=007874b0, sof=0040, portsc1=0080, portsc2=0095 Nov 29 21:38:35 vaio kernel: usb_schedsoftintr: polling=0 Nov 29 21:38:35 vaio kernel: usb0: uhci_softintr (0) Nov 29 21:38:35 vaio kernel: uhci_check_intr: ii=0xc1571864 Nov 29 21:38:35 vaio kernel: uhci_check_intr: ii=0xc1571864 done Nov 29 21:38:35 vaio kernel: uhci_idone: ii=0xc1571864 Nov 29 21:38:35 vaio kernel: uhci_idone: ii=0xc1571864, xfer=0xc1571800, pipe=0xc1b1e500 ready Nov 29 21:38:35 vaio kernel: TD(0xc16aff80) at 00763f80 = link=0x00763f44 status=0x18000007 token=0x00e0002d buffer=0x007725f0 Nov 29 21:38:35 vaio kernel: 763f44<VF> 18000007,errcnt=3,actlen=8 pid=2d,addr=0,endpt=0,D=0,maxlen=8 Nov 29 21:38:35 vaio kernel: TD(0xc16aff40) at 00763f40 = link=0x00763f64 status=0x18000007 token=0x00e80069 buffer=0x007720e0 Nov 29 21:38:35 vaio kernel: 763f64<VF> 18000007,errcnt=3,actlen=8 pid=69,addr=0,endpt=0,D=1,maxlen=8 Nov 29 21:38:35 vaio kernel: TD(0xc16aff60) at 00763f60 = link=0x00000001 status=0x190007ff token=0xffe800e1 buffer=0x00000000 Nov 29 21:38:35 vaio kernel: 1<T> 190007ff<IOC>,errcnt=3,actlen=0 pid=e1,addr=0,endpt=0,D=1,maxlen=0 Nov 29 21:38:35 vaio kernel: uhci_idone: actlen=8, status=0x0 Nov 29 21:38:35 vaio kernel: usb_transfer_complete: pipe=0xc1b1e500 xfer=0xc1571800 status=0 actlen=8 Nov 29 21:38:36 vaio kernel: usb_transfer_complete: repeat=0 new head=0 Nov 29 21:38:36 vaio kernel: uhci_remove_hs_ctrl: sqh=0xc16b0f40 Nov 29 21:38:36 vaio kernel: uhci_find_prev_qh: pqh=0xc16b1fa0 sqh=0xc16b0f40 Nov 29 21:38:36 vaio kernel: uhci_ctrl_done: length=8 Nov 29 21:38:36 vaio kernel: usbd_start_next: pipe=0xc1b1e500, xfer=0 Nov 29 21:38:36 vaio kernel: uhci_idone: ii=0xc1571864 done Nov 29 21:38:36 vaio kernel: usb0: uhci_intr: exit Nov 29 21:38:36 vaio kernel: uhci_intr: real interrupt Nov 29 21:38:36 vaio kernel: usb1: uhci_intr1 Nov 29 21:38:36 vaio kernel: usb1 regs: cmd=0081, sts=0000, intr=000f, frnum=06fd, flbase=0075ebf4, sof=0040, portsc1=0095, portsc2=0080 Nov 29 21:38:36 vaio kernel: usbd_free_xfer: 0xc1571800 Nov 29 21:38:36 vaio kernel: usbd_new_device: adding unit addr=2, rev=110, class=0, subclass=0, protocol=0, maxpacket=8, len=18, speed=2 Nov 29 21:38:36 vaio kernel: usbd_get_device_desc: Nov 29 21:38:36 vaio kernel: usbd_get_desc: type=1, index=0, len=18 Nov 29 21:38:36 vaio kernel: usbd_alloc_xfer() = 0xc1571800 Nov 29 21:38:36 vaio kernel: usbd_transfer: xfer=0xc1571800, flags=2, pipe=0xc1b1e500, running=0 Nov 29 21:38:36 vaio kernel: usbd_dump_queue: pipe=0xc1b1e500 Nov 29 21:38:36 vaio kernel: usb_insert_transfer: pipe=0xc1b1e500 running=0 timeout=5000 Nov 29 21:38:36 vaio kernel: uhci_device_control type=0x80, request=0x06, wValue=0x0100, wIndex=0x0000 len=18, addr=0, endpt=0 Nov 29 21:38:36 vaio kernel: uhci_alloc_std_chain: addr=0 endpt=0 len=18 speed=2 flags=0x2 Nov 29 21:38:36 vaio kernel: uhci_alloc_std_chain: maxp=8 ntd=3 Nov 29 21:38:36 vaio kernel: uhci_alloc_std_chain: nexttog=0 Nov 29 21:38:36 vaio kernel: uhci_device_request: before transfer Nov 29 21:38:36 vaio kernel: TD(0xc16aff80) at 00763f80 = link=0x00763f04 status=0x18800000 token=0x00e0002d buffer=0x007725f0 Nov 29 21:38:36 vaio kernel: 763f04<VF> 18800000<ACTIVE>,errcnt=3,actlen=1 pid=2d,addr=0,endpt=0,D=0,maxlen=8 Nov 29 21:38:36 vaio kernel: TD(0xc16aff00) at 00763f00 = link=0x00763f24 status=0x188003ff token=0x00e80069 buffer=0x007c1580 Nov 29 21:38:36 vaio kernel: 763f24<VF> 188003ff<ACTIVE>,errcnt=3,actlen=0 pid=69,addr=0,endpt=0,D=1,maxlen=8 Nov 29 21:38:36 vaio kernel: TD(0xc16aff20) at 00763f20 = link=0x00763f44 status=0x188003ff token=0x00e00069 buffer=0x007c1588 Nov 29 21:38:36 vaio kernel: 763f44<VF> 188003ff<ACTIVE>,errcnt=3,actlen=0 pid=69,addr=0,endpt=0,D=0,maxlen=8 Nov 29 21:38:36 vaio kernel: TD(0xc16aff40) at 00763f40 = link=0x00763f64 status=0x188003ff token=0x00280069 buffer=0x007c1590 Nov 29 21:38:36 vaio kernel: 763f64<VF> 188003ff<ACTIVE>,errcnt=3,actlen=0 pid=69,addr=0,endpt=0,D=1,maxlen=2 Nov 29 21:38:36 vaio kernel: TD(0xc16aff60) at 00763f60 = link=0x00000001 status=0x19800000 token=0xffe800e1 buffer=0x00000000 Nov 29 21:38:36 vaio kernel: 1<T> 19800000<ACTIVE,IOC>,errcnt=3,actlen=1 pid=e1,addr=0,endpt=0,D=1,maxlen=0 Nov 29 21:38:36 vaio kernel: uhci_add_ctrl: sqh=0xc16b0f40 Nov 29 21:38:36 vaio kernel: uhci_enter_ctl_q: follow from [0] Nov 29 21:38:36 vaio kernel: TD(0xc16b2fc0) at 00746fc0 = link=0x00785f62 status=0x02000000 token=0x00000000 buffer=0x00000000 Nov 29 21:38:36 vaio kernel: 785f62<Q> 2000000<ISO>,errcnt=0,actlen=1 pid=00,addr=0,endpt=0,D=0,maxlen=1 Nov 29 21:38:36 vaio kernel: QH(0xc16b1f60) at 00785f60: hlink=00785f82 elink=00000001 Nov 29 21:38:36 vaio kernel: QH(0xc16b1f60) at 00785f60: hlink=00785f82 elink=00000001 Nov 29 21:38:36 vaio kernel: QH(0xc16b1f80) at 00785f80: hlink=00785fa2 elink=00000001 Nov 29 21:38:36 vaio kernel: QH(0xc16b1fc0) at 00785fc0: hlink=00785fe2 elink=00000001 Nov 29 21:38:36 vaio kernel: QH(0xc16b1fe0) at 00785fe0: hlink=00000001 elink=00746fe0 Nov 29 21:38:36 vaio kernel: Enqueued QH: Nov 29 21:38:36 vaio kernel: QH(0xc16b0f40) at 00764f40: hlink=00785fc2 elink=00763f04 Nov 29 21:38:36 vaio kernel: TD(0xc16aff80) at 00763f80 = link=0x00763f04 status=0x18000007 token=0x00e0002d buffer=0x007725f0 Nov 29 21:38:36 vaio kernel: 763f04<VF> 18000007,errcnt=3,actlen=8 pid=2d,addr=0,endpt=0,D=0,maxlen=8 Nov 29 21:38:36 vaio kernel: TD(0xc16aff00) at 00763f00 = link=0x00763f24 status=0x188807ff token=0x00e80069 buffer=0x007c1580 Nov 29 21:38:36 vaio kernel: 763f24<VF> 188807ff<NAK,ACTIVE>,errcnt=3,actlen=0 pid=69,addr=0,endpt=0,D=1,maxlen=8 Nov 29 21:38:36 vaio kernel: TD(0xc16aff20) at 00763f20 = link=0x00763f44 status=0x188003ff token=0x00e00069 buffer=0x007c1588 Nov 29 21:38:36 vaio kernel: 763f44<VF> 188003ff<ACTIVE>,errcnt=3,actlen=0 pid=69,addr=0,endpt=0,D=0,maxlen=8 Nov 29 21:38:36 vaio kernel: TD(0xc16aff40) at 00763f40 = link=0x00763f64 status=0x188003ff token=0x00280069 buffer=0x007c1590 Nov 29 21:38:36 vaio kernel: 763f64<VF> 188003ff<ACTIVE>,errcnt=3,actlen=0 pid=69,addr=0,endpt=0,D=1,maxlen=2 Nov 29 21:38:36 vaio kernel: TD(0xc16aff60) at 00763f60 = link=0x00000001 status=0x19800000 token=0xffe800e1 buffer=0x00000000 Nov 29 21:38:36 vaio kernel: 1<T> 19800000<ACTIVE,IOC>,errcnt=3,actlen=1 pid=e1,addr=0,endpt=0,D=1,maxlen=0 Nov 29 21:38:36 vaio kernel: uhci_poll_hub Nov 29 21:38:40 vaio last message repeated 37 times Nov 29 21:38:40 vaio kernel: uhci_timeout: uxfer=0xc1571800 Nov 29 21:38:40 vaio kernel: usb_add_task: task=0xc157187c Nov 29 21:38:40 vaio kernel: uhci_poll_hub Nov 29 21:38:40 vaio kernel: uhci_poll_hub Nov 29 21:38:40 vaio kernel: usb_task_thread: woke up task=0xc157187c Nov 29 21:38:40 vaio kernel: uhci_timeout_task: xfer=0xc1571800 Nov 29 21:38:40 vaio kernel: uhci_abort_xfer: xfer=0xc1571800, status=15 Nov 29 21:38:40 vaio kernel: uhci_abort_xfer: stop ii=0xc1571864 Nov 29 21:38:40 vaio kernel: usb_schedsoftintr: polling=0 Nov 29 21:38:40 vaio kernel: usb0: uhci_softintr (0) Nov 29 21:38:40 vaio kernel: uhci_check_intr: ii=0xc1571864 Nov 29 21:38:40 vaio kernel: uhci_check_intr: aborted xfer=0xc1571800 Nov 29 21:38:40 vaio kernel: uhci_abort_xfer: callback Nov 29 21:38:40 vaio kernel: usb_transfer_complete: pipe=0xc1b1e500 xfer=0xc1571800 status=15 actlen=0 Nov 29 21:38:40 vaio kernel: usb_transfer_complete: repeat=0 new head=0 Nov 29 21:38:40 vaio kernel: uhci_remove_hs_ctrl: sqh=0xc16b0f40 Nov 29 21:38:40 vaio kernel: uhci_find_prev_qh: pqh=0xc16b1fa0 sqh=0xc16b0f40 Nov 29 21:38:40 vaio kernel: uhci_ctrl_done: length=0 Nov 29 21:38:40 vaio kernel: usbd_start_next: pipe=0xc1b1e500, xfer=0 Nov 29 21:38:40 vaio kernel: usbd_free_xfer: 0xc1571800 Nov 29 21:38:40 vaio kernel: usbd_new_device: addr=2, getting full desc failed Nov 29 21:38:40 vaio kernel: usbd_remove_device: 0xc1b1ee00 Nov 29 21:38:40 vaio kernel: usbd_ar_pipe: pipe=0xc1b1e500 Nov 29 21:38:40 vaio kernel: usbd_dump_queue: pipe=0xc1b1e500 Nov 29 21:38:40 vaio kernel: uhub_explore: usb_new_device failed, error=TIMEOUT Nov 29 21:38:40 vaio kernel: uhub0: device problem, disabling port 2 Nov 29 21:38:40 vaio kernel: usbd_alloc_xfer() = 0xc1571800 Nov 29 21:38:40 vaio kernel: usbd_transfer: xfer=0xc1571800, flags=2, pipe=0xc15dc080, running=0 Nov 29 21:38:40 vaio kernel: usbd_dump_queue: pipe=0xc15dc080 Nov 29 21:38:40 vaio kernel: usb_insert_transfer: pipe=0xc15dc080 running=0 timeout=5000 Nov 29 21:38:40 vaio kernel: uhci_root_ctrl_control type=0x23 request=01 Nov 29 21:38:40 vaio kernel: uhci_root_ctrl_control: UR_CLEAR_PORT_FEATURE port=2 feature=1 Nov 29 21:38:40 vaio kernel: usb_transfer_complete: pipe=0xc15dc080 xfer=0xc1571800 status=0 actlen=0 Nov 29 21:38:40 vaio kernel: usb_transfer_complete: repeat=0 new head=0 Nov 29 21:38:40 vaio kernel: usbd_start_next: pipe=0xc15dc080, xfer=0 Nov 29 21:38:40 vaio kernel: usbd_free_xfer: 0xc1571800 Nov 29 21:38:41 vaio kernel: uhci_poll_hub Nov 29 21:38:46 vaio last message repeated 43 times Nov 29 21:38:46 vaio kernel: uhci_intr: real interrupt Nov 29 21:38:46 vaio kernel: usb0: uhci_intr1 Nov 29 21:38:46 vaio kernel: usb0 regs: cmd=0081, sts=0001, intr=000f, frnum=0245, flbase=00787914, sof=0040, portsc1=0080, portsc2=0091 Nov 29 21:38:46 vaio kernel: usb_schedsoftintr: polling=0 Nov 29 21:38:46 vaio kernel: usb0: uhci_softintr (0) Nov 29 21:38:46 vaio kernel: usb0: uhci_intr: exit Nov 29 21:38:46 vaio kernel: uhci_intr: real interrupt Nov 29 21:38:46 vaio kernel: usb1: uhci_intr1 Nov 29 21:38:46 vaio kernel: usb1 regs: cmd=0081, sts=0000, intr=000f, frnum=0016, flbase=0075e058, sof=0040, portsc1=0095, portsc2=0080 Nov 29 21:38:46 vaio kernel: uhci_poll_hub Nov 29 21:38:55 vaio last message repeated 71 times Nov 29 21:38:55 vaio kernel: uhci_intr: real interrupt Nov 29 21:38:55 vaio kernel: usb0: uhci_intr1 Nov 29 21:38:55 vaio kernel: usb0 regs: cmd=0081, sts=0000, intr=000f, frnum=0638, flbase=007878e0, sof=0040, portsc1=0080, portsc2=0091 Nov 29 21:38:55 vaio kernel: uhci_intr: real interrupt Nov 29 21:38:55 vaio kernel: usb1: uhci_intr1 Nov 29 21:38:55 vaio kernel: usb1 regs: cmd=0081, sts=0000, intr=000f, frnum=0408, flbase=0075e020, sof=0040, portsc1=0095, portsc2=0080 Nov 29 21:38:55 vaio kernel: uhci_poll_hub Nov 29 21:39:02 vaio last message repeated 55 times Nov 29 21:39:02 vaio kernel: uhci_intr: real interrupt Nov 29 21:39:02 vaio kernel: usb0: uhci_intr1 Nov 29 21:39:02 vaio kernel: usb0 regs: cmd=0081, sts=0000, intr=000f, frnum=00c8, flbase=00787320, sof=0040, portsc1=0080, portsc2=0091 Nov 29 21:39:02 vaio kernel: uhci_intr: real interrupt Nov 29 21:39:02 vaio kernel: usb1: uhci_intr1 Nov 29 21:39:02 vaio kernel: usb1 regs: cmd=0081, sts=0000, intr=000f, frnum=0699, flbase=0075ea64, sof=0040, portsc1=0095, portsc2=0080 Nov 29 21:39:02 vaio kernel: uhci_poll_hub Nov 29 21:39:18 vaio last message repeated 127 times Nov 29 21:39:18 vaio kernel: uhci_intr: real interrupt Nov 29 21:39:18 vaio kernel: usb0: uhci_intr1 Nov 29 21:39:18 vaio kernel: usb0 regs: cmd=0081, sts=0000, intr=000f, frnum=074b, flbase=00787d2c, sof=0040, portsc1=0080, portsc2=0091 Nov 29 21:39:18 vaio kernel: uhci_intr: real interrupt Nov 29 21:39:18 vaio kernel: usb1: uhci_intr1 Nov 29 21:39:18 vaio kernel: usb1 regs: cmd=0081, sts=0000, intr=000f, frnum=051b, flbase=0075e46c, sof=0040, portsc1=0095, portsc2=0080 Nov 29 21:39:18 vaio kernel: uhci_poll_hub Oliver -------- Original Message -------- Subject: USB wierdness on Vaio Date: Thu, 28 Nov 2002 18:17:45 +0100 From: Oliver Enzmann <oliver@cosec.org> To: freebsd-mobile@freebsd.org Hi, External USB devices on my Sony VAIO R600 laptop disable the USB port under 5.0-CURRENT (cvsup'ed on the 26th): Nov 28 17:19:34 vaio kernel: usb_new_device: set address 2 failed Nov 28 17:19:34 vaio kernel: uhub_explore: usb_new_device failed, error=SET_ADDR_FAILED Nov 28 17:19:34 vaio kernel: uhub0: device problem, disabling port 1 However, this laptop also has a built-in (USB) Sony Memorystick reader that works just fine. Also, the two USB devices I am testing with work just fine with 5.0-DP2 on an old Dell laptop. The USB devices are: Iomega USB CDRW9602EXT-B Y-E DATA USB Floppy Drive On the Vaio, USB port status changes from - to 1. port 1 powered 2. port 1 addr 0 should never happen! 3. port 1 addr 2: full speed, power 100 mA, unconfigured, product \ 0x0055(0x0055), Iomega(0x059b), rev 11.00 4. port 1 enabled usbdevs -v and Dmesg below. Your help is greatly appreciated, Oliver --------------------------------------------------------------- usbdevs -v: Controller /dev/usb0: addr 1: full speed, self powered, config 1, UHCI root hub(0x0000), Intel(0x0000), rev 1.00 port 1 powered port 2 powered Controller /dev/usb1: addr 1: full speed, self powered, config 1, UHCI root hub(0x0000), Intel(0x0000), rev 1.00 port 1 addr 2: full speed, self powered, config 1, USB Memory Stick Slot(0x0056), Sony(0x054c), rev 1.80 port 2 powered --------------------------------------------------------------- dmesg: FreeBSD 5.0-CURRENT #1: Thu Nov 28 16:55:29 CET 2002 root@vaio.cosec.org:/usr/src/sys/i386/compile/VAIOMAX Preloaded elf kernel "/boot/vaiomax/kernel" at 0xc0482000. Timecounter "i8254" frequency 1193182 Hz Timecounter "TSC" frequency 844610161 Hz CPU: Pentium III/Pentium III Xeon/Celeron (844.61-MHz 686-class CPU) Origin = "GenuineIntel" Id = 0x68a Stepping = 10 Features=0x383f9ff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,MMX,FXSR,SSE> real memory = 132644864 (126 MB) avail memory = 123904000 (118 MB) Initializing GEOMetry subsystem Pentium Pro MTRR support enabled acpi0: <SONY U1 > on motherboard Using $PIR table, 9 entries at 0xc00fdf30 Timecounter "ACPI-fast" frequency 3579545 Hz acpi_cpu0: <CPU> on acpi0 acpi_tz0: <thermal zone> on acpi0 acpi_lid0: <Control Method Lid Switch> on acpi0 acpi_button0: <Power Button> on acpi0 pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0 ---- initial configuration ------------------------ \\_SB_.LNKA irq 9: [ 9] low,level,sharable 0.1.0 \\_SB_.LNKB irq 9: [ 9] low,level,sharable 0.1.1 \\_SB_.LNKC irq 0: [ 9] low,level,sharable 0.1.2 \\_SB_.LNKD irq 0: [ 9] low,level,sharable 0.1.3 \\_SB_.LNKA irq 9: [ 9] low,level,sharable 0.2.0 \\_SB_.LNKB irq 9: [ 9] low,level,sharable 0.31.1 \\_SB_.LNKH irq 9: [ 9] low,level,sharable 0.31.2 \\_SB_.LNKD irq 0: [ 9] low,level,sharable 0.31.3 ---- before setting priority for links ------------ \\_SB_.LNKC: interrupts: 9 penalty: 830 references: 1 priority: 0 \\_SB_.LNKD: interrupts: 9 penalty: 830 references: 2 priority: 0 ---- before fixup boot-disabled links ------------- \\_SB_.LNKD: interrupts: 9 penalty: 830 references: 2 priority: 1660 \\_SB_.LNKC: interrupts: 9 penalty: 830 references: 1 priority: 830 ---- after fixup boot-disabled links -------------- \\_SB_.LNKD: interrupts: 9 penalty: 830 references: 2 priority: 1660 \\_SB_.LNKC: interrupts: 9 penalty: 830 references: 1 priority: 830 ---- arbitrated configuration --------------------- \\_SB_.LNKA irq 9: [ 9] low,level,sharable 0.1.0 \\_SB_.LNKB irq 9: [ 9] low,level,sharable 0.1.1 \\_SB_.LNKC irq 0: [ 9] low,level,sharable 0.1.2 \\_SB_.LNKD irq 0: [ 9] low,level,sharable 0.1.3 \\_SB_.LNKA irq 9: [ 9] low,level,sharable 0.2.0 \\_SB_.LNKB irq 9: [ 9] low,level,sharable 0.31.1 \\_SB_.LNKH irq 9: [ 9] low,level,sharable 0.31.2 \\_SB_.LNKD irq 0: [ 9] low,level,sharable 0.31.3 pci0: <ACPI PCI bus> on pcib0 agp0: <Intel 82815 (i815 GMCH) SVGA controller> mem 0xf4000000-0xf407ffff,0xf8000000-0xfbffffff irq 9 at device 2.0 on pci0 pcib1: <ACPI PCI-PCI bridge> at device 30.0 on pci0 ---- initial configuration ------------------------ \\_SB_.LNKA irq 9: [ 9] low,level,sharable 1.0.0 \\_SB_.LNKC irq 0: [ 9] low,level,sharable 1.2.0 \\_SB_.LNKD irq 0: [ 9] low,level,sharable 1.2.1 \\_SB_.LNKE irq 9: [ 9] low,level,sharable 1.8.0 \\_SB_.LNKA irq 9: [ 9] low,level,sharable 1.12.0 \\_SB_.LNKB irq 9: [ 9] low,level,sharable 1.12.1 \\_SB_.LNKC irq 0: [ 9] low,level,sharable 1.12.2 \\_SB_.LNKD irq 0: [ 9] low,level,sharable 1.12.3 ---- before setting priority for links ------------ \\_SB_.LNKD: interrupts: 9 penalty: 1670 references: 4 priority: 1660 \\_SB_.LNKC: interrupts: 9 penalty: 1670 references: 3 priority: 830 ---- before fixup boot-disabled links ------------- \\_SB_.LNKD: interrupts: 9 penalty: 1670 references: 4 priority: 6680 \\_SB_.LNKC: interrupts: 9 penalty: 1670 references: 3 priority: 5010 ---- after fixup boot-disabled links -------------- \\_SB_.LNKD: interrupts: 9 penalty: 1670 references: 4 priority: 6680 \\_SB_.LNKC: interrupts: 9 penalty: 1670 references: 3 priority: 5010 ---- arbitrated configuration --------------------- \\_SB_.LNKA irq 9: [ 9] low,level,sharable 1.0.0 \\_SB_.LNKC irq 0: [ 9] low,level,sharable 1.2.0 \\_SB_.LNKD irq 0: [ 9] low,level,sharable 1.2.1 \\_SB_.LNKE irq 9: [ 9] low,level,sharable 1.8.0 \\_SB_.LNKA irq 9: [ 9] low,level,sharable 1.12.0 \\_SB_.LNKB irq 9: [ 9] low,level,sharable 1.12.1 \\_SB_.LNKC irq 0: [ 9] low,level,sharable 1.12.2 \\_SB_.LNKD irq 0: [ 9] low,level,sharable 1.12.3 pci1: <ACPI PCI bus> on pcib1 fwohci0: <Texas Instruments TSB43AA22> mem 0xf4104000-0xf4107fff,0xf4101000-0xf41017ff irq 9 at device 0.0 on pci1 fwohci0: PCI bus latency was changing to 250. cache size 8. fwohci0: OHCI version 1.0 (ROM=1) fwohci0: No. of Isochronous channel is 4. fwohci0: resetting OHCI...done (0) fwohci0: BUS_OPT 0xa002 -> 0xf800a002 fwohci0: Link 1394a available S400, 2 ports, maxrec 2048 bytes. fwohci0: Enable 1394a Enhancements fwohci0: EUI64 08:00:46:03:00:c3:9a:d3 fwochi_set_intr: 1 firewire0: <IEEE1394(Firewire) bus> on fwohci0 firewire0: firewire bus attach sbp_identify sbp_probe sbp0: <SBP2/SCSI over firewire> on firewire0 sbp_attach cbb0: <RF5C475 PCI-CardBus Bridge> at device 2.0 on pci1 start (88000000) < sc->membase (f4100000) end (ffffffff) > sc->memlimit (f41fffff) cardbus0: <CardBus bus> on cbb0 pccard0: <16-bit PCCard bus> on cbb0 pcib1: possible interrupts: 9 pcib1: slot 2 INTA routed to irq 9 via \\_SB_.LNKC fxp0: <Intel Pro/100 Ethernet> port 0x3000-0x303f mem 0xf4100000-0xf4100fff irq 9 at device 8.0 on pci1 fxp0: Ethernet address 08:00:46:17:51:ab inphy0: <i82562ET 10/100 media interface> on miibus0 inphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto isab0: <PCI-ISA bridge> at device 31.0 on pci0 isa0: <ISA bus> on isab0 atapci0: <Intel ICH2 ATA100 controller> port 0x1800-0x180f at device 31.1 on pci0 ata0: at 0x1f0 irq 14 on atapci0 ata1: at 0x170 irq 15 on atapci0 uhci0: <Intel 82801BA/BAM (ICH2) USB controller USB-A> port 0x1820-0x183f irq 9 at device 31.2 on pci0 uhci0: LegSup = 0x00001f20 usb0: <Intel 82801BA/BAM (ICH2) USB controller USB-A> on uhci0 usb0: USB revision 1.0 uhub0: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1 uhub0: 2 ports with 2 removable, self powered pci0: <serial bus, SMBus> at device 31.3 (no driver attached) uhci1: <Intel 82801BA/BAM (ICH2) USB controller USB-B> port 0x2400-0x241f irq 9 at device 31.4 on pci0 uhci1: LegSup = 0x00002f00 usb1: <Intel 82801BA/BAM (ICH2) USB controller USB-B> on uhci1 usb1: USB revision 1.0 uhub1: Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1 uhub1: 2 ports with 2 removable, self powered umass0: Sony USB Memory Stick Slot, rev 1.10/1.80, addr 2 umass0: 8070i (ATAPI) over CBI with CCI (using CBI); quirks = 0x0000 umass0:1:0:-1: Attached to scbus1 as device 0 pci0: <multimedia, audio> at device 31.5 (no driver attached) pci0: <simple comms> at device 31.6 (no driver attached) atkbdc0: <Keyboard controller (i8042)> port 0x64,0x60 irq 1 on acpi0 atkbd0: <AT Keyboard> flags 0x1 irq 1 on atkbdc0 kbd0 at atkbd0 psm0: <PS/2 Mouse> irq 12 on atkbdc0 psm0: model GlidePoint, device ID 0 acpi_ec0: <embedded controller> port 0x66,0x62 on acpi0 acpi_cmbat0: <Control method Battery> on acpi0 acpi_acad0: <AC adapter> on acpi0 sio0 port 0x3f8-0x3ff irq 4 on acpi0 sio0: type 16550A ppc0 port 0x778-0x77f,0x378-0x37f irq 7 drq 3 on acpi0 ppc0: SMC-like chipset (ECP/EPP/PS2/NIBBLE) in COMPATIBLE mode ppc0: FIFO with 16/16/15 bytes threshold lpt0: <Printer> on ppbus0 lpt0: Interrupt-driven port acpi_timer0: <24-bit timer at 3.579545MHz> port 0x1008-0x100b on acpi0 npx0: <math processor> on motherboard npx0: INT 16 interface pmtimer0 on isa0 orm0: <Option ROMs> at iomem 0xdc000-0xdffff,0xd8000-0xdbfff,0xc0000-0xcbfff on isa0 sc0: <System console> at flags 0x100 on isa0 sc0: VGA <16 virtual consoles, flags=0x300> spic0: device model type = 2 spic0: <Sony Programmable I/O Controller> at port 0x10a0-0x10a4 on isa0 vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0 Timecounters tick every 10.000 msec firewire0: BUS reset firewire0: node_id = 0xc000ffc0, CYCLEMASTER mode firewire0: 1 nodes, maxhop <= 0, cable IRM = 0 (me) fw_set_bus_manager: 63->0 (loop=0) send phy_config root_node=-1 gap_count=1 start AT DMA status=0 acpi_cpu: CPU throttling enabled, 8 steps from 100% to 12.5% start (88000000) < sc->membase (f4100000) end (ffffffff) > sc->memlimit (f41fffff) start (88000000) < sc->membase (f4100000) end (ffffffff) > sc->memlimit (f41fffff) wi0: <Instant Wireless Network PC Card> at port 0x100-0x13f irq 9 function 0 config 1 on pccard0 wi0: 802.11 address: xx:yy:xx:yy:xx:yy wi0: using RF:PRISM2.5 MAC:ISL3873 wi0: Intersil Firmware: Primary 1.00.07, Station 1.03.05 bus_explore done ad0: 19077MB <HITACHI_DK23BA-20> [38760/16/63] at ata0-master UDMA66 (probe7:umass-sim0:0:0:0): INQUIRY. CDB: 12 0 0 0 24 0 (probe7:umass-sim0:0:0:0): CAM Status: SCSI Status Error (probe7:umass-sim0:0:0:0): SCSI Status: Check Condition (probe7:umass-sim0:0:0:0): UNIT ATTENTION asc:28,0 (probe7:umass-sim0:0:0:0): Not ready to ready change, medium may have changed (probe7:umass-sim0:0:0:0): Retrying Command (per Sense Data) To Unsubscribe: send mail to majordomo@FreeBSD.org with "unsubscribe freebsd-mobile" in the body of the message -- Oliver Enzmann - Datagram ninja and protector of the holy session DSS Key: 0x5B5158C6 - RSA Key: 0x3FD52195 - Lat 47°08.083N Long 7°14.350E "Time flies like an arrow. Fruit flies like a banana." - Lisa Grossman My statements in this message are personal opinions which may have no basis whatsoever in fact. All the usual warnings about destructive technology apply. Harmful or fatal if swallowed. No user-serviceable parts inside. To Unsubscribe: send mail to majordomo@FreeBSD.org with "unsubscribe freebsd-mobile" in the body of the message
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?3DE7D216.1010508>