Skip site navigation (1)Skip section navigation (2)
Date:      Thu, 15 Mar 2012 13:48:40 +0100
From:      Holm Tiffe <holm@freibergnet.de>
To:        freebsd-usb@freebsd.org
Subject:   USB UHCI Problems on 8-Stable
Message-ID:  <20120315124840.GA64319@beast.freibergnet.de>

next in thread | raw e-mail | index | archive | help
Hi,

For some time I had problems with my Cam (Olympus FE-230), it wouldn't get
recognized again after unplugging it from the USB Connector.

Now I have a very similar Problem while trying to get an J-Link V8
compatible Jtag debugger working in conjunction with openocd.
Maybe there are bugs in openocd, but the bigger ones seems to be in
FreeBSDs USB drivers.

In the meantime I've cvs-supped to 8.3PRERELEASE (yesterday) built world
and kernel. I've commented the ?hci drivers out of the kernel config and
set USB_DEBUG.


At fist please Cc me, I'm not on freebsd-usb.

After Loading the uhci module:

Mar 15 13:21:09 unicorn kernel: uhci1: <VIA 83C572 USB controller> port 0xd000-0xd01f irq 15 at device 16.1 on pci0
Mar 15 13:21:09 unicorn kernel: uhci1: [ITHREAD]
Mar 15 13:21:09 unicorn kernel: usbus0: 12Mbps Full Speed USB v1.0
Mar 15 13:21:10 unicorn kernel: usb_bus_port_set_device: bus 0xc6e44cf0 devices[1] = 0xc5608800
Mar 15 13:21:10 unicorn kernel: ugen0.1: <VIA> at usbus0
Mar 15 13:21:10 unicorn kernel: uhub0: <VIA UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus0
Mar 15 13:21:10 unicorn kernel: uhub_attach: depth=0 selfpowered=1, parent=0, parent->selfpowered=0
Mar 15 13:21:10 unicorn kernel: uhub_attach: Getting HUB descriptor
Mar 15 13:21:10 unicorn kernel: usb_needs_explore: 
Mar 15 13:21:10 unicorn kernel: usb_needs_explore: No root HUB
Mar 15 13:21:10 unicorn kernel: usbus1: <VIA 83C572 USB controller> on uhci1
Mar 15 13:21:10 unicorn kernel: usbus1: 12Mbps Full Speed USB v1.0
Mar 15 13:21:10 unicorn kernel: usb_needs_explore: 
Mar 15 13:21:10 unicorn kernel: usb_needs_explore: No root HUB
Mar 15 13:21:10 unicorn kernel: uhci2: <VIA 83C572 USB controller> port 0xd400-0xd41f irq 5 at device 16.2 on pci0
Mar 15 13:21:10 unicorn kernel: uhci2: [ITHREAD]
Mar 15 13:21:10 unicorn kernel: usb_bus_port_set_device: bus 0xc6ed4cf0 devices[1] = 0xc5511c00
Mar 15 13:21:10 unicorn kernel: ugen1.1: <VIA> at usbus1
Mar 15 13:21:10 unicorn kernel: uhub1: <VIA UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus1
Mar 15 13:21:10 unicorn kernel: uhub_attach: depth=0 selfpowered=1, parent=0, parent->selfpowered=0
Mar 15 13:21:10 unicorn kernel: uhub_attach: Getting HUB descriptor
Mar 15 13:21:10 unicorn kernel: uhub_attach: turn on port 1 power
Mar 15 13:21:10 unicorn kernel: usb_needs_explore: 
Mar 15 13:21:10 unicorn kernel: usb_needs_explore: No root HUB
Mar 15 13:21:10 unicorn kernel: usbus2: <VIA 83C572 USB controller> on uhci2
Mar 15 13:21:10 unicorn kernel: usbus2: 12Mbps Full Speed USB v1.0
Mar 15 13:21:10 unicorn kernel: usb_needs_explore: 

...

# usbconfig list
ugen0.1: <UHCI root HUB VIA> at usbus0, cfg=0 md=HOST spd=FULL (12Mbps) pwr=SAVE
ugen1.1: <UHCI root HUB VIA> at usbus1, cfg=0 md=HOST spd=FULL (12Mbps) pwr=SAVE
ugen2.1: <UHCI root HUB VIA> at usbus2, cfg=0 md=HOST spd=FULL (12Mbps) pwr=SAVE
# 

Now connecting the J-Link

Mar 15 13:23:41 unicorn kernel: usb_bus_powerd: Recomputing power masks Mar 15 13:23:41 unicorn kernel: uhub_explore: udev=0xc5511c00 addr=1
Mar 15 13:23:41 unicorn kernel: uhub_read_port_status: port 1, wPortStatus=0x0108, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:23:41 unicorn kernel: uhub_read_port_status: port 2, wPortStatus=0x0109, wPortChange=0x0003, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:23:41 unicorn kernel: uhub_reattach_port: reattaching port 2
Mar 15 13:23:41 unicorn kernel: uhub_read_port_status: port 2, wPortStatus=0x0109, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:23:41 unicorn kernel: uhub_reattach_port: Port 2 is in Host Mode
Mar 15 13:23:42 unicorn root: Unknown USB device: vendor 0x1366 product 0x0101 bus uhub1
Mar 15 13:23:42 unicorn kernel: uhub_read_port_status: port 2, wPortStatus=0x010b, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:23:42 unicorn kernel: usbd_transfer_power_ref: Adding type 0 to power state
Mar 15 13:23:42 unicorn kernel: usbd_transfer_power_ref: needs power
Mar 15 13:23:42 unicorn kernel: usb_bus_port_set_device: bus 0xc6ed4cf0 devices[2] = 0xc5602400
Mar 15 13:23:42 unicorn kernel: ugen1.2: <SEGGER> at usbus1
Mar 15 13:23:42 unicorn kernel: usb_needs_explore: 
Mar 15 13:23:42 unicorn kernel: usb_bus_powerd: bus=0xc6e44cf0
Mar 15 13:23:42 unicorn kernel: usb_bus_powerd: Recomputing power masks
Mar 15 13:23:42 unicorn kernel: uhub_explore: udev=0xc5608800 addr=1
Mar 15 13:23:42 unicorn kernel: uhub_read_port_status: port 1, wPortStatus=0x0108, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:23:42 unicorn kernel: uhub_read_port_status: port 2, wPortStatus=0x0108, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:23:42 unicorn kernel: usb_needs_explore: 

..

# usbconfig list
ugen0.1: <UHCI root HUB VIA> at usbus0, cfg=0 md=HOST spd=FULL (12Mbps) pwr=SAVE
ugen1.1: <UHCI root HUB VIA> at usbus1, cfg=0 md=HOST spd=FULL (12Mbps) pwr=SAVE
ugen2.1: <UHCI root HUB VIA> at usbus2, cfg=0 md=HOST spd=FULL (12Mbps) pwr=SAVE
ugen1.2: <J-Link SEGGER> at usbus1, cfg=0 md=HOST spd=FULL (12Mbps) pwr=ON
# 

now starting openocd:

$ openocd  -f jlinkv8.cfg
Open On-Chip Debugger 0.5.0 (2012-03-15-08:44)
Licensed under GNU GPL v2
For bug reports, read
        http://openocd.berlios.de/doc/doxygen/bugs.html
Warn : Adapter driver 'jlink' did not declare which transports it allows;
assuming legacy JTAG-only
Info : only one transport option; autoselect 'jtag'
1000 kHz
adapter_nsrst_delay: 100
jtag_ntrst_delay: 100
cortex_m3 reset_config sysresetreq
250 kHz
Info : J-Link initialization started / target CPU reset initiated
Info : J-Link ARM V8 compiled Dec 16 2010 20:21:29
Info : J-Link caps 0xb9ff7bbf
Info : J-Link hw version 80000
Info : J-Link hw type J-Link
Info : J-Link max mem block 8368
Info : J-Link configuration
Info : USB-Address: 0x0
Info : Kickstart power on JTAG-pin 19: 0xffffffff
Info : Vref = 3.228 TCK = 1 TDI = 0 TDO = 1 TMS = 0 SRST = 0 TRST = 0
Info : J-Link JTAG Interface ready
Error: usb_bulk_read failed (requested=1, result=-6)
Error: jlink_tap_execute, wrong result -107 (expected 1)
Error: J-Link setting speed failed (-60)
in procedure 'init'
$

every next try end like this:

$ openocd  -f jlinkv8.cfg
Open On-Chip Debugger 0.5.0 (2012-03-15-08:44)
Licensed under GNU GPL v2
For bug reports, read
        http://openocd.berlios.de/doc/doxygen/bugs.html
Warn : Adapter driver 'jlink' did not declare which transports it allows;
assuming legacy JTAG-only
Info : only one transport option; autoselect 'jtag'
1000 kHz
adapter_nsrst_delay: 100
jtag_ntrst_delay: 100
cortex_m3 reset_config sysresetreq
250 kHz
Info : J-Link initialization started / target CPU reset initiated
Error: J-Link command 0xde failed (-60)
Error: J-Link command 0xdc failed (-60)
Error: J-Link command 0x01 failed (-60)
Error: J-Link command EMU_CMD_VERSION failed (-60)
Info : J-Link JTAG Interface ready
Error: J-Link command 0xdd failed (-60)
Error: J-Link command 0xdf failed (-60)
Error: usb_bulk_write failed (requested=6, result=-60)
Error: jlink_tap_execute, wrong result -107 (expected 1)
Error: J-Link setting speed failed (-60)
in procedure 'init'
$ 
# usbconfig list
ugen0.1: <UHCI root HUB VIA> at usbus0, cfg=0 md=HOST spd=FULL (12Mbps) pwr=SAVE
ugen1.1: <UHCI root HUB VIA> at usbus1, cfg=0 md=HOST spd=FULL (12Mbps) pwr=SAVE
ugen2.1: <UHCI root HUB VIA> at usbus2, cfg=0 md=HOST spd=FULL (12Mbps) pwr=SAVE
ugen1.2: <J-Link SEGGER> at usbus1, cfg=0 md=HOST spd=FULL (12Mbps) pwr=ON
#  

If I disconnect the J-Link the device disappears:

Mar 15 13:27:42 unicorn kernel: uhub_explore: udev=0xc561f000 addr=1
Mar 15 13:27:42 unicorn kernel: uhub_read_port_status: port 1, wPortStatus=0x0108, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:27:42 unicorn kernel: uhub_read_port_status: port 2, wPortStatus=0x0108, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:27:46 unicorn kernel: usb_needs_explore: 
Mar 15 13:27:46 unicorn kernel: usb_bus_powerd: bus=0xc6ed4cf0
Mar 15 13:27:46 unicorn kernel: usb_bus_powerd: Recomputing power masks
Mar 15 13:27:46 unicorn kernel: uhub_explore: udev=0xc5511c00 addr=1
Mar 15 13:27:46 unicorn kernel: uhub_read_port_status: port 1, wPortStatus=0x0108, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:27:46 unicorn kernel: uhub_read_port_status: port 2, wPortStatus=0x0108, wPortChange=0x0003, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:27:46 unicorn kernel: uhub_reattach_port: reattaching port 2
Mar 15 13:27:46 unicorn kernel: ugen1.2: <SEGGER> at usbus1 (disconnected)
Mar 15 13:27:46 unicorn kernel: usb_bus_port_set_device: bus 0xc6ed4cf0 devices[2] = 0
Mar 15 13:27:46 unicorn kernel: uhub_read_port_status: port 2, wPortStatus=0x0108, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:27:46 unicorn kernel: usb_needs_explore: 
Mar 15 13:27:46 unicorn kernel: usb_bus_powerd: bus=0xc6e44cf0
Mar 15 13:27:46 unicorn kernel: usb_bus_powerd: Recomputing power masks
Mar 15 13:27:46 unicorn kernel: uhub_explore: udev=0xc5608800 addr=1
Mar 15 13:27:46 unicorn kernel: uhub_read_port_status: port 1, wPortStatus=0x0108, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION


#usbconfig list
ugen0.1: <UHCI root HUB VIA> at usbus0, cfg=0 md=HOST spd=FULL (12Mbps) pwr=SAVE
ugen1.1: <UHCI root HUB VIA> at usbus1, cfg=0 md=HOST spd=FULL (12Mbps) pwr=SAVE
ugen2.1: <UHCI root HUB VIA> at usbus2, cfg=0 md=HOST spd=FULL (12Mbps) pwr=SAVE
# 

but after replugging it, it never gets connected again:

Mar 15 13:29:34 unicorn kernel: usb_bus_powerd: Recomputing power masks
Mar 15 13:29:34 unicorn kernel: uhub_explore: udev=0xc561f000 addr=1
Mar 15 13:29:34 unicorn kernel: uhub_read_port_status: port 1, wPortStatus=0x0108, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:29:34 unicorn kernel: uhub_read_port_status: port 2, wPortStatus=0x0108, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:29:36 unicorn kernel: usb_needs_explore: 
Mar 15 13:29:36 unicorn kernel: usb_bus_powerd: bus=0xc6ed4cf0
Mar 15 13:29:36 unicorn kernel: usb_bus_powerd: Recomputing power masks
Mar 15 13:29:36 unicorn kernel: uhub_explore: udev=0xc5511c00 addr=1
Mar 15 13:29:36 unicorn kernel: uhub_read_port_status: port 1, wPortStatus=0x0108, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:29:36 unicorn kernel: uhub_read_port_status: port 2, wPortStatus=0x0109, wPortChange=0x0003, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:29:36 unicorn kernel: uhub_reattach_port: reattaching port 2
Mar 15 13:29:36 unicorn kernel: uhub_read_port_status: port 2, wPortStatus=0x0109, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:29:36 unicorn kernel: uhub_reattach_port: Port 2 is in Host Mode
Mar 15 13:29:37 unicorn kernel: uhub_read_port_status: port 2, wPortStatus=0x010b, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:29:37 unicorn kernel: usbd_transfer_power_ref: Adding type 0 to power state
Mar 15 13:29:37 unicorn kernel: usbd_transfer_power_ref: needs power
Mar 15 13:29:38 unicorn kernel: usb_alloc_device: set address 2 failed (USB_ERR_TIMEOUT, ignored)
Mar 15 13:29:38 unicorn kernel: usb_needs_explore: 
Mar 15 13:29:38 unicorn kernel: usb_bus_powerd: bus=0xc6e44cf0
Mar 15 13:29:38 unicorn kernel: usb_bus_powerd: Recomputing power masks
Mar 15 13:29:38 unicorn kernel: uhub_explore: udev=0xc5608800 addr=1
Mar 15 13:29:38 unicorn kernel: uhub_read_port_status: port 1, wPortStatus=0x0108, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:29:38 unicorn kernel: uhub_read_port_status: port 2, wPortStatus=0x0108, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION


# usbconfig list
ugen0.1: <UHCI root HUB VIA> at usbus0, cfg=0 md=HOST spd=FULL (12Mbps) pwr=SAVE
ugen1.1: <UHCI root HUB VIA> at usbus1, cfg=0 md=HOST spd=FULL (12Mbps) pwr=SAVE
ugen2.1: <UHCI root HUB VIA> at usbus2, cfg=0 md=HOST spd=FULL (12Mbps) pwr=SAVE
# 

# usbconfig -d ugen1.1 reset
usbconfig: could not reset device: Invalid argument
# # usbconfig -d ugen1.1 resume
usbconfig: could not set resume: Invalid argument
# 

Before I've built the kernel w/o the =hci drivers compiled in a reboot was
the only solution to get this device on this port recognized again.

Now a simple kldunload/kldload uhci does the job, even w/o disconnecting
the J-Linki (it leaves powered up). This is why I thing that the bug sits
in FreeBSDs usb driver.

Mar 15 13:35:06 unicorn kernel: uhub_read_port_status: port 2, wPortStatus=0x0108, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:35:10 unicorn kernel: ugen0.1: <VIA> at usbus0 (disconnected)
Mar 15 13:35:10 unicorn kernel: usb_bus_port_set_device: bus 0xc6e44cf0 devices[1] = 0
Mar 15 13:35:10 unicorn kernel: unknown: at usbus0, port 1, addr 1 (disconnected)
Mar 15 13:35:10 unicorn kernel: usbus0: detached
Mar 15 13:35:10 unicorn kernel: uhci0: detached
Mar 15 13:35:10 unicorn kernel: pci0: <serial bus, USB> at device 16.0 (no driver attached)
Mar 15 13:35:10 unicorn kernel: ugen1.1: <VIA> at usbus1 (disconnected)
Mar 15 13:35:10 unicorn kernel: usb_bus_port_set_device: bus 0xc6ed4cf0 devices[1] = 0
Mar 15 13:35:10 unicorn kernel: unknown: at usbus1, port 1, addr 1 (disconnected)
Mar 15 13:35:10 unicorn kernel: usbus1: detached
Mar 15 13:35:10 unicorn kernel: uhci1: detached
Mar 15 13:35:10 unicorn kernel: pci0: <serial bus, USB> at device 16.1 (no driver attached)
Mar 15 13:35:10 unicorn kernel: ugen2.1: <VIA> at usbus2 (disconnected)
Mar 15 13:35:10 unicorn kernel: usb_bus_port_set_device: bus 0xc7657cf0 devices[1] = 0
Mar 15 13:35:10 unicorn kernel: unknown: at usbus2, port 1, addr 1 (disconnected)
Mar 15 13:35:10 unicorn kernel: usbus2: detached
Mar 15 13:35:10 unicorn kernel: uhci2: detached
Mar 15 13:35:10 unicorn kernel: pci0: <serial bus, USB> at device 16.2 (no driver attached)
Mar 15 13:35:15 unicorn kernel: uhci0: <VIA 83C572 USB controller> port 0xcc00-0xcc1f irq 15 at device 16.0 on pci0
Mar 15 13:35:15 unicorn kernel: uhci0: [ITHREAD]
Mar 15 13:35:16 unicorn kernel: usb_needs_explore: 
Mar 15 13:35:16 unicorn kernel: usb_needs_explore: No root HUB
Mar 15 13:35:16 unicorn kernel: usbus0: <VIA 83C572 USB controller> on uhci0
Mar 15 13:35:16 unicorn kernel: usb_needs_explore: 
Mar 15 13:35:16 unicorn kernel: usb_needs_explore: No root HUB
Mar 15 13:35:16 unicorn kernel: usbus0: 12Mbps Full Speed USB v1.0
Mar 15 13:35:16 unicorn kernel: uhci1: <VIA 83C572 USB controller> port 0xd000-0xd01f irq 15 at device 16.1 on pci0
Mar 15 13:35:16 unicorn kernel: uhci1: [ITHREAD]
Mar 15 13:35:16 unicorn kernel: usb_bus_port_set_device: bus 0xc6e44cf0 devices[1] = 0xc5591c00
Mar 15 13:35:16 unicorn kernel: ugen0.1: <VIA> at usbus0
Mar 15 13:35:16 unicorn kernel: uhub0: <VIA UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus0
Mar 15 13:35:16 unicorn kernel: uhub_attach: depth=0 selfpowered=1, parent=0, parent->selfpowered=0
Mar 15 13:35:16 unicorn kernel: uhub_attach: Getting HUB descriptor
Mar 15 13:35:16 unicorn kernel: usb_needs_explore: 
Mar 15 13:35:16 unicorn kernel: usb_needs_explore: No root HUB
Mar 15 13:35:16 unicorn kernel: usbus1: <VIA 83C572 USB controller> on uhci1
Mar 15 13:35:16 unicorn kernel: usbus1: 12Mbps Full Speed USB v1.0
Mar 15 13:35:16 unicorn kernel: usb_needs_explore: 
Mar 15 13:35:16 unicorn kernel: usb_needs_explore: No root HUB
Mar 15 13:35:16 unicorn kernel: uhci2: <VIA 83C572 USB controller> port 0xd400-0xd41f irq 5 at device 16.2 on pci0
Mar 15 13:35:16 unicorn kernel: uhci2: [ITHREAD]
Mar 15 13:35:16 unicorn kernel: usb_bus_port_set_device: bus 0xc6ed4cf0 devices[1] = 0xc5517800
Mar 15 13:35:16 unicorn kernel: ugen1.1: <VIA> at usbus1
Mar 15 13:35:16 unicorn kernel: uhub1: <VIA UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus1
Mar 15 13:35:16 unicorn kernel: uhub_attach: depth=0 selfpowered=1, parent=0, parent->selfpowered=0
Mar 15 13:35:16 unicorn kernel: uhub_attach: Getting HUB descriptor
Mar 15 13:35:16 unicorn kernel: uhub_attach: turn on port 1 power
Mar 15 13:35:16 unicorn kernel: usb_needs_explore: 
Mar 15 13:35:16 unicorn kernel: usb_needs_explore: No root HUB
Mar 15 13:35:16 unicorn kernel: usbus2: <VIA 83C572 USB controller> on uhci2
Mar 15 13:35:16 unicorn kernel: usbus2: 12Mbps Full Speed USB v1.0
Mar 15 13:35:16 unicorn kernel: usb_needs_explore: 
Mar 15 13:35:16 unicorn kernel: usb_needs_explore: No root HUB
Mar 15 13:35:16 unicorn kernel: usb_bus_port_set_device: bus 0xc7657cf0 devices[1] = 0xc5608800
Mar 15 13:35:16 unicorn kernel: ugen2.1: <VIA> at usbus2
Mar 15 13:35:16 unicorn kernel: uhub2: <VIA UHCI root HUB, class 9/0, rev 1.00/1.00, addr 1> on usbus2
Mar 15 13:35:16 unicorn kernel: uhub_attach: depth=0 selfpowered=1, parent=0, parent->selfpowered=0
Mar 15 13:35:16 unicorn kernel: uhub_attach: Getting HUB descriptor
Mar 15 13:35:16 unicorn kernel: uhub_attach: turn on port 2 power
Mar 15 13:35:16 unicorn kernel: uhub_attach: turn on port 1 power
Mar 15 13:35:16 unicorn kernel: uhub0: 2 ports with 2 removable, self powered
Mar 15 13:35:16 unicorn kernel: usb_needs_explore: 
Mar 15 13:35:16 unicorn kernel: usb_needs_explore: 
Mar 15 13:35:16 unicorn kernel: usb_bus_powerd: bus=0xc6e44cf0
Mar 15 13:35:16 unicorn kernel: usb_bus_powerd: Recomputing power masks
Mar 15 13:35:16 unicorn kernel: uhub_explore: udev=0xc5591c00 addr=1
Mar 15 13:35:16 unicorn kernel: uhub_read_port_status: port 1, wPortStatus=0x0108, wPortChange=0x0003, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:35:16 unicorn kernel: uhub_reattach_port: reattaching port 1
Mar 15 13:35:16 unicorn kernel: uhub_read_port_status: port 1, wPortStatus=0x0108, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:35:16 unicorn kernel: uhub_read_port_status: port 2, wPortStatus=0x0108, wPortChange=0x0003, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:35:16 unicorn kernel: uhub_reattach_port: reattaching port 2
Mar 15 13:35:16 unicorn kernel: uhub_read_port_status: port 2, wPortStatus=0x0108, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:35:16 unicorn kernel: usb_bus_powerd: bus=0xc6e44cf0
Mar 15 13:35:16 unicorn kernel: usb_bus_powerd: Recomputing power masks
Mar 15 13:35:16 unicorn kernel: uhub_explore: udev=0xc5591c00 addr=1
Mar 15 13:35:16 unicorn kernel: uhub_read_port_status: port 1, wPortStatus=0x0108, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:35:16 unicorn kernel: uhub_read_port_status: port 2, wPortStatus=0x0108, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:35:16 unicorn kernel: uhub_attach: turn on port 2 power
Mar 15 13:35:16 unicorn kernel: uhub_attach: turn on port 1 power
Mar 15 13:35:16 unicorn kernel: uhub1: 2 ports with 2 removable, self powered
Mar 15 13:35:16 unicorn kernel: usb_needs_explore: 
Mar 15 13:35:16 unicorn kernel: usb_needs_explore: 
Mar 15 13:35:16 unicorn kernel: usb_bus_powerd: bus=0xc6ed4cf0
Mar 15 13:35:16 unicorn kernel: usb_bus_powerd: Recomputing power masks
Mar 15 13:35:16 unicorn kernel: uhub_explore: udev=0xc5517800 addr=1
Mar 15 13:35:16 unicorn kernel: uhub_read_port_status: port 1, wPortStatus=0x0108, wPortChange=0x0003, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:35:16 unicorn kernel: uhub_reattach_port: reattaching port 1
Mar 15 13:35:16 unicorn kernel: uhub_read_port_status: port 1, wPortStatus=0x0108, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:35:16 unicorn kernel: uhub_read_port_status: port 2, wPortStatus=0x0109, wPortChange=0x0003, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:35:16 unicorn kernel: uhub_reattach_port: reattaching port 2
Mar 15 13:35:16 unicorn kernel: uhub_read_port_status: port 2, wPortStatus=0x0109, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:35:16 unicorn kernel: uhub_reattach_port: Port 2 is in Host Mode
Mar 15 13:35:16 unicorn kernel: uhub_attach: turn on port 2 power
Mar 15 13:35:16 unicorn kernel: uhub2: 2 ports with 2 removable, self powered
Mar 15 13:35:16 unicorn kernel: usb_needs_explore: 
Mar 15 13:35:16 unicorn kernel: usb_needs_explore: 
Mar 15 13:35:16 unicorn kernel: usb_bus_powerd: bus=0xc7657cf0
Mar 15 13:35:16 unicorn kernel: usb_bus_powerd: Recomputing power masks
Mar 15 13:35:16 unicorn kernel: uhub_explore: udev=0xc5608800 addr=1
Mar 15 13:35:16 unicorn kernel: uhub_read_port_status: port 1, wPortStatus=0x0108, wPortChange=0x0003, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:35:16 unicorn kernel: uhub_reattach_port: reattaching port 1
Mar 15 13:35:16 unicorn kernel: uhub_read_port_status: port 1, wPortStatus=0x0108, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:35:16 unicorn kernel: uhub_read_port_status: port 2, wPortStatus=0x0108, wPortChange=0x0003, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:35:16 unicorn kernel: uhub_reattach_port: reattaching port 2
Mar 15 13:35:16 unicorn kernel: uhub_read_port_status: port 2, wPortStatus=0x0108, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:35:16 unicorn kernel: usb_bus_powerd: bus=0xc7657cf0
Mar 15 13:35:16 unicorn kernel: usb_bus_powerd: Recomputing power masks
Mar 15 13:35:16 unicorn kernel: uhub_explore: udev=0xc5608800 addr=1
Mar 15 13:35:16 unicorn kernel: uhub_read_port_status: port 1, wPortStatus=0x0108, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:35:16 unicorn kernel: uhub_read_port_status: port 2, wPortStatus=0x0108, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:35:17 unicorn kernel: usb_needs_explore: 
Mar 15 13:35:17 unicorn root: Unknown USB device: vendor 0x1366 product 0x0101 bus uhub1
Mar 15 13:35:17 unicorn kernel: uhub_read_port_status: port 2, wPortStatus=0x010b, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:35:17 unicorn kernel: usbd_transfer_power_ref: Adding type 0 to power state
Mar 15 13:35:17 unicorn kernel: usbd_transfer_power_ref: needs power
Mar 15 13:35:17 unicorn kernel: usb_bus_port_set_device: bus 0xc6ed4cf0 devices[2] = 0xc5602000
Mar 15 13:35:17 unicorn kernel: ugen1.2: <SEGGER> at usbus1
Mar 15 13:35:17 unicorn kernel: usb_bus_powerd: bus=0xc6ed4cf0
Mar 15 13:35:17 unicorn kernel: uhub_explore: udev=0xc5517800 addr=1
Mar 15 13:35:17 unicorn kernel: uhub_read_port_status: port 1, wPortStatus=0x0108, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION
Mar 15 13:35:17 unicorn kernel: uhub_read_port_status: port 2, wPortStatus=0x010b, wPortChange=0x0000, err=USB_ERR_NORMAL_COMPLETION


It is exactly the same story with the Olympus Cam, but I need it seldom on
my FreeBSD PC. This J-Link I need to make a income, so please can anyobody
look at this? I think this misbehavior has popped up between 8.1 and 8.2.


$ uname -a
FreeBSD unicorn.tsht.lan 8.3-PRERELEASE FreeBSD 8.3-PRERELEASE #22: Thu Mar 15 11:25:58 CET 2012
holm@unicorn.tsht.lan:/data/FreeBSD/obj/data/FreeBSD/src/sys/UNICORN  i386
$ 

Copyright (c) 1992-2012 The FreeBSD Project.
Copyright (c) 1979, 1980, 1983, 1986, 1988, 1989, 1991, 1992, 1993, 1994
        The Regents of the University of California. All rights reserved.
FreeBSD is a registered trademark of The FreeBSD Foundation.
FreeBSD 8.3-PRERELEASE #22: Thu Mar 15 11:25:58 CET 2012
    holm@unicorn.tsht.lan:/data/FreeBSD/obj/data/FreeBSD/src/sys/UNICORN i386
Timecounter "i8254" frequency 1193182 Hz quality 0
CPU: AMD Athlon(tm) XP 3000+ (2109.49-MHz 686-class CPU)
  Origin = "AuthenticAMD"  Id = 0x6a0  Family = 6  Model = a  Stepping = 0
Features=0x383fbff<FPU,VME,DE,PSE,TSC,MSR,PAE,MCE,CX8,APIC,SEP,MTRR,PGE,MCA,CMOV,PAT,PSE36,MMX,FXSR,SSE>
  AMD Features=0xc0400800<SYSCALL,MMX+,3DNow!+,3DNow!>
real memory  = 2147483648 (2048 MB)
avail memory = 2092113920 (1995 MB)
kbd1 at kbdmux0
acpi0: <GBT AWRDACPI> on motherboard
acpi0: [ITHREAD]
acpi0: Power Button (fixed)
acpi0: reservation of 0, a0000 (3) failed
acpi0: reservation of 100000, 7fef0000 (3) failed
cpu0: <ACPI CPU> on acpi0
acpi_button0: <Power Button> on acpi0
pcib0: <ACPI Host-PCI bridge> port 0xcf8-0xcff on acpi0
pci0: <ACPI PCI bus> on pcib0
agp0: <VIA 8377 (Apollo KT400/KT400A/KT600) host to PCI bridge> on hostb0
agp0: aperture size is 128M
pcib1: <PCI-PCI bridge> at device 1.0 on pci0
pci1: <PCI bus> on pcib1
vgapci0: <VGA-compatible display> port 0xa000-0xa0ff mem
0xd8000000-0xdfffffff,0xe9000000-0xe900ffff irq 15 at device 0.0 on pci1
drm0: <ATI Radeon RV280 9200 SE> on vgapci0
info: [drm] AGP at 0xd0000000 128MB
info: [drm] Initialized radeon 1.31.0 20080613
vgapci1: <VGA-compatible display> mem
0xe0000000-0xe7ffffff,0xe9010000-0xe901ffff at device 0.1 on pci1
de0: <Digital 21040 Ethernet> port 0xb000-0xb07f mem 0xeb002000-0xeb00207f
irq 15 at device 9.0 on pci0
de0: Cogent 21040 [10Mb/s] pass 2.3
de0: WARNING: using obsoleted if_watchdog interface
de0: Ethernet address: 00:00:92:90:09:8d
de0: [ITHREAD]
ahd0: <Adaptec 29320LP Ultra320 SCSI adapter> port
0xb400-0xb4ff,0xb800-0xb8ff mem 0xeb000000-0xeb001fff irq 11 at device 11.0
on pci0
ahd0: [ITHREAD]
aic7901A: Ultra320 Wide Channel A, SCSI Id=7, PCI 33 or 66MHz, 512 SCBs
puc0: <Oxford Semiconductor OX16PCI952 UARTs> port
0xbc00-0xbc07,0xc000-0xc007,0xc400-0xc41f mem
0xeb003000-0xeb003fff,0xeb004000-0xeb004fff irq 15 at device 12.0 on pci0
puc0: [FILTER]
uart2: <16550 or compatible> at port 1 on puc0
uart2: [FILTER]
uart3: <16550 or compatible> at port 2 on puc0
uart3: [FILTER]
sym0: <810a> port 0xc800-0xc8ff mem 0xeb005000-0xeb0050ff irq 15 at device
13.0 on pci0
sym0: No NVRAM, ID 7, Fast-10, SE, parity checking
sym0: [ITHREAD]
pci0: <serial bus, USB> at device 16.0 (no driver attached)
pci0: <serial bus, USB> at device 16.1 (no driver attached)
pci0: <serial bus, USB> at device 16.2 (no driver attached)
pci0: <serial bus, USB> at device 16.3 (no driver attached)
isab0: <PCI-ISA bridge> at device 17.0 on pci0
isa0: <ISA bus> on isab0
atapci0: <VIA 8235 UDMA133 controller> port
0x1f0-0x1f7,0x3f6,0x170-0x177,0x376,0xd800-0xd80f at device 17.1 on pci0
ata0: <ATA channel> at channel 0 on atapci0
ata0: [ITHREAD]
ata1: <ATA channel> at channel 1 on atapci0
ata1: [ITHREAD]
pcm0: <VIA VT8235> port 0xdc00-0xdcff irq 5 at device 17.5 on pci0
pcm0: [ITHREAD]
pcm0: <Avance Logic ALC655 AC97 Codec>
pcm0: <VIA DXS Enabled: DXS 4 / SGD 1 / REC 1>
rl0: <RealTek 8139 10/100BaseTX> port 0xe000-0xe0ff mem
0xeb007000-0xeb0070ff irq 5 at device 19.0 on pci0
miibus0: <MII bus> on rl0
rlphy0: <RealTek internal media interface> PHY 0 on miibus0
rlphy0:  10baseT, 10baseT-FDX, 100baseTX, 100baseTX-FDX, auto
rl0: Ethernet address: 00:0d:61:c3:c4:5a
rl0: [ITHREAD]
atrtc0: <AT realtime clock> port 0x70-0x73 irq 8 on acpi0
fdc0: <floppy drive controller> port 0x3f0-0x3f5,0x3f7 irq 6 drq 2 on acpi0
fdc0: [FILTER]
fd0: <1440-KB 3.5" drive> on fdc0 drive 0
fd1: <1200-KB 5.25" drive> on fdc0 drive 1
uart0: <16550 or compatible> port 0x3f8-0x3ff irq 4 flags 0x10 on acpi0
uart0: [FILTER]
uart1: <16550 or compatible> port 0x2f8-0x2ff irq 3 on acpi0
uart1: [FILTER]
ppc0: <Parallel port> port 0x378-0x37f,0x778-0x77b irq 7 drq 3 on acpi0
ppc0: SMC-like chipset (ECP/EPP/PS2/NIBBLE) in COMPATIBLE mode
ppc0: FIFO with 16/16/16 bytes threshold
ppc0: [ITHREAD]
ppbus0: <Parallel port bus> on ppc0
plip0: <PLIP network interface> on ppbus0
plip0: [ITHREAD]
lpt0: <Printer> on ppbus0
lpt0: [ITHREAD]
lpt0: Interrupt-driven port
ppi0: <Parallel I/O> on ppbus0
atkbdc0: <Keyboard controller (i8042)> port 0x60,0x64 irq 1 on acpi0
atkbd0: <AT Keyboard> irq 1 on atkbdc0
kbd0 at atkbd0
atkbd0: [GIANT-LOCKED]
atkbd0: [ITHREAD]
psm0: <PS/2 Mouse> irq 12 on atkbdc0
psm0: [GIANT-LOCKED]
psm0: [ITHREAD]
psm0: model MouseMan+, device ID 0
pmtimer0 on isa0
orm0: <ISA Option ROMs> at iomem 0xc0000-0xccfff,0xd0000-0xd97ff pnpid
ORM0000 on isa0
sc0: <System console> at flags 0x100 on isa0
sc0: VGA <16 virtual consoles, flags=0x300>
vga0: <Generic ISA VGA> at port 0x3c0-0x3df iomem 0xa0000-0xbffff on isa0
Timecounter "TSC" frequency 2109485501 Hz quality 800
Timecounters tick every 1.000 msec
(probe21:sym0:0:6:0): TEST UNIT READY. CDB: 0 0 0 0 0 0 
(probe21:sym0:0:6:0): CAM status: SCSI Status Error
(probe21:sym0:0:6:0): SCSI status: Check Condition
(probe21:sym0:0:6:0): SCSI sense: UNIT ATTENTION asc:29,0 (Power on, reset,
or bus device reset occurred)
(probe20:sym0:0:5:0): TEST UNIT READY. CDB: 0 0 0 0 0 0 
(probe20:sym0:0:5:0): CAM status: SCSI Status Error
(probe20:sym0:0:5:0): SCSI status: Check Condition
(probe20:sym0:0:5:0): SCSI sense: UNIT ATTENTION asc:29,0 (Power on, reset,
or bus device reset occurred)
(probe20:sym0:0:5:0): TEST UNIT READY. CDB: 0 0 0 0 0 0 
(probe20:sym0:0:5:0): CAM status: SCSI Status Error
(probe20:sym0:0:5:0): SCSI status: Check Condition
(probe20:sym0:0:5:0): SCSI sense: NOT READY asc:3a,0 (Medium not present)
sa0 at sym0 bus 0 scbus1 target 5 lun 0
sa0: <TANDBERG SLR5 4/8GB =09:> Removable Sequential Access SCSI-2 device 
sa0: 4.166MB/s transfers (4.166MHz, offset 8)
sa1 at sym0 bus 0 scbus1 target 6 lun 0
sa1: <COMPAQ DLT4000 D887> Removable Sequential Access SCSI-2 device 
sa1: 10.000MB/s transfers (10.000MHz, offset 8)
da0 at ahd0 bus 0 scbus0 target 0 lun 0
da0: <IBM-SSG S53D073 C61E> Fixed Direct Access SCSI-3 device 
da0: 160.000MB/s transfers (80.000MHz DT, offset 63, 16bit)
da0: Command Queueing enabled
da0: 70006MB (143374000 512 byte sectors: 255H 63S/T 8924C)
da1 at ahd0 bus 0 scbus0 target 1 lun 0
da1: <IBM-SSG S53D073 C61E> Fixed Direct Access SCSI-3 device 
da1: 160.000MB/s transfers (80.000MHz DT, offset 63, 16bit)
da1: Command Queueing enabled
da1: 70006MB (143374000 512 byte sectors: 255H 63S/T 8924C)
da2 at ahd0 bus 0 scbus0 target 2 lun 0
da2: <IBM-SSG S53D073 C61A> Fixed Direct Access SCSI-3 device 
da2: 160.000MB/s transfers (80.000MHz DT, offset 63, 16bit)
(probe20:sym0:0:5:0): TEST UNIT READY. CDB: 0 0 0 0 0 0 
(probe20:sym0:0:5:0): CAM status: SCSI Status Error
(probe20:sym0:0:5:0): SCSI status: Check Condition
(probe20:sym0:0:5:0): SCSI sense: UNIT ATTENTION asc:29,0 (Power on, reset,
or bus device reset occurred)
(probe20:sym0:0:5:0): TEST UNIT READY. CDB: 0 0 0 0 0 0 
(probe20:sym0:0:5:0): CAM status: SCSI Status Error
(probe20:sym0:0:5:0): SCSI status: Check Condition
(probe20:sym0:0:5:0): SCSI sense: NOT READY asc:3a,0 (Medium not present)
sa0 at sym0 bus 0 scbus1 target 5 lun 0
sa0: <TANDBERG SLR5 4/8GB =09:> Removable Sequential Access SCSI-2 device 
sa0: 4.166MB/s transfers (4.166MHz, offset 8)
sa1 at sym0 bus 0 scbus1 target 6 lun 0
sa1: <COMPAQ DLT4000 D887> Removable Sequential Access SCSI-2 device 
sa1: 10.000MB/s transfers (10.000MHz, offset 8)
da0 at ahd0 bus 0 scbus0 target 0 lun 0
da0: <IBM-SSG S53D073 C61E> Fixed Direct Access SCSI-3 device 
da0: 160.000MB/s transfers (80.000MHz DT, offset 63, 16bit)
da0: Command Queueing enabled
da0: 70006MB (143374000 512 byte sectors: 255H 63S/T 8924C)
da1 at ahd0 bus 0 scbus0 target 1 lun 0
da1: <IBM-SSG S53D073 C61E> Fixed Direct Access SCSI-3 device 
da1: 160.000MB/s transfers (80.000MHz DT, offset 63, 16bit)
da1: Command Queueing enabled
da1: 70006MB (143374000 512 byte sectors: 255H 63S/T 8924C)
da2 at ahd0 bus 0 scbus0 target 2 lun 0
da2: <IBM-SSG S53D073 C61A> Fixed Direct Access SCSI-3 device 
da2: 160.000MB/s transfers (80.000MHz DT, offset 63, 16bit)
da2: Command Queueing enabled
da2: 70006MB (143374000 512 byte sectors: 255H 63S/T 8924C)
da3 at ahd0 bus 0 scbus0 target 3 lun 0
da3: <IBM-SSG S53D073 C61A> Fixed Direct Access SCSI-3 device 
da3: 160.000MB/s transfers (80.000MHz DT, offset 63, 16bit)
da3: Command Queueing enabled
da3: 70006MB (143374000 512 byte sectors: 255H 63S/T 8924C)
GEOM_CONCAT: Device gc0d created (id=2065581164).
GEOM_CONCAT: Disk da0d attached to gc0d.
GEOM_CONCAT: Device data created (id=2038144655).
GEOM_CONCAT: Disk da0g attached to data.
GEOM_MIRROR: Device mirror/gm0a launched (2/2).
GEOM_CONCAT: Disk da1d attached to gc0d.
GEOM_CONCAT: Device gc0d activated.
GEOM_MIRROR: Device mirror/gm0e launched (2/2).
GEOM_MIRROR: Device mirror/gm0f launched (2/2).
GEOM_CONCAT: Disk da1g attached to data.
GEOM_CONCAT: Disk da2a attached to data.
GEOM_CONCAT: Disk da2b attached to data.
GEOM_CONCAT: Device data activated.
Trying to mount root from ufs:/dev/mirror/gm0a
bridge0: Ethernet address: 02:82:44:4d:6c:00

Regards,

Holm

-- 
      Technik Service u. Handel Tiffe, www.tsht.de, Holm Tiffe, 
     Freiberger Straße 42, 09600 Oberschöna, USt-Id: DE253710583
  www.tsht.de, info@tsht.de, Fax +49 3731 74200, Mobil: 0172 8790 741




Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?20120315124840.GA64319>