CHAP requires that password storage keeps it in cleartext, so you cannot use CHAP with passwd file that contains hashes of passwords, not cleartext. PAP places no such restrictions and can be used with hashed storage like passwd file if you setup the software correctly.
mpd5 supports PAP and CHAP. With RADIUS, mpd5 just passes authorization task to the RADIUS server that is distinct software.
You need to consult documentation of your RADIUS server if it supports PAP+passwd. And how to configure it that way.
Use logs of mpd5 and of RADIUS server to debug an attempt to authorize. Also, you may find tcpdump handy to see what's going on at wire level.
If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
If you removed log file that was opened by syslogd and recreated the file, you need to inform syslogd about that, so it closes old file (so it can be really removed) and opens new file with command:
killall -1 syslogd
Open file is not really removed until all processes close it. Removing open file just unlinks its name from its directory.
Last edit: Eugene Grosbein 2020-06-24
If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
startup:
# configure mpd users
set user admin Secret admin
set global l2tptimeout
log +echo +iface +iface2 +link +auth +bund +lcp +lcp2 +phys +phys2 +radi
us +radius2
#set user foo1 bar1
# configure the console
set console self 127.0.0.1 5005
set console open
# configure the web server
set web self 0.0.0.0 5006
set web open
PAP won't be used If end-user system does not support it or not configured to use it. Also PAP may be not used if both of mpd5 and end-user are enabled to use both PAP and CHAP, so CHAP may be preferred.
Also, you should remove "pap" from the command "set link no pap chap eap"
If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
All right removing pap so that we have set link no chap eap we get from logs
Jun 25 08:23:40 doctor mpd[28435]: PPTP: Incoming control connection from 75.156.190.254 51408 to 204.209.81.1 1723
Jun 25 08:23:40 doctor mpd[28435]: pptp0: attached to connection with 75.156.190.254 51408
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] Accepting PPTP connection
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] Link: OPEN event
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] LCP: Open event
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] LCP: state change Initial --> Starting
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] LCP: LayerStart
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] device: OPEN event
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] PPTP: attaching to peer's outgoing call
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] device: UP event
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] Link: UP event
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] Link: origination is remote
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] LCP: Up event
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] LCP: state change Starting --> Req-Sent
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] LCP: phase shift DEAD --> ESTABLISH
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigReq #1
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0xda968882
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] MP MRRU 2048
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] MP SHORTSEQ
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] ENDPOINTDISC [802.1] 0c c4 7a ac 51 1e
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Request #0 (Req-Sent)
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] MRU 1400
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0x050f708f
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] CALLBACK 6
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigRej #0
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] CALLBACK 6
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Request #1 (Req-Sent)
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] MRU 1400
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0x050f708f
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigAck #1
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] MRU 1400
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0x050f708f
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] LCP: state change Req-Sent --> Ack-Sent
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigReq #2
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0xda968882
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MP MRRU 2048
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MP SHORTSEQ
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] ENDPOINTDISC [802.1] 0c c4 7a ac 51 1e
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Reject #2 (Ack-Sent)
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MP MRRU 2048
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MP SHORTSEQ
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] ENDPOINTDISC [802.1] 0c c4 7a ac 51 1e
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigReq #3
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0xda968882
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Nak #3 (Ack-Sent)
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO CHAP MSOFTv2
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigReq #4
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0xda968882
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Nak #4 (Ack-Sent)
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO CHAP MSOFTv2
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigReq #5
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0xda968882
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Nak #5 (Ack-Sent)
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO CHAP MSOFTv2
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigReq #6
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0xda968882
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Nak #6 (Ack-Sent)
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO CHAP MSOFTv2
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigReq #7
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0xda968882
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Nak #7 (Ack-Sent)
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO CHAP MSOFTv2
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigReq #8
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0xda968882
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Nak #8 (Ack-Sent)
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO CHAP MSOFTv2
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigReq #9
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0xda968882
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Nak #9 (Ack-Sent)
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO CHAP MSOFTv2
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigReq #10
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0xda968882
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Nak #10 (Ack-Sent)
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO CHAP MSOFTv2
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: not converging
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: parameter negotiation failed
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: state change Ack-Sent --> Stopped
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: LayerFinish
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] device: CLOSE event
Jun 25 08:23:42 doctor mpd[28435]: pptp0-0: clearing call
Jun 25 08:23:42 doctor mpd[28435]: pptp0-0: killing channel
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] PPTP call terminated
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] device: DOWN event
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] Link: DOWN event
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: Close event
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: state change Stopped --> Closed
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: Down event
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: state change Closed --> Initial
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: phase shift ESTABLISH --> DEAD
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] Link: SHUTDOWN event
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] Link: Shutdown
Jun 25 08:23:52 doctor mpd[28435]: pptp0: closing connection with 75.156.190.254 51408
Jun 25 08:23:52 doctor mpd[28435]: pptp0: killing connection with 75.156.190.254
Jun 25 08:23:52 doctor mpd[28435]: pptp0: killing connection with 75.156.190.254 51408
If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Nak #3 (Ack-Sent)
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO CHAP MSOFTv2
This is exactly what I was talking about earlier: end-user system insists on MS-CHAP2. It is not configured for PAP.
If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
All right question 1) , what do I need in my pf.conf to get the ng interfaces to work proerly?
2) Jun 25 10:44:18 doctor mpd[28435]: PPTP: Incoming control connection from 75.156
.190.254 53207 to 204.209.81.1 1723
Jun 25 10:44:18 doctor mpd[28435]: pptp0: attached to connection with 75.156.190
.254 53207
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] Accepting PPTP connection
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] Link: OPEN event
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] LCP: Open event
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] LCP: state change Initial --> Star
ting
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] LCP: LayerStart
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] device: OPEN event
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] PPTP: attaching to peer's outgoing
call
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] device: UP event
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] Link: UP event
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] Link: origination is remote
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] LCP: Up event
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] LCP: state change Starting --> Req
-Sent
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] LCP: phase shift DEAD --> ESTABLISH
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigReq #1
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0x12c94a15
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] MP MRRU 2048
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] MP SHORTSEQ
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] ENDPOINTDISC [802.1] 0c c4 7a ac 51 1e
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Request #0 (Req-Sent)
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] MRU 1400
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0x7b341ff6
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] CALLBACK 6
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigRej #0
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] CALLBACK 6
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Request #1 (Req-Sent)
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] MRU 1400
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0x7b341ff6
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigAck #1
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] MRU 1400
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0x7b341ff6
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] LCP: state change Req-Sent --> Ack-Sent
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigReq #2
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0x12c94a15
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MP MRRU 2048
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MP SHORTSEQ
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] ENDPOINTDISC [802.1] 0c c4 7a ac 51 1e
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Reject #2 (Ack-Sent)
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MP MRRU 2048
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MP SHORTSEQ
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] ENDPOINTDISC [802.1] 0c c4 7a ac 51 1e
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigReq #3
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0x12c94a15
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Ack #3 (Ack-Sent)
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0x12c94a15
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] LCP: state change Ack-Sent --> Opened
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] LCP: phase shift ESTABLISH --> AUTHENTICATE
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] LCP: auth: peer wants nothing, I want PAP
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] LCP: LayerUp
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Ident #2 (Opened)
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MESG: MSRASV5.20
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Ident #3 (Opened)
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MESG: MSRAS-0-DESKTOP-NLNAQH7
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Ident #4 (Opened)
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MESG: )<e4>R
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MESG: <a8><c2> AM-^<b8><cd><ca>0H<a4>M-^N
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] PAP: rec'd REQUEST #2 len: 17
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] AUTH: Trying RADIUS
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Authenticating user 'aboo'
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Adding server 204.209.81.1 1812
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_NAS_IDENTIFIER: doctor.nl2k.ab.ca
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_NAS_IP_ADDRESS: 204.209.81.1
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_NAS_IP_ADDRESS: 204.209.81.1
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put Message Authenticator
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_ACCT_SESSION_ID: 3103460-L_pptp-2
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_NAS_PORT: 2
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_NAS_PORT_TYPE: 5
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_SERVICE_TYPE: RAD_FRAMED
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_FRAMED_PROTOCOL: RAD_PPP
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_CALLING_STATION_ID:
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_CALLED_STATION_ID:
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_MPD_LINK: L_pptp-2
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_MPD_PEER_IDENT: MSRASV5.20 MSRAS-0-DESKTOP-NLNAQH7 )<e4>R^M<a8><c2> AM-^</c2></a8></e4></a4></ca></cd></b8><b8><cd><ca>0H<a4>M-^N
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_TUNNEL_TYPE: 1
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_TUNNEL_MEDIUM_TYPE: 1
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_TUNNEL_SERVER_ENDPOINT: 204.209.81.1
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_TUNNEL_CLIENT_ENDPOINT: 75.156.190.254
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_TUNNEL_SERVER_AUTH_ID: doctor.nl2k.ab.ca
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_USER_NAME: aboo
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_USER_PASSWORD
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Send request for user 'aboo'
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Sending request for user 'aboo'
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Rec'd RAD_ACCESS_REJECT for user 'aboo'
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] AUTH: RADIUS returned: failed
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] AUTH: Trying INTERNAL
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] AUTH: INTERNAL returned: undefined
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] PAP: Auth return status: undefined
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] PAP: Response is valid
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] PAP: Reply message: Welcome
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] PAP: sending ACK #2 len: 12
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] LCP: authorization successful
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] LCP: phase shift AUTHENTICATE --> NETWORK
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] Link: Matched action 'bundle "B_pptp" ""'
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] Creating new bundle using template "B_pptp".
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] Bundle: Interface ng0 created
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] Link: Join bundle "B_pptp-2"
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IFACE: setting ng0 MTU to 1400 bytes
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: Open event
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: state change Initial --> Starting
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: LayerStart
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] CCP: Open event
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] CCP: state change Initial --> Starting
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] CCP: LayerStart
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: Up event
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: Got IP 10.10.0.2 from pool "pool1" for peer
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: state change Starting --> Req-Sent
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: SendConfigReq #1
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPADDR 10.10.0.1
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] CCP: Up event
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] CCP: Protocol mppc disabled as useless for this setup
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] CCP: state change Starting --> Req-Sent
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] CCP: SendConfigReq #1
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Accounting user 'aboo' (Type: 1)
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] rec'd unexpected protocol IPV6CP, rejecting
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Adding server 204.209.81.1 1813
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: rec'd Configure Request #6 (Req-Sent)
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_NAS_IDENTIFIER: doctor.nl2k.ab.ca
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPADDR 204.209.81.1
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] NAKing with 10.10.0.2
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] PRIDNS 0.0.0.0
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] NAKing with 204.209.81.1
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] PRINBNS 0.0.0.0
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] SECDNS 0.0.0.0
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] SECNBNS 0.0.0.0
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: SendConfigRej #6
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] PRINBNS 0.0.0.0
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] SECDNS 0.0.0.0
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] SECNBNS 0.0.0.0
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_NAS_IP_ADDRESS: 0.0.0.0
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_ACCT_SESSION_ID: 3103460-L_pptp-2
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_NAS_PORT: 2
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_NAS_PORT_TYPE: 5
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_SERVICE_TYPE: RAD_FRAMED
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_FRAMED_PROTOCOL: RAD_PPP
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_CALLING_STATION_ID:
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_CALLED_STATION_ID:
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_MPD_LINK: L_pptp-2
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_MPD_PEER_IDENT: MSRASV5.20 MSRAS-0-DESKTOP-NLNAQH7 )<e4>R^M<a8><c2> AM-^<b8><cd><ca>0H<a4>M-^N
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_TUNNEL_TYPE: 1
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_TUNNEL_MEDIUM_TYPE: 1
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_TUNNEL_SERVER_ENDPOINT: 204.209.81.1
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_TUNNEL_CLIENT_ENDPOINT: 75.156.190.254
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_TUNNEL_SERVER_AUTH_ID: doctor.nl2k.ab.ca
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_ACCT_STATUS_TYPE: RAD_START
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_FRAMED_IP_ADDRESS: 10.10.0.2
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_FRAMED_IPV6_ADDRESS: ::
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_USER_NAME: aboo
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_ACCT_MULTI_SESSION_ID: 3103461-B_pptp-2
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_MPD_BUNDLE: B_pptp-2
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_MPD_IFACE: ng0
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_MPD_IFACE_INDEX: 23
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_MPD_PEER_IDENT: MSRASV5.20 MSRAS-0-DESKTOP-NLNAQH7 )<e4>R^M<a8><c2> AM-^</c2></a8></e4></a4></ca></cd></b8><b8><cd><ca>0H<a4>M-^N
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_ACCT_LINK_COUNT: 1
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_ACCT_AUTHENTIC: 2
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Send request for user 'aboo'
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: rec'd Configure Reject #1 (Req-Sent)
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: SendConfigReq #2
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPADDR 10.10.0.1
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Protocol Reject #7 (Opened)
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] LCP: protocol CCP was rejected
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] CCP: protocol was rejected by peer
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] CCP: state change Req-Sent --> Stopped
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] CCP: LayerFinish
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: rec'd Configure Request #8 (Req-Sent)
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPADDR 0.0.0.0
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] NAKing with 10.10.0.2
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] PRIDNS 0.0.0.0
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] NAKing with 204.209.81.1
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: SendConfigNak #8
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPADDR 10.10.0.2
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] PRIDNS 204.209.81.1
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: rec'd Configure Ack #2 (Req-Sent)
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPADDR 10.10.0.1
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: state change Req-Sent --> Ack-Rcvd
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: rec'd Configure Request #9 (Ack-Rcvd)
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPADDR 10.10.0.2
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] 10.10.0.2 is OK
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] PRIDNS 204.209.81.1
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: SendConfigAck #9</a4></ca></cd></b8></c2></a8></e4></a4></ca></cd></b8></c2></a8></e4>
If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
This is not full log. Following lines should contain "IPCP: LayerUp" and "IFACE: Up event". If they do, you should have ng0 interface up and running.
PPtP uses TCP port 1723 for controlling connection and GRE (IP protocol 47) for user data. It's not uncommon for Internet Service Providers (ISP) to filter GRE protocol, so if your users connect via public Internet, they may find PPtP unusable.
L2TP uses UDP port 1701 only and should pass through any modern ISP without problems, so you may find it preferable.
If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
root@doctor:~ # tail /var/log/mpd.log[4h-[4l[4hf[4l[4h [4lJun 26 22:05:30 doctor mpd[4846]: [L_pptp-5] ACCT: wtmp L_pptp-5 logout
Jun 26 22:05:30 doctor mpd[4846]: [L_pptp-5] Link: SHUTDOWN event
Jun 26 22:05:30 doctor mpd[4846]: [L_pptp-5] Link: Shutdown
Jun 26 22:05:37 doctor mpd[4846]: pptp0: closing connection with 75.156.190.254 49955
Jun 26 22:05:40 doctor mpd[4846]: pptp0: no reply to StopCtrlConnRequest after 3 sec
Jun 26 22:05:40 doctor mpd[4846]: pptp0: killing connection with 75.156.190.254 49955
Jun 26 22:07:50 doctor mpd[4846]: PPTP: Incoming control connection from 139.162.102.46 56604 to 204.209.81.1 1723
Jun 26 22:07:50 doctor mpd[4846]: pptp0: attached to connection with 139.162.102.46 56604
Jun 26 22:07:50 doctor mpd[4846]: pptp0: read: Connection reset by peer
Jun 26 22:07:50 doctor mpd[4846]: pptp0: killing connection with 139.162.102.46 56604
Jun 26 22:12:35 doctor mpd[4846]: PPTP: Incoming control connection from 75.156.190.254 50078 to 204.209.81.1 1723
Jun 26 22:12:35 doctor mpd[4846]: pptp0: attached to connection with 75.156.190.254 50078
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] Accepting PPTP connection
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] Link: OPEN event
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] LCP: Open event
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] LCP: state change Initial --> Starting
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] LCP: LayerStart
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] device: OPEN event
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] PPTP: attaching to peer's outgoing call
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] device: UP event
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] Link: UP event
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] Link: origination is remote
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] LCP: Up event
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] LCP: state change Starting --> Req-Sent
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] LCP: phase shift DEAD --> ESTABLISH
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] LCP: SendConfigReq #1
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] ACFCOMP
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] PROTOCOMP
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] MRU 1500
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] MAGICNUM 0xd811fe08
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] AUTHPROTO PAP
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] MP MRRU 2048
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] MP SHORTSEQ
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] ENDPOINTDISC [802.1] 0c c4 7a ac 51 1e
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] LCP: rec'd Configure Request #0 (Req-Sent)
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] MRU 1400
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] MAGICNUM 0x2f1d61d7
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] PROTOCOMP
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] ACFCOMP
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] CALLBACK 6
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] LCP: SendConfigRej #0
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] CALLBACK 6
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] LCP: rec'd Configure Request #1 (Req-Sent)
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] MRU 1400
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] MAGICNUM 0x2f1d61d7
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] PROTOCOMP
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] ACFCOMP
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] LCP: SendConfigAck #1
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] MRU 1400
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] MAGICNUM 0x2f1d61d7
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] PROTOCOMP
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] ACFCOMP
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] LCP: state change Req-Sent --> Ack-Sent
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] LCP: SendConfigReq #2
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] ACFCOMP
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] PROTOCOMP
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] MRU 1500
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] MAGICNUM 0xd811fe08
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] AUTHPROTO PAP
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] MP MRRU 2048
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] MP SHORTSEQ
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] ENDPOINTDISC [802.1] 0c c4 7a ac 51 1e
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] LCP: rec'd Configure Reject #2 (Ack-Sent)
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] MP MRRU 2048
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] MP SHORTSEQ
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] ENDPOINTDISC [802.1] 0c c4 7a ac 51 1e
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] LCP: SendConfigReq #3
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] ACFCOMP
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] PROTOCOMP
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] MRU 1500
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] MAGICNUM 0xd811fe08
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] AUTHPROTO PAP
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] LCP: rec'd Configure Ack #3 (Ack-Sent)
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] ACFCOMP
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] PROTOCOMP
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] MRU 1500
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] MAGICNUM 0xd811fe08
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] AUTHPROTO PAP
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] LCP: state change Ack-Sent --> Opened
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] LCP: phase shift ESTABLISH --> AUTHENTICATE
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] LCP: auth: peer wants nothing, I want PAP
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] LCP: LayerUp
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] LCP: rec'd Ident #2 (Opened)
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] MESG: MSRASV5.20
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] LCP: rec'd Ident #3 (Opened)
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] MESG: MSRAS-0-DESKTOP-NLNAQH7
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] LCP: rec'd Ident #4 (Opened)
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] MESG: ÿjb^[ïM-^MCJM-^U^]?Zõ^^^Â
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] PAP: rec'd REQUEST #2 len: 17
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] AUTH: Trying RADIUS
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Authenticating user 'aboo'
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Adding server 204.209.81.1 1645
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_NAS_IDENTIFIER: doctor.nl2k.ab.ca
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_NAS_IP_ADDRESS: 204.209.81.1
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put Message Authenticator
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_SESSION_ID: 3231157-L_pptp-5
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_NAS_PORT: 5
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_NAS_PORT_TYPE: 5
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_SERVICE_TYPE: RAD_FRAMED
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_FRAMED_PROTOCOL: RAD_PPP
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_CALLING_STATION_ID:
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_CALLED_STATION_ID:
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_LINK: L_pptp-5
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_PEER_IDENT: MSRASV5.20 MSRAS-0-DESKTOP-NLNAQH7 ÿjb^[ïM-^MCJM-^U^]?Zõ^^^Â
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_TYPE: 1
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_MEDIUM_TYPE: 1
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_SERVER_ENDPOINT: 204.209.81.1
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_CLIENT_ENDPOINT: 75.156.190.254
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_SERVER_AUTH_ID: doctor.nl2k.ab.ca
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_USER_NAME: aboo
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_USER_PASSWORD
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Send request for user 'aboo'
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Sending request for user 'aboo'
Jun 26 22:12:37 doctor syslogd: last message repeated 1 times
Jun 26 22:12:39 doctor mpd[4846]: [L_pptp-5] PAP: rec'd REQUEST #3 len: 17
Jun 26 22:12:39 doctor mpd[4846]: [L_pptp-5] PAP: Auth return status: busy
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Sending request for user 'aboo'
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: rad_send_request for user 'aboo' failed: No valid RADIUS responses received
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] AUTH: RADIUS returned error
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] AUTH: Trying INTERNAL
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] AUTH: INTERNAL returned: undefined
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] PAP: Auth return status: undefined
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] PAP: Response is valid
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] PAP: Reply message: Welcome
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] PAP: sending ACK #2 len: 12
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] LCP: authorization successful
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] LCP: phase shift AUTHENTICATE --> NETWORK
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] Link: Matched action 'bundle "B_pptp" ""'
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] Creating new bundle using template "B_pptp".
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] Bundle: Interface ng0 created
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] Link: Join bundle "B_pptp-3"
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IFACE: setting ng0 MTU to 1400 bytes
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: Open event
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: state change Initial --> Starting
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: LayerStart
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] CCP: Open event
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] CCP: state change Initial --> Starting
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] CCP: LayerStart
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: Up event
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: Got IP 10.10.0.2 from pool "pool1" for peer
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: state change Starting --> Req-Sent
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: SendConfigReq #1
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPADDR 10.10.0.1
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] CCP: Up event
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] CCP: Protocol mppc disabled as useless for this setup
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] CCP: state change Starting --> Req-Sent
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] CCP: SendConfigReq #1
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Accounting user 'aboo' (Type: 1)
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Adding server 204.209.81.1 1646
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_NAS_IDENTIFIER: doctor.nl2k.ab.ca
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_NAS_IP_ADDRESS: 204.209.81.1
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_SESSION_ID: 3231157-L_pptp-5
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_NAS_PORT: 5
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_NAS_PORT_TYPE: 5
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_SERVICE_TYPE: RAD_FRAMED
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_FRAMED_PROTOCOL: RAD_PPP
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_CALLING_STATION_ID:
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_CALLED_STATION_ID:
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_LINK: L_pptp-5
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_PEER_IDENT: MSRASV5.20 MSRAS-0-DESKTOP-NLNAQH7 ÿjb^[ïM-^MCJM-^U^]?Zõ^^^Â
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_TYPE: 1
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_MEDIUM_TYPE: 1
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_SERVER_ENDPOINT: 204.209.81.1
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_CLIENT_ENDPOINT: 75.156.190.254
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_SERVER_AUTH_ID: doctor.nl2k.ab.ca
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_STATUS_TYPE: RAD_START
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_FRAMED_IP_ADDRESS: 10.10.0.2
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_FRAMED_IPV6_ADDRESS: ::
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_USER_NAME: aboo
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_MULTI_SESSION_ID: 3231160-B_pptp-3
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_BUNDLE: B_pptp-3
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_IFACE: ng0
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_IFACE_INDEX: 23
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_PEER_IDENT: MSRASV5.20 MSRAS-0-DESKTOP-NLNAQH7 ÿjb^[ïM-^MCJM-^U^]?Zõ^^^Â
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_LINK_COUNT: 1
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_AUTHENTIC: 2
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Send request for user 'aboo'
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Sending request for user 'aboo'
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] rec'd unexpected protocol IPV6CP, rejecting
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: rec'd Configure Request #6 (Req-Sent)
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPADDR 0.0.0.0
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] NAKing with 10.10.0.2
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] PRIDNS 0.0.0.0
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] NAKing with 204.209.81.1
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] PRINBNS 0.0.0.0
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] SECDNS 0.0.0.0
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] SECNBNS 0.0.0.0
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: SendConfigRej #6
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] PRINBNS 0.0.0.0
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] SECDNS 0.0.0.0
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] SECNBNS 0.0.0.0
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: rec'd Configure Reject #1 (Req-Sent)
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: SendConfigReq #2
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPADDR 10.10.0.1
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] LCP: rec'd Protocol Reject #7 (Opened)
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] LCP: protocol CCP was rejected
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] CCP: protocol was rejected by peer
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] CCP: state change Req-Sent --> Stopped
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] CCP: LayerFinish
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Sending request for user 'aboo'
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: rec'd Configure Request #8 (Req-Sent)
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPADDR 0.0.0.0
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] NAKing with 10.10.0.2
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] PRIDNS 0.0.0.0
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] NAKing with 204.209.81.1
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: SendConfigNak #8
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPADDR 10.10.0.2
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] PRIDNS 204.209.81.1
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: rec'd Configure Ack #2 (Req-Sent)
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPADDR 10.10.0.1
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: state change Req-Sent --> Ack-Rcvd
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: rec'd Configure Request #9 (Ack-Rcvd)
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPADDR 10.10.0.2
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] 10.10.0.2 is OK
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] PRIDNS 204.209.81.1
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: SendConfigAck #9
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPADDR 10.10.0.2
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] PRIDNS 204.209.81.1
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: state change Ack-Rcvd --> Opened
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: LayerUp
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] 10.10.0.1 -> 10.10.0.2
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IFACE: Connecting tcpmssfix
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IFACE: Configuring ng_tcpmss mpd4846-B_pptp-3-mss: 1360
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IFACE: Add address 10.10.0.1/32->10.10.0.2 to ng0
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IFACE: No interface to proxy arp on for 10.10.0.2
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IFACE: Up event
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IFACE: idle-timeout: 1800 seconds
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IFACE: setting "ng0" description to "pptp: aboo 10.10.0.2 204.209.81.1 75.156.190.254"
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IFACE: Add description "pptp: aboo 10.10.0.2 204.209.81.1 75.156.190.254"
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IFACE: Change interface ng0 flags: -0 +1
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Sending request for user 'aboo'
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: rad_send_request for user 'aboo' failed: No valid RADIUS responses received
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] ACCT: wtmp L_pptp-5 aboo 75.156.190.254 login
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] ACCT: Close link due to accounting start error
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] ACCT: Link close requested by the accounting
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] Link: CLOSE event
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] LCP: Close event
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] LCP: state change Opened --> Closing
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] LCP: phase shift NETWORK --> TERMINATE
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] Link: Leave bundle "B_pptp-3"
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Accounting user 'aboo' (Type: 2)
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IFACE: setting ng0 MTU to 1500 bytes
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Adding server 204.209.81.1 1646
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_NAS_IDENTIFIER: doctor.nl2k.ab.ca
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IFACE: Configuring ng_tcpmss mpd4846-B_pptp-3-mss: 1460
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_NAS_IP_ADDRESS: 204.209.81.1
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_SESSION_ID: 3231157-L_pptp-5
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_NAS_PORT: 5
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_NAS_PORT_TYPE: 5
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_SERVICE_TYPE: RAD_FRAMED
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_FRAMED_PROTOCOL: RAD_PPP
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_CALLING_STATION_ID:
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_CALLED_STATION_ID:
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_LINK: L_pptp-5
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_PEER_IDENT: MSRASV5.20 MSRAS-0-DESKTOP-NLNAQH7 ÿjb^[ïM-^MCJM-^U^]?Zõ^^^Â
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_TYPE: 1
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_MEDIUM_TYPE: 1
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_SERVER_ENDPOINT: 204.209.81.1
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_CLIENT_ENDPOINT: 75.156.190.254
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_SERVER_AUTH_ID: doctor.nl2k.ab.ca
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_FRAMED_IP_ADDRESS: 10.10.0.2
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_FRAMED_IPV6_ADDRESS: ::
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_USER_NAME: aboo
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_MULTI_SESSION_ID: 3231160-B_pptp-3
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_BUNDLE: B_pptp-3
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_IFACE: ng0
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_IFACE_INDEX: 23
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_PEER_IDENT: MSRASV5.20 MSRAS-0-DESKTOP-NLNAQH7 ÿjb^[ïM-^MCJM-^U^]?Zõ^^^Â
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_LINK_COUNT: 1
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_AUTHENTIC: 2
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_STATUS_TYPE: RAD_STOP
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_TERMINATE_CAUSE: Manual, RADIUS: 6
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_SESSION_TIME: 8
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_INPUT_OCTETS: 9928
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_INPUT_GIGAWORDS: 0
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_INPUT_PACKETS: 91
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_OUTPUT_OCTETS: 8743
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_OUTPUT_GIGAWORDS: 0
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_OUTPUT_PACKETS: 87
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Send request for user 'aboo'
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] Bundle: Status update: up 0 links, total bandwidth 9600 bps
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IPCP: Close event
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IPCP: state change Opened --> Closing
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IPCP: SendTerminateReq #3
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IPCP: LayerDown
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IFACE: Remove address 10.10.0.1/32->10.10.0.2 from ng0
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IFACE: Down event
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IFACE: Change interface ng0 flags: -4097 +0
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IFACE: setting "ng0" description to "-: aboo UNSPEC - -"
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IFACE: Set description "-: aboo UNSPEC - -"
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] CCP: Close event
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] CCP: state change Stopped --> Closed
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IPCP: Down event
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IPCP: LayerFinish
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] Bundle: No NCPs left. Closing links...
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IPCP: state change Closing --> Initial
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] CCP: Down event
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] CCP: state change Closed --> Initial
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] Bundle: Shutdown
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Sending request for user 'aboo'
Jun 26 22:12:43 doctor syslogd: last message repeated 1 times
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] LCP: SendTerminateReq #4
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] LCP: LayerDown
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] rec'd proto IP during terminate phase
Jun 26 22:12:43 doctor syslogd: last message repeated 4 times
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] LCP: rec'd Terminate Ack #4 (Closing)
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] LCP: state change Closing --> Closed
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] LCP: phase shift TERMINATE --> ESTABLISH
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] LCP: LayerFinish
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] device: CLOSE event
Jun 26 22:12:43 doctor mpd[4846]: pptp0-0: clearing call
Jun 26 22:12:43 doctor mpd[4846]: pptp0-0: killing channel
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] PPTP call terminated
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] device: DOWN event
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] Link: DOWN event
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] LCP: Down event
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] LCP: state change Closed --> Initial
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] LCP: phase shift ESTABLISH --> DEAD
Jun 26 22:12:45 doctor mpd[4846]: pptp0: CID 0xc39e in CallClearRequest not found
Jun 26 22:12:46 doctor mpd[4846]: pptp0: got StopCtrlConnRequest: reason=none
Jun 26 22:12:46 doctor mpd[4846]: pptp0: killing connection with 75.156.190.254 50078
Jun 26 22:12:46 doctor mpd[4846]: [L_pptp-5] RADIUS: Sending request for user 'aboo'
Jun 26 22:12:46 doctor mpd[4846]: [L_pptp-5] RADIUS: rad_send_request for user 'aboo' failed: No valid RADIUS responses received
Jun 26 22:12:46 doctor mpd[4846]: [L_pptp-5] ACCT: wtmp L_pptp-5 logout
Jun 26 22:12:46 doctor mpd[4846]: [L_pptp-5] Link: SHUTDOWN event
Jun 26 22:12:46 doctor mpd[4846]: [L_pptp-5] Link: Shutdown
If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: rad_send_request for user 'aboo' failed: No valid RADIUS responses received
Your RADIUS server does not respond, but user is found in mpd.secret, so authorization succeeded locally. However, you also enabled RADIUS accounting and mpd5 disconnects uses just after successfull connection because RADIUS server did not acknowledge accounting.
mpd5 will not serve users until you fix connectivity with RADIUS server or disable accounting.
If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
Jun 27 07:29:53 doctor mpd[50059]: PPTP: Incoming control connection from 75.156.190.254 51937 to 204.209.81.1 1723
Jun 27 07:29:53 doctor mpd[50059]: pptp0: attached to connection with 75.156.190.254 51937
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] Accepting PPTP connection
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] Link: OPEN event
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] LCP: Open event
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] LCP: state change Initial --> Starting
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] LCP: LayerStart
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] device: OPEN event
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] PPTP: attaching to peer's outgoing call
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] device: UP event
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] Link: UP event
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] Link: origination is remote
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] LCP: Up event
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] LCP: state change Starting --> Req-Sent
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] LCP: phase shift DEAD --> ESTABLISH
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] LCP: SendConfigReq #1
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] ACFCOMP
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] PROTOCOMP
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] MRU 1500
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] MAGICNUM 0xadde165e
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] AUTHPROTO PAP
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] MP MRRU 2048
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] MP SHORTSEQ
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] ENDPOINTDISC [802.1] 0c c4 7a ac 51 1e
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] LCP: rec'd Configure Request #0 (Req-Sent)
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] MRU 1400
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] MAGICNUM 0x762a7976
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] PROTOCOMP
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] ACFCOMP
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] CALLBACK 6
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] LCP: SendConfigRej #0
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] CALLBACK 6
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] LCP: rec'd Configure Request #1 (Req-Sent)
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] MRU 1400
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] MAGICNUM 0x762a7976
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] PROTOCOMP
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] ACFCOMP
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] LCP: SendConfigAck #1
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] MRU 1400
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] MAGICNUM 0x762a7976
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] PROTOCOMP
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] ACFCOMP
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] LCP: state change Req-Sent --> Ack-Sent
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] LCP: SendConfigReq #2
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] ACFCOMP
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] PROTOCOMP
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] MRU 1500
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] MAGICNUM 0xadde165e
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] AUTHPROTO PAP
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] MP MRRU 2048
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] MP SHORTSEQ
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] ENDPOINTDISC [802.1] 0c c4 7a ac 51 1e
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] LCP: rec'd Configure Reject #2 (Ack-Sent)
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] MP MRRU 2048
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] MP SHORTSEQ
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] ENDPOINTDISC [802.1] 0c c4 7a ac 51 1e
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] LCP: SendConfigReq #3
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] ACFCOMP
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] PROTOCOMP
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] MRU 1500
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] MAGICNUM 0xadde165e
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] AUTHPROTO PAP
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] LCP: rec'd Configure Ack #3 (Ack-Sent)
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] ACFCOMP
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] PROTOCOMP
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] MRU 1500
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] MAGICNUM 0xadde165e
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] AUTHPROTO PAP
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] LCP: state change Ack-Sent --> Opened
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] LCP: phase shift ESTABLISH --> AUTHENTICATE
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] LCP: auth: peer wants nothing, I want PAP
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] LCP: LayerUp
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] LCP: rec'd Ident #2 (Opened)
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] MESG: MSRASV5.20
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] LCP: rec'd Ident #3 (Opened)
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] MESG: MSRAS-0-DESKTOP-NLNAQH7
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] LCP: rec'd Ident #4 (Opened)
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] MESG: AáM-^ASÏð¢G¥ÊVź.ºf
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] PAP: rec'd REQUEST #0 len: 17
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] AUTH: Trying RADIUS
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Authenticating user 'aboo'
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Adding server 204.209.81.1 1645
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_NAS_IDENTIFIER: doctor.nl2k.ab.ca
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_NAS_IP_ADDRESS: 204.209.81.1
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put Message Authenticator
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_ACCT_SESSION_ID: 3264596-L_pptp-5
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_NAS_PORT: 5
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_NAS_PORT_TYPE: 5
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_SERVICE_TYPE: RAD_FRAMED
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_FRAMED_PROTOCOL: RAD_PPP
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_CALLING_STATION_ID:
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_CALLED_STATION_ID:
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_MPD_LINK: L_pptp-5
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_MPD_PEER_IDENT: MSRASV5.20 MSRAS-0-DESKTOP-NLNAQH7 AáM-^ASÏð¢G¥ÊVź.ºf
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_TYPE: 1
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_MEDIUM_TYPE: 1
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_SERVER_ENDPOINT: 204.209.81.1
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_CLIENT_ENDPOINT: 75.156.190.254
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_SERVER_AUTH_ID: doctor.nl2k.ab.ca
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_USER_NAME: aboo
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_USER_PASSWORD
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Send request for user 'aboo'
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Sending request for user 'aboo'
Jun 27 07:29:56 doctor syslogd: last message repeated 1 times
Jun 27 07:29:58 doctor mpd[50059]: [L_pptp-5] PAP: rec'd REQUEST #1 len: 17
Jun 27 07:29:58 doctor mpd[50059]: [L_pptp-5] PAP: Auth return status: busy
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] RADIUS: Sending request for user 'aboo'
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] RADIUS: rad_send_request for user 'aboo' failed: No valid RADIUS responses received
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] AUTH: RADIUS returned error
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] AUTH: Trying INTERNAL
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] AUTH: INTERNAL returned: undefined
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] PAP: Auth return status: undefined
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] PAP: Response is valid
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] PAP: Reply message: Welcome
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] PAP: sending ACK #0 len: 12
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] LCP: authorization successful
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] LCP: phase shift AUTHENTICATE --> NETWORK
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] Link: Matched action 'bundle "B_pptp" ""'
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] Creating new bundle using template "B_pptp".
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] Bundle: Interface ng0 created
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] Link: Join bundle "B_pptp-3"
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IFACE: setting ng0 MTU to 1400 bytes
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: Open event
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: state change Initial --> Starting
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: LayerStart
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] CCP: Open event
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] CCP: state change Initial --> Starting
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] CCP: LayerStart
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: Up event
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: Got IP 10.10.0.2 from pool "pool1" for peer
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: state change Starting --> Req-Sent
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: SendConfigReq #1
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPADDR 10.10.0.1
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] CCP: Up event
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] CCP: Protocol mppc disabled as useless for this setup
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] CCP: state change Starting --> Req-Sent
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] CCP: SendConfigReq #1
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] ACCT: wtmp L_pptp-5 aboo 75.156.190.254 login
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] rec'd unexpected protocol IPV6CP, rejecting
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: rec'd Configure Request #6 (Req-Sent)
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPADDR 0.0.0.0
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] NAKing with 10.10.0.2
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] PRIDNS 0.0.0.0
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] NAKing with 204.209.81.1
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] PRINBNS 0.0.0.0
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] SECDNS 0.0.0.0
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] SECNBNS 0.0.0.0
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: SendConfigRej #6
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] PRINBNS 0.0.0.0
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] SECDNS 0.0.0.0
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] SECNBNS 0.0.0.0
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: rec'd Configure Reject #1 (Req-Sent)
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: SendConfigReq #2
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPADDR 10.10.0.1
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] LCP: rec'd Protocol Reject #7 (Opened)
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] LCP: protocol CCP was rejected
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] CCP: protocol was rejected by peer
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] CCP: state change Req-Sent --> Stopped
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] CCP: LayerFinish
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: rec'd Configure Request #8 (Req-Sent)
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPADDR 0.0.0.0
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] NAKing with 10.10.0.2
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] PRIDNS 0.0.0.0
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] NAKing with 204.209.81.1
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: SendConfigNak #8
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPADDR 10.10.0.2
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] PRIDNS 204.209.81.1
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: rec'd Configure Ack #2 (Req-Sent)
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPADDR 10.10.0.1
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: state change Req-Sent --> Ack-Rcvd
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: rec'd Configure Request #9 (Ack-Rcvd)
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPADDR 10.10.0.2
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] 10.10.0.2 is OK
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] PRIDNS 204.209.81.1
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: SendConfigAck #9
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPADDR 10.10.0.2
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] PRIDNS 204.209.81.1
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: state change Ack-Rcvd --> Opened
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: LayerUp
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] 10.10.0.1 -> 10.10.0.2
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IFACE: Connecting tcpmssfix
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IFACE: Configuring ng_tcpmss mpd50059-B_pptp-3-mss: 1360
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IFACE: Add address 10.10.0.1/32->10.10.0.2 to ng0
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IFACE: No interface to proxy arp on for 10.10.0.2
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IFACE: Up event
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IFACE: idle-timeout: 1800 seconds
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IFACE: setting "ng0" description to "pptp: aboo 10.10.0.2 204.209.81.1 75.156.190.254"
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IFACE: Add description "pptp: aboo 10.10.0.2 204.209.81.1 75.156.190.254"
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IFACE: Change interface ng0 flags: -0 +1
Jun 27 07:32:06 doctor mpd[50059]: [L_pptp-5] LCP: rec'd Terminate Request #10 (Opened)
Jun 27 07:32:06 doctor mpd[50059]: [L_pptp-5] LCP: state change Opened --> Stopping
Jun 27 07:32:06 doctor mpd[50059]: [L_pptp-5] LCP: phase shift NETWORK --> TERMINATE
Jun 27 07:32:06 doctor mpd[50059]: [L_pptp-5] Link: Leave bundle "B_pptp-3"
Jun 27 07:32:06 doctor mpd[50059]: [L_pptp-5] ACCT: wtmp L_pptp-5 logout
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IFACE: setting ng0 MTU to 1500 bytes
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IFACE: Configuring ng_tcpmss mpd50059-B_pptp-3-mss: 1460
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] Bundle: Status update: up 0 links, total bandwidth 9600 bps
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IPCP: Close event
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IPCP: state change Opened --> Closing
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IPCP: SendTerminateReq #3
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IPCP: LayerDown
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IFACE: Remove address 10.10.0.1/32->10.10.0.2 from ng0
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IFACE: Down event
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IFACE: Change interface ng0 flags: -4097 +0
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IFACE: setting "ng0" description to "-: aboo UNSPEC - -"
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IFACE: Set description "-: aboo UNSPEC - -"
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] CCP: Close event
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] CCP: state change Stopped --> Closed
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IPCP: Down event
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IPCP: LayerFinish
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] Bundle: No NCPs left. Closing links...
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IPCP: state change Closing --> Initial
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] CCP: Down event
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] CCP: state change Closed --> Initial
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] Bundle: Shutdown
Jun 27 07:32:06 doctor mpd[50059]: [L_pptp-5] LCP: SendTerminateAck #4
Jun 27 07:32:06 doctor mpd[50059]: [L_pptp-5] LCP: LayerDown
Jun 27 07:32:06 doctor mpd[50059]: [L_pptp-5] rec'd proto IP during terminate phase
Jun 27 07:32:08 doctor syslogd: last message repeated 5 times
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] LCP: state change Stopping --> Stopped
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] LCP: phase shift TERMINATE --> ESTABLISH
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] LCP: LayerFinish
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] device: CLOSE event
Jun 27 07:32:08 doctor mpd[50059]: pptp0-0: clearing call
Jun 27 07:32:08 doctor mpd[50059]: pptp0-0: killing channel
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] PPTP call terminated
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] device: DOWN event
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] Link: DOWN event
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] LCP: Close event
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] LCP: state change Stopped --> Closed
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] LCP: Down event
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] LCP: state change Closed --> Initial
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] LCP: phase shift ESTABLISH --> DEAD
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] Link: SHUTDOWN event
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] Link: Shutdown
Jun 27 07:32:18 doctor mpd[50059]: pptp0: closing connection with 75.156.190.254 51937
Jun 27 07:32:21 doctor mpd[50059]: pptp0: no reply to StopCtrlConnRequest after 3 sec
Jun 27 07:32:21 doctor mpd[50059]: pptp0: killing connection with 75.156.190.254 51937
I terminated the other since since no web pages were materialising.
If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
I terminated the other since since no web pages were materialising.
You seem to disable RADIUS accounting because your RADIUS server still does not respond but mpd5 establishes and keeps connection just fine.
As to web pages access, you'll need to debug it apart of mpd5 configuration. You use private IP addresses for ngX interfaces, so you need to check if you configured NAT correctly; if tcpdump shows requests on ng0 client interface (maybe client does not route requests to this connection) etc.
Also make sure you enabled routing in the /etc/rc.conf with gateway_enable="YES" or else you won't get any packet forwarding by the kernel.
Last edit: Eugene Grosbein 2020-06-27
If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
gateway_enable (not enabled). You should verify if "sysctl net.inet.ip.forwarding" prints 1 and not 0.
As for pf, consult its manual. pf configuration is not related to mpd5.
Make sure that client connection is configured to use it as default gateway. Run ping from the client box against some external IP like 8.8.8.8 and use tcpdump -pni ng0 to see if client actually directs such request to the connection to mpd5. Next, use tcpdump at external interface to see if the request actually forwarded by the kernel. Check that its source address is translated correctly, too.
Last edit: Eugene Grosbein 2020-06-27
If you would like to refer to this comment somewhere else in this project, copy and paste the following link:
question: how do you debug if PAP is working?
Something odd just happened. I needed to erase the log file and create a new one, but no debugging information is occuring. How can this be fixed?
If you removed log file that was opened by syslogd and recreated the file, you need to inform syslogd about that, so it closes old file (so it can be really removed) and opens new file with command:
killall -1 syslogd
Open file is not really removed until all processes close it. Removing open file just unlinks its name from its directory.
Last edit: Eugene Grosbein 2020-06-24
I can confirm that PAP+passwd is supported.
Last edit: Dave Yadallee 2020-06-24
I am using tcpdump
Just debugging and PAP is not getting calling, just CHAP and the radius server cannot find and Auth-type for CHAP.
better just update the config file for context:
startup:
# configure mpd users
set user admin Secret admin
set global l2tptimeout
log +echo +iface +iface2 +link +auth +bund +lcp +lcp2 +phys +phys2 +radi
us +radius2
#set user foo1 bar1
# configure the console
set console self 127.0.0.1 5005
set console open
# configure the web server
set web self 0.0.0.0 5006
set web open
default:
#load radius
load l2tp_server
load pptp_server
pptp_server:
Define dynamic IP address pool.
Create clonable bundle template named B
Specify IP address pool for dynamic assigment.
The five lines below enable Microsoft Point-to-Point encryption
(MPPE) using the ng_mppc(8) netgraph node type.
Create clonable link template named L
Set bundle template to use
Multilink adds some overhead, but gives full 1500 MTU.
We can use use RADIUS authentication/accounting by including
another config section with label 'radius'.
Enable utmp/wtmp logging
We reducing link mtu to avoid GRE packet fragmentation.
Configure PPTP
Allow to accept calls
l2tp_server:
Define dynamic IP address pool.
Create clonable bundle template named B
Specify IP address pool for dynamic assigment.
Create clonable link template named L
We can use use RADIUS authentication/accounting by including
another config section with label 'radius'.
Enable utmp/wtmp logging
Configure L2TP
Allow to accept calls
radius:
You can use radius.conf(5), its useful, because you can share the
same config with userland-ppp and other apps.
or specify the server directly here
send the given IP in the RAD_NAS_IP_ADDRESS attribute to the server.
send accounting updates every 5 minutes
enable RADIUS, and fallback to mpd.secret, if RADIUS auth failed
enable RADIUS accounting
protect our requests with the message-authenticator
PAP won't be used If end-user system does not support it or not configured to use it. Also PAP may be not used if both of mpd5 and end-user are enabled to use both PAP and CHAP, so CHAP may be preferred.
Also, you should remove "pap" from the command "set link no pap chap eap"
All right removing pap so that we have set link no chap eap we get from logs
Jun 25 08:23:40 doctor mpd[28435]: PPTP: Incoming control connection from 75.156.190.254 51408 to 204.209.81.1 1723
Jun 25 08:23:40 doctor mpd[28435]: pptp0: attached to connection with 75.156.190.254 51408
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] Accepting PPTP connection
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] Link: OPEN event
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] LCP: Open event
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] LCP: state change Initial --> Starting
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] LCP: LayerStart
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] device: OPEN event
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] PPTP: attaching to peer's outgoing call
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] device: UP event
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] Link: UP event
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] Link: origination is remote
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] LCP: Up event
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] LCP: state change Starting --> Req-Sent
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] LCP: phase shift DEAD --> ESTABLISH
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigReq #1
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0xda968882
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] MP MRRU 2048
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] MP SHORTSEQ
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] ENDPOINTDISC [802.1] 0c c4 7a ac 51 1e
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Request #0 (Req-Sent)
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] MRU 1400
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0x050f708f
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] CALLBACK 6
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigRej #0
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] CALLBACK 6
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Request #1 (Req-Sent)
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] MRU 1400
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0x050f708f
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigAck #1
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] MRU 1400
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0x050f708f
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 08:23:40 doctor mpd[28435]: [L_pptp-2] LCP: state change Req-Sent --> Ack-Sent
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigReq #2
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0xda968882
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MP MRRU 2048
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MP SHORTSEQ
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] ENDPOINTDISC [802.1] 0c c4 7a ac 51 1e
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Reject #2 (Ack-Sent)
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MP MRRU 2048
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MP SHORTSEQ
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] ENDPOINTDISC [802.1] 0c c4 7a ac 51 1e
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigReq #3
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0xda968882
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Nak #3 (Ack-Sent)
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO CHAP MSOFTv2
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigReq #4
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0xda968882
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Nak #4 (Ack-Sent)
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO CHAP MSOFTv2
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigReq #5
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0xda968882
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Nak #5 (Ack-Sent)
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO CHAP MSOFTv2
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigReq #6
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0xda968882
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Nak #6 (Ack-Sent)
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO CHAP MSOFTv2
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigReq #7
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0xda968882
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Nak #7 (Ack-Sent)
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO CHAP MSOFTv2
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigReq #8
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0xda968882
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Nak #8 (Ack-Sent)
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO CHAP MSOFTv2
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigReq #9
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0xda968882
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Nak #9 (Ack-Sent)
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO CHAP MSOFTv2
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigReq #10
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0xda968882
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Nak #10 (Ack-Sent)
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] AUTHPROTO CHAP MSOFTv2
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: not converging
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: parameter negotiation failed
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: state change Ack-Sent --> Stopped
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: LayerFinish
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] device: CLOSE event
Jun 25 08:23:42 doctor mpd[28435]: pptp0-0: clearing call
Jun 25 08:23:42 doctor mpd[28435]: pptp0-0: killing channel
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] PPTP call terminated
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] device: DOWN event
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] Link: DOWN event
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: Close event
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: state change Stopped --> Closed
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: Down event
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: state change Closed --> Initial
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] LCP: phase shift ESTABLISH --> DEAD
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] Link: SHUTDOWN event
Jun 25 08:23:42 doctor mpd[28435]: [L_pptp-2] Link: Shutdown
Jun 25 08:23:52 doctor mpd[28435]: pptp0: closing connection with 75.156.190.254 51408
Jun 25 08:23:52 doctor mpd[28435]: pptp0: killing connection with 75.156.190.254
Jun 25 08:23:52 doctor mpd[28435]: pptp0: killing connection with 75.156.190.254 51408
Your PPTP client is configured for MSCHAPv2 only.
Last edit: Alexander Motin 2020-06-25
This is exactly what I was talking about earlier: end-user system insists on MS-CHAP2. It is not configured for PAP.
so how do I configure away from CHAP whatever to PAP?
Found the issue, the client in MSWin10 was set to CHAP. Debugging the rest
All right question 1) , what do I need in my pf.conf to get the ng interfaces to work proerly?
2) Jun 25 10:44:18 doctor mpd[28435]: PPTP: Incoming control connection from 75.156
.190.254 53207 to 204.209.81.1 1723
Jun 25 10:44:18 doctor mpd[28435]: pptp0: attached to connection with 75.156.190
.254 53207
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] Accepting PPTP connection
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] Link: OPEN event
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] LCP: Open event
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] LCP: state change Initial --> Star
ting
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] LCP: LayerStart
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] device: OPEN event
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] PPTP: attaching to peer's outgoing
call
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] device: UP event
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] Link: UP event
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] Link: origination is remote
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] LCP: Up event
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] LCP: state change Starting --> Req
-Sent
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] LCP: phase shift DEAD --> ESTABLISH
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigReq #1
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0x12c94a15
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] MP MRRU 2048
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] MP SHORTSEQ
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] ENDPOINTDISC [802.1] 0c c4 7a ac 51 1e
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Request #0 (Req-Sent)
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] MRU 1400
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0x7b341ff6
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] CALLBACK 6
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigRej #0
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] CALLBACK 6
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Request #1 (Req-Sent)
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] MRU 1400
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0x7b341ff6
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigAck #1
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] MRU 1400
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0x7b341ff6
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 10:44:18 doctor mpd[28435]: [L_pptp-2] LCP: state change Req-Sent --> Ack-Sent
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigReq #2
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0x12c94a15
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MP MRRU 2048
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MP SHORTSEQ
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] ENDPOINTDISC [802.1] 0c c4 7a ac 51 1e
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Reject #2 (Ack-Sent)
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MP MRRU 2048
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MP SHORTSEQ
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] ENDPOINTDISC [802.1] 0c c4 7a ac 51 1e
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] LCP: SendConfigReq #3
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0x12c94a15
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Configure Ack #3 (Ack-Sent)
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] ACFCOMP
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] PROTOCOMP
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MRU 1500
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MAGICNUM 0x12c94a15
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] AUTHPROTO PAP
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] LCP: state change Ack-Sent --> Opened
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] LCP: phase shift ESTABLISH --> AUTHENTICATE
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] LCP: auth: peer wants nothing, I want PAP
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] LCP: LayerUp
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Ident #2 (Opened)
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MESG: MSRASV5.20
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Ident #3 (Opened)
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MESG: MSRAS-0-DESKTOP-NLNAQH7
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Ident #4 (Opened)
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MESG: )<e4>R
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] MESG: <a8><c2> AM-^<b8><cd><ca>0H<a4>M-^N
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] PAP: rec'd REQUEST #2 len: 17
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] AUTH: Trying RADIUS
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Authenticating user 'aboo'
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Adding server 204.209.81.1 1812
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_NAS_IDENTIFIER: doctor.nl2k.ab.ca
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_NAS_IP_ADDRESS: 204.209.81.1
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_NAS_IP_ADDRESS: 204.209.81.1
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put Message Authenticator
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_ACCT_SESSION_ID: 3103460-L_pptp-2
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_NAS_PORT: 2
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_NAS_PORT_TYPE: 5
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_SERVICE_TYPE: RAD_FRAMED
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_FRAMED_PROTOCOL: RAD_PPP
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_CALLING_STATION_ID:
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_CALLED_STATION_ID:
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_MPD_LINK: L_pptp-2
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_MPD_PEER_IDENT: MSRASV5.20 MSRAS-0-DESKTOP-NLNAQH7 )<e4>R^M<a8><c2> AM-^</c2></a8></e4></a4></ca></cd></b8><b8><cd><ca>0H<a4>M-^N
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_TUNNEL_TYPE: 1
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_TUNNEL_MEDIUM_TYPE: 1
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_TUNNEL_SERVER_ENDPOINT: 204.209.81.1
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_TUNNEL_CLIENT_ENDPOINT: 75.156.190.254
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_TUNNEL_SERVER_AUTH_ID: doctor.nl2k.ab.ca
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_USER_NAME: aboo
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_USER_PASSWORD
Jun 25 10:44:20 doctor mpd[28435]: [L_pptp-2] RADIUS: Send request for user 'aboo'
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Sending request for user 'aboo'
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Rec'd RAD_ACCESS_REJECT for user 'aboo'
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] AUTH: RADIUS returned: failed
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] AUTH: Trying INTERNAL
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] AUTH: INTERNAL returned: undefined
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] PAP: Auth return status: undefined
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] PAP: Response is valid
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] PAP: Reply message: Welcome
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] PAP: sending ACK #2 len: 12
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] LCP: authorization successful
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] LCP: phase shift AUTHENTICATE --> NETWORK
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] Link: Matched action 'bundle "B_pptp" ""'
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] Creating new bundle using template "B_pptp".
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] Bundle: Interface ng0 created
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] Link: Join bundle "B_pptp-2"
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IFACE: setting ng0 MTU to 1400 bytes
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: Open event
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: state change Initial --> Starting
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: LayerStart
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] CCP: Open event
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] CCP: state change Initial --> Starting
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] CCP: LayerStart
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: Up event
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: Got IP 10.10.0.2 from pool "pool1" for peer
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: state change Starting --> Req-Sent
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: SendConfigReq #1
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPADDR 10.10.0.1
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] CCP: Up event
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] CCP: Protocol mppc disabled as useless for this setup
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] CCP: state change Starting --> Req-Sent
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] CCP: SendConfigReq #1
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Accounting user 'aboo' (Type: 1)
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] rec'd unexpected protocol IPV6CP, rejecting
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Adding server 204.209.81.1 1813
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: rec'd Configure Request #6 (Req-Sent)
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_NAS_IDENTIFIER: doctor.nl2k.ab.ca
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPADDR 204.209.81.1
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] NAKing with 10.10.0.2
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] PRIDNS 0.0.0.0
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] NAKing with 204.209.81.1
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] PRINBNS 0.0.0.0
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] SECDNS 0.0.0.0
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] SECNBNS 0.0.0.0
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: SendConfigRej #6
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] PRINBNS 0.0.0.0
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] SECDNS 0.0.0.0
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] SECNBNS 0.0.0.0
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_NAS_IP_ADDRESS: 0.0.0.0
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_ACCT_SESSION_ID: 3103460-L_pptp-2
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_NAS_PORT: 2
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_NAS_PORT_TYPE: 5
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_SERVICE_TYPE: RAD_FRAMED
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_FRAMED_PROTOCOL: RAD_PPP
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_CALLING_STATION_ID:
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_CALLED_STATION_ID:
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_MPD_LINK: L_pptp-2
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_MPD_PEER_IDENT: MSRASV5.20 MSRAS-0-DESKTOP-NLNAQH7 )<e4>R^M<a8><c2> AM-^<b8><cd><ca>0H<a4>M-^N
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_TUNNEL_TYPE: 1
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_TUNNEL_MEDIUM_TYPE: 1
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_TUNNEL_SERVER_ENDPOINT: 204.209.81.1
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_TUNNEL_CLIENT_ENDPOINT: 75.156.190.254
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_TUNNEL_SERVER_AUTH_ID: doctor.nl2k.ab.ca
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_ACCT_STATUS_TYPE: RAD_START
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_FRAMED_IP_ADDRESS: 10.10.0.2
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_FRAMED_IPV6_ADDRESS: ::
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_USER_NAME: aboo
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_ACCT_MULTI_SESSION_ID: 3103461-B_pptp-2
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_MPD_BUNDLE: B_pptp-2
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_MPD_IFACE: ng0
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_MPD_IFACE_INDEX: 23
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_MPD_PEER_IDENT: MSRASV5.20 MSRAS-0-DESKTOP-NLNAQH7 )<e4>R^M<a8><c2> AM-^</c2></a8></e4></a4></ca></cd></b8><b8><cd><ca>0H<a4>M-^N
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_ACCT_LINK_COUNT: 1
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Put RAD_ACCT_AUTHENTIC: 2
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] RADIUS: Send request for user 'aboo'
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: rec'd Configure Reject #1 (Req-Sent)
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: SendConfigReq #2
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPADDR 10.10.0.1
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] LCP: rec'd Protocol Reject #7 (Opened)
Jun 25 10:44:21 doctor mpd[28435]: [L_pptp-2] LCP: protocol CCP was rejected
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] CCP: protocol was rejected by peer
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] CCP: state change Req-Sent --> Stopped
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] CCP: LayerFinish
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: rec'd Configure Request #8 (Req-Sent)
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPADDR 0.0.0.0
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] NAKing with 10.10.0.2
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] PRIDNS 0.0.0.0
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] NAKing with 204.209.81.1
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: SendConfigNak #8
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPADDR 10.10.0.2
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] PRIDNS 204.209.81.1
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: rec'd Configure Ack #2 (Req-Sent)
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPADDR 10.10.0.1
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: state change Req-Sent --> Ack-Rcvd
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: rec'd Configure Request #9 (Ack-Rcvd)
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPADDR 10.10.0.2
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] 10.10.0.2 is OK
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] PRIDNS 204.209.81.1
Jun 25 10:44:21 doctor mpd[28435]: [B_pptp-2] IPCP: SendConfigAck #9</a4></ca></cd></b8></c2></a8></e4></a4></ca></cd></b8></c2></a8></e4>
This is not full log. Following lines should contain "IPCP: LayerUp" and "IFACE: Up event". If they do, you should have ng0 interface up and running.
PPtP uses TCP port 1723 for controlling connection and GRE (IP protocol 47) for user data. It's not uncommon for Internet Service Providers (ISP) to filter GRE protocol, so if your users connect via public Internet, they may find PPtP unusable.
L2TP uses UDP port 1701 only and should pass through any modern ISP without problems, so you may find it preferable.
continuing Question 2 : What should I be looking for?
Well I turned GRE on and here is what I got
root@doctor:~ # tail /var/log/mpd.log[4h-[4l[4hf[4l[4h [4lJun 26 22:05:30 doctor mpd[4846]: [L_pptp-5] ACCT: wtmp L_pptp-5 logout
Jun 26 22:05:30 doctor mpd[4846]: [L_pptp-5] Link: SHUTDOWN event
Jun 26 22:05:30 doctor mpd[4846]: [L_pptp-5] Link: Shutdown
Jun 26 22:05:37 doctor mpd[4846]: pptp0: closing connection with 75.156.190.254 49955
Jun 26 22:05:40 doctor mpd[4846]: pptp0: no reply to StopCtrlConnRequest after 3 sec
Jun 26 22:05:40 doctor mpd[4846]: pptp0: killing connection with 75.156.190.254 49955
Jun 26 22:07:50 doctor mpd[4846]: PPTP: Incoming control connection from 139.162.102.46 56604 to 204.209.81.1 1723
Jun 26 22:07:50 doctor mpd[4846]: pptp0: attached to connection with 139.162.102.46 56604
Jun 26 22:07:50 doctor mpd[4846]: pptp0: read: Connection reset by peer
Jun 26 22:07:50 doctor mpd[4846]: pptp0: killing connection with 139.162.102.46 56604
Jun 26 22:12:35 doctor mpd[4846]: PPTP: Incoming control connection from 75.156.190.254 50078 to 204.209.81.1 1723
Jun 26 22:12:35 doctor mpd[4846]: pptp0: attached to connection with 75.156.190.254 50078
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] Accepting PPTP connection
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] Link: OPEN event
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] LCP: Open event
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] LCP: state change Initial --> Starting
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] LCP: LayerStart
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] device: OPEN event
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] PPTP: attaching to peer's outgoing call
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] device: UP event
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] Link: UP event
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] Link: origination is remote
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] LCP: Up event
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] LCP: state change Starting --> Req-Sent
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] LCP: phase shift DEAD --> ESTABLISH
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] LCP: SendConfigReq #1
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] ACFCOMP
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] PROTOCOMP
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] MRU 1500
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] MAGICNUM 0xd811fe08
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] AUTHPROTO PAP
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] MP MRRU 2048
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] MP SHORTSEQ
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] ENDPOINTDISC [802.1] 0c c4 7a ac 51 1e
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] LCP: rec'd Configure Request #0 (Req-Sent)
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] MRU 1400
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] MAGICNUM 0x2f1d61d7
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] PROTOCOMP
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] ACFCOMP
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] CALLBACK 6
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] LCP: SendConfigRej #0
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] CALLBACK 6
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] LCP: rec'd Configure Request #1 (Req-Sent)
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] MRU 1400
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] MAGICNUM 0x2f1d61d7
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] PROTOCOMP
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] ACFCOMP
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] LCP: SendConfigAck #1
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] MRU 1400
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] MAGICNUM 0x2f1d61d7
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] PROTOCOMP
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] ACFCOMP
Jun 26 22:12:35 doctor mpd[4846]: [L_pptp-5] LCP: state change Req-Sent --> Ack-Sent
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] LCP: SendConfigReq #2
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] ACFCOMP
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] PROTOCOMP
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] MRU 1500
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] MAGICNUM 0xd811fe08
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] AUTHPROTO PAP
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] MP MRRU 2048
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] MP SHORTSEQ
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] ENDPOINTDISC [802.1] 0c c4 7a ac 51 1e
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] LCP: rec'd Configure Reject #2 (Ack-Sent)
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] MP MRRU 2048
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] MP SHORTSEQ
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] ENDPOINTDISC [802.1] 0c c4 7a ac 51 1e
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] LCP: SendConfigReq #3
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] ACFCOMP
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] PROTOCOMP
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] MRU 1500
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] MAGICNUM 0xd811fe08
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] AUTHPROTO PAP
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] LCP: rec'd Configure Ack #3 (Ack-Sent)
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] ACFCOMP
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] PROTOCOMP
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] MRU 1500
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] MAGICNUM 0xd811fe08
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] AUTHPROTO PAP
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] LCP: state change Ack-Sent --> Opened
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] LCP: phase shift ESTABLISH --> AUTHENTICATE
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] LCP: auth: peer wants nothing, I want PAP
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] LCP: LayerUp
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] LCP: rec'd Ident #2 (Opened)
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] MESG: MSRASV5.20
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] LCP: rec'd Ident #3 (Opened)
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] MESG: MSRAS-0-DESKTOP-NLNAQH7
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] LCP: rec'd Ident #4 (Opened)
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] MESG: ÿjb^[ïM-^MCJM-^U^]?Zõ^^^Â
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] PAP: rec'd REQUEST #2 len: 17
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] AUTH: Trying RADIUS
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Authenticating user 'aboo'
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Adding server 204.209.81.1 1645
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_NAS_IDENTIFIER: doctor.nl2k.ab.ca
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_NAS_IP_ADDRESS: 204.209.81.1
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put Message Authenticator
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_SESSION_ID: 3231157-L_pptp-5
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_NAS_PORT: 5
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_NAS_PORT_TYPE: 5
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_SERVICE_TYPE: RAD_FRAMED
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_FRAMED_PROTOCOL: RAD_PPP
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_CALLING_STATION_ID:
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_CALLED_STATION_ID:
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_LINK: L_pptp-5
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_PEER_IDENT: MSRASV5.20 MSRAS-0-DESKTOP-NLNAQH7 ÿjb^[ïM-^MCJM-^U^]?Zõ^^^Â
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_TYPE: 1
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_MEDIUM_TYPE: 1
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_SERVER_ENDPOINT: 204.209.81.1
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_CLIENT_ENDPOINT: 75.156.190.254
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_SERVER_AUTH_ID: doctor.nl2k.ab.ca
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_USER_NAME: aboo
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_USER_PASSWORD
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Send request for user 'aboo'
Jun 26 22:12:37 doctor mpd[4846]: [L_pptp-5] RADIUS: Sending request for user 'aboo'
Jun 26 22:12:37 doctor syslogd: last message repeated 1 times
Jun 26 22:12:39 doctor mpd[4846]: [L_pptp-5] PAP: rec'd REQUEST #3 len: 17
Jun 26 22:12:39 doctor mpd[4846]: [L_pptp-5] PAP: Auth return status: busy
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Sending request for user 'aboo'
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: rad_send_request for user 'aboo' failed: No valid RADIUS responses received
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] AUTH: RADIUS returned error
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] AUTH: Trying INTERNAL
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] AUTH: INTERNAL returned: undefined
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] PAP: Auth return status: undefined
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] PAP: Response is valid
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] PAP: Reply message: Welcome
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] PAP: sending ACK #2 len: 12
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] LCP: authorization successful
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] LCP: phase shift AUTHENTICATE --> NETWORK
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] Link: Matched action 'bundle "B_pptp" ""'
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] Creating new bundle using template "B_pptp".
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] Bundle: Interface ng0 created
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] Link: Join bundle "B_pptp-3"
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IFACE: setting ng0 MTU to 1400 bytes
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: Open event
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: state change Initial --> Starting
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: LayerStart
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] CCP: Open event
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] CCP: state change Initial --> Starting
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] CCP: LayerStart
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: Up event
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: Got IP 10.10.0.2 from pool "pool1" for peer
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: state change Starting --> Req-Sent
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: SendConfigReq #1
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPADDR 10.10.0.1
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] CCP: Up event
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] CCP: Protocol mppc disabled as useless for this setup
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] CCP: state change Starting --> Req-Sent
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] CCP: SendConfigReq #1
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Accounting user 'aboo' (Type: 1)
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Adding server 204.209.81.1 1646
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_NAS_IDENTIFIER: doctor.nl2k.ab.ca
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_NAS_IP_ADDRESS: 204.209.81.1
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_SESSION_ID: 3231157-L_pptp-5
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_NAS_PORT: 5
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_NAS_PORT_TYPE: 5
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_SERVICE_TYPE: RAD_FRAMED
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_FRAMED_PROTOCOL: RAD_PPP
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_CALLING_STATION_ID:
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_CALLED_STATION_ID:
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_LINK: L_pptp-5
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_PEER_IDENT: MSRASV5.20 MSRAS-0-DESKTOP-NLNAQH7 ÿjb^[ïM-^MCJM-^U^]?Zõ^^^Â
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_TYPE: 1
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_MEDIUM_TYPE: 1
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_SERVER_ENDPOINT: 204.209.81.1
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_CLIENT_ENDPOINT: 75.156.190.254
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_SERVER_AUTH_ID: doctor.nl2k.ab.ca
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_STATUS_TYPE: RAD_START
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_FRAMED_IP_ADDRESS: 10.10.0.2
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_FRAMED_IPV6_ADDRESS: ::
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_USER_NAME: aboo
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_MULTI_SESSION_ID: 3231160-B_pptp-3
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_BUNDLE: B_pptp-3
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_IFACE: ng0
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_IFACE_INDEX: 23
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_PEER_IDENT: MSRASV5.20 MSRAS-0-DESKTOP-NLNAQH7 ÿjb^[ïM-^MCJM-^U^]?Zõ^^^Â
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_LINK_COUNT: 1
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_AUTHENTIC: 2
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Send request for user 'aboo'
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Sending request for user 'aboo'
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] rec'd unexpected protocol IPV6CP, rejecting
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: rec'd Configure Request #6 (Req-Sent)
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPADDR 0.0.0.0
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] NAKing with 10.10.0.2
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] PRIDNS 0.0.0.0
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] NAKing with 204.209.81.1
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] PRINBNS 0.0.0.0
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] SECDNS 0.0.0.0
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] SECNBNS 0.0.0.0
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: SendConfigRej #6
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] PRINBNS 0.0.0.0
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] SECDNS 0.0.0.0
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] SECNBNS 0.0.0.0
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: rec'd Configure Reject #1 (Req-Sent)
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: SendConfigReq #2
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPADDR 10.10.0.1
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] LCP: rec'd Protocol Reject #7 (Opened)
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] LCP: protocol CCP was rejected
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] CCP: protocol was rejected by peer
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] CCP: state change Req-Sent --> Stopped
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] CCP: LayerFinish
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: Sending request for user 'aboo'
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: rec'd Configure Request #8 (Req-Sent)
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPADDR 0.0.0.0
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] NAKing with 10.10.0.2
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] PRIDNS 0.0.0.0
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] NAKing with 204.209.81.1
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: SendConfigNak #8
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPADDR 10.10.0.2
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] PRIDNS 204.209.81.1
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: rec'd Configure Ack #2 (Req-Sent)
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPADDR 10.10.0.1
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: state change Req-Sent --> Ack-Rcvd
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: rec'd Configure Request #9 (Ack-Rcvd)
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPADDR 10.10.0.2
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] 10.10.0.2 is OK
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] PRIDNS 204.209.81.1
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: SendConfigAck #9
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPADDR 10.10.0.2
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] PRIDNS 204.209.81.1
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: state change Ack-Rcvd --> Opened
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IPCP: LayerUp
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] 10.10.0.1 -> 10.10.0.2
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IFACE: Connecting tcpmssfix
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IFACE: Configuring ng_tcpmss mpd4846-B_pptp-3-mss: 1360
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IFACE: Add address 10.10.0.1/32->10.10.0.2 to ng0
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IFACE: No interface to proxy arp on for 10.10.0.2
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IFACE: Up event
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IFACE: idle-timeout: 1800 seconds
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IFACE: setting "ng0" description to "pptp: aboo 10.10.0.2 204.209.81.1 75.156.190.254"
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IFACE: Add description "pptp: aboo 10.10.0.2 204.209.81.1 75.156.190.254"
Jun 26 22:12:40 doctor mpd[4846]: [B_pptp-3] IFACE: Change interface ng0 flags: -0 +1
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Sending request for user 'aboo'
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: rad_send_request for user 'aboo' failed: No valid RADIUS responses received
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] ACCT: wtmp L_pptp-5 aboo 75.156.190.254 login
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] ACCT: Close link due to accounting start error
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] ACCT: Link close requested by the accounting
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] Link: CLOSE event
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] LCP: Close event
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] LCP: state change Opened --> Closing
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] LCP: phase shift NETWORK --> TERMINATE
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] Link: Leave bundle "B_pptp-3"
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Accounting user 'aboo' (Type: 2)
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IFACE: setting ng0 MTU to 1500 bytes
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Adding server 204.209.81.1 1646
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_NAS_IDENTIFIER: doctor.nl2k.ab.ca
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IFACE: Configuring ng_tcpmss mpd4846-B_pptp-3-mss: 1460
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_NAS_IP_ADDRESS: 204.209.81.1
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_SESSION_ID: 3231157-L_pptp-5
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_NAS_PORT: 5
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_NAS_PORT_TYPE: 5
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_SERVICE_TYPE: RAD_FRAMED
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_FRAMED_PROTOCOL: RAD_PPP
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_CALLING_STATION_ID:
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_CALLED_STATION_ID:
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_LINK: L_pptp-5
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_PEER_IDENT: MSRASV5.20 MSRAS-0-DESKTOP-NLNAQH7 ÿjb^[ïM-^MCJM-^U^]?Zõ^^^Â
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_TYPE: 1
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_MEDIUM_TYPE: 1
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_SERVER_ENDPOINT: 204.209.81.1
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_CLIENT_ENDPOINT: 75.156.190.254
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_SERVER_AUTH_ID: doctor.nl2k.ab.ca
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_FRAMED_IP_ADDRESS: 10.10.0.2
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_FRAMED_IPV6_ADDRESS: ::
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_USER_NAME: aboo
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_MULTI_SESSION_ID: 3231160-B_pptp-3
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_BUNDLE: B_pptp-3
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_IFACE: ng0
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_IFACE_INDEX: 23
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_MPD_PEER_IDENT: MSRASV5.20 MSRAS-0-DESKTOP-NLNAQH7 ÿjb^[ïM-^MCJM-^U^]?Zõ^^^Â
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_LINK_COUNT: 1
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_AUTHENTIC: 2
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_STATUS_TYPE: RAD_STOP
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_TERMINATE_CAUSE: Manual, RADIUS: 6
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_SESSION_TIME: 8
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_INPUT_OCTETS: 9928
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_INPUT_GIGAWORDS: 0
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_INPUT_PACKETS: 91
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_OUTPUT_OCTETS: 8743
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_OUTPUT_GIGAWORDS: 0
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Put RAD_ACCT_OUTPUT_PACKETS: 87
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Send request for user 'aboo'
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] Bundle: Status update: up 0 links, total bandwidth 9600 bps
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IPCP: Close event
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IPCP: state change Opened --> Closing
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IPCP: SendTerminateReq #3
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IPCP: LayerDown
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IFACE: Remove address 10.10.0.1/32->10.10.0.2 from ng0
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IFACE: Down event
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IFACE: Change interface ng0 flags: -4097 +0
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IFACE: setting "ng0" description to "-: aboo UNSPEC - -"
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IFACE: Set description "-: aboo UNSPEC - -"
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] CCP: Close event
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] CCP: state change Stopped --> Closed
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IPCP: Down event
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IPCP: LayerFinish
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] Bundle: No NCPs left. Closing links...
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] IPCP: state change Closing --> Initial
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] CCP: Down event
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] CCP: state change Closed --> Initial
Jun 26 22:12:43 doctor mpd[4846]: [B_pptp-3] Bundle: Shutdown
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] RADIUS: Sending request for user 'aboo'
Jun 26 22:12:43 doctor syslogd: last message repeated 1 times
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] LCP: SendTerminateReq #4
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] LCP: LayerDown
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] rec'd proto IP during terminate phase
Jun 26 22:12:43 doctor syslogd: last message repeated 4 times
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] LCP: rec'd Terminate Ack #4 (Closing)
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] LCP: state change Closing --> Closed
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] LCP: phase shift TERMINATE --> ESTABLISH
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] LCP: LayerFinish
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] device: CLOSE event
Jun 26 22:12:43 doctor mpd[4846]: pptp0-0: clearing call
Jun 26 22:12:43 doctor mpd[4846]: pptp0-0: killing channel
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] PPTP call terminated
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] device: DOWN event
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] Link: DOWN event
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] LCP: Down event
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] LCP: state change Closed --> Initial
Jun 26 22:12:43 doctor mpd[4846]: [L_pptp-5] LCP: phase shift ESTABLISH --> DEAD
Jun 26 22:12:45 doctor mpd[4846]: pptp0: CID 0xc39e in CallClearRequest not found
Jun 26 22:12:46 doctor mpd[4846]: pptp0: got StopCtrlConnRequest: reason=none
Jun 26 22:12:46 doctor mpd[4846]: pptp0: killing connection with 75.156.190.254 50078
Jun 26 22:12:46 doctor mpd[4846]: [L_pptp-5] RADIUS: Sending request for user 'aboo'
Jun 26 22:12:46 doctor mpd[4846]: [L_pptp-5] RADIUS: rad_send_request for user 'aboo' failed: No valid RADIUS responses received
Jun 26 22:12:46 doctor mpd[4846]: [L_pptp-5] ACCT: wtmp L_pptp-5 logout
Jun 26 22:12:46 doctor mpd[4846]: [L_pptp-5] Link: SHUTDOWN event
Jun 26 22:12:46 doctor mpd[4846]: [L_pptp-5] Link: Shutdown
Jun 26 22:12:40 doctor mpd[4846]: [L_pptp-5] RADIUS: rad_send_request for user 'aboo' failed: No valid RADIUS responses received
Your RADIUS server does not respond, but user is found in mpd.secret, so authorization succeeded locally. However, you also enabled RADIUS accounting and mpd5 disconnects uses just after successfull connection because RADIUS server did not acknowledge accounting.
mpd5 will not serve users until you fix connectivity with RADIUS server or disable accounting.
So here is the update
Jun 27 07:29:53 doctor mpd[50059]: PPTP: Incoming control connection from 75.156.190.254 51937 to 204.209.81.1 1723
Jun 27 07:29:53 doctor mpd[50059]: pptp0: attached to connection with 75.156.190.254 51937
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] Accepting PPTP connection
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] Link: OPEN event
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] LCP: Open event
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] LCP: state change Initial --> Starting
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] LCP: LayerStart
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] device: OPEN event
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] PPTP: attaching to peer's outgoing call
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] device: UP event
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] Link: UP event
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] Link: origination is remote
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] LCP: Up event
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] LCP: state change Starting --> Req-Sent
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] LCP: phase shift DEAD --> ESTABLISH
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] LCP: SendConfigReq #1
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] ACFCOMP
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] PROTOCOMP
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] MRU 1500
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] MAGICNUM 0xadde165e
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] AUTHPROTO PAP
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] MP MRRU 2048
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] MP SHORTSEQ
Jun 27 07:29:53 doctor mpd[50059]: [L_pptp-5] ENDPOINTDISC [802.1] 0c c4 7a ac 51 1e
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] LCP: rec'd Configure Request #0 (Req-Sent)
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] MRU 1400
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] MAGICNUM 0x762a7976
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] PROTOCOMP
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] ACFCOMP
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] CALLBACK 6
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] LCP: SendConfigRej #0
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] CALLBACK 6
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] LCP: rec'd Configure Request #1 (Req-Sent)
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] MRU 1400
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] MAGICNUM 0x762a7976
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] PROTOCOMP
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] ACFCOMP
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] LCP: SendConfigAck #1
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] MRU 1400
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] MAGICNUM 0x762a7976
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] PROTOCOMP
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] ACFCOMP
Jun 27 07:29:54 doctor mpd[50059]: [L_pptp-5] LCP: state change Req-Sent --> Ack-Sent
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] LCP: SendConfigReq #2
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] ACFCOMP
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] PROTOCOMP
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] MRU 1500
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] MAGICNUM 0xadde165e
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] AUTHPROTO PAP
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] MP MRRU 2048
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] MP SHORTSEQ
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] ENDPOINTDISC [802.1] 0c c4 7a ac 51 1e
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] LCP: rec'd Configure Reject #2 (Ack-Sent)
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] MP MRRU 2048
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] MP SHORTSEQ
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] ENDPOINTDISC [802.1] 0c c4 7a ac 51 1e
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] LCP: SendConfigReq #3
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] ACFCOMP
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] PROTOCOMP
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] MRU 1500
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] MAGICNUM 0xadde165e
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] AUTHPROTO PAP
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] LCP: rec'd Configure Ack #3 (Ack-Sent)
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] ACFCOMP
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] PROTOCOMP
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] MRU 1500
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] MAGICNUM 0xadde165e
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] AUTHPROTO PAP
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] LCP: state change Ack-Sent --> Opened
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] LCP: phase shift ESTABLISH --> AUTHENTICATE
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] LCP: auth: peer wants nothing, I want PAP
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] LCP: LayerUp
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] LCP: rec'd Ident #2 (Opened)
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] MESG: MSRASV5.20
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] LCP: rec'd Ident #3 (Opened)
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] MESG: MSRAS-0-DESKTOP-NLNAQH7
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] LCP: rec'd Ident #4 (Opened)
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] MESG: AáM-^ASÏð¢G¥ÊVź.ºf
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] PAP: rec'd REQUEST #0 len: 17
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] AUTH: Trying RADIUS
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Authenticating user 'aboo'
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Adding server 204.209.81.1 1645
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_NAS_IDENTIFIER: doctor.nl2k.ab.ca
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_NAS_IP_ADDRESS: 204.209.81.1
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put Message Authenticator
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_ACCT_SESSION_ID: 3264596-L_pptp-5
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_NAS_PORT: 5
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_NAS_PORT_TYPE: 5
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_SERVICE_TYPE: RAD_FRAMED
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_FRAMED_PROTOCOL: RAD_PPP
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_CALLING_STATION_ID:
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_CALLED_STATION_ID:
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_MPD_LINK: L_pptp-5
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_MPD_PEER_IDENT: MSRASV5.20 MSRAS-0-DESKTOP-NLNAQH7 AáM-^ASÏð¢G¥ÊVź.ºf
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_TYPE: 1
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_MEDIUM_TYPE: 1
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_SERVER_ENDPOINT: 204.209.81.1
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_CLIENT_ENDPOINT: 75.156.190.254
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_TUNNEL_SERVER_AUTH_ID: doctor.nl2k.ab.ca
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_USER_NAME: aboo
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Put RAD_USER_PASSWORD
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Send request for user 'aboo'
Jun 27 07:29:56 doctor mpd[50059]: [L_pptp-5] RADIUS: Sending request for user 'aboo'
Jun 27 07:29:56 doctor syslogd: last message repeated 1 times
Jun 27 07:29:58 doctor mpd[50059]: [L_pptp-5] PAP: rec'd REQUEST #1 len: 17
Jun 27 07:29:58 doctor mpd[50059]: [L_pptp-5] PAP: Auth return status: busy
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] RADIUS: Sending request for user 'aboo'
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] RADIUS: rad_send_request for user 'aboo' failed: No valid RADIUS responses received
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] AUTH: RADIUS returned error
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] AUTH: Trying INTERNAL
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] AUTH: INTERNAL returned: undefined
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] PAP: Auth return status: undefined
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] PAP: Response is valid
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] PAP: Reply message: Welcome
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] PAP: sending ACK #0 len: 12
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] LCP: authorization successful
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] LCP: phase shift AUTHENTICATE --> NETWORK
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] Link: Matched action 'bundle "B_pptp" ""'
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] Creating new bundle using template "B_pptp".
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] Bundle: Interface ng0 created
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] Link: Join bundle "B_pptp-3"
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IFACE: setting ng0 MTU to 1400 bytes
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] Bundle: Status update: up 1 link, total bandwidth 64000 bps
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: Open event
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: state change Initial --> Starting
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: LayerStart
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] CCP: Open event
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] CCP: state change Initial --> Starting
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] CCP: LayerStart
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: Up event
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: Got IP 10.10.0.2 from pool "pool1" for peer
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: state change Starting --> Req-Sent
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: SendConfigReq #1
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPADDR 10.10.0.1
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] CCP: Up event
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] CCP: Protocol mppc disabled as useless for this setup
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] CCP: state change Starting --> Req-Sent
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] CCP: SendConfigReq #1
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] ACCT: wtmp L_pptp-5 aboo 75.156.190.254 login
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] rec'd unexpected protocol IPV6CP, rejecting
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: rec'd Configure Request #6 (Req-Sent)
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPADDR 0.0.0.0
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] NAKing with 10.10.0.2
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] PRIDNS 0.0.0.0
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] NAKing with 204.209.81.1
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] PRINBNS 0.0.0.0
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] SECDNS 0.0.0.0
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] SECNBNS 0.0.0.0
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: SendConfigRej #6
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] PRINBNS 0.0.0.0
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] SECDNS 0.0.0.0
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] SECNBNS 0.0.0.0
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: rec'd Configure Reject #1 (Req-Sent)
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: SendConfigReq #2
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPADDR 10.10.0.1
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] LCP: rec'd Protocol Reject #7 (Opened)
Jun 27 07:29:59 doctor mpd[50059]: [L_pptp-5] LCP: protocol CCP was rejected
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] CCP: protocol was rejected by peer
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] CCP: state change Req-Sent --> Stopped
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] CCP: LayerFinish
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: rec'd Configure Request #8 (Req-Sent)
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPADDR 0.0.0.0
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] NAKing with 10.10.0.2
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] PRIDNS 0.0.0.0
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] NAKing with 204.209.81.1
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: SendConfigNak #8
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPADDR 10.10.0.2
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] PRIDNS 204.209.81.1
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: rec'd Configure Ack #2 (Req-Sent)
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPADDR 10.10.0.1
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: state change Req-Sent --> Ack-Rcvd
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: rec'd Configure Request #9 (Ack-Rcvd)
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPADDR 10.10.0.2
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] 10.10.0.2 is OK
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] PRIDNS 204.209.81.1
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: SendConfigAck #9
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPADDR 10.10.0.2
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] PRIDNS 204.209.81.1
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: state change Ack-Rcvd --> Opened
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IPCP: LayerUp
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] 10.10.0.1 -> 10.10.0.2
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IFACE: Connecting tcpmssfix
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IFACE: Configuring ng_tcpmss mpd50059-B_pptp-3-mss: 1360
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IFACE: Add address 10.10.0.1/32->10.10.0.2 to ng0
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IFACE: No interface to proxy arp on for 10.10.0.2
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IFACE: Up event
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IFACE: idle-timeout: 1800 seconds
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IFACE: setting "ng0" description to "pptp: aboo 10.10.0.2 204.209.81.1 75.156.190.254"
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IFACE: Add description "pptp: aboo 10.10.0.2 204.209.81.1 75.156.190.254"
Jun 27 07:29:59 doctor mpd[50059]: [B_pptp-3] IFACE: Change interface ng0 flags: -0 +1
Jun 27 07:32:06 doctor mpd[50059]: [L_pptp-5] LCP: rec'd Terminate Request #10 (Opened)
Jun 27 07:32:06 doctor mpd[50059]: [L_pptp-5] LCP: state change Opened --> Stopping
Jun 27 07:32:06 doctor mpd[50059]: [L_pptp-5] LCP: phase shift NETWORK --> TERMINATE
Jun 27 07:32:06 doctor mpd[50059]: [L_pptp-5] Link: Leave bundle "B_pptp-3"
Jun 27 07:32:06 doctor mpd[50059]: [L_pptp-5] ACCT: wtmp L_pptp-5 logout
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IFACE: setting ng0 MTU to 1500 bytes
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IFACE: Configuring ng_tcpmss mpd50059-B_pptp-3-mss: 1460
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] Bundle: Status update: up 0 links, total bandwidth 9600 bps
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IPCP: Close event
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IPCP: state change Opened --> Closing
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IPCP: SendTerminateReq #3
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IPCP: LayerDown
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IFACE: Remove address 10.10.0.1/32->10.10.0.2 from ng0
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IFACE: Down event
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IFACE: Change interface ng0 flags: -4097 +0
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IFACE: setting "ng0" description to "-: aboo UNSPEC - -"
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IFACE: Set description "-: aboo UNSPEC - -"
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] CCP: Close event
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] CCP: state change Stopped --> Closed
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IPCP: Down event
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IPCP: LayerFinish
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] Bundle: No NCPs left. Closing links...
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] IPCP: state change Closing --> Initial
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] CCP: Down event
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] CCP: state change Closed --> Initial
Jun 27 07:32:06 doctor mpd[50059]: [B_pptp-3] Bundle: Shutdown
Jun 27 07:32:06 doctor mpd[50059]: [L_pptp-5] LCP: SendTerminateAck #4
Jun 27 07:32:06 doctor mpd[50059]: [L_pptp-5] LCP: LayerDown
Jun 27 07:32:06 doctor mpd[50059]: [L_pptp-5] rec'd proto IP during terminate phase
Jun 27 07:32:08 doctor syslogd: last message repeated 5 times
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] LCP: state change Stopping --> Stopped
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] LCP: phase shift TERMINATE --> ESTABLISH
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] LCP: LayerFinish
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] device: CLOSE event
Jun 27 07:32:08 doctor mpd[50059]: pptp0-0: clearing call
Jun 27 07:32:08 doctor mpd[50059]: pptp0-0: killing channel
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] PPTP call terminated
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] device: DOWN event
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] Link: DOWN event
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] LCP: Close event
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] LCP: state change Stopped --> Closed
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] LCP: Down event
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] LCP: state change Closed --> Initial
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] LCP: phase shift ESTABLISH --> DEAD
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] Link: SHUTDOWN event
Jun 27 07:32:08 doctor mpd[50059]: [L_pptp-5] Link: Shutdown
Jun 27 07:32:18 doctor mpd[50059]: pptp0: closing connection with 75.156.190.254 51937
Jun 27 07:32:21 doctor mpd[50059]: pptp0: no reply to StopCtrlConnRequest after 3 sec
Jun 27 07:32:21 doctor mpd[50059]: pptp0: killing connection with 75.156.190.254 51937
I terminated the other since since no web pages were materialising.
You seem to disable RADIUS accounting because your RADIUS server still does not respond but mpd5 establishes and keeps connection just fine.
As to web pages access, you'll need to debug it apart of mpd5 configuration. You use private IP addresses for ngX interfaces, so you need to check if you configured NAT correctly; if tcpdump shows requests on ng0 client interface (maybe client does not route requests to this connection) etc.
Also make sure you enabled routing in the /etc/rc.conf with gateway_enable="YES" or else you won't get any packet forwarding by the kernel.
Last edit: Eugene Grosbein 2020-06-27
gateway enabled is yes; what about packet filtering / pf? I can see the LAN, I can
ping and traceroue as well.
gateway_enable (not enabled). You should verify if "sysctl net.inet.ip.forwarding" prints 1 and not 0.
As for pf, consult its manual. pf configuration is not related to mpd5.
Make sure that client connection is configured to use it as default gateway. Run ping from the client box against some external IP like 8.8.8.8 and use tcpdump -pni ng0 to see if client actually directs such request to the connection to mpd5. Next, use tcpdump at external interface to see if the request actually forwarded by the kernel. Check that its source address is translated correctly, too.
Last edit: Eugene Grosbein 2020-06-27
Turns out is was the pool that was being assigned was not in the pf declaration. PPTP is wrking. Now to work on L2TP and I am using racoon2.