I can't repeat the problem at will. It did happen 3 times in a day one day, I don't remember what kernel version I was using then, probably 2.6.18 with the generic usb serial driver. Now, it's more like once a week, with the option driver. I have to unplug and replug the device to make it work. The last kernel I tried this on was 2.6.29-rc3. The logs here are from that version of the kernel. I have installed 2.6.29-rc5 now but haven't had the problem yet. This is what is logged in kern.log when the device disconnects: Feb 17 11:49:52 000212 kernel: usb 1-3: USB disconnect, address 5 Feb 17 11:49:52 000212 kernel: option 1-3:1.0: device disconnected Feb 17 11:49:52 000212 kernel: option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1 Feb 17 11:49:52 000212 kernel: option 1-3:1.1: device disconnected Feb 17 11:49:52 000212 kernel: option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0 Feb 17 11:49:53 000212 kernel: usb 1-3: new full speed USB device using ohci_hcd and address 6 Feb 17 11:49:53 000212 kernel: usb 1-3: New USB device found, idVendor=1410, idProduct=4400 Feb 17 11:49:53 000212 kernel: usb 1-3: New USB device strings: Mfr=1, Product=2, SerialNumber=4 Feb 17 11:49:53 000212 kernel: usb 1-3: Product: Novatel Wireless HSDPA Modem Feb 17 11:49:53 000212 kernel: usb 1-3: Manufacturer: Novatel Wireless Feb 17 11:49:53 000212 kernel: usb 1-3: SerialNumber: 356846011987541 Feb 17 11:49:53 000212 kernel: usb 1-3: configuration #1 chosen from 1 choice Feb 17 11:49:53 000212 kernel: option 1-3:1.0: GSM modem (1-port) converter detected Feb 17 11:49:53 000212 kernel: usb 1-3: GSM modem (1-port) converter now attached to ttyUSB0 Feb 17 11:49:53 000212 kernel: option 1-3:1.1: GSM modem (1-port) converter detected Feb 17 11:49:53 000212 kernel: usb 1-3: GSM modem (1-port) converter now attached to ttyUSB1 But the ttyUSB0 device is not working properly afterwards. When I unplug and replug the usb device, this gets logged : Feb 17 13:46:38 000212 kernel: usb 1-3: USB disconnect, address 6 Feb 17 13:46:38 000212 kernel: option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0 Feb 17 13:46:38 000212 kernel: option 1-3:1.0: device disconnected Feb 17 13:46:38 000212 kernel: option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1 Feb 17 13:46:38 000212 kernel: option 1-3:1.1: device disconnected Feb 17 13:46:46 000212 kernel: usb 1-3: new full speed USB device using ohci_hcd and address 7 Feb 17 13:46:47 000212 kernel: usb 1-3: New USB device found, idVendor=1410, idProduct=5010 Feb 17 13:46:47 000212 kernel: usb 1-3: New USB device strings: Mfr=1, Product=2, SerialNumber=4 Feb 17 13:46:47 000212 kernel: usb 1-3: Product: Novatel Wireless HSUPA Modem Feb 17 13:46:47 000212 kernel: usb 1-3: Manufacturer: Novatel Wireless Feb 17 13:46:47 000212 kernel: usb 1-3: SerialNumber: 356846011987540 Feb 17 13:46:47 000212 kernel: usb 1-3: configuration #1 chosen from 1 choice Feb 17 13:46:49 000212 kernel: Initializing USB Mass Storage driver... Feb 17 13:46:49 000212 kernel: scsi6 : SCSI emulation for USB Mass Storage devices Feb 17 13:46:49 000212 kernel: usb-storage: device found at 7 Feb 17 13:46:49 000212 kernel: usb-storage: waiting for device to settle before scanning Feb 17 13:46:49 000212 kernel: usbcore: registered new interface driver usb-storage Feb 17 13:46:49 000212 kernel: USB Mass Storage support registered. Feb 17 13:46:49 000212 kernel: Driver 'sr' needs updating - please use bus_type methods Feb 17 13:46:54 000212 kernel: usb-storage: device scan complete Feb 17 13:46:54 000212 kernel: scsi 6:0:0:0: CD-ROM Novatel Mass Storage 1.00 PQ: 0 ANSI: 2 Feb 17 13:46:54 000212 kernel: sr0: scsi-1 drive Feb 17 13:46:54 000212 kernel: Uniform CD-ROM driver Revision: 3.20 Feb 17 13:46:54 000212 kernel: sr 6:0:0:0: Attached scsi CD-ROM sr0 Feb 17 13:46:54 000212 kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Feb 17 13:46:54 000212 kernel: sr 6:0:0:0: Attached scsi generic sg1 type 5 Feb 17 13:46:55 000212 kernel: usb 1-3: USB disconnect, address 7 Feb 17 13:46:56 000212 kernel: usb 1-3: new full speed USB device using ohci_hcd and address 8 Feb 17 13:46:57 000212 kernel: usb 1-3: New USB device found, idVendor=1410, idProduct=4400 Feb 17 13:46:57 000212 kernel: usb 1-3: New USB device strings: Mfr=1, Product=2, SerialNumber=4 Feb 17 13:46:57 000212 kernel: usb 1-3: Product: Novatel Wireless HSUPA Modem Feb 17 13:46:57 000212 kernel: usb 1-3: Manufacturer: Novatel Wireless Feb 17 13:46:57 000212 kernel: usb 1-3: SerialNumber: 356846011987541 Feb 17 13:46:57 000212 kernel: usb 1-3: configuration #1 chosen from 1 choice Feb 17 13:46:57 000212 kernel: option 1-3:1.0: GSM modem (1-port) converter detected Feb 17 13:46:57 000212 kernel: usb 1-3: GSM modem (1-port) converter now attached to ttyUSB0 Feb 17 13:46:57 000212 kernel: option 1-3:1.1: GSM modem (1-port) converter detected Feb 17 13:46:57 000212 kernel: usb 1-3: GSM modem (1-port) converter now attached to ttyUSB1 I have configured hotplug to eject /dev/sr0 when it sees 1410:5010, so that it can become 1410:4400. I had other problems with this setup so I did add some debugging output to pppd's logs that were not intended for this problem but they might help anyway. They show that pppd hangs when calling restore_tty(), which calls tcsetattr(tty_fd, TCSAFLUSH, &inittermios). Feb 17 11:49:52 000212 pppd[4081]: Hangup (SIGHUP) Feb 17 11:49:52 000212 pppd[4081]: Modem hangup Feb 17 11:49:52 000212 pppd[4081]: Connect time 445.7 minutes. Feb 17 11:49:52 000212 pppd[4081]: Sent 543836 bytes, received 598004 bytes. Feb 17 11:49:52 000212 pppd[7447]: tty_close_fds : real_ttyfd = 8 Feb 17 11:49:52 000212 pppd[4081]: Script /etc/ppp/ip-down started (pid 7447) Feb 17 11:49:52 000212 pppd[4081]: Connection terminated. Feb 17 11:49:52 000212 pppd[4081]: finish_tty : drop dtr on fd = 8 Feb 17 11:49:52 000212 pppd[4081]: finish_tty : restore_tty(8) Feb 17 11:49:52 000212 pppd[4081]: finish_tty : close(8) Feb 17 11:49:52 000212 pppd[4081]: sleeping 60 seconds Feb 17 11:49:52 000212 pppd[4081]: Script /etc/ppp/ip-down finished (pid 7447), status = 0x0 Feb 17 11:50:52 000212 pppd[4081]: connect_tty : opening /dev/ttyUSB0 Feb 17 11:50:52 000212 pppd[4081]: connect_tty : real_ttyfd = 8 Feb 17 11:50:53 000212 pppd[7828]: tty_close_fds : real_ttyfd = 8 Feb 17 11:50:54 000212 chat[7828]: abort on (BUSY) Feb 17 11:50:54 000212 chat[7828]: abort on (ERROR) Feb 17 11:50:54 000212 chat[7828]: abort on (NO ANSWER) Feb 17 11:50:54 000212 chat[7828]: abort on (NO CARRIER) Feb 17 11:50:54 000212 chat[7828]: abort on (NO DIALTONE) Feb 17 11:50:54 000212 chat[7828]: abort on (Invalid Login) Feb 17 11:50:54 000212 chat[7828]: abort on (Login incorrect) Feb 17 11:50:54 000212 chat[7828]: send (ATZ^M) Feb 17 11:50:54 000212 chat[7828]: timeout set to 10 seconds Feb 17 11:50:54 000212 chat[7828]: expect (OK) Feb 17 11:51:04 000212 chat[7828]: alarm Feb 17 11:51:04 000212 chat[7828]: Failed Feb 17 11:51:04 000212 pppd[4081]: Connect script failed Feb 17 11:51:04 000212 pppd[4081]: finish_tty : drop dtr on fd = 8 Feb 17 11:51:05 000212 pppd[4081]: finish_tty : restore_tty(8) Feb 17 13:46:38 000212 pppd[4081]: finish_tty : close(8) The `sleeping 60 seconds' comes from the `holdoff 60' pppd option. What can I do to help find out what is wrong? Thanks, François -- 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
