From owner-freebsd-net@FreeBSD.ORG Sat Oct 25 19:08:51 2008 Return-Path: Delivered-To: freebsd-net@freebsd.org Received: from mx1.freebsd.org (mx1.freebsd.org [IPv6:2001:4f8:fff6::34]) by hub.freebsd.org (Postfix) with ESMTP id 321FC1065671 for ; Sat, 25 Oct 2008 19:08:51 +0000 (UTC) (envelope-from ck@yourserveradmin.com) Received: from tetrahedron.itechcraft.com (tetrahedron.itechcraft.com [72.34.45.195]) by mx1.freebsd.org (Postfix) with ESMTP id 174618FC1B for ; Sat, 25 Oct 2008 19:08:51 +0000 (UTC) (envelope-from ck@yourserveradmin.com) Received: from dodekaedr.techs.com.ua ([193.109.101.2]:1706 helo=[10.10.10.12]) by tetrahedron.itechcraft.com with esmtpa (Exim 4.69) (envelope-from ) id 1Kto6T-0003Og-Ca; Sat, 25 Oct 2008 21:43:13 +0300 Message-ID: <490368C0.1010704@yourserveradmin.com> Date: Sat, 25 Oct 2008 21:43:12 +0300 From: CK User-Agent: Thunderbird 2.0.0.17 (Windows/20080914) MIME-Version: 1.0 To: freebsd-net@freebsd.org Content-Type: text/plain; charset=ISO-8859-1; format=flowed Content-Transfer-Encoding: 7bit X-AntiAbuse: This header was added to track abuse, please include it with any abuse report X-AntiAbuse: Primary Hostname - tetrahedron.itechcraft.com X-AntiAbuse: Original Domain - freebsd.org X-AntiAbuse: Originator/Caller UID/GID - [47 12] / [47 12] X-AntiAbuse: Sender Address Domain - yourserveradmin.com Cc: mav@freebsd.org Subject: mpd - lcp protocol rejects X-BeenThere: freebsd-net@freebsd.org X-Mailman-Version: 2.1.5 Precedence: list Reply-To: freebsd-net@freebsd.org List-Id: Networking and TCP/IP with FreeBSD List-Unsubscribe: , List-Archive: List-Post: List-Help: List-Subscribe: , X-List-Received-Date: Sat, 25 Oct 2008 19:08:51 -0000 Hello Everyone, I'm running mpd 4.4 on 6.3-STABLE #4. Connecting with mpd to my ISP's VPN server running poptop. Everything is ok for some time, and then all of a sudden mpd starts throwing weird protocol rejects to log file and vpn connection stops working. mpd.conf: pptp: new -i ng0 pptp pptp set iface disable on-demand set iface disable proxy-arp set iface enable tcpmssfix set iface up-script /usr/local/etc/mpd4/if_up.sh set iface down-script /usr/local/etc/mpd4/if_down.sh set iface idle 0 set bundle disable multilink set bundle yes compression set bundle disable noretry set bundle enable crypt-reqd set auth authname "login_here" set link accept acfcomp protocomp set link accept chap set link keep-alive 10 60 set link max-redial 0 set link accmap 0x00000000 set ipcp yes vjcomp set ccp yes mppc set ccp yes mpp-compress set ccp no mpp-e40 set ccp yes mpp-e128 set ccp yes mpp-stateless open mpd.links: pptp: set phys type pptp set pptp peer [ISP's vpn serv here] set pptp enable originate set pptp disable incoming set pptp enable windowing And lots of logs: Oct 19 03:06:10 tazek mpd: [pptp] PPTP call successful Oct 19 03:06:10 tazek mpd: [pptp] link: UP event Oct 19 03:06:10 tazek mpd: [pptp] link: origination is local Oct 19 03:06:10 tazek mpd: [pptp] LCP: Up event Oct 19 03:06:10 tazek mpd: [pptp] LCP: state change Starting --> Req-Sent Oct 19 03:06:10 tazek mpd: [pptp] LCP: SendConfigReq #1 Oct 19 03:06:10 tazek mpd: ACFCOMP Oct 19 03:06:10 tazek mpd: PROTOCOMP Oct 19 03:06:10 tazek mpd: ACCMAP 0x000a0000 Oct 19 03:06:10 tazek mpd: MRU 1460 Oct 19 03:06:10 tazek mpd: MAGICNUM bc85bd60 Oct 19 03:06:10 tazek mpd: [pptp] LCP: rec'd Configure Request #1 (Req-Sent) Oct 19 03:06:10 tazek mpd: ACCMAP 0x00000000 Oct 19 03:06:10 tazek mpd: AUTHPROTO CHAP MSOFTv2 Oct 19 03:06:10 tazek mpd: MAGICNUM 0b145760 Oct 19 03:06:10 tazek mpd: PROTOCOMP Oct 19 03:06:10 tazek mpd: ACFCOMP Oct 19 03:06:10 tazek mpd: [pptp] LCP: SendConfigAck #1 Oct 19 03:06:10 tazek mpd: ACCMAP 0x00000000 Oct 19 03:06:10 tazek mpd: AUTHPROTO CHAP MSOFTv2 Oct 19 03:06:10 tazek mpd: MAGICNUM 0b145760 Oct 19 03:06:10 tazek mpd: PROTOCOMP Oct 19 03:06:10 tazek mpd: ACFCOMP Oct 19 03:06:10 tazek mpd: [pptp] LCP: state change Req-Sent --> Ack-Sent Oct 19 03:06:12 tazek mpd: [pptp] LCP: SendConfigReq #2 Oct 19 03:06:12 tazek mpd: ACFCOMP Oct 19 03:06:12 tazek mpd: PROTOCOMP Oct 19 03:06:12 tazek mpd: ACCMAP 0x000a0000 Oct 19 03:06:12 tazek mpd: MRU 1460 Oct 19 03:06:12 tazek mpd: MAGICNUM bc85bd60 Oct 19 03:06:12 tazek mpd: [pptp] LCP: rec'd Configure Ack #2 (Ack-Sent) Oct 19 03:06:12 tazek mpd: ACFCOMP Oct 19 03:06:12 tazek mpd: PROTOCOMP Oct 19 03:06:12 tazek mpd: ACCMAP 0x000a0000 Oct 19 03:06:12 tazek mpd: MRU 1460 Oct 19 03:06:12 tazek mpd: MAGICNUM bc85bd60 Oct 19 03:06:12 tazek mpd: [pptp] LCP: state change Ack-Sent --> Opened Oct 19 03:06:12 tazek mpd: [pptp] LCP: auth: peer wants CHAP, I want nothing Oct 19 03:06:12 tazek mpd: [pptp] LCP: LayerUp Oct 19 03:06:12 tazek mpd: [pptp] CHAP: rec'd CHALLENGE #19 Oct 19 03:06:12 tazek mpd: Name: "pptpd" Oct 19 03:06:12 tazek mpd: Using authname "login_here" Oct 19 03:06:12 tazek mpd: [pptp] CHAP: sending RESPONSE len:63 Oct 19 03:06:12 tazek mpd: [pptp] CHAP: rec'd SUCCESS #19 Oct 19 03:06:12 tazek mpd: MESG: S=376C81163CF8923DF663DC2D672D8802BBDAAD3B Oct 19 03:06:12 tazek mpd: [pptp] LCP: authorization successful Oct 19 03:06:12 tazek mpd: [pptp] Bundle up: 1 link, total bandwidth 64000 bps Oct 19 03:06:12 tazek mpd: [pptp] IPCP: Open event Oct 19 03:06:12 tazek mpd: [pptp] IPCP: state change Initial --> Starting Oct 19 03:06:12 tazek mpd: [pptp] IPCP: LayerStart Oct 19 03:06:12 tazek mpd: [pptp] CCP: Open event Oct 19 03:06:12 tazek mpd: [pptp] CCP: state change Initial --> Starting Oct 19 03:06:12 tazek mpd: [pptp] CCP: LayerStart Oct 19 03:06:12 tazek mpd: [pptp] IPCP: Up event Oct 19 03:06:12 tazek mpd: [pptp] IPCP: state change Starting --> Req-Sent Oct 19 03:06:12 tazek mpd: [pptp] IPCP: SendConfigReq #1 Oct 19 03:06:12 tazek mpd: IPADDR 192.168.1.2 Oct 19 03:06:12 tazek mpd: COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Oct 19 03:06:12 tazek mpd: [pptp] CCP: Up event Oct 19 03:06:12 tazek mpd: [pptp] CCP: state change Starting --> Req-Sent Oct 19 03:06:12 tazek mpd: [pptp] CCP: SendConfigReq #1 Oct 19 03:06:12 tazek mpd: MPPC Oct 19 03:06:12 tazek mpd: 0x01000041:MPPC, MPPE(128 bits), stateless Oct 19 03:06:12 tazek mpd: [pptp] IPCP: rec'd Terminate Ack #1 (Req-Sent) Oct 19 03:06:12 tazek mpd: [pptp] CCP: rec'd Configure Nak #1 (Req-Sent) Oct 19 03:06:12 tazek mpd: MPPC Oct 19 03:06:12 tazek mpd: 0x01000040:MPPE(128 bits), stateless Oct 19 03:06:12 tazek mpd: [pptp] CCP: SendConfigReq #2 Oct 19 03:06:12 tazek mpd: MPPC Oct 19 03:06:12 tazek mpd: 0x01000040:MPPE(128 bits), stateless Oct 19 03:06:12 tazek mpd: [pptp] CCP: rec'd Configure Ack #2 (Req-Sent) Oct 19 03:06:12 tazek mpd: MPPC Oct 19 03:06:12 tazek mpd: 0x01000040:MPPE(128 bits), stateless Oct 19 03:06:12 tazek mpd: [pptp] CCP: state change Req-Sent --> Ack-Rcvd Oct 19 03:06:14 tazek mpd: [pptp] IPCP: SendConfigReq #2 Oct 19 03:06:14 tazek mpd: IPADDR 192.168.1.2 Oct 19 03:06:14 tazek mpd: COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Oct 19 03:06:14 tazek mpd: [pptp] IPCP: rec'd Terminate Ack #2 (Req-Sent) Oct 19 03:06:14 tazek mpd: [pptp] CCP: state change Ack-Rcvd --> Req-Sent Oct 19 03:06:14 tazek mpd: [pptp] CCP: SendConfigReq #3 Oct 19 03:06:14 tazek mpd: MPPC Oct 19 03:06:14 tazek mpd: 0x01000040:MPPE(128 bits), stateless Oct 19 03:06:14 tazek mpd: [pptp] CCP: rec'd Configure Ack #3 (Req-Sent) Oct 19 03:06:14 tazek mpd: MPPC Oct 19 03:06:14 tazek mpd: 0x01000040:MPPE(128 bits), stateless Oct 19 03:06:14 tazek mpd: [pptp] CCP: state change Req-Sent --> Ack-Rcvd Oct 19 03:06:15 tazek mpd: [pptp] CCP: rec'd Configure Request #1 (Ack-Rcvd) Oct 19 03:06:15 tazek mpd: MPPC Oct 19 03:06:15 tazek mpd: 0x01000040:MPPE(128 bits), stateless Oct 19 03:06:15 tazek mpd: [pptp] CCP: SendConfigAck #1 Oct 19 03:06:15 tazek mpd: MPPC Oct 19 03:06:15 tazek mpd: 0x01000040:MPPE(128 bits), stateless Oct 19 03:06:15 tazek mpd: [pptp] CCP: state change Ack-Rcvd --> Opened Oct 19 03:06:15 tazek mpd: [pptp] CCP: LayerUp Oct 19 03:06:15 tazek mpd: Compress using: mppc (MPPE(128 bits), stateless) Oct 19 03:06:15 tazek mpd: Decompress using: mppc (MPPE(128 bits), stateless) Oct 19 03:06:15 tazek mpd: [pptp] IPCP: rec'd Configure Request #1 (Req-Sent) Oct 19 03:06:15 tazek mpd: COMPPROTO VJCOMP, 16 comp. channels, allow comp-cid Oct 19 03:06:15 tazek mpd: IPADDR 172.16.30.42 Oct 19 03:06:15 tazek mpd: 172.16.30.42 is OK Oct 19 03:06:15 tazek mpd: [pptp] IPCP: SendConfigAck #1 Oct 19 03:06:15 tazek mpd: COMPPROTO VJCOMP, 16 comp. channels, allow comp-cid Oct 19 03:06:15 tazek mpd: IPADDR 172.16.30.42 Oct 19 03:06:15 tazek mpd: [pptp] IPCP: state change Req-Sent --> Ack-Sent Oct 19 03:06:16 tazek mpd: [pptp] IPCP: SendConfigReq #3 Oct 19 03:06:16 tazek mpd: IPADDR 192.168.1.2 Oct 19 03:06:16 tazek mpd: COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Oct 19 03:06:16 tazek mpd: [pptp] IPCP: rec'd Configure Nak #3 (Ack-Sent) Oct 19 03:06:16 tazek mpd: IPADDR ext_ip Oct 19 03:06:16 tazek mpd: ext_ip is OK Oct 19 03:06:16 tazek mpd: [pptp] IPCP: SendConfigReq #4 Oct 19 03:06:16 tazek mpd: IPADDR ext_ip Oct 19 03:06:16 tazek mpd: COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Oct 19 03:06:16 tazek mpd: [pptp] IPCP: rec'd Configure Ack #4 (Ack-Sent) Oct 19 03:06:16 tazek mpd: IPADDR ext_ip Oct 19 03:06:16 tazek mpd: COMPPROTO VJCOMP, 16 comp. channels, no comp-cid Oct 19 03:06:16 tazek mpd: [pptp] IPCP: state change Ack-Sent --> Opened Oct 19 03:06:16 tazek mpd: [pptp] IPCP: LayerUp Oct 19 03:06:16 tazek mpd: ext_ip -> 172.16.30.42 Oct 19 03:06:16 tazek mpd: [pptp] IFACE: Up event Oct 19 11:10:26 tazek mpd: [pptp] LCP: rec'd Protocol Reject #2 (Opened) Oct 19 11:10:26 tazek mpd: [pptp] LCP: protocol 0x000b was rejected Oct 19 11:10:26 tazek mpd: [pptp] LCP: rec'd Protocol Reject #3 (Opened) Oct 19 11:10:26 tazek mpd: [pptp] LCP: protocol 0xf679 was rejected Oct 19 11:10:26 tazek mpd: [pptp] LCP: rec'd Protocol Reject #4 (Opened) Oct 19 11:10:26 tazek mpd: [pptp] LCP: protocol 0x000f was rejected Oct 19 11:10:26 tazek mpd: [pptp] LCP: rec'd Protocol Reject #5 (Opened) Oct 19 11:10:26 tazek mpd: [pptp] LCP: protocol CRYPT was rejected Oct 19 11:10:26 tazek mpd: [pptp] ECP: protocol was rejected by peer Oct 19 11:10:26 tazek mpd: [pptp] ECP: Close event Oct 19 11:10:26 tazek mpd: [pptp] LCP: rec'd Protocol Reject #6 (Opened) Oct 19 11:10:26 tazek mpd: [pptp] LCP: protocol 0x009f was rejected Oct 19 11:10:26 tazek mpd: [pptp] LCP: rec'd Protocol Reject #7 (Opened) Oct 19 11:10:26 tazek mpd: [pptp] LCP: protocol 0x8683 was rejected Oct 19 11:10:26 tazek mpd: [pptp] LCP: rec'd Protocol Reject #8 (Opened) Oct 19 11:10:26 tazek mpd: [pptp] LCP: protocol 0x0073 was rejected Oct 19 11:10:26 tazek mpd: [pptp] LCP: rec'd Protocol Reject #9 (Opened) Oct 19 11:10:26 tazek mpd: [pptp] LCP: protocol 0x7e56 was rejected Oct 19 11:10:26 tazek mpd: [pptp] LCP: rec'd Protocol Reject #10 (Opened) Oct 19 11:10:26 tazek mpd: [pptp] LCP: protocol 0x56f2 was rejected Oct 19 11:10:26 tazek mpd: [pptp] LCP: rec'd Protocol Reject #11 (Opened) Oct 19 11:10:26 tazek mpd: [pptp] LCP: protocol 0x0047 was rejected No errors between up event and protocol rejects. Help... I've found this post http://lists.freebsd.org/pipermail/freebsd-stable/2003-June/001878.html but patch is for older ng_ppp.c and I do not speak C well enough to write code for kernel modules. Also, saw some other guys having same problems - but no solutions. Maybe community has something to say? Please include me in CC because I'm not subscribed to the list. Thanks