3g usb not recognized after connection dropped

[qbrick]

My internet via 3g (HSDPA/HSUPA) usb (Huawei E620 - Vodafone K3715) is giving me a hard time. Recently, I encountered several hangups. In most cases, the reception is very bad. What bugs me however is, that when I try to reestablish the conncetion, my usb modem doesn't get recognized properly. Several minutes of trying to reconnect, the modem is dropped from the list of network devices in the network applet. After plugging in the stick again it takes around 15 minutes, until it reappears in the network center. But even if I manage to reestablish the connection, after loading a single site, the connection stops working although the applet shows a working connection with DNS and everything.
End of the line, I have to shutdown the system and boot again (a reboot doesn't solve the problem)
Here is the output of my syslog file:

Code: [Select]

Aug 12 21:00:19 localhost pppd[2690]: Modem hangup
Aug 12 21:00:19 localhost pppd[2690]: Connect time 165.9 minutes.
Aug 12 21:00:19 localhost pppd[2690]: Sent 5304478 bytes, received 12022201 bytes.
Aug 12 21:00:19 localhost klogd: option: option_instat_callback: error -108
Aug 12 21:00:19 localhost klogd: option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0
Aug 12 21:00:19 localhost klogd: option 1-6:1.0: device disconnected
Aug 12 21:00:19 localhost klogd: option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1
Aug 12 21:00:19 localhost klogd: option 1-6:1.1: device disconnected
Aug 12 21:00:19 localhost klogd: option1 ttyUSB2: GSM modem (1-port) converter now disconnected from ttyUSB2
Aug 12 21:00:19 localhost klogd: option 1-6:1.2: device disconnected
Aug 12 21:00:19 localhost pppd[2690]: Script /etc/ppp/ip-down started (pid 18603)
Aug 12 21:00:19 localhost pppd[2690]: Connection terminated.
Aug 12 21:00:19 localhost klogd: usb 1-6: reset high speed USB device using ehci_hcd and address 4
Aug 12 21:00:19 localhost pppd[2690]: Script /etc/ppp/ip-down finished (pid 18603), status = 0x0
Aug 12 21:00:19 localhost pppd[2690]: Exit.
Aug 12 21:00:19 localhost klogd: option 1-6:1.2: GSM modem (1-port) converter detected
Aug 12 21:00:19 localhost klogd: usb 1-6: GSM modem (1-port) converter now attached to ttyUSB0
Aug 12 21:00:19 localhost klogd: option 1-6:1.1: GSM modem (1-port) converter detected
Aug 12 21:00:19 localhost klogd: usb 1-6: GSM modem (1-port) converter now attached to ttyUSB1
Aug 12 21:00:19 localhost klogd: option 1-6:1.0: GSM modem (1-port) converter detected
Aug 12 21:00:19 localhost klogd: usb 1-6: GSM modem (1-port) converter now attached to ttyUSB2
Aug 12 21:00:21 localhost ifup-ppp: Setting up a new /etc/ppp/peers/ppp0 config file
Aug 12 21:00:22 localhost ifup-ppp: pppd started for ppp0 on /dev/ttyUSB0 at 115200
Aug 12 21:00:22 localhost pppd[18714]: pppd 2.4.4 started by root, uid 0
Aug 12 21:00:23 localhost chat[18746]: abort on (BUSY)
Aug 12 21:00:23 localhost chat[18746]: abort on (ERROR)
Aug 12 21:00:23 localhost chat[18746]: abort on (NO CARRIER)
Aug 12 21:00:23 localhost chat[18746]: abort on (NO DIALTONE)
Aug 12 21:00:23 localhost chat[18746]: abort on (Invalid Login)
Aug 12 21:00:23 localhost chat[18746]: abort on (Login incorrect)
Aug 12 21:00:23 localhost chat[18746]: abort on (VOICE)
Aug 12 21:00:23 localhost chat[18746]: abort on (NO ANSWER)
Aug 12 21:00:23 localhost chat[18746]: abort on (DELAYED)
Aug 12 21:00:23 localhost chat[18746]: abort on (SIM PIN)
Aug 12 21:00:23 localhost chat[18746]: send (ATZ^M)
Aug 12 21:00:23 localhost chat[18746]: expect (OK)
Aug 12 21:00:23 localhost chat[18746]: ^M
Aug 12 21:00:23 localhost chat[18746]: ^RSSI:12^M
Aug 12 21:00:24 localhost chat[18746]: ATZ^M^M
Aug 12 21:00:24 localhost chat[18746]: OK
Aug 12 21:00:24 localhost chat[18746]:  -- got it
Aug 12 21:00:24 localhost chat[18746]: send (AT+CPIN?^M)
Aug 12 21:00:24 localhost chat[18746]: expect (OK)
Aug 12 21:00:24 localhost chat[18746]: ^M
Aug 12 21:00:24 localhost chat[18746]: AT+CPIN?^M^M
Aug 12 21:00:24 localhost chat[18746]: +CPIN: READY^M
Aug 12 21:00:24 localhost chat[18746]: ^M
Aug 12 21:00:24 localhost chat[18746]: OK
Aug 12 21:00:24 localhost chat[18746]:  -- got it
Aug 12 21:00:24 localhost chat[18746]: send (AT+CMEE=2^M)
Aug 12 21:00:24 localhost chat[18746]: expect (OK)
Aug 12 21:00:24 localhost chat[18746]: ^M
Aug 12 21:00:24 localhost chat[18746]: AT+CMEE=2^M^M
Aug 12 21:00:24 localhost chat[18746]: OK
Aug 12 21:00:24 localhost chat[18746]:  -- got it
Aug 12 21:00:24 localhost chat[18746]: send (AT+CGDCONT=3,"IP","web.vodafone.de"^M)
Aug 12 21:00:24 localhost chat[18746]: expect (OK)
Aug 12 21:00:24 localhost chat[18746]: ^M
Aug 12 21:00:24 localhost chat[18746]: AT+CGDCONT=3,"IP","web.vodafone.de"^M^M
Aug 12 21:00:24 localhost chat[18746]: OK
Aug 12 21:00:24 localhost chat[18746]:  -- got it
Aug 12 21:00:24 localhost chat[18746]: send (AT+CGATT?^M)
Aug 12 21:00:24 localhost chat[18746]: expect (OK)
Aug 12 21:00:24 localhost chat[18746]: ^M
Aug 12 21:00:24 localhost chat[18746]: AT+CGATT?^M^M
Aug 12 21:00:24 localhost chat[18746]: +CGATT: 1^M
Aug 12 21:00:24 localhost chat[18746]: ^M
Aug 12 21:00:24 localhost chat[18746]: OK
Aug 12 21:00:24 localhost chat[18746]:  -- got it
Aug 12 21:00:24 localhost chat[18746]: send (ATDT*99***3#^M)
Aug 12 21:00:24 localhost chat[18746]: timeout set to 120 seconds
Aug 12 21:00:24 localhost chat[18746]: expect (CONNECT)
Aug 12 21:00:24 localhost chat[18746]: ^M
Aug 12 21:00:24 localhost chat[18746]: ATDT*99***3#^M^M
Aug 12 21:00:24 localhost chat[18746]: CONNECT
Aug 12 21:00:24 localhost chat[18746]:  -- got it
Aug 12 21:00:24 localhost chat[18746]: send (^M)
Aug 12 21:00:24 localhost chat[18746]: timeout set to 5 seconds
Aug 12 21:00:24 localhost chat[18746]: expect (~)
Aug 12 21:00:24 localhost chat[18746]: ^M
Aug 12 21:00:25 localhost chat[18746]: ^M
Aug 12 21:00:25 localhost chat[18746]: ^BOOT:27542811,0,0,0,73^M
Aug 12 21:00:26 localhost chat[18746]: ^M
Aug 12 21:00:26 localhost chat[18746]: ^RSSI:12^M
Aug 12 21:00:29 localhost chat[18746]: alarm
Aug 12 21:00:29 localhost chat[18746]: send (^M)
Aug 12 21:00:29 localhost chat[18746]: send (^M)
Aug 12 21:00:29 localhost pppd[18714]: Serial connection established.
Aug 12 21:00:29 localhost pppd[18714]: using channel 3
Aug 12 21:00:29 localhost pppd[18714]: Using interface ppp0
Aug 12 21:00:29 localhost pppd[18714]: Connect: ppp0 <--> /dev/ttyUSB0
Aug 12 21:00:30 localhost pppd[18714]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x1175b286> <pcomp> <accomp>]
Aug 12 21:00:57 localhost last message repeated 9 times
Aug 12 21:01:00 localhost pppd[18714]: LCP: timeout sending Config-Requests
Aug 12 21:01:00 localhost pppd[18714]: Connection terminated.
Aug 12 21:01:00 localhost pppd[18714]: Receive serial link is not 8-bit clean:
Aug 12 21:01:00 localhost pppd[18714]: Problem: all had bit 7 set to 0
Aug 12 21:01:01 localhost CROND[18805]: (root) CMD (nice -n 19 run-parts --report /etc/cron.hourly)
Aug 12 21:01:01 localhost anacron[18811]: Can't chdir to /var/lib/spool/anacron: No such file or directory
Aug 12 21:01:01 localhost CROND[18812]: (CRON) EXEC FAILED (/usr/sbin/sendmail): Datei oder Verzeichnis nicht gefunden
Aug 12 21:01:01 localhost CROND[18804]: (root) MAIL (mailed 164 bytes of output but got status 0x0001 )
Aug 12 21:01:02 localhost pppd[18714]: Modem hangup
Aug 12 21:01:02 localhost pppd[18714]: Exit.
Aug 12 21:01:23 localhost net_applet[2383]: running: /usr/bin/draknetcenter
Aug 12 21:01:23 localhost draknetcenter[18863]: ### Program is starting ###
Aug 12 21:01:24 localhost draknetcenter[18863]: running: udevadm info --query=property --path=/sys/bus/usb/devices/1-6
Aug 12 21:01:25 localhost draknetcenter[18863]: running: /bin/rpm -q --qf %{name}  comgt ppp
Aug 12 21:01:25 localhost draknetcenter[18863]: No settings found for driver "option" in category "network::connection::cellular_card"
Aug 12 21:01:25 localhost draknetcenter[18863]: running: udevadm info --query=property --path=/sys/bus/usb/devices/1-6
Aug 12 21:01:25 localhost draknetcenter[18863]: running: udevadm info --query=property --path=/sys/bus/usb/devices/1-6
Aug 12 21:01:25 localhost draknetcenter[18863]: running: comgt -d /dev/ttyUSB2 PIN
Aug 12 21:01:38 localhost ifup-ppp: pppd started for ppp0 on /dev/ttyUSB0 at 115200
Aug 12 21:01:38 localhost pppd[18897]: pppd 2.4.4 started by root, uid 0
Aug 12 21:02:04 localhost klogd: option: option_instat_callback: error -108
Aug 12 21:02:04 localhost klogd: option1 ttyUSB2: GSM modem (1-port) converter now disconnected from ttyUSB2
Aug 12 21:02:04 localhost klogd: option 1-6:1.0: device disconnected
Aug 12 21:02:04 localhost klogd: option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1
Aug 12 21:02:04 localhost klogd: option 1-6:1.1: device disconnected
Aug 12 21:02:04 localhost klogd: option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0
Aug 12 21:02:04 localhost klogd: option 1-6:1.2: device disconnected
Aug 12 21:02:05 localhost chat[18997]: Can't get terminal parameters: Input/output error
Aug 12 21:02:05 localhost pppd[18897]: Connect script failed
Aug 12 21:02:05 localhost klogd: usb 1-6: reset high speed USB device using ehci_hcd and address 4
Aug 12 21:02:06 localhost pppd[18897]: Exit.
Aug 12 21:02:20 localhost klogd: usb 1-6: device descriptor read/64, error -110
Aug 12 21:02:36 localhost klogd: usb 1-6: device descriptor read/64, error -110
Aug 12 21:02:36 localhost klogd: usb 1-6: reset high speed USB device using ehci_hcd and address 4
Aug 12 21:02:48 localhost draknetcenter[18863]: running: udevadm info --query=property --path=/sys/bus/usb/devices/1-6
Aug 12 21:02:51 localhost klogd: usb 1-6: device descriptor read/64, error -110
Aug 12 21:03:06 localhost klogd: sd 10:0:0:0: Device offlined - not ready after error recovery
Aug 12 21:03:06 localhost klogd: usb 1-6: USB disconnect, address 4
Aug 12 21:03:06 localhost klogd: sr 9:0:0:0: Device offlined - not ready after error recovery
Aug 12 21:03:07 localhost klogd: usb 1-6: new high speed USB device using ehci_hcd and address 5
Aug 12 21:03:22 localhost klogd: usb 1-6: device descriptor read/64, error -110


[AndrzejL]

Hi qbrick.

Mine 3G does not re-connects either. I didn't really mind the reboots once every several hours or few days but if it happens more often I found it easier to run service network restart command as root...

1) Open konsole
2) Type in su [ENTER]
3) Type in roots password [ENTER]
4) Type in service network restart [ENTER]
5) Close konsole after reconnection

Hope it works for You too.

Andy

[luikki]

I see ttyUSB devices connecting and disconnecting on system logs. What can I do?

    This can be happening for many reasons:

        * Electrical problems on USB bus. Maybe an external hard disk, powered solely by USB bus, produces excess load, resulting to drops.
        * A damaged USB device affects all USB devices sharing same USB bus,
        * Your kernel has difficulties with your device,
        * Your device has stability issues,

here: http://www.sakis3g.org/

[qbrick]

Hi Andy, I didn't try that one yet. Fiddled with 'modprobe usbserial 'commands, because with recent kernels on other distros there was always a problem with usb, and the stick being recognized minutes after you have pluged it in, seemed to point in that direction. If restarting the internet service works for you, it may work for me too.  

PS: Restarting network didn't improve anything, unfortunately. I left-clicked on the network-applet, but it took the network-center about ten minutes to come up with the 3G device missing.
My guess is, there are two problems, and the biggest one is with usb. I'm looking forward to a newer kernel maybe, to get this stuff handled in more reliable manner. Thewhole thing is a major annoyance, because turning off the box and restart it isn't a solution.
I use this 3G stick with my netbook, too. I have an another distribution's release from 2009 with and a <2.6.28 kernel and the Gnome network-manager 0.7 installed. It works like a charm.
 
The Sakis3G site looks like interesting. Thanks for that link, luikki.

[AndrzejL]

I am using the 33.7 bfs kernel on the machine that has the Huawei E160G dongle...

What kernel are You using?

Andy