Topic: ppoe often disconnect (Help really needed there)

[Laurent DINCLAUX]

Hi,

I use SME 6.0b4 and ppoe sometimes disconnect, making impossible to send large email for exemple.
It can stays 5min connected or 70min or 22min....

Here what /var/log/pppoe/curent says:

2003-12-04 15:52:51.210883500 Connect: ppp0 <--> /dev/pts/0
2003-12-04 15:52:52.217901500 sent [LCP ConfReq id=0x1 ]
2003-12-04 15:52:55.227624500 sent [LCP ConfReq id=0x1 ]
2003-12-04 15:52:58.237612500 sent [LCP ConfReq id=0x1 ]
2003-12-04 15:53:01.247531500 sent [LCP ConfReq id=0x1 ]
2003-12-04 15:53:02.058187500 rcvd [LCP ConfReq id=0x58 ]
2003-12-04 15:53:02.058193500 sent [LCP ConfAck id=0x58 ]
2003-12-04 15:53:02.060604500 rcvd [LCP ConfAck id=0x1 ]
2003-12-04 15:53:02.060606500 sent [LCP EchoReq id=0x0 magic=0x20b43704]
2003-12-04 15:53:02.075296500 rcvd [LCP ConfAck id=0x1 ]
2003-12-04 15:53:02.091452500 rcvd [LCP ConfAck id=0x1 ]
2003-12-04 15:53:02.094180500 rcvd [LCP ConfAck id=0x1 ]
2003-12-04 15:53:02.108812500 rcvd [CHAP Challenge id=0x1 , name = "BASGAL"]
2003-12-04 15:53:02.108819500 sent [CHAP Response id=0x1 , name = "birdyc@adslagoon.nc"]
2003-12-04 15:53:02.115474500 rcvd [LCP EchoRep id=0x0 magic=0x7415cfb1]
2003-12-04 15:53:03.017534500 rcvd [LCP ConfReq id=0x1 ]
2003-12-04 15:53:03.017540500 sent [LCP ConfReq id=0x2 ]
2003-12-04 15:53:03.017542500 sent [LCP ConfAck id=0x1 ]
2003-12-04 15:53:03.044551500 rcvd [LCP ConfAck id=0x2 ]
2003-12-04 15:53:03.044555500 sent [LCP EchoReq id=0x0 magic=0x4dcf20dc]
2003-12-04 15:53:03.044556500 sent [PAP AuthReq id=0x1 user="birdyc@adslagoon.nc" password=]
2003-12-04 15:53:03.070457500 rcvd [LCP EchoRep id=0x0 magic=0x6b316de7]
2003-12-04 15:53:03.143130500 rcvd [PAP AuthAck id=0x1 ""]
2003-12-04 15:53:03.143135500 PAP authentication succeeded
2003-12-04 15:53:03.143136500 sent [IPCP ConfReq id=0x1 ]
2003-12-04 15:53:03.145697500 rcvd [IPCP ConfReq id=0x1 ]
2003-12-04 15:53:03.145699500 sent [IPCP ConfAck id=0x1 ]
2003-12-04 15:53:03.170711500 rcvd [IPCP ConfNak id=0x1 ]
2003-12-04 15:53:03.170713500 sent [IPCP ConfReq id=0x2 ]
2003-12-04 15:53:03.200179500 rcvd [IPCP ConfAck id=0x2 ]
2003-12-04 15:53:03.200185500 local  IP address 202.22.147.166
2003-12-04 15:53:03.200186500 remote IP address 202.22.134.1
2003-12-04 15:53:03.200187500 Script /etc/ppp/ip-up started (pid 22397)
2003-12-04 15:53:14.487163500 Script /etc/ppp/ip-up finished (pid 22397), status = 0x0
2003-12-04 15:54:49.990546500 rcvd [LCP TermReq id=0x59]
2003-12-04 15:54:49.990552500 LCP terminated by peer
2003-12-04 15:54:49.990553500 Script /etc/ppp/ip-down started (pid 22851)
2003-12-04 15:54:49.990554500 sent [LCP TermAck id=0x59]
2003-12-04 15:54:51.305208500 Script /etc/ppp/ip-down finished (pid 22851), status = 0x0
2003-12-04 15:54:52.998950500 Connection terminated.
2003-12-04 15:54:52.998956500 Connect time 2.0 minutes.
2003-12-04 15:54:52.998957500 Sent 681381 bytes, received 119841 bytes.
2003-12-04 15:54:53.003070500 Waiting for 1 child processes...
2003-12-04 15:54:53.003075500   script /usr/sbin/pppoe -I eth1 -T 120 -U -m 1412, pid 22357
2003-12-04 15:54:53.003077500 pppoe: read (asyncReadFromPPP): Input/output error
2003-12-04 15:54:53.004189500 Script /usr/sbin/pppoe -I eth1 -T 120 -U -m 1412 finished (pid 22357), status = 0x1
2003-12-04 15:54:53.004195500 Connect time 2.0 minutes.
2003-12-04 15:54:53.004196500 Sent 681381 bytes, received 119841 bytes.
2003-12-04 15:54:53.143284500 using channel 18
2003-12-04 15:54:53.148534500 Using interface ppp0
2003-12-04 15:54:53.149197500 Connect: ppp0 <--> /dev/pts/0
2003-12-04 15:54:54.159083500 sent [LCP ConfReq id=0x1 ]
2003-12-04 15:54:55.370769500 rcvd [LCP ConfReq id=0x9b ]
2003-12-04 15:54:55.370856500 sent [LCP ConfAck id=0x9b ]
2003-12-04 15:54:55.373179500 rcvd [LCP ConfAck id=0x1 ]
2003-12-04 15:54:55.373258500 sent [LCP EchoReq id=0x0 magic=0xd1dc5b82]
2003-12-04 15:54:55.404111500 rcvd [CHAP Challenge id=0x1 <25d4aab4694a5cabd5a8dcd58cbb5e6b>, name = "BASGAL"]
2003-12-04 15:54:55.404357500 sent [CHAP Response id=0x1 <5af5d145e8c20a99d911f13c85484f8c>, name = "birdyc@adslagoon.nc"]
2003-12-04 15:54:55.406686500 rcvd [LCP EchoRep id=0x0 magic=0x390b1950]
2003-12-04 15:54:56.506204500 rcvd [LCP ConfReq id=0x1 ]
2003-12-04 15:54:56.506542500 sent [LCP ConfReq id=0x2 ]
2003-12-04 15:54:56.506608500 sent [LCP ConfAck id=0x1 ]
2003-12-04 15:54:56.552579500 rcvd [LCP ConfAck id=0x2 ]
2003-12-04 15:54:56.552778500 sent [LCP EchoReq id=0x0 magic=0xb4d7de82]
2003-12-04 15:54:56.552985500 sent [PAP AuthReq id=0x1 user="birdyc@adslagoon.nc" password=]
2003-12-04 15:54:56.579853500 rcvd [LCP EchoRep id=0x0 magic=0x6b33296c]
2003-12-04 15:54:56.649652500 rcvd [PAP AuthAck id=0x1 ""]
2003-12-04 15:54:56.649771500 PAP authentication succeeded
2003-12-04 15:54:56.649827500 sent [IPCP ConfReq id=0x1 ]
2003-12-04 15:54:56.652167500 rcvd [IPCP ConfReq id=0x1 ]
2003-12-04 15:54:56.652739500 sent [IPCP ConfAck id=0x1 ]
2003-12-04 15:54:56.672183500 rcvd [IPCP ConfNak id=0x1 ]
2003-12-04 15:54:56.672278500 sent [IPCP ConfReq id=0x2 ]
2003-12-04 15:54:56.702813500 rcvd [IPCP ConfAck id=0x2 ]
2003-12-04 15:54:56.704086500 local  IP address 202.22.147.166
2003-12-04 15:54:56.704248500 remote IP address 202.22.134.1
2003-12-04 15:54:56.704747500 Script /etc/ppp/ip-up started (pid 23087)
2003-12-04 15:55:15.547329500 Script /etc/ppp/ip-up finished (pid 23087), status = 0x0
2003-12-04 16:02:40.191157500 rcvd [LCP TermReq id=0x9c]
2003-12-04 16:02:40.191163500 LCP terminated by peer
2003-12-04 16:02:40.191164500 Script /etc/ppp/ip-down started (pid 23977)
2003-12-04 16:02:40.191165500 sent [LCP TermAck id=0x9c]
2003-12-04 16:02:41.594402500 Script /etc/ppp/ip-down finished (pid 23977), status = 0x0
2003-12-04 16:02:43.193576500 Connection terminated.
2003-12-04 16:02:43.193581500 Connect time 7.8 minutes.
2003-12-04 16:02:43.193582500 Sent 2951094 bytes, received 543112 bytes.
2003-12-04 16:02:43.199838500 Waiting for 1 child processes...
2003-12-04 16:02:43.199844500   script /usr/sbin/pppoe -I eth1 -T 120 -U -m 1412, pid 23078
2003-12-04 16:02:43.199845500 pppoe: read (asyncReadFromPPP): Input/output error
2003-12-04 16:02:43.200696500 Script /usr/sbin/pppoe -I eth1 -T 120 -U -m 1412 finished (pid 23078), status = 0x1
2003-12-04 16:02:43.200700500 Connect time 7.8 minutes.
2003-12-04 16:02:43.200701500 Sent 2951094 bytes, received 543112 bytes.
2003-12-04 16:02:43.343823500 using channel 19
2003-12-04 16:02:43.349780500 Using interface ppp0
2003-12-04 16:02:43.350319500 Connect: ppp0 <--> /dev/pts/0
2003-12-04 16:02:44.353274500 sent [LCP ConfReq id=0x1 ]
2003-12-04 16:02:47.362667500 sent [LCP ConfReq id=0x1 ]
2003-12-04 16:02:48.471424500 rcvd [LCP ConfReq id=0x7f ]
2003-12-04 16:02:48.471525500 sent [LCP ConfAck id=0x7f ]
2003-12-04 16:02:48.473795500 rcvd [LCP ConfAck id=0x1 ]
2003-12-04 16:02:48.473885500 sent [LCP EchoReq id=0x0 magic=0x6b58f0fa]
2003-12-04 16:02:48.487547500 rcvd [LCP ConfAck id=0x1 ]
2003-12-04 16:02:48.504312500 rcvd [CHAP Challenge id=0x1 <2400d132617cd41f068500f9a5ca4b09>, name = "BASGAL"]
2003-12-04 16:02:48.504591500 sent [CHAP Response id=0x1 <6f49cd0bd3a5597181d2bc6f8b413a2b>, name = "birdyc@adslagoon.nc"]
2003-12-04 16:02:48.510240500 rcvd [LCP EchoRep id=0x0 magic=0x65b0ba64]
2003-12-04 16:02:49.493552500 rcvd [LCP ConfReq id=0x1 ]
2003-12-04 16:02:49.493931500 sent [LCP ConfReq id=0x2 ]
2003-12-04 16:02:49.494025500 sent [LCP ConfAck id=0x1 ]
2003-12-04 16:02:49.519246500 rcvd [LCP ConfAck id=0x2 ]
2003-12-04 16:02:49.519351500 sent [LCP EchoReq id=0x0 magic=0xd7cd529a]
2003-12-04 16:02:49.519616500 sent [PAP AuthReq id=0x1 user="birdyc@adslagoon.nc" password=]
2003-12-04 16:02:49.545492500 rcvd [LCP EchoRep id=0x0 magic=0x6b3a61f7]
2003-12-04 16:02:49.625125500 rcvd [PAP AuthAck id=0x1 ""]
2003-12-04 16:02:49.625213500 PAP authentication succeeded
2003-12-04 16:02:49.625271500 sent [IPCP ConfReq id=0x1 ]
2003-12-04 16:02:49.627451500 rcvd [IPCP ConfReq id=0x1 ]
2003-12-04 16:02:49.628032500 sent [IPCP ConfAck id=0x1 ]
2003-12-04 16:02:49.651182500 rcvd [IPCP ConfNak id=0x1 ]
2003-12-04 16:02:49.651252500 sent [IPCP ConfReq id=0x2 ]
2003-12-04 16:02:49.679786500 rcvd [IPCP ConfAck id=0x2 ]
2003-12-04 16:02:49.681214500 local  IP address 202.22.146.56
2003-12-04 16:02:49.681299500 remote IP address 202.22.134.1
2003-12-04 16:02:49.681832500 Script /etc/ppp/ip-up started (pid 24231)
2003-12-04 16:03:17.998898500 Script /etc/ppp/ip-up finished (pid 24231), status = 0x0


Any idea PLEASE ???

Regards.

[Lloyd Keen]

Laurent,
I had this problem also, sometimes 20 or 30 disconnects a day. My logs were full of pppoe stuff. I had an Alcatel Speedtouch Home that had been converted to a Pro using a well known hack. The modem worked fine while in bridged mode with a static IP but didn't seem to handle the pppoe too well. I replaced the modem with a Web Excel (3808) and all my problems went away. HTH

[Charlie Brady]

Laurent DINCLAUX wrote:

> 2003-12-04 16:02:40.191163500 LCP
> terminated by peer

You need to find out why your ISP is disconnecting you. ("peer" is your ISP)

[It's always a good idea to read and try to understand the logs...]

Charlie

[Laurent DINCLAUX]

>You need to find out why your ISP is disconnecting you.

Already noticed that:

2003-12-04 16:02:40.191157500 rcvd [LCP TermReq id=0x9c]
2003-12-04 16:02:40.191163500 LCP terminated by peer

Seems like pppoe receive a "terminate request" isn't it ?
Already phoned my isp (before posting) He told me that he only sees that I get connected then, a few minutes after, I disconnect... That is all he told me!

So pppoe received 'TermReq id=0x9c' seaching on the internet, I found that this message comes most of time with an error message... So seems there is no error there...

> ("peer" is your ISP)

Can't "peer" be the ADSL modem? Can't it be a problem with my ethernet cable between my server and my ADSL modem, for exemple??

[michel]

Hello,
Did tou try an other modem? The message it's indicating a deconnection from you provider but I had the same problem and it was the modem.
Good luck
Michel

[Brett]

I have the same problem using Australian ADSL. I went to /etc/ppp/ and change the config in the file "pppoe-server-options"

 # PPP options for the PPPoE server
require-pap
login
lcp-echo-interval 10
lcp-echo-failure 2

I changed  the following line lcp-echo-failure to 10 instead of 2.

Just to make sure i also went to   /etc/e-smith/templates/etc/ppp/options/

and edit the file "lcp-echo-options" file as above. Not to sure which file e-smith uses but it is a little better now. Some days I get one disconnection and some days more. I checked the logs on the ISP side and it is always "user request" for disconnections? Before using e-smith ver 6.0 final I had no problems. Still looking for the right solutions but this seems to help.

Brett