MP3 Player not recognized by USB stack

Peter Jeremy peter.jeremy at alcatel-lucent.com.au
Thu Apr 10 03:07:56 UTC 2008 

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 at 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 = "GenuineIntel"  Id = 0xf43  Stepping = 3
  Features=0xbfebfbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,CLFLUSH,DTS,ACPI,MMX,FXSR,SSE,SSE2,SS,HTT,TM,PBE>
  Features2=0x649d<SSE3,RSVD2,MON,DS_CPL,EST,CNXT-ID,CX16,xTPR>
  AMD Features=0x20100800<SYSCALL,NX,LM>
  Logical CPUs per core: 2
usable memory = 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 = 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 = 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 = 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 = 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-0xffa80bff 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 uhub1
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 uhub5
ums0: 3 buttons and Z dir.

With hw.usb.debug=3, hw.usb.ehci.debug=3, hw.usb.uhci.debug=3 and
hw.usb.umass.debug=10, plugging in the player reports:

ehci_pcd: change=0x02
usb_needs_explore
usb_event_thread: woke up
usb_discover
ehci after reset, status=0x00001005
ehci port 1 reset, status = 0x00001005
usbd_reset_port: port 1 reset done, error=NORMAL_COMPLETION
usbd_new_device bus=0xffffff0001236000 port=1 depth=1 speed=3
usbd_new_device: high speed port 0
usbd_setup_pipe: dev=0xffffff0004024800 iface=0 ep=0xffffff0004024838 pipe=0xffffff0004024808
ehci_open: pipe=0xffffff000460f980, addr=0, endpt=0 (1)
ehci_alloc_sqtd_chain: start len=8
ehci_check_intr: ex=0xffffff000123f800
ehci_idone: ex=0xffffff000123f800
ehci_idone: xfer=0xffffff000123f800, pipe=0xffffff000460f980 ready
ehci_idone: len=8, actlen=8, cerr=3, status=0x8c00
ehci_idone: ex=0xffffff000123f800 done
usbd_new_device: adding unit addr=2, rev=200, class=0, subclass=0, protocol=0, maxpacket=64, len=18, speed=3
usbd_ar_pipe: pipe=0xffffff000460f980
ehci_device_ctrl_close: pipe=0xffffff000460f980
ehci_sync_hc: enter
ehci_sync_hc: cmd=0x00020071 sts=0x0000c000
ehci_intr1: door bell
ehci_sync_hc: cmd=0x00020031 sts=0x0000c000
ehci_sync_hc: exit
usbd_setup_pipe: dev=0xffffff0004024800 iface=0 ep=0xffffff0004024838 pipe=0xffffff0004024808
ehci_open: pipe=0xffffff000460f900, addr=0, endpt=0 (1)
ehci_alloc_sqtd_chain: start len=18
ehci_check_intr: ex=0xffffff000123f800
ehci_idone: ex=0xffffff000123f800
ehci_idone: xfer=0xffffff000123f800, pipe=0xffffff000460f900 ready
ehci_idone: len=18, actlen=18, cerr=3, status=0x8c00
ehci_idone: ex=0xffffff000123f800 done
ehci_check_intr: ex=0xffffff000123f800
ehci_idone: ex=0xffffff000123f800
ehci_idone: xfer=0xffffff000123f800, pipe=0xffffff000460f900 ready
ehci_idone: len=0, actlen=0, cerr=3, status=0x8d00
ehci_idone: ex=0xffffff000123f800 done
usbd_ar_pipe: pipe=0xffffff000460f900
ehci_device_ctrl_close: pipe=0xffffff000460f900
ehci_sync_hc: enter
ehci_sync_hc: cmd=0x00020071 sts=0x0000c000
ehci_intr1: door bell
ehci_sync_hc: cmd=0x00020031 sts=0x0000c000
ehci_sync_hc: exit
usbd_setup_pipe: dev=0xffffff0004024800 iface=0 ep=0xffffff0004024838 pipe=0xffffff0004024808
ehci_open: pipe=0xffffff000460f880, addr=2, endpt=0 (1)
usbd_new_device: new dev (addr 2), dev=0xffffff0004024800, parent=0xffffff0001248600
usbd_probe_and_attach: trying device specific drivers
ehci_alloc_sqtd_chain: start len=2
ehci_check_intr: ex=0xffffff000123f800
ehci_idone: ex=0xffffff000123f800
ehci_idone: xfer=0xffffff000123f800, pipe=0xffffff000460f880 ready
ehci_idone: len=2, actlen=2, cerr=3, status=0x8c00
ehci_idone: ex=0xffffff000123f800 done
ehci_alloc_sqtd_chain: start len=4
ehci_check_intr: ex=0xffffff000123f800
ehci_idone: ex=0xffffff000123f800
ehci_idone: xfer=0xffffff000123f800, pipe=0xffffff000460f880 ready
ehci_idone: len=4, actlen=4, cerr=3, status=0x8c00
ehci_idone: ex=0xffffff000123f800 done
ehci_alloc_sqtd_chain: start len=2
ehci_check_intr: ex=0xffffff000123f800
ehci_idone: ex=0xffffff000123f800
ehci_idone: xfer=0xffffff000123f800, pipe=0xffffff000460f880 ready
ehci_idone: len=2, actlen=2, cerr=3, status=0x8c00
ehci_idone: ex=0xffffff000123f800 done
ehci_alloc_sqtd_chain: start len=48
ehci_check_intr: ex=0xffffff000123f800
ehci_idone: ex=0xffffff000123f800
ehci_idone: xfer=0xffffff000123f800, pipe=0xffffff000460f880 ready
ehci_idone: len=48, actlen=48, cerr=3, status=0x8c00
ehci_idone: ex=0xffffff000123f800 done
ehci_alloc_sqtd_chain: start len=2
ehci_check_intr: ex=0xffffff000123f800
ehci_idone: ex=0xffffff000123f800
ehci_idone: xfer=0xffffff000123f800, pipe=0xffffff000460f880 ready
ehci_idone: len=2, actlen=2, cerr=3, status=0x8c00
ehci_idone: ex=0xffffff000123f800 done
ehci_alloc_sqtd_chain: start len=66
ehci_check_intr: ex=0xffffff000123f800
ehci_idone: ex=0xffffff000123f800
ehci_idone: xfer=0xffffff000123f800, pipe=0xffffff000460f880 ready
ehci_idone: len=66, actlen=0, cerr=0, status=0x80420148
ehci_idone: error, addr=2, endpt=0x00, status 0x80420148<HALTED,XACTERR>
QH(0xffffffffaedb8f80) at 0x02f22f80:
sqtd=0xffffffffaedb9f00 inactivesqtd=0xffffffffaedb9f80
link=0x0285f002<QH>
endp=0x80406002
addr=0x02 inact=0 endpt=0 eps=2 dtc=1 hrecl=0
mpl=0x40 ctl=0 nrl=8
endphub=0x40811c00
smask=0x00 cmask=0x1c huba=0x01 port=1 mult=1
curqtd=0x030bae00<>
Overlay qTD:
next=0x030baf80<> altnext=0x030baf90<>
status=0x80420148: toggle=1 bytes=0x42 ioc=0 c_page=0x0
cerr=0 pid=1 stat=0x48<HALTED,XACTERR>
buffer[0]=0x01253ff0
buffer[1]=0x01254000
buffer[2]=0x00000000
buffer[3]=0x00000000
buffer[4]=0x00000000
QTD(0xffffffffaedb9f00) at 0x030baf00:
next=0x030bae00<> altnext=0x030baf80<>
status=0x80000e00: toggle=1 bytes=0x0 ioc=0 c_page=0x0
cerr=3 pid=2 stat=0x0
buffer[0]=0x01dee988
buffer[1]=0x00000000
buffer[2]=0x00000000
buffer[3]=0x00000000
buffer[4]=0x00000000
QTD(0xffffffffaedb9e00) at 0x030bae00:
next=0x030bae80<> altnext=0x030baf80<>
status=0x80420148: toggle=1 bytes=0x42 ioc=0 c_page=0x0
cerr=0 pid=1 stat=0x48<HALTED,XACTERR>
buffer[0]=0x01253ff0
buffer[1]=0x01254000
buffer[2]=0x00000000
buffer[3]=0x00000000
buffer[4]=0x00000000
QTD(0xffffffffaedb9e80) at 0x030bae80:
next=0x030baf80<> altnext=0x030baf80<>
status=0x80008c80: toggle=1 bytes=0x0 ioc=1 c_page=0x0
cerr=3 pid=0 stat=0x80<ACTIVE>
buffer[0]=0x00000000
buffer[1]=0x00000000
buffer[2]=0x00000000
buffer[3]=0x00000000
buffer[4]=0x00000000
QTD(0xffffffffaedb9f80) at 0x030baf80:
next=0x00000001<T> altnext=0x00000001<T>
status=0x00000000: toggle=0 bytes=0x0 ioc=0 c_page=0x0
cerr=0 pid=0 stat=0x0
buffer[0]=0x00000000
buffer[1]=0x00000000
buffer[2]=0x00000000
buffer[3]=0x00000000
buffer[4]=0x00000000
ehci_idone: ex=0xffffff000123f800 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=0
ehci_alloc_sqtd_chain: start len=9
Unknown USB device: vendor 0x043e product 0x70b0 bus uhub4
[5 second delay here]
ehci_timeout: exfer=0xffffff000123f800
usbd_dump_pipe: pipe=0xffffff000460f880
usbd_dump_iface: iface=0
usbd_dump_device: dev=0xffffff0004024800
bus=0xffffff0001236000 default_pipe=0xffffff000460f880
address=2 config=0 depth=1 speed=3 self_powered=0 power=100 langid=1033
usbd_dump_endpoint: endp=0xffffff0004024838
edesc=0xffffff0004024848 refcnt=1
bEndpointAddress=0x00
(usbd_dump_pipe:)
refcnt=1 running=1 aborting=0
intrxfer=0, repeat=0, interval=-1
usb_add_task: task=0xffffff000123fad8
usb_task_thread: woke up task=0xffffff000123fad8
ehci_timeout_task: xfer=0xffffff000123f800
ehci_abort_xfer: xfer=0xffffff000123f800 pipe=0xffffff000460f880
ehci_sync_hc: enter
ehci_sync_hc: cmd=0x00020071 sts=0x0000c008
ehci_intr1: door bell
ehci_sync_hc: cmd=0x00020031 sts=0x0000c000
ehci_sync_hc: exit
ehci_check_intr: ex=0xffffff000123f800
uhub4: port 1, set config at addr 2 failed, error=TIMEOUT
usbd_remove_device: 0xffffff0004024800
usbd_ar_pipe: pipe=0xffffff000460f880
ehci_device_ctrl_close: pipe=0xffffff000460f880
ehci_sync_hc: enter
ehci_sync_hc: cmd=0x00020071 sts=0x0000c000
ehci_intr1: door bell
ehci_sync_hc: cmd=0x00020031 sts=0x0000c000
ehci_sync_hc: exit
uhub_explore: usb_new_device failed, error=TIMEOUT
uhub4: device problem (TIMEOUT), disabling port 1
[device removed here]
ehci_pcd: change=0x02
usb_needs_explore
usb_event_thread: woke up
usb_discover

-- 
Peter Jeremy
-------------- next part --------------
A non-text attachment was scrubbed...
Name: not available
Type: application/pgp-signature
Size: 195 bytes
Desc: not available
Url : http://lists.freebsd.org/pipermail/freebsd-usb/attachments/20080410/b0a1e440/attachment.pgp

More information about the freebsd-usb mailing list