Strange PPP Disconnect errors?

Derek Atkins warlord at MIT.EDU
Fri Dec 1 14:18:25 EST 2006


Quoting Chip Marshall <chip at 2bithacker.net>:

>> Any suggestions of help would be greatly appreciated!
>
> I had this problem with a Sprint EVDO card. If I remember correctly,
> Linux's PPP implementation will attempt to do LCP echo tests by default,
> which the card doesn't support. After a certain number of these echos
> time out, the session will disconnect.

But wouldn't that disconnect come from the local PPP instead of the
remote PPP?

> Since your session lasted exactly 40 seconds, I'd suspect that it's
> trying 4 echos at 10 second intervals.
>
> Try adding 'lcp-echo-interval 0' to your ppp configuration, this will
> disable the test.

Done.  But didn't really help.  This time it went 72 seconds....

pppd lock modem crtscts asyncmap 00000000 defaultroute usepeerdns user web
remotename SprintPCS nodetach /dev/rfcomm0 460800 ipparam SprintPCS linkname
SprintPCS call SprintPCS noauth debug noauth novj nopcomp lcp-echo-interval 0

Dec  1 14:11:45 cliodev wvdial[28922]: WvDial: Internet dialer version 1.54.0
Dec  1 14:11:45 cliodev wvdial[28922]: Initializing modem.
Dec  1 14:11:45 cliodev wvdial[28922]: Sending: ATZ
Dec  1 14:11:45 cliodev wvdial[28922]: ATZ
Dec  1 14:11:45 cliodev wvdial[28922]: OK
Dec  1 14:11:45 cliodev wvdial[28922]: Sending: ATQ0 V1 E1 S0=0 &C1 &D2
+FCLASS=0
Dec  1 14:11:45 cliodev wvdial[28922]: ATQ0 V1 E1 S0=0 &C1 &D2 +FCLASS=0
Dec  1 14:11:45 cliodev wvdial[28922]: OK
Dec  1 14:11:45 cliodev wvdial[28922]: Sending: ATM0
Dec  1 14:11:45 cliodev wvdial[28922]: ATM0
Dec  1 14:11:45 cliodev wvdial[28922]: OK
Dec  1 14:11:45 cliodev wvdial[28922]: Modem initialized.
Dec  1 14:11:45 cliodev wvdial[28922]: Sending: ATDT#777
Dec  1 14:11:45 cliodev wvdial[28922]: Waiting for carrier.
Dec  1 14:11:45 cliodev wvdial[28922]: ATDT#777
Dec  1 14:11:48 cliodev wvdial[28922]: CONNECT
Dec  1 14:11:48 cliodev wvdial[28922]: Carrier detected.  Chatmode finished.
Dec  1 14:11:48 cliodev pppd[28921]: Serial connection established.
Dec  1 14:11:48 cliodev pppd[28921]: using channel 32
Dec  1 14:11:48 cliodev pppd[28921]: Using interface ppp0
Dec  1 14:11:48 cliodev pppd[28921]: Connect: ppp0 <--> /dev/rfcomm0
Dec  1 14:11:49 cliodev pppd[28921]: sent [LCP ConfReq id=0x1 <asyncmap 0x0>
<magic 0x5cd34b70> <accomp>]
Dec  1 14:11:49 cliodev pppd[28921]: rcvd [LCP ConfReq id=0x37 <asyncmap 0x0>
<magic 0xb01cf496> <pcomp> <accomp>]
Dec  1 14:11:49 cliodev pppd[28921]: sent [LCP ConfRej id=0x37 <pcomp>]
Dec  1 14:11:49 cliodev pppd[28921]: rcvd [LCP ConfNak id=0x1 <pcomp>]
Dec  1 14:11:49 cliodev pppd[28921]: sent [LCP ConfReq id=0x2 <asyncmap 0x0>
<magic 0x5cd34b70> <accomp>]
Dec  1 14:11:49 cliodev pppd[28921]: rcvd [LCP ConfReq id=0x38 <asyncmap 0x0>
<magic 0xb01cf496> <accomp>]
Dec  1 14:11:49 cliodev pppd[28921]: sent [LCP ConfAck id=0x38 <asyncmap 0x0>
<magic 0xb01cf496> <accomp>]
Dec  1 14:11:49 cliodev pppd[28921]: rcvd [LCP ConfNak id=0x2 <pcomp>]
Dec  1 14:11:49 cliodev pppd[28921]: sent [LCP ConfReq id=0x3 <asyncmap 0x0>
<magic 0x5cd34b70> <accomp>]
Dec  1 14:11:49 cliodev pppd[28921]: rcvd [LCP ConfNak id=0x3 <pcomp>]
Dec  1 14:11:49 cliodev pppd[28921]: sent [LCP ConfReq id=0x4 <asyncmap 0x0>
<magic 0x5cd34b70> <accomp>]
Dec  1 14:11:49 cliodev pppd[28921]: rcvd [LCP ConfAck id=0x4 <asyncmap 0x0>
<magic 0x5cd34b70> <accomp>]
Dec  1 14:11:49 cliodev pppd[28921]: sent [CCP ConfReq id=0x1 <deflate 15>
<deflate(old#) 15>]
Dec  1 14:11:49 cliodev pppd[28921]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0>
<ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
Dec  1 14:11:49 cliodev pppd[28921]: rcvd [LCP DiscReq id=0x39
magic=0xb01cf496]Dec  1 14:11:49 cliodev pppd[28921]: rcvd [IPCP 
ConfReq id=0xc
<addr 68.28.153.69>]
Dec  1 14:11:49 cliodev pppd[28921]: sent [IPCP ConfAck id=0xc <addr
68.28.153.69>]
Dec  1 14:11:49 cliodev pppd[28921]: rcvd [LCP ProtRej id=0x3a 80 fd 01 
01 00 0c
1a 04 78 00 18 04 78 00]
Dec  1 14:11:49 cliodev pppd[28921]: rcvd [IPCP ConfNak id=0x1 <addr
68.246.143.54> <ms-dns1 68.28.154.11> <ms-dns3 68.28.146.11>]
Dec  1 14:11:49 cliodev pppd[28921]: sent [IPCP ConfReq id=0x2 <addr
68.246.143.54> <ms-dns1 68.28.154.11> <ms-dns3 68.28.146.11>]
Dec  1 14:11:49 cliodev pppd[28921]: rcvd [IPCP ConfAck id=0x2 <addr
68.246.143.54> <ms-dns1 68.28.154.11> <ms-dns3 68.28.146.11>]
Dec  1 14:11:49 cliodev pppd[28921]: local  IP address 68.246.143.54
Dec  1 14:11:49 cliodev pppd[28921]: remote IP address 68.28.153.69
Dec  1 14:11:49 cliodev pppd[28921]: primary   DNS address 68.28.154.11
Dec  1 14:11:49 cliodev pppd[28921]: secondary DNS address 68.28.146.11
Dec  1 14:11:49 cliodev pppd[28921]: Script /etc/ppp/ip-up started (pid 28936)
Dec  1 14:11:49 cliodev pppd[28921]: Script /etc/ppp/ip-up finished 
(pid 28936),
status = 0x0
Dec  1 14:13:01 cliodev pppd[28921]: rcvd [LCP TermReq id=0x3b]
Dec  1 14:13:01 cliodev pppd[28921]: LCP terminated by peer
Dec  1 14:13:01 cliodev pppd[28921]: Connect time 1.2 minutes.
Dec  1 14:13:01 cliodev pppd[28921]: Sent 51579 bytes, received 245639 bytes.
Dec  1 14:13:01 cliodev pppd[28921]: Script /etc/ppp/ip-down started (pid
29067)Dec  1 14:13:01 cliodev pppd[28921]: sent [LCP TermAck id=0x3b]
Dec  1 14:13:01 cliodev pppd[28921]: Script /etc/ppp/ip-down finished (pid
29067), status = 0x0
Dec  1 14:13:01 cliodev pppd[28921]: Modem hangup
Dec  1 14:13:01 cliodev pppd[28921]: Connection terminated.
Dec  1 14:13:02 cliodev pppd[28921]: Exit.

-derek

-- 
       Derek Atkins, SB '93 MIT EE, SM '95 MIT Media Laboratory
       Member, MIT Student Information Processing Board  (SIPB)
       URL: http://web.mit.edu/warlord/    PP-ASEL-IA     N1NWH
       warlord at MIT.EDU                        PGP key available



More information about the gnhlug-discuss mailing list