CDMA: PPP connection restart on unexpeted peer LCP reconfiguration request

[Tomas Sieger]

Hi,
   I'm experiencing problems with my Eurotel CDMA modem.

   I can successfully establish a PPP connection, but it gets
shut down and restarted after a while. It seems that the
reason is the peer (Eurotel) has sent an LCP request for
reconfiguration, which causes ip-down to be started etc.
   The time delay between connection establishment and
shutdown varies from 2 seconds to several hours. It seems
this delay depends on the time of day - on working days it
is quite often - in order of minutes or tens of minutes,
while in evenings and on weekends it takes much more time
to experience it. Perhaps the network load is the key?

I'm using wvdial 1.54.0 / pppd 2.4.2 on FC 3, kernel 2.6.9,
Intel platform.

Note: when I just set up the PPP connection, but do not actively use it
(using default route via eth0), the problem does not appear.

Please, take a look at the snippet of /var/log/messages attached.

thanks in advance for any help
best regards,
   Tomas S.

PS: I'm writing in English to ease other people to google
help using English terms ;-).

------------- další část ---------------
May 27 09:08:17 pango pppd[10432]: pppd options in effect:
May 27 09:08:17 pango pppd[10432]: debug		# (from /etc/ppp/options)
May 27 09:08:17 pango pppd[10432]: -detach		# (from command line)
May 27 09:08:17 pango pppd[10432]: idle 0		# (from command line)
May 27 09:08:17 pango pppd[10432]: logfd 6		# (from command line)
May 27 09:08:17 pango pppd[10432]: dump		# (from /etc/ppp/options)
May 27 09:08:17 pango pppd[10432]: noauth		# (from /etc/ppp/peers/wvdial)
May 27 09:08:17 pango pppd[10432]: name xxxxx		# (from /etc/ppp/peers/wvdial)
May 27 09:08:17 pango pppd[10432]: user xxxxxxxxx na etcz		# (from command line)
May 27 09:08:17 pango pppd[10432]: usehostname		# (from command line)
May 27 09:08:17 pango pppd[10432]: remotename *		# (from command line)
May 27 09:08:17 pango pppd[10432]: 460800		# (from command line)
May 27 09:08:17 pango pppd[10432]: lock		# (from /etc/ppp/options)
May 27 09:08:17 pango pppd[10432]: crtscts		# (from command line)
May 27 09:08:17 pango pppd[10432]: modem		# (from command line)
May 27 09:08:17 pango pppd[10432]: lcp-restart 1000		# (from /etc/ppp/options)
May 27 09:08:17 pango pppd[10432]: noipdefault		# (from command line)
May 27 09:08:17 pango pppd[10432]: defaultroute		# (from command line)
May 27 09:08:17 pango pppd[10432]: usepeerdns		# (from command line)
May 27 09:08:17 pango pppd[10432]: pppd 2.4.2 started by root, uid 0
May 27 09:08:17 pango pppd[10432]: using channel 4
May 27 09:08:17 pango pppd[10432]: Using interface ppp0
May 27 09:08:17 pango pppd[10432]: Connect: ppp0 <--> /dev/ttyACM0
May 27 09:08:17 pango pppd[10432]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <magic 0x23915177> <pcomp> <accomp>]
May 27 09:08:18 pango pppd[10432]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <magic 0x23915177> <pcomp> <accomp>]
May 27 09:08:21 pango pppd[10432]: rcvd [LCP ConfReq id=0x32 <magic 0x28ffdb3c> <mru 1500> <asyncmap 0x0> <auth chap MD5> <pcomp> <accomp>]
May 27 09:08:21 pango pppd[10432]: sent [LCP ConfAck id=0x32 <magic 0x28ffdb3c> <mru 1500> <asyncmap 0x0> <auth chap MD5> <pcomp> <accomp>]
May 27 09:08:21 pango pppd[10432]: rcvd [CHAP Challenge id=0x1 <dc7b076c9a336102c4780567455b6160>, name = "eurotel_pdsn"]
May 27 09:08:21 pango pppd[10432]: sent [CHAP Response id=0x1 <5ad5dd7f3701f06f807c554cf00e5d25>, name = "xxxxxxxxx na etcz"]
May 27 09:08:21 pango pppd[10432]: rcvd [CHAP Success id=0x1 "ok"]
May 27 09:08:21 pango pppd[10432]: CHAP authentication succeeded: ok
May 27 09:08:21 pango pppd[10432]: sent [CCP ConfReq id=0x1 <deflate 15> <deflate(old#) 15>]
May 27 09:08:21 pango pppd[10432]: sent [IPCP ConfReq id=0x1 <compress VJ 0f 01> <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
May 27 09:08:21 pango pppd[10432]: rcvd [IPCP ConfReq id=0xbd <addr 10.160.4.6>]
May 27 09:08:21 pango pppd[10432]: sent [IPCP ConfAck id=0xbd <addr 10.160.4.6>]
May 27 09:08:21 pango pppd[10432]: rcvd [LCP ProtRej id=0x33 80 fd 01 01 00 0c 1a 04 78 00 18 04 78 00]
May 27 09:08:21 pango pppd[10432]: rcvd [IPCP ConfRej id=0x1 <compress VJ 0f 01>]
May 27 09:08:21 pango pppd[10432]: sent [IPCP ConfReq id=0x2 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
May 27 09:08:22 pango pppd[10432]: rcvd [IPCP ConfNak id=0x2 <addr 10.162.196.105> <ms-dns1 160.218.10.200> <ms-dns3 160.218.43.200>]
May 27 09:08:22 pango pppd[10432]: sent [IPCP ConfReq id=0x3 <addr 10.162.196.105> <ms-dns1 160.218.10.200> <ms-dns3 160.218.43.200>]
May 27 09:08:22 pango pppd[10432]: rcvd [IPCP ConfAck id=0x3 <addr 10.162.196.105> <ms-dns1 160.218.10.200> <ms-dns3 160.218.43.200>]
May 27 09:08:22 pango pppd[10432]: local  IP address 10.162.196.105
May 27 09:08:22 pango pppd[10432]: remote IP address 10.160.4.6
May 27 09:08:22 pango pppd[10432]: primary   DNS address 160.218.10.200
May 27 09:08:22 pango pppd[10432]: secondary DNS address 160.218.43.200
May 27 09:08:22 pango pppd[10432]: Script /etc/ppp/ip-up started (pid 10451)
May 27 09:08:22 pango pppd[10432]: Script /etc/ppp/ip-up finished (pid 10451), status = 0x0

all goes well for a while, and suddenly:

May 27 09:22:21 pango pppd[10432]: rcvd [LCP ConfReq id=0x34 <magic 0x66c79934> <mru 1500> <asyncmap 0x0> <auth chap MD5> <pcomp> <accomp>]
May 27 09:22:21 pango pppd[10432]: Script /etc/ppp/ip-down started (pid 13177)
May 27 09:22:21 pango pppd[10432]: sent [LCP ConfReq id=0x2 <asyncmap 0x0> <magic 0x47bab5d3> <pcomp> <accomp>]
May 27 09:22:21 pango pppd[10432]: sent [LCP ConfAck id=0x34 <magic 0x66c79934> <mru 1500> <asyncmap 0x0> <auth chap MD5> <pcomp> <accomp>]
May 27 09:22:21 pango pppd[10432]: Script /etc/ppp/ip-down finished (pid 13177), status = 0x0
May 27 09:22:21 pango pppd[10432]: rcvd [LCP ConfAck id=0x2 <asyncmap 0x0> <magic 0x47bab5d3> <pcomp> <accomp>]