Hi
and thanks for your quick respond.
> > After stopping pppd, ps -aux shows a process called
> > "pptp: call manager for 10.0.0.138"
> > Sending SIGKILL (kill -9 ; SIGTERM doesn't do anything) to this process,
> > and reconnecting works fine.
>
> If kill -9 do not work, this os not a problem in pptp-linux. This happen
> if the kernel can not release the process and this this is most probably
> a kernel issue.
what i ment was, kill -9 (i.e. sending SIGKILL) did work, while a kill
without -signr (i.e. SIGTERM) did nothing.
>
> The log show that you may have a problem with gre as you get an input/output
> error.
I didn't pay attention to this error jet, since it occours only and
always after "pppd[...]: Connection terminated."
I have a firewall running, but it is configured to only block
unrequested packages coming in over ppp0.
However, how can i find out more about this?
> When do this error occur then?
It only occurs when messages like
pppd[5687]: rcvd [IPCP TermReq id=0x0]
pppd[5687]: IPCP terminated by peer
and
pppd[5687]: rcvd [LCP TermReq id=0x0].
pppd[5687]: LCP terminated by peer
are given as reason for disconnecting.
When the link is disconnected for other reasons('pppd[...]: No response
to 3 echo-requests' or 'pppd[...]: Terminating connection due to lack of
activity.' etc.), its possible to reconnect without killing anything.
If you want me to, i can modify log levels of pppd and pptp, but i don't
want to spam my syslog (and this list) for no reason.
The following is an (annoying) connection breakup where reconnecting
works: (note that there are more lines from pptp than in the other
case)
...
Apr 11 20:13:27 verleihnix pppd[6548]: No response to 3 echo-requests
Apr 11 20:13:27 verleihnix pppd[6548]: Serial link appears to be
disconnected.
Apr 11 20:13:27 verleihnix pppd[6548]: Connect time 53.7 minutes.
Apr 11 20:13:27 verleihnix pppd[6548]: Sent 248896 bytes, received
1369993 bytes.
Apr 11 20:13:27 verleihnix pppd[6548]: Script /etc/ppp/ip-down started
(pid 6702)
Apr 11 20:13:27 verleihnix pppd[6548]: sent [LCP TermReq id=0x3 "Peer
not responding"]
Apr 11 20:13:28 verleihnix pppd[6548]: Script /etc/ppp/ip-down finished
(pid 6702), status = 0x0
Apr 11 20:13:30 verleihnix pppd[6548]: sent [LCP TermReq id=0x4 "Peer
not responding"]
Apr 11 20:13:33 verleihnix pppd[6548]: Connection terminated.
Apr 11 20:13:33 verleihnix pptp[6564]: anon
warn[decaps_hdlc:pptp_gre.c:197]: short read (-1): Input/output error
Apr 11 20:13:33 verleihnix pptp[6564]: anon
warn[decaps_hdlc:pptp_gre.c:209]: pppd may have shutdown, see pppd log
Apr 11 20:13:33 verleihnix pptp[6567]: anon
log[callmgr_main:pptp_callmgr.c:228]: Closing connection
Apr 11 20:13:33 verleihnix pppd[6548]: Script /usr/sbin/pptp 10.0.0.138
--nolaunchpppd --loglevel 0 finished (pid 6564), status = 0x0
Apr 11 20:13:33 verleihnix pptp[6567]: anon
log[ctrlp_rep:pptp_ctrl.c:243]: Sent control packet type is 12
'Call-Clear-Request'
Apr 11 20:13:35 verleihnix pptp[6567]: anon
log[ctrlp_rep:pptp_ctrl.c:243]: Sent control packet type is 12
'Call-Clear-Request'
Apr 11 20:13:35 verleihnix pptp[6567]: anon
log[pptp_conn_close:pptp_ctrl.c:425]: Closing PPTP connection
Apr 11 20:13:35 verleihnix pptp[6567]: anon
log[ctrlp_rep:pptp_ctrl.c:243]: Sent control packet type is 3
'Stop-Control-Connection-Request'
Apr 11 20:13:39 verleihnix pptp[6567]: anon
log[call_callback:pptp_callmgr.c:77]: Closing connection
... and reconnecting ...
Apr 11 20:36:03 verleihnix pppd[6548]: Starting link
Apr 11 20:36:03 verleihnix pptp[6751]: anon log[main:pptp.c:243]: The
synchronous pptp option is NOT activated
Apr 11 20:36:03 verleihnix pppd[6548]: Serial connection established.
Apr 11 20:36:03 verleihnix pppd[6548]: using channel 1162
Apr 11 20:36:03 verleihnix pppd[6548]: Connect: ppp0 <--> /dev/pts/0
Apr 11 20:36:03 verleihnix pptp[6754]: anon
log[ctrlp_rep:pptp_ctrl.c:243]: Sent control packet type is 1
'Start-Control-Connection-Request'
Apr 11 20:36:03 verleihnix pptp[6754]: anon
log[ctrlp_disp:pptp_ctrl.c:721]: Received Start Control Connection Reply
Apr 11 20:36:03 verleihnix pptp[6754]: anon
log[ctrlp_disp:pptp_ctrl.c:755]: Client connection established.
Apr 11 20:36:04 verleihnix pppd[6548]: sent [LCP ConfReq id=0x5
<asyncmap 0x0> <magic 0xcd639fe1> <pcomp> <accomp>]
Apr 11 20:36:04 verleihnix pptp[6754]: anon
log[ctrlp_rep:pptp_ctrl.c:243]: Sent control packet type is 7
'Outgoing-Call-Request'
Apr 11 20:36:04 verleihnix pptp[6754]: anon
log[ctrlp_disp:pptp_ctrl.c:841]: Received Outgoing Call Reply.
Apr 11 20:36:04 verleihnix pptp[6754]: anon
log[ctrlp_disp:pptp_ctrl.c:880]: Outgoing call established (call ID 0,
peer's call ID 32849).
Apr 11 20:36:04 verleihnix pppd[6548]: rcvd [LCP ConfReq id=0x0 <magic
0x253ce283> <auth pap>]
Apr 11 20:36:04 verleihnix pppd[6548]: sent [LCP ConfAck id=0x0 <magic
0x253ce283> <auth pap>]
Apr 11 20:36:04 verleihnix pppd[6548]: rcvd [LCP ConfRej id=0x5 <pcomp>
<accomp>]
Apr 11 20:36:04 verleihnix pppd[6548]: sent [LCP ConfReq id=0x6
<asyncmap 0x0> <magic 0xcd639fe1>]
Apr 11 20:36:04 verleihnix pppd[6548]: rcvd [LCP ConfAck id=0x6
<asyncmap 0x0> <magic 0xcd639fe1>]
Apr 11 20:36:04 verleihnix pppd[6548]: sent [LCP EchoReq id=0x0
magic=0xcd639fe1]
Apr 11 20:36:04 verleihnix pppd[6548]: sent [PAP AuthReq id=0x2
user="[EMAIL PROTECTED]" password=<hidden>]
Apr 11 20:36:04 verleihnix pppd[6548]: rcvd [LCP EchoRep id=0x0
magic=0x253ce283]
Apr 11 20:36:04 verleihnix pppd[6548]: rcvd [PAP AuthAck id=0x2
"Authorization successful"]
Apr 11 20:36:04 verleihnix pppd[6548]: Remote message: Authorization
successful
Apr 11 20:36:04 verleihnix pppd[6548]: PAP authentication succeeded
Apr 11 20:36:04 verleihnix pppd[6548]: sent [CCP ConfReq id=0x2 <deflate
15> <deflate(old#) 15> <bsd v1 15>]
Apr 11 20:36:04 verleihnix pppd[6548]: sent [IPCP ConfReq id=0x3 <addr
0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
Apr 11 20:36:04 verleihnix pppd[6548]: rcvd [IPCP ConfReq id=0x0 <addr
172.25.46.47>]
Apr 11 20:36:04 verleihnix pppd[6548]: sent [IPCP ConfAck id=0x0 <addr
172.25.46.47>]
Apr 11 20:36:04 verleihnix pppd[6548]: rcvd [LCP ProtRej id=0x0 80 fd 01
02 00 0f 1a 04 78 00 18 04 78 00 15 03 2f]
Apr 11 20:36:04 verleihnix pppd[6548]: rcvd [IPCP ConfNak id=0x3 <addr
xxx.xxx.xxx.xxx> <ms-dns1 195.58.160.194> <ms-dns3 195.58.161.122>]
Apr 11 20:36:04 verleihnix pppd[6548]: sent [IPCP ConfReq id=0x4 <addr
xxx.xxx.xxx.xxx> <ms-dns1 195.58.160.194> <ms-dns3 195.58.161.122>]
Apr 11 20:36:04 verleihnix pppd[6548]: rcvd [IPCP ConfAck id=0x4 <addr
xxx.xxx.xxx.xxx> <ms-dns1 195.58.160.194> <ms-dns3 195.58.161.122>]
Apr 11 20:36:04 verleihnix pppd[6548]: Local IP address changed to
xxx.xxx.xxx.xxx
Apr 11 20:36:05 verleihnix pppd[6548]: Open UDP xxx.xxx.xxx.xxx:33021 ->
195.58.160.194:53
Apr 11 20:36:05 verleihnix pppd[6548]: sent [IP data] 45 00 00 3b 01 e0
40 00 ...
Apr 11 20:36:05 verleihnix pppd[6548]: Open UDP xxx.xxx.xxx.xxx:33021 ->
195.58.161.122:53
Apr 11 20:36:05 verleihnix pppd[6548]: sent [IP data] 45 00 00 3b 01 e1
40 00 ...
Apr 11 20:36:05 verleihnix pppd[6548]: Script /etc/ppp/ip-up started
(pid 6755)
Apr 11 20:36:07 verleihnix pppd[6548]: Script /etc/ppp/ip-up finished
(pid 6755), status = 0x0
- Robert
--
To UNSUBSCRIBE, email to [EMAIL PROTECTED]
with a subject of "unsubscribe". Trouble? Contact [EMAIL PROTECTED]
