Re: crash for ftdi_sio

For some reason usb-devel is bouncing these messages saying:

Message rejected by filter rule match

Anyway, kernel 2.6.18 compiled with
CONFIG_USB_DEBUG=y

dmesg seems more detailed but /var/log/messages is not, so I have both:
Anyway, here goes:

Here we have /var/log/messages of the modprobe

May 24 10:16:38 shep kernel: USB Universal Host Controller Interface
driver v3.0
May 24 10:16:38 shep kernel: PCI: Enabling device 0000:00:07.2 (0000 ->
0001)
May 24 10:16:38 shep kernel: PCI: Found IRQ 7 for device 0000:00:07.2
May 24 10:16:38 shep kernel: PCI: Sharing IRQ 7 with 0000:00:07.3
May 24 10:16:38 shep kernel: PCI: Sharing IRQ 7 with 0000:00:0a.0
May 24 10:16:38 shep kernel: uhci_hcd 0000:00:07.2: UHCI Host Controller
May 24 10:16:38 shep kernel: uhci_hcd 0000:00:07.2: new USB bus
registered, assigned bus number 1
May 24 10:16:38 shep kernel: uhci_hcd 0000:00:07.2: detected 2 ports
May 24 10:16:38 shep kernel: uhci_hcd 0000:00:07.2: irq 7, io base
0x0000d400
May 24 10:16:38 shep kernel: usb usb1: Product: UHCI Host Controller
May 24 10:16:38 shep kernel: usb usb1: Manufacturer: Linux 2.6.18sam
uhci_hcd
May 24 10:16:38 shep kernel: usb usb1: SerialNumber: 0000:00:07.2
May 24 10:16:38 shep kernel: usb usb1: configuration #1 chosen from 1 choice
May 24 10:16:38 shep kernel: hub 1-0:1.0: USB hub found
May 24 10:16:38 shep kernel: hub 1-0:1.0: 2 ports detected
May 24 10:16:38 shep kernel: PCI: Enabling device 0000:00:07.3 (0000 ->
0001)
May 24 10:16:38 shep kernel: PCI: Found IRQ 7 for device 0000:00:07.3
May 24 10:16:38 shep kernel: PCI: Sharing IRQ 7 with 0000:00:07.2
May 24 10:16:38 shep kernel: PCI: Sharing IRQ 7 with 0000:00:0a.0
May 24 10:16:38 shep kernel: uhci_hcd 0000:00:07.3: UHCI Host Controller
May 24 10:16:38 shep kernel: uhci_hcd 0000:00:07.3: new USB bus
registered, assigned bus number 2
May 24 10:16:38 shep kernel: uhci_hcd 0000:00:07.3: detected 2 ports
May 24 10:16:38 shep kernel: uhci_hcd 0000:00:07.3: irq 7, io base
0x0000d800
May 24 10:16:38 shep kernel: usb usb2: Product: UHCI Host Controller
May 24 10:16:38 shep kernel: usb usb2: Manufacturer: Linux 2.6.18sam
uhci_hcd
May 24 10:16:38 shep kernel: usb usb2: SerialNumber: 0000:00:07.3
May 24 10:16:38 shep kernel: usb usb2: configuration #1 chosen from 1 choice
May 24 10:16:38 shep kernel: hub 2-0:1.0: USB hub found
May 24 10:16:38 shep kernel: hub 2-0:1.0: 2 ports detected
May 24 10:16:39 shep kernel: usb 1-1: new low speed USB device using
uhci_hcd and address 2
May 24 10:16:39 shep kernel: usb 1-1: Product: PS2 to USB Converter
May 24 10:16:39 shep kernel: usb 1-1: Manufacturer: CHESEN
May 24 10:16:39 shep kernel: usb 1-1: configuration #1 chosen from 1 choice
May 24 10:16:39 shep kernel: input: CHESEN PS2 to USB Converter as
/class/input/input15
May 24 10:16:39 shep kernel: input: USB HID v1.10 Keyboard [CHESEN PS2
to USB Converter] on usb-0000:00:07.2-1
May 24 10:16:39 shep kernel: input: CHESEN PS2 to USB Converter as
/class/input/input16
May 24 10:16:39 shep kernel: input: USB HID v1.10 Mouse [CHESEN PS2 to
USB Converter] on usb-0000:00:07.2-1
May 24 10:16:39 shep kernel: usb 1-2: new full speed USB device using
uhci_hcd and address 3
May 24 10:16:39 shep kernel: usb 1-2: Product: Generic USB Hub
May 24 10:16:39 shep kernel: usb 1-2: Manufacturer: ALCOR
May 24 10:16:39 shep kernel: usb 1-2: configuration #1 chosen from 1 choice
May 24 10:16:39 shep kernel: hub 1-2:1.0: USB hub found
May 24 10:16:39 shep kernel: hub 1-2:1.0: 4 ports detected
May 24 10:16:40 shep kernel: usb 1-2.1: new full speed USB device using
uhci_hcd and address 4
May 24 10:16:40 shep kernel: usb 1-2.1: Product: USB HS SERIAL CONVERTER
May 24 10:16:40 shep kernel: usb 1-2.1: Manufacturer: FTDI
May 24 10:16:40 shep kernel: usb 1-2.1: SerialNumber: FTCEUPT7
May 24 10:16:40 shep kernel: usb 1-2.1: configuration #1 chosen from 1
choice
May 24 10:16:40 shep kernel: ftdi_sio 1-2.1:1.0: FTDI USB Serial Device
converter detected
May 24 10:16:40 shep kernel: drivers/usb/serial/ftdi_sio.c: Detected FT232BM
May 24 10:16:40 shep kernel: usb 1-2.1: FTDI USB Serial Device converter
now attached to ttyUSB0
May 24 10:16:40 shep kernel: usb 1-2.2: new full speed USB device using
uhci_hcd and address 5
May 24 10:16:40 shep kernel: usb 1-2.2: Product: USB HS SERIAL CONVERTER
May 24 10:16:40 shep kernel: usb 1-2.2: Manufacturer: FTDI
May 24 10:16:40 shep kernel: usb 1-2.2: SerialNumber: FTCEUPTD
May 24 10:16:40 shep kernel: usb 1-2.2: configuration #1 chosen from 1
choice
May 24 10:16:40 shep kernel: ftdi_sio 1-2.2:1.0: FTDI USB Serial Device
converter detected
May 24 10:16:40 shep kernel: drivers/usb/serial/ftdi_sio.c: Detected FT232BM
May 24 10:16:40 shep kernel: usb 1-2.2: FTDI USB Serial Device converter
now attached to ttyUSB1

Then I "logger MINICOM" and run minicom and power the remote end

May 24 10:16:48 shep root: MINICOM
May 24 10:16:52 shep kernel: usb 1-2: USB disconnect, address 3
May 24 10:16:52 shep kernel: usb 1-2.1: USB disconnect, address 4
May 24 10:16:52 shep kernel: ftdi_sio 1-2.1:1.0: device disconnected
May 24 10:16:52 shep kernel: usb 1-2.2: USB disconnect, address 5
May 24 10:16:52 shep kernel: ftdi_sio ttyUSB1: FTDI USB Serial Device
converter now disconnected from ttyUSB1
May 24 10:16:52 shep kernel: ftdi_sio 1-2.2:1.0: device disconnected
May 24 10:16:53 shep kernel: usb 1-2: new full speed USB device using
uhci_hcd and address 6
May 24 10:16:56 shep kernel: ftdi_sio ttyUSB0: FTDI USB Serial Device
converter now disconnected from ttyUSB0

then I quit minicom and "logger done"
May 24 10:16:59 shep root: done
May 24 10:17:11 shep kernel: usb 1-2: new full speed USB device using
uhci_hcd and address 7


Now the same; dmesg output for the modprobe:

USB Universal Host Controller Interface driver v3.0
PCI: Enabling device 0000:00:07.2 (0000 -> 0001)
PCI: Found IRQ 7 for device 0000:00:07.2
PCI: Sharing IRQ 7 with 0000:00:07.3
PCI: Sharing IRQ 7 with 0000:00:0a.0
uhci_hcd 0000:00:07.2: UHCI Host Controller
drivers/usb/core/inode.c: creating file 'devices'
drivers/usb/core/inode.c: creating file '001'
uhci_hcd 0000:00:07.2: new USB bus registered, assigned bus number 1
uhci_hcd 0000:00:07.2: detected 2 ports
uhci_hcd 0000:00:07.2: uhci_check_and_reset_hc: cmd = 0x0000
uhci_hcd 0000:00:07.2: Performing full reset
uhci_hcd 0000:00:07.2: irq 7, io base 0x0000d400
usb usb1: default language 0x0409
usb usb1: new device strings: Mfr=3, Product=2, SerialNumber=1
usb usb1: Product: UHCI Host Controller
usb usb1: Manufacturer: Linux 2.6.18sam uhci_hcd
usb usb1: SerialNumber: 0000:00:07.2
usb usb1: uevent
usb usb1: configuration #1 chosen from 1 choice
usb usb1: adding 1-0:1.0 (config #1, interface 0)
usb 1-0:1.0: uevent
hub 1-0:1.0: usb_probe_interface
hub 1-0:1.0: usb_probe_interface - got id
hub 1-0:1.0: USB hub found
hub 1-0:1.0: 2 ports detected
hub 1-0:1.0: standalone hub
hub 1-0:1.0: no power switching (usb 1.0)
hub 1-0:1.0: individual port over-current protection
hub 1-0:1.0: power on to power good time: 2ms
hub 1-0:1.0: local power source is good
hub 1-0:1.0: trying to enable port power on non-switchable hub
drivers/usb/core/inode.c: creating file '001'
PCI: Enabling device 0000:00:07.3 (0000 -> 0001)
PCI: Found IRQ 7 for device 0000:00:07.3
PCI: Sharing IRQ 7 with 0000:00:07.2
PCI: Sharing IRQ 7 with 0000:00:0a.0
uhci_hcd 0000:00:07.3: UHCI Host Controller
drivers/usb/core/inode.c: creating file '002'
uhci_hcd 0000:00:07.3: new USB bus registered, assigned bus number 2
uhci_hcd 0000:00:07.3: detected 2 ports
uhci_hcd 0000:00:07.3: uhci_check_and_reset_hc: cmd = 0x0000
uhci_hcd 0000:00:07.3: Performing full reset
uhci_hcd 0000:00:07.3: irq 7, io base 0x0000d800
usb usb2: default language 0x0409
usb usb2: new device strings: Mfr=3, Product=2, SerialNumber=1
usb usb2: Product: UHCI Host Controller
usb usb2: Manufacturer: Linux 2.6.18sam uhci_hcd
usb usb2: SerialNumber: 0000:00:07.3
usb usb2: uevent
usb usb2: configuration #1 chosen from 1 choice
usb usb2: adding 2-0:1.0 (config #1, interface 0)
usb 2-0:1.0: uevent
hub 2-0:1.0: usb_probe_interface
hub 2-0:1.0: usb_probe_interface - got id
hub 2-0:1.0: USB hub found
hub 2-0:1.0: 2 ports detected
hub 2-0:1.0: standalone hub
hub 2-0:1.0: no power switching (usb 1.0)
hub 2-0:1.0: individual port over-current protection
hub 2-0:1.0: power on to power good time: 2ms
hub 2-0:1.0: local power source is good
hub 2-0:1.0: trying to enable port power on non-switchable hub
hub 1-0:1.0: state 7 ports 2 chg 0000 evt 0006
uhci_hcd 0000:00:07.2: port 1 portsc 01ab,00
hub 1-0:1.0: port 1, status 0301, change 0003, 1.5 Mb/s
drivers/usb/core/inode.c: creating file '001'
hub 1-0:1.0: debounce: port 1: total 100ms stable 100ms status 0x301
usb 1-1: new low speed USB device using uhci_hcd and address 2
usb 1-1: skipped 1 descriptor after interface
usb 1-1: skipped 1 descriptor after interface
usb 1-1: default language 0x0409
usb 1-1: new device strings: Mfr=1, Product=2, SerialNumber=0
usb 1-1: Product: PS2 to USB Converter
usb 1-1: Manufacturer: CHESEN
usb 1-1: uevent
usb 1-1: configuration #1 chosen from 1 choice
usb 1-1: adding 1-1:1.0 (config #1, interface 0)
usb 1-1:1.0: uevent
usbhid 1-1:1.0: usb_probe_interface
usbhid 1-1:1.0: usb_probe_interface - got id
input: CHESEN PS2 to USB Converter as /class/input/input15
input: USB HID v1.10 Keyboard [CHESEN PS2 to USB Converter] on
usb-0000:00:07.2-1
usb 1-1: adding 1-1:1.1 (config #1, interface 1)
usb 1-1:1.1: uevent
usbhid 1-1:1.1: usb_probe_interface
usbhid 1-1:1.1: usb_probe_interface - got id
input: CHESEN PS2 to USB Converter as /class/input/input16
input: USB HID v1.10 Mouse [CHESEN PS2 to USB Converter] on
usb-0000:00:07.2-1
drivers/usb/core/inode.c: creating file '002'
uhci_hcd 0000:00:07.2: port 2 portsc 009b,00
hub 1-0:1.0: port 2, status 0101, change 0003, 12 Mb/s
hub 1-0:1.0: debounce: port 2: total 100ms stable 100ms status 0x101
usb 1-2: new full speed USB device using uhci_hcd and address 3
usb 1-2: ep0 maxpacket = 8
usb 1-2: default language 0x0409
usb 1-2: new device strings: Mfr=1, Product=2, SerialNumber=0
usb 1-2: Product: Generic USB Hub
usb 1-2: Manufacturer: ALCOR
usb 1-2: uevent
usb 1-2: configuration #1 chosen from 1 choice
usb 1-2: adding 1-2:1.0 (config #1, interface 0)
usb 1-2:1.0: uevent
hub 1-2:1.0: usb_probe_interface
hub 1-2:1.0: usb_probe_interface - got id
hub 1-2:1.0: USB hub found
hub 1-2:1.0: 4 ports detected
hub 1-2:1.0: standalone hub
hub 1-2:1.0: individual port power switching
hub 1-2:1.0: individual port over-current protection
hub 1-2:1.0: power on to power good time: 44ms
hub 1-2:1.0: local power source is good
hub 1-2:1.0: enabling power on all ports
drivers/usb/core/inode.c: creating file '003'
hub 2-0:1.0: state 7 ports 2 chg 0000 evt 0006
uhci_hcd 0000:00:07.3: port 1 portsc 018a,00
hub 2-0:1.0: port 1, status 0300, change 0003, 1.5 Mb/s
hub 2-0:1.0: debounce: port 1: total 100ms stable 100ms status 0x300
uhci_hcd 0000:00:07.3: port 2 portsc 008a,00
hub 2-0:1.0: port 2, status 0100, change 0003, 12 Mb/s
hub 2-0:1.0: debounce: port 2: total 100ms stable 100ms status 0x100
hub 1-0:1.0: state 7 ports 2 chg 0000 evt 0004
hub 1-2:1.0: state 7 ports 4 chg 0000 evt 0006
hub 1-2:1.0: port 1, status 0101, change 0001, 12 Mb/s
hub 1-2:1.0: debounce: port 1: total 100ms stable 100ms status 0x101
usb 1-2.1: new full speed USB device using uhci_hcd and address 4
usb 1-2.1: ep0 maxpacket = 8
usb 1-2.1: default language 0x0409
usb 1-2.1: new device strings: Mfr=1, Product=2, SerialNumber=3
usb 1-2.1: Product: USB HS SERIAL CONVERTER
usb 1-2.1: Manufacturer: FTDI
usb 1-2.1: SerialNumber: FTCEUPT7
usb 1-2.1: uevent
usb 1-2.1: configuration #1 chosen from 1 choice
usb 1-2.1: adding 1-2.1:1.0 (config #1, interface 0)
usb 1-2.1:1.0: uevent
usbserial_generic 1-2.1:1.0: usb_probe_interface
usbserial_generic 1-2.1:1.0: usb_probe_interface - got id
ftdi_sio 1-2.1:1.0: usb_probe_interface
ftdi_sio 1-2.1:1.0: usb_probe_interface - got id
ftdi_sio 1-2.1:1.0: FTDI USB Serial Device converter detected
drivers/usb/serial/ftdi_sio.c: Detected FT232BM
usb 1-2.1: FTDI USB Serial Device converter now attached to ttyUSB0
drivers/usb/core/inode.c: creating file '004'
hub 1-2:1.0: port 2, status 0101, change 0001, 12 Mb/s
hub 1-2:1.0: debounce: port 2: total 100ms stable 100ms status 0x101
usb 1-2.2: new full speed USB device using uhci_hcd and address 5
usb 1-2.2: ep0 maxpacket = 8
usb 1-2.2: default language 0x0409
usb 1-2.2: new device strings: Mfr=1, Product=2, SerialNumber=3
usb 1-2.2: Product: USB HS SERIAL CONVERTER
usb 1-2.2: Manufacturer: FTDI
usb 1-2.2: SerialNumber: FTCEUPTD
usb 1-2.2: uevent
usb 1-2.2: configuration #1 chosen from 1 choice
usb 1-2.2: adding 1-2.2:1.0 (config #1, interface 0)
usb 1-2.2:1.0: uevent
usbserial_generic 1-2.2:1.0: usb_probe_interface
usbserial_generic 1-2.2:1.0: usb_probe_interface - got id
ftdi_sio 1-2.2:1.0: usb_probe_interface
ftdi_sio 1-2.2:1.0: usb_probe_interface - got id
ftdi_sio 1-2.2:1.0: FTDI USB Serial Device converter detected
drivers/usb/serial/ftdi_sio.c: Detected FT232BM
usb 1-2.2: FTDI USB Serial Device converter now attached to ttyUSB1
drivers/usb/core/inode.c: creating file '005'
hub 2-0:1.0: state 7 ports 2 chg 0000 evt 0000
hub 1-2:1.0: state 7 ports 4 chg 0000 evt 0004
usb usb2: suspend_rh (auto-stop)

And now dmesg for the power-up remote end and hang:

hub 1-0:1.0: state 7 ports 2 chg 0000 evt 0004
uhci_hcd 0000:00:07.2: port 2 portsc 019b,00
hub 1-0:1.0: port 2, status 0301, change 0003, 1.5 Mb/s
usb 1-2: USB disconnect, address 3
usb 1-2.1: USB disconnect, address 4
usb 1-2.1: usb_disable_device nuking all URBs
uhci_hcd 0000:00:07.2: shutdown urb d82d2920 pipe c0408480 ep1in-bulk
usb 1-2.1: unregistering interface 1-2.1:1.0
 usbdev1.4_ep81: ep_device_release called for usbdev1.4_ep81
 usbdev1.4_ep02: ep_device_release called for usbdev1.4_ep02
ftdi_sio 1-2.1:1.0: device disconnected
usb 1-2.1:1.0: uevent
usb 1-2.1: unregistering device
 usbdev1.4_ep00: ep_device_release called for usbdev1.4_ep00
usb 1-2.1: uevent
usb 1-2.2: USB disconnect, address 5
usb 1-2.2: usb_disable_device nuking all URBs
usb 1-2.2: unregistering interface 1-2.2:1.0
 usbdev1.5_ep81: ep_device_release called for usbdev1.5_ep81
 usbdev1.5_ep02: ep_device_release called for usbdev1.5_ep02
ftdi_sio ttyUSB1: FTDI USB Serial Device converter now disconnected from
ttyUSB1
ftdi_sio 1-2.2:1.0: device disconnected
usb 1-2.2:1.0: uevent
usb 1-2.2: unregistering device
 usbdev1.5_ep00: ep_device_release called for usbdev1.5_ep00
usb 1-2.2: uevent
usb 1-2: usb_disable_device nuking all URBs
uhci_hcd 0000:00:07.2: shutdown urb dd7f4740 pipe 40408380 ep1in-intr
usb 1-2: unregistering interface 1-2:1.0
 usbdev1.3_ep81: ep_device_release called for usbdev1.3_ep81
usb 1-2:1.0: uevent
usb 1-2: unregistering device
 usbdev1.3_ep00: ep_device_release called for usbdev1.3_ep00
usb 1-2: uevent
hub 1-0:1.0: debounce: port 2: total 100ms stable 100ms status 0x301
usb 1-2: new full speed USB device using uhci_hcd and address 6
usb 1-2: khubd timed out on ep0in len=-8/64
usb 1-2: khubd timed out on ep0in len=-8/64
usb 1-2: khubd timed out on ep0in len=-8/64
drivers/usb/serial/ftdi_sio.c: error from flowcontrol urb
drivers/usb/serial/ftdi_sio.c: update_mctrl Error from MODEM_CTRL urb:
DTR LOW, RTS LOW
ftdi_sio ttyUSB0: FTDI USB Serial Device converter now disconnected from
ttyUSB0
usb 1-2: device descriptor read/64, error -110
usb 1-2: khubd timed out on ep0in len=-8/64

Thanks for your time on this.

Sam

-------------------------------------------------------------------------
This SF.net email is sponsored by DB2 Express
Download DB2 Express C - the FREE version of DB2 express and take
control of your XML. No limits. Just data. Click to get it now.
http://sourceforge.net/powerbar/db2/
_______________________________________________
[email protected]
To unsubscribe, use the last form field at:
https://lists.sourceforge.net/lists/listinfo/linux-usb-devel