mpd - lcp protocol rejects

Fri Oct 24 12:39:02 PDT 2008

Hello,

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?

Thanks