Running Squeeze / Sid
This modem has been working fine, but recently (possibly after installing new packages) it has stopped communicating after a connection has been established.
I have tried 3 methods of connecting, all previously working, now not working: wvdia, Vodafone Mobile Connect and Network Manager.
Here are my findings:
versions
Code:
network manager 0.7.1-2
wvdialVersion: 1.60.1+nmu2
libc6Version: 2.9-27
ppp Version: 2.4.4rel-10.1
wvdial
Code:
--> local IP address 41.7.77.81
--> pppd: ��$[08]@�$[08]
--> remote IP address 10.64.64.64
--> pppd: ��$[08]@�$[08]
--> primary DNS address 196.207.40.167
--> pppd: ��$[08]@�$[08]
--> secondary DNS address 196.207.40.165
--> pppd: ��$[08]@�$[08]
--> pppd: ��$[08]@�$[08]
--> Script /etc/ppp/ip-up run successful
--> Default route Ok.
--> warning, no nameserver found `/etc/resolv.conf`
--> Nameserver (DNS) failure, the connection may not work.
--> Connected... Press Ctrl-C to disconnect
--> pppd: ��$[08]@�$[08]
vodafone app
Code:
Oct 15 10:34:13 kerala wpa_supplicant[2848]: CTRL-EVENT-SCAN-RESULTS
Oct 15 10:34:31 kerala pppd[12200]: pppd 2.4.4 started by alleyoopster, uid 1000
Oct 15 10:34:31 kerala pppd[12200]: using channel 12
Oct 15 10:34:31 kerala pppd[12200]: Using interface ppp0
Oct 15 10:34:31 kerala pppd[12200]: Connect: ppp0 <--> /dev/ttyUSB0
Oct 15 10:34:31 kerala pppd[12200]: Warning - secret file /etc/ppp/pap-secrets has world and/or group access
Oct 15 10:34:31 kerala pppd[12200]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <pcomp> <accomp>]
Oct 15 10:34:31 kerala pppd[12200]: rcvd [LCP ConfReq id=0x12 <asyncmap 0x0> <auth chap MD5> <magic 0x3c5d3778> <pcomp> <accomp>]
Oct 15 10:34:31 kerala pppd[12200]: sent [LCP ConfRej id=0x12 <magic 0x3c5d3778>]
Oct 15 10:34:31 kerala pppd[12200]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <pcomp> <accomp>]
Oct 15 10:34:31 kerala pppd[12200]: rcvd [LCP ConfReq id=0x13 <asyncmap 0x0> <auth chap MD5> <pcomp> <accomp>]
Oct 15 10:34:31 kerala pppd[12200]: sent [LCP ConfAck id=0x13 <asyncmap 0x0> <auth chap MD5> <pcomp> <accomp>]
Oct 15 10:34:31 kerala pppd[12200]: sent [LCP EchoReq id=0x0 magic=0x0]
Oct 15 10:34:31 kerala pppd[12200]: rcvd [LCP DiscReq id=0x14 magic=0x3c5d3778]
Oct 15 10:34:31 kerala pppd[12200]: rcvd [CHAP Challenge id=0x1 <a82178100aa5e93eca53525da222fbc7>, name = "UMTS_CHAP_SRVR"]
Oct 15 10:34:31 kerala pppd[12200]: Warning - secret file /etc/ppp/chap-secrets has world and/or group access
Oct 15 10:34:31 kerala pppd[12200]: sent [CHAP Response id=0x1 <b9cf1a776406cde9fe645685fba998c6>, name = "*"]
Oct 15 10:34:31 kerala pppd[12200]: rcvd [LCP EchoRep id=0x0 magic=0x3c5d3778 00 00 00 00]
Oct 15 10:34:31 kerala pppd[12200]: rcvd [CHAP Success id=0x1 ""]
Oct 15 10:34:31 kerala pppd[12200]: CHAP authentication succeeded
Oct 15 10:34:31 kerala pppd[12200]: CHAP authentication succeeded
Oct 15 10:34:31 kerala pppd[12200]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
Oct 15 10:34:32 kerala pppd[12200]: rcvd [IPCP ConfNak id=0x1 <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14> <ms-wins 10.11.12.13> <ms-wins 10.11.12.14>]
Oct 15 10:34:32 kerala pppd[12200]: sent [IPCP ConfReq id=0x2 <addr 0.0.0.0> <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>]
Oct 15 10:34:33 kerala pppd[12200]: rcvd [IPCP ConfNak id=0x2 <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14> <ms-wins 10.11.12.13> <ms-wins 10.11.12.14>]
Oct 15 10:34:33 kerala pppd[12200]: sent [IPCP ConfReq id=0x3 <addr 0.0.0.0> <ms-dns1 10.11.12.13> <ms-dns3 10.11.12.14>]
Oct 15 10:34:34 kerala pppd[12200]: rcvd [IPCP ConfReq id=0xc]
Oct 15 10:34:34 kerala pppd[12200]: sent [IPCP ConfNak id=0xc <addr 0.0.0.0>]
Oct 15 10:34:34 kerala pppd[12200]: rcvd [IPCP ConfNak id=0x3 <addr 41.5.63.121> <ms-dns1 196.207.40.167> <ms-dns3 196.207.40.165>]
Oct 15 10:34:34 kerala pppd[12200]: sent [IPCP ConfReq id=0x4 <addr 41.5.63.121> <ms-dns1 196.207.40.167> <ms-dns3 196.207.40.165>]
Oct 15 10:34:34 kerala pppd[12200]: rcvd [IPCP ConfAck id=0x4 <addr 41.5.63.121> <ms-dns1 196.207.40.167> <ms-dns3 196.207.40.165>]
Oct 15 10:34:35 kerala pppd[12200]: rcvd [IPCP ConfReq id=0xd]
Oct 15 10:34:35 kerala pppd[12200]: sent [IPCP ConfAck id=0xd]
Oct 15 10:34:35 kerala pppd[12200]: Could not determine remote IP address: defaulting to 10.64.64.64
Oct 15 10:34:35 kerala pppd[12200]: Cannot determine ethernet address for proxy ARP
Oct 15 10:34:35 kerala pppd[12200]: local IP address 41.5.63.121
Oct 15 10:34:35 kerala pppd[12200]: remote IP address 10.64.64.64
Oct 15 10:34:35 kerala pppd[12200]: primary DNS address 196.207.40.167
Oct 15 10:34:35 kerala pppd[12200]: secondary DNS address 196.207.40.165
Oct 15 10:34:35 kerala pppd[12200]: Script /etc/ppp/ip-up started (pid 12204)
Oct 15 10:34:35 kerala pppd[12200]: Script /etc/ppp/ip-up finished (pid 12204), status = 0x0
network-manager (boot)
Code:
Oct 15 10:37:52 kerala NetworkManager: <info> (ttyUSB0): device state change: 1 -> 2
Oct 15 10:37:52 kerala NetworkManager: <info> (ttyUSB0): deactivating device (reason: 2).
Oct 15 10:37:52 kerala NetworkManager: nm_system_device_flush_ip4_routes_with_iface: assertion `iface_idx >= 0' failed
Oct 15 10:37:52 kerala NetworkManager: nm_system_device_flush_ip4_addresses_with_iface: assertion `iface_idx >= 0' failed
Oct 15 10:37:52 kerala NetworkManager: <info> (eth1): device state change: 2 -> 3
Oct 15 10:37:52 kerala NetworkManager: <info> (ttyUSB0): device state change: 2 -> 3
Oct 15 10:37:52 kerala NetworkManager: <info> (eth1): supplicant interface state: starting -> ready
Oct 15 10:37:52 kerala wpa_supplicant[2890]: CTRL-EVENT-SCAN-RESULTS
Oct 15 10:37:53 kerala wpa_supplicant[2890]: CTRL-EVENT-SCAN-RESULTS
Oct 15 10:37:53 kerala acpid: client connected from 3024[0:0]
Oct 15 10:37:53 kerala acpid: 1 client rule loaded
Oct 15 10:37:59 kerala kdm_greet[3065]: Cannot load /usr/share/kde4/apps/kdm/faces/.default.face: No such file or directory
Oct 15 10:38:02 kerala kernel: [ 30.968027] eth1: no IPv6 routers present
Oct 15 10:38:03 kerala console-kit-daemon[2669]: WARNING: Couldn't read /proc/2668/environ: Failed to open file '/proc/2668/environ': No such file or directory
Oct 15 10:38:10 kerala anacron[3305]: Anacron 2.3 started on 2009-10-15
Oct 15 10:38:10 kerala anacron[3305]: Normal exit (0 jobs run)
Oct 15 10:38:12 kerala wpa_supplicant[2890]: CTRL-EVENT-SCAN-RESULTS
Oct 15 10:38:42 kerala wpa_supplicant[2890]: CTRL-EVENT-SCAN-RESULTS
Oct 15 10:38:47 kerala NetworkManager: <info> Activation (ttyUSB0) starting connection 'vodacom'
Oct 15 10:38:47 kerala NetworkManager: <info> (ttyUSB0): device state change: 3 -> 4
Oct 15 10:38:47 kerala NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) scheduled...
Oct 15 10:38:47 kerala NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) started...
Oct 15 10:38:47 kerala NetworkManager: <debug> [1255595927.932212] nm_serial_device_open(): (ttyUSB0) opening device...
Oct 15 10:38:47 kerala NetworkManager: <info> Activation (ttyUSB0) Stage 1 of 5 (Device Prepare) complete.
Oct 15 10:38:48 kerala NetworkManager: <info> (ttyUSB0): powering up...
Oct 15 10:38:48 kerala NetworkManager: <info> Registered on Home network
Oct 15 10:38:48 kerala NetworkManager: <info> Associated with network: +COPS: 1,0,"Vodacom - SA",2
Oct 15 10:38:48 kerala NetworkManager: <info> Connected, Woo!
Oct 15 10:38:48 kerala NetworkManager: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) scheduled...
Oct 15 10:38:48 kerala NetworkManager: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) starting...
Oct 15 10:38:48 kerala NetworkManager: <info> (ttyUSB0): device state change: 4 -> 5
Oct 15 10:38:48 kerala NetworkManager: <info> Starting pppd connection
Oct 15 10:38:48 kerala NetworkManager: <debug> [1255595928.405215] nm_ppp_manager_start(): Command line: /usr/sbin/pppd nodetach lock nodefaultroute user internet ttyUSB0 noipdefault noauth usepeerdns lcp-echo-failure 0 lcp-echo-interval 0 ipparam /org/freedesktop/NetworkManager/PPP/0 plugin /usr/lib/pppd/2.4.4/nm-pppd-plugin.so
Oct 15 10:38:48 kerala NetworkManager: <debug> [1255595928.419971] nm_ppp_manager_start(): ppp started with pid 3425
Oct 15 10:38:48 kerala NetworkManager: <info> Activation (ttyUSB0) Stage 2 of 5 (Device Configure) complete.
Oct 15 10:38:48 kerala pppd[3425]: Plugin /usr/lib/pppd/2.4.4/nm-pppd-plugin.so loaded.
Oct 15 10:38:48 kerala kernel: [ 77.323868] PPP generic driver version 2.4.2
Oct 15 10:38:48 kerala pppd[3425]: pppd 2.4.4 started by root, uid 0
Oct 15 10:38:48 kerala pppd[3425]: Using interface ppp0
Oct 15 10:38:48 kerala pppd[3425]: Connect: ppp0 <--> /dev/ttyUSB0
Oct 15 10:38:48 kerala NetworkManager: <info> (ttyUSB0): device state change: 5 -> 6
Oct 15 10:38:48 kerala pppd[3425]: CHAP authentication succeeded
Oct 15 10:38:48 kerala pppd[3425]: CHAP authentication succeeded
Oct 15 10:38:48 kerala NetworkManager: <info> (ttyUSB0): device state change: 6 -> 7
Oct 15 10:38:48 kerala kernel: [ 77.682451] PPP BSD Compression module registered
Oct 15 10:38:48 kerala kernel: [ 77.726231] PPP Deflate Compression module registered
Oct 15 10:38:52 kerala pppd[3425]: Could not determine remote IP address: defaulting to 10.64.64.64
Oct 15 10:38:52 kerala pppd[3425]: Cannot determine ethernet address for proxy ARP
Oct 15 10:38:52 kerala pppd[3425]: local IP address 41.7.168.71
Oct 15 10:38:52 kerala pppd[3425]: remote IP address 10.64.64.64
Oct 15 10:38:52 kerala pppd[3425]: primary DNS address 196.207.40.167
Oct 15 10:38:52 kerala pppd[3425]: secondary DNS address 196.207.40.165
Oct 15 10:38:52 kerala NetworkManager: <info> PPP manager(IP Config Get) reply received.
Oct 15 10:38:52 kerala NetworkManager: <info> Activation (ttyUSB0) Stage 4 of 5 (IP Configure Get) scheduled...
Oct 15 10:38:52 kerala NetworkManager: <info> Activation (ttyUSB0) Stage 4 of 5 (IP Configure Get) started...
Oct 15 10:38:52 kerala NetworkManager: <info> Activation (ttyUSB0) Stage 5 of 5 (IP Configure Commit) scheduled...
Oct 15 10:38:52 kerala NetworkManager: <info> Activation (ttyUSB0) Stage 4 of 5 (IP Configure Get) complete.
Oct 15 10:38:52 kerala NetworkManager: <info> Activation (ttyUSB0) Stage 5 of 5 (IP Configure Commit) started...
Oct 15 10:38:53 kerala NetworkManager: <info> (ppp0): writing resolv.conf to /sbin/resolvconf
Oct 15 10:38:53 kerala NetworkManager: <info> (ttyUSB0): device state change: 7 -> 8
Oct 15 10:38:53 kerala NetworkManager: <info> (ppp0): writing resolv.conf to /sbin/resolvconf
Oct 15 10:38:53 kerala NetworkManager: <info> Policy set 'vodacom' (ppp0) as default for routing and DNS.
Oct 15 10:38:53 kerala NetworkManager: <info> Activation (ttyUSB0) successful, device activated.
Oct 15 10:38:53 kerala NetworkManager: <info> Activation (ttyUSB0) Stage 5 of 5 (IP Configure Commit) complete.
network-manager stop / start
Code:
Oct 15 10:59:39 kerala pppd[3783]: Could not determine remote IP address: defaulting to 10.64.64.64
Oct 15 10:59:39 kerala pppd[3783]: Cannot determine ethernet address for proxy ARP
Oct 15 10:59:39 kerala pppd[3783]: local IP address 41.5.28.34
Oct 15 10:59:39 kerala pppd[3783]: remote IP address 10.64.64.64
Oct 15 10:59:39 kerala pppd[3783]: primary DNS address 10.11.12.13
Oct 15 10:59:39 kerala pppd[3783]: secondary DNS address 10.11.12.14
Oct 15 10:59:48 kerala NetworkManager: <WARN> pppd_timed_out(): Looks like pppd didn't initialize our dbus module
Oct 15 10:59:48 kerala NetworkManager: <info> (ttyUSB0): device state change: 5 -> 9
Oct 15 10:59:48 kerala NetworkManager: <debug> [1255597188.000620] nm_serial_device_close(): Closing device 'ttyUSB0'
Oct 15 10:59:48 kerala pppd[3783]: Terminating on signal 15
Oct 15 10:59:48 kerala pppd[3783]: Connect time 0.2 minutes.
Oct 15 10:59:48 kerala pppd[3783]: Sent 0 bytes, received 0 bytes.
Oct 15 10:59:48 kerala NetworkManager: <info> Marking connection 'vodacom' invalid.
Oct 15 10:59:48 kerala NetworkManager: <info> Activation (ttyUSB0) failed.
Oct 15 10:59:48 kerala NetworkManager: <info> (ttyUSB0): device state change: 9 -> 3
Oct 15 10:59:48 kerala NetworkManager: <info> (ttyUSB0): deactivating device (reason: 0).
Oct 15 10:59:48 kerala NetworkManager: nm_system_device_flush_ip4_routes_with_iface: assertion `iface_idx >= 0' failed
Oct 15 10:59:48 kerala NetworkManager: nm_system_device_flush_ip4_addresses_with_iface: assertion `iface_idx >= 0' failed
Oct 15 10:59:48 kerala pppd[3783]: Connection terminated.
Oct 15 10:59:49 kerala pppd[3783]: Exit.
ifconfig ppp0
Code:
ppp0 Link encap:Point-to-Point Protocol
inet addr:41.7.168.71 P-t-P:10.64.64.64 Mask:255.255.255.255
UP POINTOPOINT RUNNING NOARP MULTICAST MTU:1500 Metric:1
RX packets:10 errors:0 dropped:0 overruns:0 frame:0
TX packets:149 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:3
RX bytes:286 (286.0 B) TX bytes:9841 (9.6 KiB)
netstat -i ppp0
Code:
Kernel Interface table
Iface MTU Met RX-OK RX-ERR RX-DRP RX-OVR TX-OK TX-ERR TX-DRP TX-OVR Flg
eth0 1500 0 0 0 0 0 0 0 0 0 BMU
eth1 1500 0 0 0 0 0 1 0 5 0 BMU
lo 16436 0 752 0 0 0 752 0 0 0 LRU
ppp0 1500 0 10 0 0 0 151 0 0 0 MOPRU
route
Code:
Kernel IP routing table
Destination Gateway Genmask Flags Metric Ref Use Iface
10.64.64.64 * 255.255.255.255 UH 0 0 0 ppp0
link-local * 255.255.0.0 U 1000 0 0 ppp0
default 10.64.64.64 0.0.0.0 UG 0 0 0 ppp0
ping 127.0.0.1
Code:
PING 127.0.0.1 (127.0.0.1) 56(84) bytes of data.
64 bytes from 127.0.0.1: icmp_seq=1 ttl=64 time=0.039 ms
64 bytes from 127.0.0.1: icmp_seq=2 ttl=64 time=0.037 ms
64 bytes from 127.0.0.1: icmp_seq=3 ttl=64 time=0.036 ms
64 bytes from 127.0.0.1: icmp_seq=4 ttl=64 time=0.039 ms
ping 41.7.168.71
Code:
PING 41.7.168.71 (41.7.168.71) 56(84) bytes of data.
64 bytes from 41.7.168.71: icmp_seq=1 ttl=64 time=0.042 ms
64 bytes from 41.7.168.71: icmp_seq=2 ttl=64 time=0.037 ms
64 bytes from 41.7.168.71: icmp_seq=3 ttl=64 time=0.075 ms
64 bytes from 41.7.168.71: icmp_seq=4 ttl=64 time=0.039 ms
ping 10.64.64.64
Code:
PING 10.64.64.64 (10.64.64.64) 56(84) bytes of data.
ping
http://www.google.com
Code:
ping: unknown host www.google.com
ping 196.207.40.165
Code:
PING 196.207.40.165 (196.207.40.165) 56(84) bytes of data.
traceroute
Code:
traceroute 196.207.40.165
traceroute to 196.207.40.165 (196.207.40.165), 30 hops max, 60 byte packets
1 (10.113.180.6) 265.373 ms 405.312 ms 514.661 ms
2 * * *
3 * * *
4 * * *
Immediately after posting this I made 2 discoveries that warrant an edit. Firstly the DNS server is not valid as I tried this from a working connection. That led me to suspect the ping and try another server, I tried the IP for google.com and this is good, so it looks like a DNS issue.
So the first question is why is DNS not working and what caused the failure?
