Date: Thu, 10 Apr 2008 12:46:48 +1000 From: Peter Jeremy <peter.jeremy@alcatel-lucent.com.au> To: freebsd-usb@freebsd.org Subject: MP3 Player not recognized by USB stack Message-ID: <20080410024648.GA960@pjdesk.alcatel.com.au>
next in thread | raw e-mail | index | archive | help
--NzB8fVQJ5HfG6fxh
Content-Type: text/plain; charset=us-ascii
Content-Disposition: inline
Content-Transfer-Encoding: quoted-printable
I am getting timeouts and I/O errors when I connect my MP3 player to
my desktop. The MP3 player works OK on my laptop. Both are running
7-stable/amd64 from about a month ago. The MP3 player does require
adding a new vendor to usbdevs and a SCSI quirk but this has been done
on both systems. Can anyone suggest where to start investigating?
The desktop is a Dell OptiPlex GX620. Relevant parts of dmesg follow:
FreeBSD 7.0-STABLE #7: Thu Apr 10 10:50:36 EST 2008
root@pjdesk.alcatel.com.au:/var/obj/usr/src/sys/pjdesk
CPU: Intel(R) Pentium(R) 4 CPU 3.20GHz (3192.01-MHz K8-class CPU)
Origin =3D "GenuineIntel" Id =3D 0xf43 Stepping =3D 3
Features=3D0xbfebfbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PG=
E,MCA,CMOV,PAT,PSE36,CLFLUSH,DTS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE>
Features2=3D0x649d<SSE3,RSVD2,MON,DS_CPL,EST,CNXT-ID,CX16,xTPR>
AMD Features=3D0x20100800<SYSCALL,NX,LM>
Logical CPUs per core: 2
usable memory =3D 2137473024 (2038 MB)
uhci0: <UHCI (generic) USB controller> port 0xff80-0xff9f irq 21 at device =
29.0 on pci0
uhci0: [GIANT-LOCKED]
uhci0: [ITHREAD]
uhci0: LegSup =3D 0x001b
usb0: <UHCI (generic) USB controller> on uhci0
usb0: USB revision 1.0
usbd_get_string: getting lang failed, using 0
uhub0: <Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1> on usb0
uhub0: 2 ports with 2 removable, self powered
uhci1: <UHCI (generic) USB controller> port 0xff60-0xff7f irq 22 at device =
29.1 on pci0
uhci1: [GIANT-LOCKED]
uhci1: [ITHREAD]
uhci1: LegSup =3D 0x0010
usb1: <UHCI (generic) USB controller> on uhci1
usb1: USB revision 1.0
usbd_get_string: getting lang failed, using 0
uhub1: <Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1> on usb1
uhub1: 2 ports with 2 removable, self powered
uhci2: <UHCI (generic) USB controller> port 0xff40-0xff5f irq 18 at device =
29.2 on pci0
uhci2: [GIANT-LOCKED]
uhci2: [ITHREAD]
uhci2: LegSup =3D 0x0010
usb2: <UHCI (generic) USB controller> on uhci2
usb2: USB revision 1.0
usbd_get_string: getting lang failed, using 0
uhub2: <Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1> on usb2
uhub2: 2 ports with 2 removable, self powered
uhci3: <UHCI (generic) USB controller> port 0xff20-0xff3f irq 23 at device =
29.3 on pci0
uhci3: [GIANT-LOCKED]
uhci3: [ITHREAD]
uhci3: LegSup =3D 0x0010
usb3: <UHCI (generic) USB controller> on uhci3
usb3: USB revision 1.0
usbd_get_string: getting lang failed, using 0
uhub3: <Intel UHCI root hub, class 9/0, rev 1.00/1.00, addr 1> on usb3
uhub3: 2 ports with 2 removable, self powered
ehci0: <Intel 82801GB/R (ICH7) USB 2.0 controller> mem 0xffa80800-0xffa80bf=
f irq 21 at device 29.7 on pci0
ehci0: [GIANT-LOCKED]
ehci0: [ITHREAD]
usb4: waiting for BIOS to give up control
usb4: EHCI version 1.0
usb4: companion controllers, 2 ports each: usb0 usb1 usb2 usb3
usb4: <Intel 82801GB/R (ICH7) USB 2.0 controller> on ehci0
usb4: USB revision 2.0
uhub4: <Intel EHCI root hub, class 9/0, rev 2.00/1.00, addr 1> on usb4
uhub4: 8 ports with 8 removable, self powered
uhub5: <Dell Dell USB Keyboard Hub, class 9/0, rev 1.10/2.00, addr 2> on uh=
ub1
uhub5: 3 ports with 2 removable, bus powered
ukbd0: <Dell Dell USB Keyboard, class 0/0, rev 1.10/2.00, addr 3> on uhub5
kbd0 at ukbd0
uhid0: <Dell Dell USB Keyboard, class 0/0, rev 1.10/2.00, addr 3> on uhub5
ums0: <vendor 0x413c product 0x3010, class 0/0, rev 2.00/2.30, addr 4> on u=
hub5
ums0: 3 buttons and Z dir.
With hw.usb.debug=3D3, hw.usb.ehci.debug=3D3, hw.usb.uhci.debug=3D3 and
hw.usb.umass.debug=3D10, plugging in the player reports:
ehci_pcd: change=3D0x02
usb_needs_explore
usb_event_thread: woke up
usb_discover
ehci after reset, status=3D0x00001005
ehci port 1 reset, status =3D 0x00001005
usbd_reset_port: port 1 reset done, error=3DNORMAL_COMPLETION
usbd_new_device bus=3D0xffffff0001236000 port=3D1 depth=3D1 speed=3D3
usbd_new_device: high speed port 0
usbd_setup_pipe: dev=3D0xffffff0004024800 iface=3D0 ep=3D0xffffff0004024838=
pipe=3D0xffffff0004024808
ehci_open: pipe=3D0xffffff000460f980, addr=3D0, endpt=3D0 (1)
ehci_alloc_sqtd_chain: start len=3D8
ehci_check_intr: ex=3D0xffffff000123f800
ehci_idone: ex=3D0xffffff000123f800
ehci_idone: xfer=3D0xffffff000123f800, pipe=3D0xffffff000460f980 ready
ehci_idone: len=3D8, actlen=3D8, cerr=3D3, status=3D0x8c00
ehci_idone: ex=3D0xffffff000123f800 done
usbd_new_device: adding unit addr=3D2, rev=3D200, class=3D0, subclass=3D0, =
protocol=3D0, maxpacket=3D64, len=3D18, speed=3D3
usbd_ar_pipe: pipe=3D0xffffff000460f980
ehci_device_ctrl_close: pipe=3D0xffffff000460f980
ehci_sync_hc: enter
ehci_sync_hc: cmd=3D0x00020071 sts=3D0x0000c000
ehci_intr1: door bell
ehci_sync_hc: cmd=3D0x00020031 sts=3D0x0000c000
ehci_sync_hc: exit
usbd_setup_pipe: dev=3D0xffffff0004024800 iface=3D0 ep=3D0xffffff0004024838=
pipe=3D0xffffff0004024808
ehci_open: pipe=3D0xffffff000460f900, addr=3D0, endpt=3D0 (1)
ehci_alloc_sqtd_chain: start len=3D18
ehci_check_intr: ex=3D0xffffff000123f800
ehci_idone: ex=3D0xffffff000123f800
ehci_idone: xfer=3D0xffffff000123f800, pipe=3D0xffffff000460f900 ready
ehci_idone: len=3D18, actlen=3D18, cerr=3D3, status=3D0x8c00
ehci_idone: ex=3D0xffffff000123f800 done
ehci_check_intr: ex=3D0xffffff000123f800
ehci_idone: ex=3D0xffffff000123f800
ehci_idone: xfer=3D0xffffff000123f800, pipe=3D0xffffff000460f900 ready
ehci_idone: len=3D0, actlen=3D0, cerr=3D3, status=3D0x8d00
ehci_idone: ex=3D0xffffff000123f800 done
usbd_ar_pipe: pipe=3D0xffffff000460f900
ehci_device_ctrl_close: pipe=3D0xffffff000460f900
ehci_sync_hc: enter
ehci_sync_hc: cmd=3D0x00020071 sts=3D0x0000c000
ehci_intr1: door bell
ehci_sync_hc: cmd=3D0x00020031 sts=3D0x0000c000
ehci_sync_hc: exit
usbd_setup_pipe: dev=3D0xffffff0004024800 iface=3D0 ep=3D0xffffff0004024838=
pipe=3D0xffffff0004024808
ehci_open: pipe=3D0xffffff000460f880, addr=3D2, endpt=3D0 (1)
usbd_new_device: new dev (addr 2), dev=3D0xffffff0004024800, parent=3D0xfff=
fff0001248600
usbd_probe_and_attach: trying device specific drivers
ehci_alloc_sqtd_chain: start len=3D2
ehci_check_intr: ex=3D0xffffff000123f800
ehci_idone: ex=3D0xffffff000123f800
ehci_idone: xfer=3D0xffffff000123f800, pipe=3D0xffffff000460f880 ready
ehci_idone: len=3D2, actlen=3D2, cerr=3D3, status=3D0x8c00
ehci_idone: ex=3D0xffffff000123f800 done
ehci_alloc_sqtd_chain: start len=3D4
ehci_check_intr: ex=3D0xffffff000123f800
ehci_idone: ex=3D0xffffff000123f800
ehci_idone: xfer=3D0xffffff000123f800, pipe=3D0xffffff000460f880 ready
ehci_idone: len=3D4, actlen=3D4, cerr=3D3, status=3D0x8c00
ehci_idone: ex=3D0xffffff000123f800 done
ehci_alloc_sqtd_chain: start len=3D2
ehci_check_intr: ex=3D0xffffff000123f800
ehci_idone: ex=3D0xffffff000123f800
ehci_idone: xfer=3D0xffffff000123f800, pipe=3D0xffffff000460f880 ready
ehci_idone: len=3D2, actlen=3D2, cerr=3D3, status=3D0x8c00
ehci_idone: ex=3D0xffffff000123f800 done
ehci_alloc_sqtd_chain: start len=3D48
ehci_check_intr: ex=3D0xffffff000123f800
ehci_idone: ex=3D0xffffff000123f800
ehci_idone: xfer=3D0xffffff000123f800, pipe=3D0xffffff000460f880 ready
ehci_idone: len=3D48, actlen=3D48, cerr=3D3, status=3D0x8c00
ehci_idone: ex=3D0xffffff000123f800 done
ehci_alloc_sqtd_chain: start len=3D2
ehci_check_intr: ex=3D0xffffff000123f800
ehci_idone: ex=3D0xffffff000123f800
ehci_idone: xfer=3D0xffffff000123f800, pipe=3D0xffffff000460f880 ready
ehci_idone: len=3D2, actlen=3D2, cerr=3D3, status=3D0x8c00
ehci_idone: ex=3D0xffffff000123f800 done
ehci_alloc_sqtd_chain: start len=3D66
ehci_check_intr: ex=3D0xffffff000123f800
ehci_idone: ex=3D0xffffff000123f800
ehci_idone: xfer=3D0xffffff000123f800, pipe=3D0xffffff000460f880 ready
ehci_idone: len=3D66, actlen=3D0, cerr=3D0, status=3D0x80420148
ehci_idone: error, addr=3D2, endpt=3D0x00, status 0x80420148<HALTED,XACTERR>
QH(0xffffffffaedb8f80) at 0x02f22f80:
sqtd=3D0xffffffffaedb9f00 inactivesqtd=3D0xffffffffaedb9f80
link=3D0x0285f002<QH>
endp=3D0x80406002
addr=3D0x02 inact=3D0 endpt=3D0 eps=3D2 dtc=3D1 hrecl=3D0
mpl=3D0x40 ctl=3D0 nrl=3D8
endphub=3D0x40811c00
smask=3D0x00 cmask=3D0x1c huba=3D0x01 port=3D1 mult=3D1
curqtd=3D0x030bae00<>
Overlay qTD:
next=3D0x030baf80<> altnext=3D0x030baf90<>
status=3D0x80420148: toggle=3D1 bytes=3D0x42 ioc=3D0 c_page=3D0x0
cerr=3D0 pid=3D1 stat=3D0x48<HALTED,XACTERR>
buffer[0]=3D0x01253ff0
buffer[1]=3D0x01254000
buffer[2]=3D0x00000000
buffer[3]=3D0x00000000
buffer[4]=3D0x00000000
QTD(0xffffffffaedb9f00) at 0x030baf00:
next=3D0x030bae00<> altnext=3D0x030baf80<>
status=3D0x80000e00: toggle=3D1 bytes=3D0x0 ioc=3D0 c_page=3D0x0
cerr=3D3 pid=3D2 stat=3D0x0
buffer[0]=3D0x01dee988
buffer[1]=3D0x00000000
buffer[2]=3D0x00000000
buffer[3]=3D0x00000000
buffer[4]=3D0x00000000
QTD(0xffffffffaedb9e00) at 0x030bae00:
next=3D0x030bae80<> altnext=3D0x030baf80<>
status=3D0x80420148: toggle=3D1 bytes=3D0x42 ioc=3D0 c_page=3D0x0
cerr=3D0 pid=3D1 stat=3D0x48<HALTED,XACTERR>
buffer[0]=3D0x01253ff0
buffer[1]=3D0x01254000
buffer[2]=3D0x00000000
buffer[3]=3D0x00000000
buffer[4]=3D0x00000000
QTD(0xffffffffaedb9e80) at 0x030bae80:
next=3D0x030baf80<> altnext=3D0x030baf80<>
status=3D0x80008c80: toggle=3D1 bytes=3D0x0 ioc=3D1 c_page=3D0x0
cerr=3D3 pid=3D0 stat=3D0x80<ACTIVE>
buffer[0]=3D0x00000000
buffer[1]=3D0x00000000
buffer[2]=3D0x00000000
buffer[3]=3D0x00000000
buffer[4]=3D0x00000000
QTD(0xffffffffaedb9f80) at 0x030baf80:
next=3D0x00000001<T> altnext=3D0x00000001<T>
status=3D0x00000000: toggle=3D0 bytes=3D0x0 ioc=3D0 c_page=3D0x0
cerr=3D0 pid=3D0 stat=3D0x0
buffer[0]=3D0x00000000
buffer[1]=3D0x00000000
buffer[2]=3D0x00000000
buffer[3]=3D0x00000000
buffer[4]=3D0x00000000
ehci_idone: ex=3D0xffffff000123f800 done
usbd_probe_and_attach: no device specific driver found
usbd_probe_and_attach: looping over 1 configurations
usbd_probe_and_attach: trying config idx=3D0
ehci_alloc_sqtd_chain: start len=3D9
Unknown USB device: vendor 0x043e product 0x70b0 bus uhub4
[5 second delay here]
ehci_timeout: exfer=3D0xffffff000123f800
usbd_dump_pipe: pipe=3D0xffffff000460f880
usbd_dump_iface: iface=3D0
usbd_dump_device: dev=3D0xffffff0004024800
bus=3D0xffffff0001236000 default_pipe=3D0xffffff000460f880
address=3D2 config=3D0 depth=3D1 speed=3D3 self_powered=3D0 power=3D100 lan=
gid=3D1033
usbd_dump_endpoint: endp=3D0xffffff0004024838
edesc=3D0xffffff0004024848 refcnt=3D1
bEndpointAddress=3D0x00
(usbd_dump_pipe:)
refcnt=3D1 running=3D1 aborting=3D0
intrxfer=3D0, repeat=3D0, interval=3D-1
usb_add_task: task=3D0xffffff000123fad8
usb_task_thread: woke up task=3D0xffffff000123fad8
ehci_timeout_task: xfer=3D0xffffff000123f800
ehci_abort_xfer: xfer=3D0xffffff000123f800 pipe=3D0xffffff000460f880
ehci_sync_hc: enter
ehci_sync_hc: cmd=3D0x00020071 sts=3D0x0000c008
ehci_intr1: door bell
ehci_sync_hc: cmd=3D0x00020031 sts=3D0x0000c000
ehci_sync_hc: exit
ehci_check_intr: ex=3D0xffffff000123f800
uhub4: port 1, set config at addr 2 failed, error=3DTIMEOUT
usbd_remove_device: 0xffffff0004024800
usbd_ar_pipe: pipe=3D0xffffff000460f880
ehci_device_ctrl_close: pipe=3D0xffffff000460f880
ehci_sync_hc: enter
ehci_sync_hc: cmd=3D0x00020071 sts=3D0x0000c000
ehci_intr1: door bell
ehci_sync_hc: cmd=3D0x00020031 sts=3D0x0000c000
ehci_sync_hc: exit
uhub_explore: usb_new_device failed, error=3DTIMEOUT
uhub4: device problem (TIMEOUT), disabling port 1
[device removed here]
ehci_pcd: change=3D0x02
usb_needs_explore
usb_event_thread: woke up
usb_discover
--=20
Peter Jeremy
--NzB8fVQJ5HfG6fxh
Content-Type: application/pgp-signature
Content-Disposition: inline
-----BEGIN PGP SIGNATURE-----
Version: GnuPG v2.0.8 (FreeBSD)
iEYEARECAAYFAkf9f5gACgkQ/opHv/APuIcs/ACfVKDaL0p1LhSaeChbnir5YYKr
iZwAoILwk6+7t4BTJJGoWtZf8Z6iK2nq
=f9Mt
-----END PGP SIGNATURE-----
--NzB8fVQJ5HfG6fxh--
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?20080410024648.GA960>