Intermittent loss of ftdi-sio link

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

 



I don't know if this is the right place to ask, but it seems to be
where the experts are.

I have a prototype board with an FT4232 on it. Mostly it's working
fine, but if I leave the PC talking to it (or a device connected to it)
for a significant time, I sometimes come back to find the link has
died. Indeed the tty device my software was using no longer exists, and
the FT4232 has been "bumped" around it.

(This is kernel 2.6.35.7 with a patch to recognise the device PID)

I've figured out that the USB side is glitching and the kernel is
re-registering the device, leaving out the tty that's already open
because it's not available. The question is why is it glitching?

Here's a syslog extract:

<here's where I power up the device>
Nov 20 18:11:49 zechariah kernel: usb 1-5: new high speed USB device using ehci_hcd and address 9
Nov 20 18:11:49 zechariah kernel: usb 1-5: New USB device found, idVendor=0403, idProduct=8cb0
Nov 20 18:11:49 zechariah kernel: usb 1-5: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Nov 20 18:11:49 zechariah kernel: usb 1-5: Product: PicoSphere
Nov 20 18:11:49 zechariah kernel: usb 1-5: Manufacturer: Oxford Vacuum Science
Nov 20 18:11:49 zechariah kernel: usb 1-5: SerialNumber: PS09A001
Nov 20 18:11:49 zechariah kernel: ftdi_sio 1-5:1.0: FTDI USB Serial Device converter detected
Nov 20 18:11:49 zechariah kernel: usb 1-5: Detected FT4232H
Nov 20 18:11:49 zechariah kernel: usb 1-5: Number of endpoints 2
Nov 20 18:11:49 zechariah kernel: usb 1-5: Endpoint 1 MaxPacketSize 512
Nov 20 18:11:49 zechariah kernel: usb 1-5: Endpoint 2 MaxPacketSize 512
Nov 20 18:11:49 zechariah kernel: usb 1-5: Setting MaxPacketSize 512
Nov 20 18:11:49 zechariah kernel: usb 1-5: FTDI USB Serial Device converter now attached to ttyUSB0
Nov 20 18:11:49 zechariah kernel: ftdi_sio 1-5:1.1: FTDI USB Serial Device converter detected
Nov 20 18:11:49 zechariah kernel: usb 1-5: Detected FT4232H
Nov 20 18:11:49 zechariah kernel: usb 1-5: Number of endpoints 2
Nov 20 18:11:49 zechariah kernel: usb 1-5: Endpoint 1 MaxPacketSize 512
Nov 20 18:11:49 zechariah kernel: usb 1-5: Endpoint 2 MaxPacketSize 512
Nov 20 18:11:49 zechariah kernel: usb 1-5: Setting MaxPacketSize 512
Nov 20 18:11:49 zechariah kernel: usb 1-5: FTDI USB Serial Device converter now attached to ttyUSB1
Nov 20 18:11:49 zechariah kernel: ftdi_sio 1-5:1.2: FTDI USB Serial Device converter detected
Nov 20 18:11:49 zechariah kernel: usb 1-5: Detected FT4232H
Nov 20 18:11:49 zechariah kernel: usb 1-5: Number of endpoints 2
Nov 20 18:11:49 zechariah kernel: usb 1-5: Endpoint 1 MaxPacketSize 512
Nov 20 18:11:49 zechariah kernel: usb 1-5: Endpoint 2 MaxPacketSize 512
Nov 20 18:11:49 zechariah kernel: usb 1-5: Setting MaxPacketSize 512
Nov 20 18:11:49 zechariah kernel: usb 1-5: FTDI USB Serial Device converter now attached to ttyUSB2
Nov 20 18:11:49 zechariah kernel: ftdi_sio 1-5:1.3: FTDI USB Serial Device converter detected
Nov 20 18:11:49 zechariah kernel: usb 1-5: Detected FT4232H
Nov 20 18:11:49 zechariah kernel: usb 1-5: Number of endpoints 2
Nov 20 18:11:49 zechariah kernel: usb 1-5: Endpoint 1 MaxPacketSize 512
Nov 20 18:11:49 zechariah kernel: usb 1-5: Endpoint 2 MaxPacketSize 512
Nov 20 18:11:49 zechariah kernel: usb 1-5: Setting MaxPacketSize 512
Nov 20 18:11:49 zechariah kernel: usb 1-5: FTDI USB Serial Device converter now attached to ttyUSB3
<then I run the software that connects>
<snip a bunch of unrelated cron entries>
Nov 20 21:21:32 zechariah kernel: usb 1-5: USB disconnect, address 9
Nov 20 21:21:32 zechariah kernel: ftdi_sio ttyUSB0: FTDI USB Serial Device converter now disconnected from ttyUSB0
Nov 20 21:21:32 zechariah kernel: ftdi_sio 1-5:1.0: device disconnected
Nov 20 21:21:32 zechariah kernel: ftdi_sio ttyUSB1: FTDI USB Serial Device converter now disconnected from ttyUSB1
Nov 20 21:21:32 zechariah kernel: ftdi_sio 1-5:1.1: device disconnected
Nov 20 21:21:32 zechariah kernel: ftdi_sio ttyUSB2: FTDI USB Serial Device converter now disconnected from ttyUSB2
Nov 20 21:21:32 zechariah kernel: ftdi_sio 1-5:1.2: device disconnected
Nov 20 21:21:32 zechariah kernel: ftdi_sio ttyUSB3: FTDI USB Serial Device converter now disconnected from ttyUSB3
Nov 20 21:21:32 zechariah kernel: ftdi_sio 1-5:1.3: device disconnected
Nov 20 21:21:32 zechariah kernel: usb 1-5: new high speed USB device using ehci_hcd and address 10
Nov 20 21:21:48 zechariah kernel: hub 1-0:1.0: unable to enumerate USB device on port 5
Nov 20 21:21:48 zechariah kernel: usb 1-6: USB disconnect, address 2
Nov 20 21:21:48 zechariah kernel: usb-storage: storage_disconnect() called
Nov 20 21:21:48 zechariah kernel: usb-storage: -- usb_stor_release_resources
Nov 20 21:21:48 zechariah kernel: usb-storage: -- sending exit command to thread
Nov 20 21:21:48 zechariah kernel: usb-storage: *** thread awakened.
Nov 20 21:21:48 zechariah kernel: usb-storage: -- exiting
Nov 20 21:21:48 zechariah kernel: usb-storage: -- dissociate_dev
Nov 20 21:21:48 zechariah kernel: usb 1-6: new high speed USB device using ehci_hcd and address 11
Nov 20 21:22:03 zechariah kernel: hub 1-0:1.0: unable to enumerate USB device on port 6
Nov 20 21:22:03 zechariah kernel: usb 4-1: new full speed USB device using uhci_hcd and address 2
Nov 20 21:22:04 zechariah kernel: usb 4-1: not running at top speed; connect to a high speed hub
Nov 20 21:22:04 zechariah kernel: usb 4-1: New USB device found, idVendor=0403, idProduct=8cb0
Nov 20 21:22:04 zechariah kernel: usb 4-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Nov 20 21:22:04 zechariah kernel: usb 4-1: Product: PicoSphere
Nov 20 21:22:04 zechariah kernel: usb 4-1: Manufacturer: Oxford Vacuum Science
Nov 20 21:22:04 zechariah kernel: usb 4-1: SerialNumber: PS09A001
Nov 20 21:22:04 zechariah kernel: ftdi_sio 4-1:1.0: FTDI USB Serial Device converter detected
Nov 20 21:22:04 zechariah kernel: usb 4-1: Detected FT4232H
Nov 20 21:22:04 zechariah kernel: usb 4-1: Number of endpoints 2
Nov 20 21:22:04 zechariah kernel: usb 4-1: Endpoint 1 MaxPacketSize 64
Nov 20 21:22:04 zechariah kernel: usb 4-1: Endpoint 2 MaxPacketSize 64
Nov 20 21:22:04 zechariah kernel: usb 4-1: Setting MaxPacketSize 64
Nov 20 21:22:04 zechariah kernel: usb 4-1: FTDI USB Serial Device converter now attached to ttyUSB0
Nov 20 21:22:04 zechariah kernel: ftdi_sio 4-1:1.1: FTDI USB Serial Device converter detected
Nov 20 21:22:04 zechariah kernel: usb 4-1: Detected FT4232H
Nov 20 21:22:04 zechariah kernel: usb 4-1: Number of endpoints 2
Nov 20 21:22:04 zechariah kernel: usb 4-1: Endpoint 1 MaxPacketSize 64
Nov 20 21:22:04 zechariah kernel: usb 4-1: Endpoint 2 MaxPacketSize 64
Nov 20 21:22:04 zechariah kernel: usb 4-1: Setting MaxPacketSize 64
Nov 20 21:22:04 zechariah kernel: usb 4-1: FTDI USB Serial Device converter now attached to ttyUSB1
Nov 20 21:22:04 zechariah kernel: ftdi_sio 4-1:1.2: FTDI USB Serial Device converter detected
Nov 20 21:22:04 zechariah kernel: usb 4-1: Detected FT4232H
Nov 20 21:22:04 zechariah kernel: usb 4-1: Number of endpoints 2
Nov 20 21:22:04 zechariah kernel: usb 4-1: Endpoint 1 MaxPacketSize 64
Nov 20 21:22:04 zechariah kernel: usb 4-1: Endpoint 2 MaxPacketSize 64
Nov 20 21:22:04 zechariah kernel: usb 4-1: Setting MaxPacketSize 64
Nov 20 21:22:04 zechariah kernel: usb 4-1: FTDI USB Serial Device converter now attached to ttyUSB3
Nov 20 21:22:04 zechariah kernel: ftdi_sio 4-1:1.3: FTDI USB Serial Device converter detected
Nov 20 21:22:04 zechariah kernel: usb 4-1: Detected FT4232H
Nov 20 21:22:04 zechariah kernel: usb 4-1: Number of endpoints 2
Nov 20 21:22:04 zechariah kernel: usb 4-1: Endpoint 1 MaxPacketSize 64
Nov 20 21:22:04 zechariah kernel: usb 4-1: Endpoint 2 MaxPacketSize 64
Nov 20 21:22:04 zechariah kernel: usb 4-1: Setting MaxPacketSize 64
Nov 20 21:22:04 zechariah kernel: usb 4-1: FTDI USB Serial Device converter now attached to ttyUSB4
Nov 20 21:22:04 zechariah kernel: usb 4-2: new full speed USB device using uhci_hcd and address 3
Nov 20 21:22:04 zechariah kernel: usb 4-2: New USB device found, idVendor=10df, idProduct=0500
Nov 20 21:22:04 zechariah kernel: usb 4-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Nov 20 21:22:04 zechariah kernel: usb 4-2: Product: iAPP CR-e500
Nov 20 21:22:04 zechariah kernel: usb 4-2: Manufacturer: IN-WIN  
Nov 20 21:22:04 zechariah kernel: usb 4-2: SerialNumber: 0000000001B9
Nov 20 21:22:04 zechariah kernel: usb-storage: USB Mass Storage device detected
Nov 20 21:22:04 zechariah kernel: usb-storage: -- associate_dev
Nov 20 21:22:04 zechariah kernel: usb-storage: Vendor: 0x10df, Product: 0x0500, Revision: 0x0096
Nov 20 21:22:04 zechariah kernel: usb-storage: Interface Subclass: 0x06, Protocol: 0x50
Nov 20 21:22:04 zechariah kernel: usb-storage: Transport: Bulk
Nov 20 21:22:04 zechariah kernel: usb-storage: Protocol: Transparent SCSI
Nov 20 21:22:04 zechariah kernel: scsi1 : usb-storage 4-2:1.0
Nov 20 21:22:04 zechariah kernel: usb-storage: *** thread sleeping.
Nov 20 21:22:04 zechariah kernel: usb-storage 4-2:1.0: device found
Nov 20 21:22:04 zechariah kernel: usb-storage 4-2:1.0: waiting for device to settle before scanning
Nov 20 21:22:05 zechariah kernel: usb-storage: usb_stor_control_msg: rq=fe rqtype=a1 value=0000 index=00 len=1
Nov 20 21:22:05 zechariah kernel: usb-storage: GetMaxLUN command result is 1, data is 3
Nov 20 21:22:05 zechariah kernel: usb-storage: queuecommand called
Nov 20 21:22:05 zechariah kernel: usb-storage: *** thread awakened.
Nov 20 21:22:05 zechariah kernel: usb-storage: Command INQUIRY (6 bytes)
Nov 20 21:22:05 zechariah kernel: usb-storage:  12 00 00 00 24 00
Nov 20 21:22:05 zechariah kernel: usb-storage: Bulk Command S 0x43425355 T 0x1 L 36 F 128 Trg 0 LUN 0 CL 6
Nov 20 21:22:05 zechariah kernel: usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
Nov 20 21:22:05 zechariah kernel: usb-storage: Status code 0; transferred 31/31
Nov 20 21:22:05 zechariah kernel: usb-storage: -- transfer complete
Nov 20 21:22:05 zechariah kernel: usb-storage: Bulk command transfer result=0
Nov 20 21:22:05 zechariah kernel: usb-storage: usb_stor_bulk_transfer_sglist: xfer 36 bytes, 1 entries
Nov 20 21:22:05 zechariah kernel: usb-storage: Status code 0; transferred 36/36
Nov 20 21:22:05 zechariah kernel: usb-storage: -- transfer complete
Nov 20 21:22:05 zechariah kernel: usb-storage: Bulk data transfer result 0x0
Nov 20 21:22:05 zechariah kernel: usb-storage: Attempting to get CSW...
Nov 20 21:22:05 zechariah kernel: usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
Nov 20 21:22:05 zechariah kernel: usb-storage: Status code 0; transferred 13/13
Nov 20 21:22:05 zechariah kernel: usb-storage: -- transfer complete
Nov 20 21:22:05 zechariah kernel: usb-storage: Bulk status result = 0
Nov 20 21:22:05 zechariah kernel: usb-storage: Bulk Status S 0x53425355 T 0x1 R 0 Stat 0x0
Nov 20 21:22:05 zechariah kernel: usb-storage: scsi cmd done, result=0x0
Nov 20 21:22:05 zechariah kernel: usb-storage: *** thread sleeping.
Nov 20 21:22:05 zechariah kernel: scsi 1:0:0:0: Direct-Access     IN-WIN   iAPP  FS-CF      0.96 PQ: 0 ANSI: 0
Nov 20 21:22:05 zechariah kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0
Nov 20 21:22:05 zechariah kernel: usb-storage: queuecommand called
Nov 20 21:22:05 zechariah kernel: usb-storage: *** thread awakened.
Nov 20 21:22:05 zechariah kernel: usb-storage: Command INQUIRY (6 bytes)
Nov 20 21:22:05 zechariah kernel: usb-storage:  12 00 00 00 24 00
Nov 20 21:22:05 zechariah kernel: usb-storage: Bulk Command S 0x43425355 T 0x2 L 36 F 128 Trg 0 LUN 1 CL 6
Nov 20 21:22:05 zechariah kernel: usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
Nov 20 21:22:05 zechariah kernel: usb-storage: Status code 0; transferred 31/31
Nov 20 21:22:05 zechariah kernel: usb-storage: -- transfer complete
Nov 20 21:22:05 zechariah kernel: usb-storage: Bulk command transfer result=0
Nov 20 21:22:05 zechariah kernel: usb-storage: usb_stor_bulk_transfer_sglist: xfer 36 bytes, 1 entries
Nov 20 21:22:05 zechariah kernel: usb-storage: Status code 0; transferred 36/36
Nov 20 21:22:05 zechariah kernel: usb-storage: -- transfer complete
Nov 20 21:22:05 zechariah kernel: usb-storage: Bulk data transfer result 0x0
Nov 20 21:22:05 zechariah kernel: usb-storage: Attempting to get CSW...
Nov 20 21:22:05 zechariah kernel: usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
Nov 20 21:22:05 zechariah kernel: usb-storage: Status code 0; transferred 13/13
Nov 20 21:22:05 zechariah kernel: usb-storage: -- transfer complete
Nov 20 21:22:05 zechariah kernel: usb-storage: Bulk status result = 0
Nov 20 21:22:05 zechariah kernel: usb-storage: Bulk Status S 0x53425355 T 0x2 R 0 Stat 0x0
Nov 20 21:22:05 zechariah kernel: usb-storage: scsi cmd done, result=0x0
Nov 20 21:22:05 zechariah kernel: usb-storage: *** thread sleeping.
Nov 20 21:22:05 zechariah kernel: usb-storage: queuecommand called
Nov 20 21:22:05 zechariah kernel: usb-storage: *** thread awakened.
Nov 20 21:22:05 zechariah kernel: usb-storage: Command TEST_UNIT_READY (6 bytes)
Nov 20 21:22:05 zechariah kernel: usb-storage:  00 00 00 00 00 00
Nov 20 21:22:05 zechariah kernel: usb-storage: Bulk Command S 0x43425355 T 0x3 L 0 F 0 Trg 0 LUN 0 CL 6
Nov 20 21:22:05 zechariah kernel: usb-storage: usb_stor_bulk_transfer_buf: xfer 31 bytes
Nov 20 21:22:05 zechariah kernel: usb-storage: Status code 0; transferred 31/31
Nov 20 21:22:05 zechariah kernel: usb-storage: -- transfer complete
Nov 20 21:22:05 zechariah kernel: usb-storage: Bulk command transfer result=0
Nov 20 21:22:05 zechariah kernel: usb-storage: Attempting to get CSW...
Nov 20 21:22:05 zechariah kernel: usb-storage: usb_stor_bulk_transfer_buf: xfer 13 bytes
Nov 20 21:22:05 zechariah kernel: scsi 1:0:0:1: Direct-Access     IN-WIN   iAPP  FS-MS      0.96 PQ: 0 ANSI: 0
Nov 20 21:22:05 zechariah kernel: sd 1:0:0:1: Attached scsi generic sg1 type 0
Nov 20 21:22:05 zechariah kernel: usb-storage: Status code 0; transferred 13/13
Nov 20 21:22:05 zechariah kernel: usb-storage: -- transfer complete
Nov 20 21:22:05 zechariah kernel: usb-storage: Bulk status result = 0
Nov 20 21:22:05 zechariah kernel: usb-storage: Bulk Status S 0x53425355 T 0x3 R 0 Stat 0x1
Nov 20 21:22:05 zechariah kernel: usb-storage: -- transport indicates command failure
<a big blob of further usb-storage stuff follows>

Now, I should probably say that the "Oxford Vacuum Science"
"PicoSphere" (the device with the FT4232) is plugged into a front panel
USB socket on a card reader, which I suspect means there's a hub in
there. And the big blob of usb-storage stuff makes me wonder whether
it's actually the card reader that's flaky.

My next step will be to try it on a different USB socket, once I can
clear enough space to re-route the 4-metre USB cable I need for that.
The only thing is that the fault doesn't happen often or predictably,
so it'll take some time to satisfy myself that way. I guess I'm hoping
someone here can look at that syslog and say "yeah, those in-Win card
readers fail like that" or "no, that's not what you think" or something.

Thanks in anticipation,

Rob
--
To unsubscribe from this list: send the line "unsubscribe linux-usb" in
the body of a message to majordomo@xxxxxxxxxxxxxxx
More majordomo info at  http://vger.kernel.org/majordomo-info.html


[Index of Archives]     [Linux Media]     [Linux Input]     [Linux Audio Users]     [Yosemite News]     [Linux Kernel]     [Linux SCSI]     [Old Linux USB Devel Archive]

  Powered by Linux