USB device not coming up (Diva MP3 player)

[Pav Lucistnik]

Hi, I run RELENG_5_1 from 31th May (identifies as 5.1-RC)
on Microstar motherboard with onboard VIA 83C572 USB2.0 controller.

I purchased Diva MP3 Player today, and I don't work here. 
I plug it in, the device powers up, and promptly I see 

uhub0: port error, restarting port 1

I don't have ehci device in my kernel, and similar device works fine for
my friend with -STABLE.

I bumped some usb debug sysctls up to 10 and here is the output:

usb_transfer_complete: pipe=0xc2607400 xfer=0xc2533300 status=0 actlen=1
usb_transfer_complete: repeat=1 new head=0xc2533300
uhub_intr: sc=0xc259f8b0
usb_needs_explore
usb_event_thread: woke up
usb_discover
usbd_alloc_xfer() = 0xc2533b00
usbd_transfer: xfer=0xc2533b00, flags=2, pipe=0xc2607000, running=0
usbd_dump_queue: pipe=0xc2607000
usb_insert_transfer: pipe=0xc2607000 running=0 timeout=5000
uhci_root_ctrl_control type=0xa3 request=00
usb_transfer_complete: pipe=0xc2607000 xfer=0xc2533b00 status=0 actlen=4
usb_transfer_complete: repeat=0 new head=0
usbd_start_next: pipe=0xc2607000, xfer=0
usbd_free_xfer: 0xc2533b00
uhub_explore: uhub0 port 1 status 0x0109 0x0001
uhub_explore: status change hub=1 port=1
usbd_alloc_xfer() = 0xc2533b00
usbd_transfer: xfer=0xc2533b00, flags=2, pipe=0xc2607000, running=0
usbd_dump_queue: pipe=0xc2607000
usb_insert_transfer: pipe=0xc2607000 running=0 timeout=5000
uhci_root_ctrl_control type=0x23 request=01
uhci_root_ctrl_control: UR_CLEAR_PORT_FEATURE port=1 feature=16
usb_transfer_complete: pipe=0xc2607000 xfer=0xc2533b00 status=0 actlen=0
usb_transfer_complete: repeat=0 new head=0
usbd_start_next: pipe=0xc2607000, xfer=0
usbd_free_xfer: 0xc2533b00
usbd_alloc_xfer() = 0xc2533b00
usbd_transfer: xfer=0xc2533b00, flags=2, pipe=0xc2607000, running=0
usbd_dump_queue: pipe=0xc2607000
usb_insert_transfer: pipe=0xc2607000 running=0 timeout=5000
uhci_root_ctrl_control type=0x23 request=03
usb_transfer_complete: pipe=0xc2607400 xfer=0xc2533300 status=0 actlen=1
usb_transfer_complete: repeat=1 new head=0xc2533300
uhub_intr: sc=0xc259f8b0
usb_needs_explore
uhci port 1 reset, status = 0x0495
usb_transfer_complete: pipe=0xc2607000 xfer=0xc2533b00 status=0 actlen=0
usb_transfer_complete: repeat=0 new head=0
usbd_start_next: pipe=0xc2607000, xfer=0
usbd_free_xfer: 0xc2533b00
usbd_reset_port: port 1 reset done, error=NORMAL_COMPLETION
usbd_alloc_xfer() = 0xc2533b00
usbd_transfer: xfer=0xc2533b00, flags=2, pipe=0xc2607000, running=0
usbd_dump_queue: pipe=0xc2607000
usb_insert_transfer: pipe=0xc2607000 running=0 timeout=5000
uhci_root_ctrl_control type=0xa3 request=00
usb_transfer_complete: pipe=0xc2607000 xfer=0xc2533b00 status=0 actlen=4
usb_transfer_complete: repeat=0 new head=0
usbd_start_next: pipe=0xc2607000, xfer=0
usbd_free_xfer: 0xc2533b00
usbd_alloc_xfer() = 0xc2533b00
usbd_transfer: xfer=0xc2533b00, flags=2, pipe=0xc2607000, running=0
usbd_dump_queue: pipe=0xc2607000
usb_insert_transfer: pipe=0xc2607000 running=0 timeout=5000
uhci_root_ctrl_control type=0x23 request=01
uhci_root_ctrl_control: UR_CLEAR_PORT_FEATURE port=1 feature=20
usb_transfer_complete: pipe=0xc2607000 xfer=0xc2533b00 status=0 actlen=0
usb_transfer_complete: repeat=0 new head=0
usbd_start_next: pipe=0xc2607000, xfer=0
usbd_free_xfer: 0xc2533b00
usb_transfer_complete: pipe=0xc2607400 xfer=0xc2533300 status=0 actlen=1
usb_transfer_complete: repeat=1 new head=0xc2533300
uhub_intr: sc=0xc259f8b0
usb_needs_explore
usbd_alloc_xfer() = 0xc2533b00
usbd_transfer: xfer=0xc2533b00, flags=2, pipe=0xc2607000, running=0
usbd_dump_queue: pipe=0xc2607000
usb_insert_transfer: pipe=0xc2607000 running=0 timeout=5000
uhci_root_ctrl_control type=0xa3 request=00
usb_transfer_complete: pipe=0xc2607000 xfer=0xc2533b00 status=0 actlen=4
usb_transfer_complete: repeat=0 new head=0
usbd_start_next: pipe=0xc2607000, xfer=0
usbd_free_xfer: 0xc2533b00
usbd_alloc_xfer() = 0xc2533b00
usbd_transfer: xfer=0xc2533b00, flags=2, pipe=0xc2607000, running=0
usbd_dump_queue: pipe=0xc2607000
usb_insert_transfer: pipe=0xc2607000 running=0 timeout=5000
uhci_root_ctrl_control type=0xa3 request=00
usb_transfer_complete: pipe=0xc2607000 xfer=0xc2533b00 status=0 actlen=4
usb_transfer_complete: repeat=0 new head=0
usbd_start_next: pipe=0xc2607000, xfer=0
usbd_free_xfer: 0xc2533b00
uhub_explore: uhub0 port 2 status 0x0108 0x0000
uhub_explore: port=2 !C_CONNECT_STATUS
usbd_alloc_xfer() = 0xc2533b00
usbd_transfer: xfer=0xc2533b00, flags=2, pipe=0xc2607000, running=0
usbd_dump_queue: pipe=0xc2607000
usb_insert_transfer: pipe=0xc2607000 running=0 timeout=5000
uhci_root_ctrl_control type=0xa3 request=00
usb_transfer_complete: pipe=0xc2607000 xfer=0xc2533b00 status=0 actlen=4
usb_transfer_complete: repeat=0 new head=0
usbd_start_next: pipe=0xc2607000, xfer=0
usbd_free_xfer: 0xc2533b00
uhub_explore: uhub0 port 1 status 0x0108 0x0003
uhub_explore: C_PORT_ENABLED
usbd_alloc_xfer() = 0xc2533b00
usbd_transfer: xfer=0xc2533b00, flags=2, pipe=0xc2607000, running=0
usbd_dump_queue: pipe=0xc2607000
usb_insert_transfer: pipe=0xc2607000 running=0 timeout=5000
uhci_root_ctrl_control type=0x23 request=01
uhci_root_ctrl_control: UR_CLEAR_PORT_FEATURE port=1 feature=17
usb_transfer_complete: pipe=0xc2607000 xfer=0xc2533b00 status=0 actlen=0
usb_transfer_complete: repeat=0 new head=0
usbd_start_next: pipe=0xc2607000, xfer=0
usbd_free_xfer: 0xc2533b00
uhub0: port error, restarting port 1
uhub_explore: port=1 !CURRENT_CONNECT_STATUS
usbd_alloc_xfer() = 0xc2533b00
usbd_transfer: xfer=0xc2533b00, flags=2, pipe=0xc2607000, running=0
usbd_dump_queue: pipe=0xc2607000
usb_insert_transfer: pipe=0xc2607000 running=0 timeout=5000
uhci_root_ctrl_control type=0xa3 request=00
usb_transfer_complete: pipe=0xc2607000 xfer=0xc2533b00 status=0 actlen=4
usb_transfer_complete: repeat=0 new head=0
usbd_start_next: pipe=0xc2607000, xfer=0
usbd_free_xfer: 0xc2533b00
uhub_explore: uhub0 port 2 status 0x0108 0x0000
uhub_explore: port=2 !C_CONNECT_STATUS
usb_transfer_complete: pipe=0xc2607400 xfer=0xc2533300 status=0 actlen=1
usb_transfer_complete: repeat=1 new head=0xc2533300
uhub_intr: sc=0xc259f8b0
usb_needs_explore
usb_event_thread: woke up
usb_discover
usbd_alloc_xfer() = 0xc2533b00
usbd_transfer: xfer=0xc2533b00, flags=2, pipe=0xc2607000, running=0
usbd_dump_queue: pipe=0xc2607000
usb_insert_transfer: pipe=0xc2607000 running=0 timeout=5000
uhci_root_ctrl_control type=0xa3 request=00
usb_transfer_complete: pipe=0xc2607000 xfer=0xc2533b00 status=0 actlen=4
usb_transfer_complete: repeat=0 new head=0
usbd_start_next: pipe=0xc2607000, xfer=0
usbd_free_xfer: 0xc2533b00
uhub_explore: uhub0 port 1 status 0x0108 0x0001
uhub_explore: status change hub=1 port=1
usbd_alloc_xfer() = 0xc2533b00
usbd_transfer: xfer=0xc2533b00, flags=2, pipe=0xc2607000, running=0
usbd_dump_queue: pipe=0xc2607000
usb_insert_transfer: pipe=0xc2607000 running=0 timeout=5000
uhci_root_ctrl_control type=0x23 request=01
uhci_root_ctrl_control: UR_CLEAR_PORT_FEATURE port=1 feature=16
usb_transfer_complete: pipe=0xc2607000 xfer=0xc2533b00 status=0 actlen=0
usb_transfer_complete: repeat=0 new head=0
usbd_start_next: pipe=0xc2607000, xfer=0
usbd_free_xfer: 0xc2533b00
uhub_explore: port=1 !CURRENT_CONNECT_STATUS
usbd_alloc_xfer() = 0xc2533b00
usbd_transfer: xfer=0xc2533b00, flags=2, pipe=0xc2607000, running=0
usbd_dump_queue: pipe=0xc2607000
usb_insert_transfer: pipe=0xc2607000 running=0 timeout=5000
uhci_root_ctrl_control type=0xa3 request=00
usb_transfer_complete: pipe=0xc2607000 xfer=0xc2533b00 status=0 actlen=4
usb_transfer_complete: repeat=0 new head=0
usbd_start_next: pipe=0xc2607000, xfer=0
usbd_free_xfer: 0xc2533b00
uhub_explore: uhub0 port 2 status 0x0108 0x0000
uhub_explore: port=2 !C_CONNECT_STATUS


-- 
Pav Lucistnik <pav at oook.cz>
My jsme pra- MEDZIK SILD LEVL NAJN -tele.