From owner-freebsd-mobile Fri Nov 29 12:50: 1 2002 Delivered-To: freebsd-mobile@freebsd.org Received: from mx1.FreeBSD.org (mx1.freebsd.org [216.136.204.125]) by hub.freebsd.org (Postfix) with ESMTP id C9F6D37B401 for ; Fri, 29 Nov 2002 12:49:46 -0800 (PST) Received: from ddba033.netstream.ch (ddba033.netstream.ch [62.65.128.33]) by mx1.FreeBSD.org (Postfix) with ESMTP id A3F6143E9C for ; Fri, 29 Nov 2002 12:49:34 -0800 (PST) (envelope-from oliver@cosec.org) Received: from gw.adsl.ddn-ddn-62-65-148-234.netstream.ch ([62.65.148.234] helo=cosec.org) by ddba033.netstream.ch with esmtp (Exim 3.36 #1) id 18Hs41-000JiL-00 for freebsd-mobile@freebsd.org; Fri, 29 Nov 2002 21:48:42 +0100 Message-ID: <3DE7D216.1010508@cosec.org> Date: Fri, 29 Nov 2002 21:46:14 +0100 From: Oliver Enzmann User-Agent: Mozilla/5.0 (X11; U; FreeBSD i386; en-US; rv:1.2) Gecko/20021128 X-Accept-Language: en-us, en MIME-Version: 1.0 To: freebsd-mobile@freebsd.org Subject: [Fwd: USB wierdness on Vaio] Content-Type: text/plain; charset=ISO-8859-1; format=flowed Content-Transfer-Encoding: 8bit Sender: owner-freebsd-mobile@FreeBSD.ORG Precedence: bulk List-ID: List-Archive: (Web Archive) List-Help: (List Instructions) List-Subscribe: List-Unsubscribe: X-Loop: FreeBSD.org 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 18800000,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 188003ff,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 19800000,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 2000000,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 18800000,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 188003ff,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 19800000,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 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 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 190007ff,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 18800000,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 188003ff,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 188003ff,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 188003ff,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 19800000,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 2000000,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 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 188807ff,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 188003ff,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 188003ff,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 19800000,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 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 real memory = 132644864 (126 MB) avail memory = 123904000 (118 MB) Initializing GEOMetry subsystem Pentium Pro MTRR support enabled acpi0: on motherboard Using $PIR table, 9 entries at 0xc00fdf30 Timecounter "ACPI-fast" frequency 3579545 Hz acpi_cpu0: on acpi0 acpi_tz0: on acpi0 acpi_lid0: on acpi0 acpi_button0: on acpi0 pcib0: 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: on pcib0 agp0: mem 0xf4000000-0xf407ffff,0xf8000000-0xfbffffff irq 9 at device 2.0 on pci0 pcib1: 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: on pcib1 fwohci0: 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: on fwohci0 firewire0: firewire bus attach sbp_identify sbp_probe sbp0: on firewire0 sbp_attach cbb0: at device 2.0 on pci1 start (88000000) < sc->membase (f4100000) end (ffffffff) > sc->memlimit (f41fffff) cardbus0: 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: port 0x3000-0x303f mem 0xf4100000-0xf4100fff irq 9 at device 8.0 on pci1 fxp0: Ethernet address 08:00:46:17:51:ab inphy0: on miibus0 inphy0: 10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto isab0: at device 31.0 on pci0 isa0: on isab0 atapci0: port 0x1800-0x180f at device 31.1 on pci0 ata0: at 0x1f0 irq 14 on atapci0 ata1: at 0x170 irq 15 on atapci0 uhci0: port 0x1820-0x183f irq 9 at device 31.2 on pci0 uhci0: LegSup = 0x00001f20 usb0: 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: at device 31.3 (no driver attached) uhci1: port 0x2400-0x241f irq 9 at device 31.4 on pci0 uhci1: LegSup = 0x00002f00 usb1: 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: at device 31.5 (no driver attached) pci0: at device 31.6 (no driver attached) atkbdc0: port 0x64,0x60 irq 1 on acpi0 atkbd0: flags 0x1 irq 1 on atkbdc0 kbd0 at atkbd0 psm0: irq 12 on atkbdc0 psm0: model GlidePoint, device ID 0 acpi_ec0: port 0x66,0x62 on acpi0 acpi_cmbat0: on acpi0 acpi_acad0: 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: on ppbus0 lpt0: Interrupt-driven port acpi_timer0: <24-bit timer at 3.579545MHz> port 0x1008-0x100b on acpi0 npx0: on motherboard npx0: INT 16 interface pmtimer0 on isa0 orm0: