Date: Mon, 6 May 2002 00:36:40 +0200 From: "Simon 'corecode' Schubert" <corecode@corecode.ath.cx> To: net@freebsd.org Subject: pptp/ppp just closing without reason Message-ID: <20020506003640.7e0f743f.corecode@corecode.ath.cx>
next in thread | raw e-mail | index | archive | help
--=.YLUG7Oa?KLIunQ
Content-Type: text/plain; charset=US-ASCII
Content-Transfer-Encoding: 7bit
hello networkers,
i've been trying to set up a pptp tunnel for more than one week now and
i wonder why this doesn't work:
pptp 129.187.10.28 pptp
returns after some seconds. the log indicates the termination of ppp,
but why does it terminate? pitty i don't have the server logs
May 6 00:27:39 spirit pptp[76897]: log[pptp_dispatch_ctrl_packet:pptp_ctrl.c:548]: Client connection established.
May 6 00:27:40 spirit pptp[76897]: log[pptp_dispatch_ctrl_packet:pptp_ctrl.c:655]: Outgoing call established (call ID 0, peer's call ID 0).
May 6 00:27:40 spirit ppp[76895]: Phase: Using interface: tun1
May 6 00:27:40 spirit ppp[76895]: Phase: deflink: Created in closed state
May 6 00:27:40 spirit ppp[76895]: tun1: Phase: PPP Started (direct mode).
May 6 00:27:40 spirit ppp[76895]: tun1: Phase: bundle: Establish
May 6 00:27:40 spirit ppp[76895]: tun1: Phase: deflink: closed -> opening
May 6 00:27:40 spirit ppp[76895]: tun1: Phase: deflink: Connected!
May 6 00:27:40 spirit ppp[76895]: tun1: Phase: deflink: opening -> carrier
May 6 00:27:41 spirit ppp[76895]: tun1: Phase: deflink: carrier -> lcp
May 6 00:27:41 spirit ppp[76895]: tun1: LCP: FSM: Using "deflink" as a transport
May 6 00:27:41 spirit ppp[76895]: tun1: LCP: deflink: State change Initial --> Closed
May 6 00:27:41 spirit ppp[76895]: tun1: LCP: deflink: State change Closed --> Stopped
May 6 00:27:42 spirit ppp[76895]: tun1: LCP: deflink: LayerStart
May 6 00:27:42 spirit ppp[76895]: tun1: LCP: deflink: SendConfigReq(1) state = Stopped
May 6 00:27:42 spirit ppp[76895]: tun1: LCP: ACFCOMP[2]
May 6 00:27:42 spirit ppp[76895]: tun1: LCP: PROTOCOMP[2]
May 6 00:27:42 spirit ppp[76895]: tun1: LCP: ACCMAP[6] 0x00000000
May 6 00:27:42 spirit ppp[76895]: tun1: LCP: MRU[4] 1500
May 6 00:27:42 spirit ppp[76895]: tun1: LCP: MAGICNUM[6] 0x94759594
May 6 00:27:42 spirit ppp[76895]: tun1: LCP: deflink: State change Stopped --> Req-Sent
May 6 00:27:45 spirit ppp[76895]: tun1: LCP: deflink: SendConfigReq(1) state = Req-Sent
May 6 00:27:45 spirit ppp[76895]: tun1: LCP: ACFCOMP[2]
May 6 00:27:45 spirit ppp[76895]: tun1: LCP: PROTOCOMP[2]
May 6 00:27:45 spirit ppp[76895]: tun1: LCP: ACCMAP[6] 0x00000000
May 6 00:27:45 spirit ppp[76895]: tun1: LCP: MRU[4] 1500
May 6 00:27:45 spirit ppp[76895]: tun1: LCP: MAGICNUM[6] 0x94759594
May 6 00:27:45 spirit ppp[76895]: tun1: LCP: deflink: RecvConfigAck(1) state = Req-Sent
May 6 00:27:45 spirit ppp[76895]: tun1: LCP: deflink: State change Req-Sent --> Ack-Rcvd
May 6 00:27:46 spirit ppp[76895]: tun1: LCP: deflink: RecvConfigReq(1) state = Ack-Rcvd
May 6 00:27:46 spirit ppp[76895]: tun1: LCP: ACCMAP[6] 0x00000000
May 6 00:27:46 spirit ppp[76895]: tun1: LCP: AUTHPROTO[4] 0xc023 (PAP)
May 6 00:27:46 spirit ppp[76895]: tun1: LCP: MAGICNUM[6] 0xb2cad472
May 6 00:27:46 spirit ppp[76895]: tun1: LCP: PROTOCOMP[2]
May 6 00:27:46 spirit ppp[76895]: tun1: LCP: ACFCOMP[2]
May 6 00:27:46 spirit ppp[76895]: tun1: LCP: deflink: SendConfigAck(1) state = Ack-Rcvd
May 6 00:27:46 spirit ppp[76895]: tun1: LCP: ACCMAP[6] 0x00000000
May 6 00:27:46 spirit ppp[76895]: tun1: LCP: AUTHPROTO[4] 0xc023 (PAP)
May 6 00:27:46 spirit ppp[76895]: tun1: LCP: MAGICNUM[6] 0xb2cad472
May 6 00:27:46 spirit ppp[76895]: tun1: LCP: PROTOCOMP[2]
May 6 00:27:46 spirit ppp[76895]: tun1: LCP: ACFCOMP[2]
May 6 00:27:46 spirit ppp[76895]: tun1: LCP: deflink: State change Ack-Rcvd --> Opened
May 6 00:27:46 spirit ppp[76895]: tun1: LCP: deflink: LayerUp
May 6 00:27:46 spirit ppp[76895]: tun1: Phase: bundle: Authenticate
May 6 00:27:46 spirit ppp[76895]: tun1: Phase: deflink: his = PAP, mine = none
May 6 00:27:46 spirit ppp[76895]: tun1: Phase: Pap Output: simons@eikon ********
May 6 00:27:46 spirit ppp[76895]: tun1: Phase: Pap Input: SUCCESS (Success)
May 6 00:27:46 spirit ppp[76895]: tun1: CCP: FSM: Using "deflink" as a transport
May 6 00:27:46 spirit ppp[76895]: tun1: CCP: deflink: State change Initial --> Closed
May 6 00:27:46 spirit ppp[76895]: tun1: CCP: deflink: State change Closed --> Stopped
May 6 00:27:46 spirit ppp[76895]: tun1: Phase: deflink: lcp -> open
May 6 00:27:46 spirit ppp[76895]: tun1: Phase: bundle: Network
May 6 00:27:46 spirit ppp[76895]: tun1: IPCP: FSM: Using "deflink" as a transport
May 6 00:27:46 spirit ppp[76895]: tun1: IPCP: deflink: State change Initial --> Closed
May 6 00:27:46 spirit ppp[76895]: tun1: IPCP: deflink: LayerStart.
May 6 00:27:46 spirit ppp[76895]: tun1: IPCP: deflink: SendConfigReq(1) state = Closed
May 6 00:27:46 spirit ppp[76895]: tun1: IPCP: IPADDR[6] 0.0.0.0
May 6 00:27:46 spirit ppp[76895]: tun1: IPCP: COMPPROTO[6] 16 VJ slots with slot compression
May 6 00:27:46 spirit ppp[76895]: tun1: IPCP: deflink: State change Closed --> Req-Sent
May 6 00:27:46 spirit ppp[76895]: tun1: IPCP: deflink: RecvConfigReq(1) state = Req-Sent
May 6 00:27:46 spirit ppp[76895]: tun1: IPCP: IPADDR[6] 129.187.10.28
May 6 00:27:46 spirit ppp[76895]: tun1: IPCP: COMPPROTO[6] 16 VJ slots with slot compression
May 6 00:27:46 spirit ppp[76895]: tun1: IPCP: deflink: SendConfigAck(1) state = Req-Sent
May 6 00:27:46 spirit ppp[76895]: tun1: IPCP: IPADDR[6] 129.187.10.28
May 6 00:27:46 spirit ppp[76895]: tun1: IPCP: COMPPROTO[6] 16 VJ slots with slot compression
May 6 00:27:46 spirit ppp[76895]: tun1: IPCP: deflink: State change Req-Sent --> Ack-Sent
May 6 00:27:46 spirit ppp[76895]: tun1: CCP: deflink: RecvConfigReq(1) state = Stopped
May 6 00:27:46 spirit ppp[76895]: tun1: CCP: DEFLATE[4] win 15
May 6 00:27:46 spirit ppp[76895]: tun1: CCP: MAGNALINK/DEFLATE[4] win 15
May 6 00:27:46 spirit ppp[76895]: tun1: CCP: BSD[3]
May 6 00:27:46 spirit ppp[76895]: tun1: CCP: deflink: SendConfigReq(1) state = Stopped
May 6 00:27:46 spirit ppp[76895]: tun1: CCP: [EMPTY]
May 6 00:27:46 spirit ppp[76895]: tun1: CCP: deflink: SendConfigRej(1) state = Stopped
May 6 00:27:46 spirit ppp[76895]: tun1: CCP: MAGNALINK/DEFLATE[4] win 15
May 6 00:27:46 spirit ppp[76895]: tun1: CCP: BSD[3]
May 6 00:27:46 spirit ppp[76895]: tun1: CCP: deflink: LayerStart.
May 6 00:27:46 spirit ppp[76895]: tun1: CCP: deflink: State change Stopped --> Req-Sent
May 6 00:27:46 spirit ppp[76895]: tun1: IPCP: deflink: RecvConfigNak(1) state = Ack-Sent
May 6 00:27:46 spirit ppp[76895]: tun1: IPCP: IPADDR[6] 129.187.48.18
May 6 00:27:46 spirit ppp[76895]: tun1: IPCP: IPADDR[6] changing address: 0.0.0.0 --> 129.187.48.18
May 6 00:27:46 spirit ppp[76895]: tun1: IPCP: deflink: SendConfigReq(2) state = Ack-Sent
May 6 00:27:46 spirit ppp[76895]: tun1: IPCP: IPADDR[6] 129.187.48.18
May 6 00:27:46 spirit ppp[76895]: tun1: IPCP: COMPPROTO[6] 16 VJ slots with slot compression
May 6 00:27:46 spirit ppp[76895]: tun1: CCP: deflink: RecvConfigAck(1) state = Req-Sent
May 6 00:27:46 spirit ppp[76895]: tun1: CCP: deflink: State change Req-Sent --> Ack-Rcvd
May 6 00:27:46 spirit ppp[76895]: tun1: IPCP: deflink: RecvConfigAck(2) state = Ack-Sent
May 6 00:27:46 spirit ppp[76895]: tun1: IPCP: deflink: State change Ack-Sent --> Opened
May 6 00:27:46 spirit ppp[76895]: tun1: IPCP: deflink: LayerUp.
May 6 00:27:46 spirit ppp[76895]: tun1: IPCP: myaddr 129.187.48.18 hisaddr = 129.187.10.28
May 6 00:27:49 spirit ppp[76895]: tun1: CCP: deflink: RecvConfigReq(1) state = Ack-Rcvd
May 6 00:27:49 spirit ppp[76895]: tun1: CCP: DEFLATE[4] win 15
May 6 00:27:49 spirit ppp[76895]: tun1: CCP: MAGNALINK/DEFLATE[4] win 15
May 6 00:27:49 spirit ppp[76895]: tun1: CCP: BSD[3]
May 6 00:27:49 spirit ppp[76895]: tun1: CCP: deflink: SendConfigRej(1) state = Ack-Rcvd
May 6 00:27:49 spirit ppp[76895]: tun1: CCP: MAGNALINK/DEFLATE[4] win 15
May 6 00:27:49 spirit ppp[76895]: tun1: CCP: BSD[3]
May 6 00:27:50 spirit ppp[76895]: tun1: Phase: Signal 15, terminate.
May 6 00:27:50 spirit ppp[76895]: tun1: IPCP: deflink: LayerDown: 129.187.48.18
May 6 00:27:50 spirit ppp[76895]: tun1: IPCP: deflink: SendTerminateReq(3) state = Opened
May 6 00:27:50 spirit ppp[76895]: tun1: IPCP: deflink: State change Opened --> Closing
May 6 00:27:50 spirit ppp[76895]: tun1: Phase: deflink: read (0): Got zero bytes
May 6 00:27:50 spirit ppp[76895]: tun1: CCP: deflink: State change Ack-Rcvd --> Starting
May 6 00:27:50 spirit ppp[76895]: tun1: CCP: deflink: LayerFinish.
May 6 00:27:50 spirit ppp[76895]: tun1: CCP: deflink: State change Starting --> Initial
May 6 00:27:50 spirit ppp[76895]: tun1: LCP: deflink: LayerDown
May 6 00:27:50 spirit ppp[76895]: tun1: LCP: deflink: State change Opened --> Starting
May 6 00:27:50 spirit ppp[76895]: tun1: Phase: deflink: open -> lcp
May 6 00:27:50 spirit ppp[76895]: tun1: IPCP: deflink: LayerFinish.
May 6 00:27:50 spirit ppp[76895]: tun1: IPCP: Connect time: 4 secs: 0 octets in, 812035 octets out
May 6 00:27:50 spirit ppp[76895]: tun1: IPCP: : 0 packets in, 1573 packets out
May 6 00:27:50 spirit ppp[76895]: tun1: IPCP: total 203008 bytes/sec, peak 135942 bytes/sec on Mon May 6 00:27:50 2002
May 6 00:27:50 spirit ppp[76895]: tun1: IPCP: deflink: State change Closing --> Initial
May 6 00:27:50 spirit ppp[76895]: tun1: Phase: bundle: Terminate
May 6 00:27:50 spirit ppp[76895]: tun1: LCP: deflink: LayerFinish
May 6 00:27:50 spirit ppp[76895]: tun1: LCP: deflink: State change Starting --> Initial
May 6 00:27:50 spirit ppp[76895]: tun1: Phase: deflink: Disconnected!
May 6 00:27:50 spirit ppp[76895]: tun1: Phase: deflink: Connect time: 10 secs: 298 octets in, 820693 octets out
May 6 00:27:50 spirit ppp[76895]: tun1: Phase: deflink: : 8 packets in, 1584 packets out
May 6 00:27:50 spirit ppp[76895]: tun1: Phase: total 82099 bytes/sec, peak 122307 bytes/sec on Mon May 6 00:27:50 2002
May 6 00:27:50 spirit ppp[76895]: tun1: Phase: deflink: lcp -> closed
May 6 00:27:50 spirit ppp[76895]: tun1: Phase: bundle: Dead
May 6 00:27:50 spirit ppp[76895]: tun1: Phase: PPP Terminated (normal).
May 6 00:27:50 spirit pptp[76897]: log[callmgr_main:pptp_callmgr.c:240]: Closing connection
May 6 00:27:50 spirit pptp[76897]: log[pptp_conn_close:pptp_ctrl.c:285]: Closing PPTP connection
May 6 00:27:52 spirit pptp[76897]: log[call_callback:pptp_callmgr.c:88]: Closing connection
my config looks like this:
pptp:
set authname "simons@eikon"
set authkey "XXXXX"
set timeout 0
set ifaddr 0.0.0.0/0
disable mppe
deny mppe
accept deflate24
disable pred1
disable deflate
set log Phase Chat Connect LCP IPCP CCP tun Warning Error command alert #debug
pppoe works just fine from this machine. i really don't see why there is
this Phase: Signal 15, terminate. seems ppp receives a SIGTERM from
somewhere...
i hope you can help me
simon
ps: i also don't understand why it keeps rejecting MAGNALINK/DEFLATE
though i'm accepting deflate24.
--
/"\ http://corecode.ath.cx/#donate
\ /
\ ASCII Ribbon Campaign
/ \ Against HTML Mail and News
--=.YLUG7Oa?KLIunQ
Content-Type: application/pgp-signature
-----BEGIN PGP SIGNATURE-----
Version: GnuPG v1.0.6 (FreeBSD)
iD8DBQE81bP8r5S+dk6z85oRAoQ6AJkBYLQAgbBHQCcXzJC+8KgfaNAeowCgstcG
2zBu+2lYPOntpO8gaa3SqeM=
=vIpk
-----END PGP SIGNATURE-----
--=.YLUG7Oa?KLIunQ--
To Unsubscribe: send mail to majordomo@FreeBSD.org
with "unsubscribe freebsd-net" in the body of the message
Want to link to this message? Use this URL: <https://mail-archive.FreeBSD.org/cgi/mid.cgi?20020506003640.7e0f743f.corecode>