[clug] GPRS failure..... ideas?

[Ambrose Andrews]

So, inspired by the example of Neil Cox en route to LCA at UNSW
connecting from a cafe to get the bus timetable, and further inspired
by the recent 3G modem advice thread starring Gary Woodman, I'm trying
to get PPP happening via a bluetooth & gprs enabled mobile.

Gotta wee laptop running Debian lenny, gotta good working USB blutooth
thingo and all, and that pat of things works fine...  Gotta vodafone
data bundle...

I followed roughly some detailed hints from some other clug (Capetown)

http://wiki.clug.org.za/wiki/GPRS_Internet_over_Bluetooth

It all looks like it is working well, until ear the end when my end
starts apparently obsessively requesting DNS sever addresses in the
face of silence from the other end, and then hangs up all cranky-like.

Here's a typical log:

/var/log/syslog:

 Mar 18 23:21:11 zeta pppd[13493]: pppd 2.4.4 started by ambrose, uid
 1000
 Mar 18 23:21:11 zeta NetworkManager: <debug> [1205842871.108946]
 nm_hal_device_added(): New device added (hal udi is
 '/org/freedesktop/Hal/devices/bluetooth_acl_17e50fd091').
 Mar 18 23:21:11 zeta hcid[3495]: link_key_request
 (sba=00:11:67:5F:54:4B, dba=00:17:E5:0F:D0:91)
 Mar 18 23:21:12 zeta chat[13496]: timeout set to 30 seconds
 Mar 18 23:21:12 zeta chat[13496]: abort on (\nBUSY\r)
 Mar 18 23:21:12 zeta chat[13496]: abort on (\nERROR\r)
 Mar 18 23:21:12 zeta chat[13496]: abort on (\nNO ANSWER\r)
 Mar 18 23:21:12 zeta chat[13496]: abort on (\nNO CARRIER\r)
 Mar 18 23:21:12 zeta chat[13496]: abort on (\nNO DIALTONE\r)
 Mar 18 23:21:12 zeta chat[13496]: abort on (\nRINGING\r\n\r\nRINGING\r)
 Mar 18 23:21:12 zeta chat[13496]: send (^MAT^M)
 Mar 18 23:21:12 zeta chat[13496]: expect (OK)
 Mar 18 23:21:12 zeta chat[13496]: AT^M^M
 Mar 18 23:21:12 zeta chat[13496]: OK
 Mar 18 23:21:12 zeta chat[13496]:  -- got it
 Mar 18 23:21:12 zeta chat[13496]: send (AT
 +CGDCONT=1,"IP","vfinternet.au"^M)
 Mar 18 23:21:13 zeta chat[13496]: expect (OK)
 Mar 18 23:21:13 zeta chat[13496]: ^M
 Mar 18 23:21:13 zeta chat[13496]: AT+CGDCONT=1,"IP","vfinternet.au"^M^M
 Mar 18 23:21:13 zeta chat[13496]: OK
 Mar 18 23:21:13 zeta chat[13496]:  -- got it
 Mar 18 23:21:13 zeta chat[13496]: send (ATD*99***1#^M)
 Mar 18 23:21:13 zeta chat[13496]: expect (CONNECT)
 Mar 18 23:21:13 zeta chat[13496]: ^M
 Mar 18 23:21:14 zeta chat[13496]: ATD*99***1#^M^M
 Mar 18 23:21:14 zeta chat[13496]: CONNECT
 Mar 18 23:21:14 zeta chat[13496]:  -- got it
 Mar 18 23:21:14 zeta chat[13496]: send (^M)
 Mar 18 23:21:14 zeta pppd[13493]: Serial connection established.
 Mar 18 23:21:14 zeta pppd[13493]: using channel 1
 Mar 18 23:21:14 zeta pppd[13493]: Using interface ppp0
 Mar 18 23:21:14 zeta pppd[13493]: Connect: ppp0 <--> /dev/rfcomm0
 Mar 18 23:21:15 zeta pppd[13493]: rcvd [LCP ConfReq id=0x1 <mru 1500>
 <asyncmap 0xa0000> <auth pap>]
 Mar 18 23:21:15 zeta pppd[13493]: sent [LCP ConfReq id=0x1 <asyncmap
 0x0> <magic 0xa9bb030> <pcomp> <accomp>]
 Mar 18 23:21:15 zeta pppd[13493]: No auth is possible
 Mar 18 23:21:15 zeta pppd[13493]: sent [LCP ConfRej id=0x1 <auth pap>]
 Mar 18 23:21:15 zeta pppd[13493]: rcvd [LCP ConfRej id=0x1 <magic
 0xa9bb030> <pcomp> <accomp>]
 Mar 18 23:21:15 zeta pppd[13493]: sent [LCP ConfReq id=0x2 <asyncmap
 0x0>]
 Mar 18 23:21:15 zeta pppd[13493]: rcvd [LCP ConfReq id=0x2 <mru 1500>
 <asyncmap 0xa0000>]
 Mar 18 23:21:15 zeta pppd[13493]: sent [LCP ConfAck id=0x2 <mru 1500>
 <asyncmap 0xa0000>]
 Mar 18 23:21:15 zeta pppd[13493]: rcvd [LCP ConfNak id=0x2 <asyncmap
 0xa0000>]
 Mar 18 23:21:15 zeta pppd[13493]: sent [LCP ConfReq id=0x3 <asyncmap
 0xa0000>]
 Mar 18 23:21:15 zeta pppd[13493]: rcvd [LCP ConfAck id=0x3 <asyncmap
 0xa0000>]
 Mar 18 23:21:15 zeta pppd[13493]: sent [IPCP ConfReq id=0x1 <addr
 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
 Mar 18 23:21:15 zeta pppd[13493]: rcvd [IPCP ConfReq id=0x3 <addr
 192.168.254.254>]
 Mar 18 23:21:15 zeta pppd[13493]: sent [IPCP ConfAck id=0x3 <addr
 192.168.254.254>]
 Mar 18 23:21:18 zeta pppd[13493]: sent [IPCP ConfReq id=0x1 <addr
 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
 Mar 18 23:21:42 zeta last message repeated 8 times
 Mar 18 23:21:45 zeta pppd[13493]: IPCP: timeout sending Config-Requests
 Mar 18 23:21:45 zeta pppd[13493]: sent [LCP TermReq id=0x4 "No network
 protocols running"]
 Mar 18 23:21:48 zeta pppd[13493]: sent [LCP TermReq id=0x5 "No network
 protocols running"]
 Mar 18 23:21:51 zeta pppd[13493]: Connection terminated.
 Mar 18 23:21:52 zeta pppd[13493]: Modem hangup
 Mar 18 23:21:52 zeta pppd[13493]: Exit.
 Mar 18 23:21:54 zeta NetworkManager: <debug> [1205842914.242701]
 nm_hal_device_removed(): Device removed (hal udi is
 '/org/freedesktop/Hal/devices/bluetooth_acl_17e50fd091').


....and thats it.  It bails out.

I guess one thing to try would be to NOT use the usepeerdns option,
and set up resolv.conf by hand...

Anyone have immediate insights?


Here's my relevant files for PPP:

 /etc/chatscripts/vodafone :

 TIMEOUT 30
 ECHO    ON
 ABORT   '\nBUSY\r'
 ABORT   '\nERROR\r'
 ABORT   '\nNO ANSWER\r'
 ABORT   '\nNO CARRIER\r'
 ABORT   '\nNO DIALTONE\r'
 ABORT   '\nRINGING\r\n\r\nRINGING\r'
 ''      \rAT
 OK      'AT+CGDCONT=1,"IP","vfinternet.au"'
 OK      ATD*99***1#
 CONNECT ""
 ambrose at zeta:~$



 /etc/ppp/peers/vodafone :

 #hide-password
 noauth

 connect "/usr/sbin/chat -v -f /etc/chatscripts/vodafone"
 debug
 #modem
 /dev/rfcomm0
 115200
 defaultroute
 noipdefault
 #persist
 user "guest"
 #password "guest"
 remotename bluetooth
 ipparam bluetooth

 usepeerdns
 lcp-echo-interval 0
 novj
 nodeflate
 nobsdcomp
 passive

 holdoff 5
 ambrose at zeta:~$


 The phone :

 Sagem ('vodafone') my411v
 [via bluetooth]

  -AA.

 --
 Ambrose Andrews
 ambrose at vrvl.net
 0415544621
 xmpp:ambrose at jabber.fsfe.org
 CE388B79C0A7DF4A4F54E352264719A1_DB3BF823
 556A6D190904827C9DB8369732D01E11_403F2BE1