Вот пытаюсь поставить заставить данную связку работать.
Всё запустилось, и даже говорит что работает, но не доконца.
Собственно проблема:
При попытке соединения с mpd, удачно проходит проверка пароля, а вот на этапе регистрации компьютера в сети, впн клиент говорит:
Ошибка 734 Протокол управления PPP-свзяью был прерван
и всё.
Порывшись в форуме нащёл схожую проблему но то решение не помогло. Порывшиь в лога обнаружил что (как я считаю) радиус не отдаёт мпд ip адрес:
Но почему? Нас я настроил, пул адресов создал, помогите разобраться.RADIUS: Termination cause: Protocol error:PPP layer IPCP failed: parameter negotiation failed, RADIUS: 15
Лог радиуса:
Код: Выделить всё
?Debug : Jul 10 10:43:29 RADIUS Auth: Packet from <xxx.xxxxx.ru>
?Debug : Jul 10 10:43:29 RADIUS Auth: User <test> connecting
?Debug : Jul 10 10:43:29 RADIUS DBA: NAS found. Data size <0>
?Debug : Jul 10 10:43:29 RADIUS DBA: Info for login <test> found. type <2>
?Debug : Jul 10 10:43:29 RADIUS Auth: Auth scheme: MS-CHAPv2
?Debug : Jul 10 10:43:29 RADIUS Auth: MS-CHAPv2: Authorized user <test>
?Debug : Jul 10 10:43:29 RADIUS Auth: MS-CHAPv2: MPPE Keys send
?Debug : Jul 10 10:43:29 RADIUS Auth: Dialup session limit:0 session count:34 for user:test
Info : Jul 10 10:43:29 UT: calc timeout
Info : Jul 10 10:43:29 UT: calc timeout
Info : Jul 10 10:43:29 UT: t 1152506609, left money 4654.016667
Info : Jul 10 10:43:29 UT: t 1152506609, left money 4654.016667
Info : Jul 10 10:43:29 UT: tr_id 1, mult 10.000000, next 1152554400
Info : Jul 10 10:43:29 UT: tr_id 1, mult 10.000000, next 1152554400
Info : Jul 10 10:43:29 UT: quantity 47791 for borders
Info : Jul 10 10:43:29 UT: quantity 47791 for borders
Info : Jul 10 10:43:29 UT: after borders q=47791, downloaded=47791, downed_as_prepaid=0 cost_info size <1>
Info : Jul 10 10:43:29 UT: after borders q=47791, downloaded=47791, downed_as_prepaid=0 cost_info size <1>
Info : Jul 10 10:43:29 UT: added entire 47791
Info : Jul 10 10:43:29 UT: added entire 47791
Info : Jul 10 10:43:29 UT: i==cost_info->end()
Info : Jul 10 10:43:29 UT: i==cost_info->end()
Info : Jul 10 10:43:29 UT: session_timeout <47791> session_limit <72000> old_session_timeout <0>
Info : Jul 10 10:43:29 UT: session_timeout <47791> session_limit <72000> old_session_timeout <0>
Info : Jul 10 10:43:29 UT: t 1152554400, left money 4521.263889
Info : Jul 10 10:43:29 UT: t 1152554400, left money 4521.263889
Info : Jul 10 10:43:29 UT: tr_id 1, mult 10.000000, next 1152626400
Info : Jul 10 10:43:29 UT: tr_id 1, mult 10.000000, next 1152626400
Info : Jul 10 10:43:29 UT: quantity 72000 for borders
Info : Jul 10 10:43:29 UT: quantity 72000 for borders
Info : Jul 10 10:43:29 UT: after borders q=72000, downloaded=119791, downed_as_prepaid=0 cost_info size <1>
Info : Jul 10 10:43:29 UT: after borders q=72000, downloaded=119791, downed_as_prepaid=0 cost_info size <1>
Info : Jul 10 10:43:29 UT: added entire 72000
Info : Jul 10 10:43:29 UT: added entire 72000
Info : Jul 10 10:43:29 UT: i==cost_info->end()
Info : Jul 10 10:43:29 UT: i==cost_info->end()
Info : Jul 10 10:43:29 UT: session_timeout <119791> session_limit <72000> old_session_timeout <47791>
Info : Jul 10 10:43:29 UT: session_timeout <119791> session_limit <72000> old_session_timeout <47791>
Info : Jul 10 10:43:29 UT: session_timeout > session_limit or session_timeout - old_session_timeout too small. Breaking ...
Info : Jul 10 10:43:29 UT: session_timeout > session_limit or session_timeout - old_session_timeout too small. Breaking ...
?Debug : Jul 10 10:43:29 RADIUS Auth: Calculated maximum session time: 72000
?Debug : Jul 10 10:43:29 RADIUS Auth: IP from Named Pool main claimed: 0xac100023
?Debug : Jul 10 10:43:29 RADIUS DBA: dialup_link_update called for slink:3
?Debug : Jul 10 10:43:29 RADIUS DBA: soft dialup_link_update for slink:3 session_count:35
?Debug : Jul 10 10:43:29 RADIUS Auth: Calling fill radius attributes for NAS. Attr storage size <0>
?Debug : Jul 10 10:43:29 RADIUS Auth: Packet from <mail.nurgush.ru>
?Debug : Jul 10 10:43:29 RADIUS Auth: User <test> connecting
?Debug : Jul 10 10:43:29 RADIUS DBA: NAS found. Data size <0>
?Debug : Jul 10 10:43:29 RADIUS DBA: Info for login <test> found. type <2>
?Debug : Jul 10 10:43:29 RADIUS Auth: Auth scheme: MS-CHAPv2
?Debug : Jul 10 10:43:29 RADIUS Auth: MS-CHAPv2: Authorized user <test>
?Debug : Jul 10 10:43:29 RADIUS Auth: MS-CHAPv2: MPPE Keys send
?Debug : Jul 10 10:43:29 RADIUS Auth: Dialup session limit:0 session count:35 for user:test
Info : Jul 10 10:43:29 UT: calc timeout
Info : Jul 10 10:43:29 UT: calc timeout
Info : Jul 10 10:43:29 UT: t 1152506609, left money 4654.016667
Info : Jul 10 10:43:29 UT: t 1152506609, left money 4654.016667
Info : Jul 10 10:43:29 UT: tr_id 1, mult 10.000000, next 1152554400
Info : Jul 10 10:43:29 UT: tr_id 1, mult 10.000000, next 1152554400
Info : Jul 10 10:43:29 UT: quantity 47791 for borders
Info : Jul 10 10:43:29 UT: quantity 47791 for borders
Info : Jul 10 10:43:29 UT: after borders q=47791, downloaded=47791, downed_as_prepaid=0 cost_info size <1>
Info : Jul 10 10:43:29 UT: after borders q=47791, downloaded=47791, downed_as_prepaid=0 cost_info size <1>
Info : Jul 10 10:43:29 UT: added entire 47791
Info : Jul 10 10:43:29 UT: added entire 47791
Info : Jul 10 10:43:29 UT: i==cost_info->end()
Info : Jul 10 10:43:29 UT: i==cost_info->end()
Info : Jul 10 10:43:29 UT: session_timeout <47791> session_limit <72000> old_session_timeout <0>
Info : Jul 10 10:43:29 UT: session_timeout <47791> session_limit <72000> old_session_timeout <0>
Info : Jul 10 10:43:29 UT: t 1152554400, left money 4521.263889
Info : Jul 10 10:43:29 UT: t 1152554400, left money 4521.263889
Info : Jul 10 10:43:29 UT: tr_id 1, mult 10.000000, next 1152626400
Info : Jul 10 10:43:29 UT: tr_id 1, mult 10.000000, next 1152626400
Info : Jul 10 10:43:29 UT: quantity 72000 for borders
Info : Jul 10 10:43:29 UT: quantity 72000 for borders
Info : Jul 10 10:43:29 UT: after borders q=72000, downloaded=119791, downed_as_prepaid=0 cost_info size <1>
Info : Jul 10 10:43:29 UT: after borders q=72000, downloaded=119791, downed_as_prepaid=0 cost_info size <1>
Info : Jul 10 10:43:29 UT: added entire 72000
Info : Jul 10 10:43:29 UT: added entire 72000
Info : Jul 10 10:43:29 UT: i==cost_info->end()
Info : Jul 10 10:43:29 UT: i==cost_info->end()
Info : Jul 10 10:43:29 UT: session_timeout <119791> session_limit <72000> old_session_timeout <47791>
Info : Jul 10 10:43:29 UT: session_timeout <119791> session_limit <72000> old_session_timeout <47791>
Info : Jul 10 10:43:29 UT: session_timeout > session_limit or session_timeout - old_session_timeout too small. Breaking ...
Info : Jul 10 10:43:29 UT: session_timeout > session_limit or session_timeout - old_session_timeout too small. Breaking ...
?Debug : Jul 10 10:43:29 RADIUS Auth: Calculated maximum session time: 72000
?Debug : Jul 10 10:43:29 RADIUS Auth: IP from Named Pool main claimed: 0xac100024
?Debug : Jul 10 10:43:29 RADIUS DBA: dialup_link_update called for slink:3
?Debug : Jul 10 10:43:29 RADIUS DBA: soft dialup_link_update for slink:3 session_count:36
?Debug : Jul 10 10:43:29 RADIUS Auth: Calling fill radius attributes for NAS. Attr storage size <0>
?Debug : Jul 10 10:43:29 RADIUS Auth: Packet from <mail.nurgush.ru>
?Debug : Jul 10 10:43:29 RADIUS Auth: User <test> connecting
?Debug : Jul 10 10:43:29 RADIUS DBA: NAS found. Data size <0>
?Debug : Jul 10 10:43:29 RADIUS DBA: Info for login <test> found. type <2>
?Debug : Jul 10 10:43:29 RADIUS Auth: Auth scheme: MS-CHAPv2
?Debug : Jul 10 10:43:29 RADIUS Auth: MS-CHAPv2: Authorized user <test>
?Debug : Jul 10 10:43:29 RADIUS Auth: MS-CHAPv2: MPPE Keys send
?Debug : Jul 10 10:43:29 RADIUS Auth: Dialup session limit:0 session count:36 for user:test
Info : Jul 10 10:43:29 UT: calc timeout
Info : Jul 10 10:43:29 UT: calc timeout
Info : Jul 10 10:43:29 UT: t 1152506609, left money 4654.016667
Info : Jul 10 10:43:29 UT: t 1152506609, left money 4654.016667
Info : Jul 10 10:43:29 UT: tr_id 1, mult 10.000000, next 1152554400
Info : Jul 10 10:43:29 UT: tr_id 1, mult 10.000000, next 1152554400
Info : Jul 10 10:43:29 UT: quantity 47791 for borders
Info : Jul 10 10:43:29 UT: quantity 47791 for borders
Info : Jul 10 10:43:29 UT: after borders q=47791, downloaded=47791, downed_as_prepaid=0 cost_info size <1>
Info : Jul 10 10:43:29 UT: after borders q=47791, downloaded=47791, downed_as_prepaid=0 cost_info size <1>
Info : Jul 10 10:43:29 UT: added entire 47791
Info : Jul 10 10:43:29 UT: added entire 47791
Info : Jul 10 10:43:29 UT: i==cost_info->end()
Info : Jul 10 10:43:29 UT: i==cost_info->end()
Info : Jul 10 10:43:29 UT: session_timeout <47791> session_limit <72000> old_session_timeout <0>
Info : Jul 10 10:43:29 UT: session_timeout <47791> session_limit <72000> old_session_timeout <0>
Info : Jul 10 10:43:29 UT: t 1152554400, left money 4521.263889
Info : Jul 10 10:43:29 UT: t 1152554400, left money 4521.263889
Info : Jul 10 10:43:29 UT: tr_id 1, mult 10.000000, next 1152626400
Info : Jul 10 10:43:29 UT: tr_id 1, mult 10.000000, next 1152626400
Info : Jul 10 10:43:29 UT: quantity 72000 for borders
Info : Jul 10 10:43:29 UT: quantity 72000 for borders
Info : Jul 10 10:43:29 UT: after borders q=72000, downloaded=119791, downed_as_prepaid=0 cost_info size <1>
Info : Jul 10 10:43:29 UT: after borders q=72000, downloaded=119791, downed_as_prepaid=0 cost_info size <1>
Info : Jul 10 10:43:29 UT: added entire 72000
Info : Jul 10 10:43:29 UT: added entire 72000
Info : Jul 10 10:43:29 UT: i==cost_info->end()
Info : Jul 10 10:43:29 UT: i==cost_info->end()
Info : Jul 10 10:43:29 UT: session_timeout <119791> session_limit <72000> old_session_timeout <47791>
Info : Jul 10 10:43:29 UT: session_timeout <119791> session_limit <72000> old_session_timeout <47791>
Info : Jul 10 10:43:29 UT: session_timeout > session_limit or session_timeout - old_session_timeout too small. Breaking ...
Info : Jul 10 10:43:29 UT: session_timeout > session_limit or session_timeout - old_session_timeout too small. Breaking ...
?Debug : Jul 10 10:43:29 RADIUS Auth: Calculated maximum session time: 72000
?Debug : Jul 10 10:43:29 RADIUS Auth: IP from Named Pool main claimed: 0xac100025
?Debug : Jul 10 10:43:29 RADIUS DBA: dialup_link_update called for slink:3
?Debug : Jul 10 10:43:29 RADIUS DBA: soft dialup_link_update for slink:3 session_count:37
?Debug : Jul 10 10:43:29 RADIUS Auth: Calling fill radius attributes for NAS. Attr storage size <0>
?Debug : Jul 10 10:43:29 RADIUS Auth: Packet from <mail.nurgush.ru>
?Debug : Jul 10 10:43:29 RADIUS Auth: User <test> connecting
?Debug : Jul 10 10:43:29 RADIUS DBA: NAS found. Data size <0>
?Debug : Jul 10 10:43:29 RADIUS DBA: Info for login <test> found. type <2>
?Debug : Jul 10 10:43:29 RADIUS Auth: Auth scheme: MS-CHAPv2
?Debug : Jul 10 10:43:29 RADIUS Auth: MS-CHAPv2: Authorized user <test>
?Debug : Jul 10 10:43:29 RADIUS Auth: MS-CHAPv2: MPPE Keys send
?Debug : Jul 10 10:43:29 RADIUS Auth: Dialup session limit:0 session count:37 for user:test
Info : Jul 10 10:43:29 UT: calc timeout
Info : Jul 10 10:43:29 UT: calc timeout
Info : Jul 10 10:43:29 UT: t 1152506609, left money 4654.016667
Info : Jul 10 10:43:29 UT: t 1152506609, left money 4654.016667
Info : Jul 10 10:43:29 UT: tr_id 1, mult 10.000000, next 1152554400
Info : Jul 10 10:43:29 UT: tr_id 1, mult 10.000000, next 1152554400
Info : Jul 10 10:43:29 UT: quantity 47791 for borders
Info : Jul 10 10:43:29 UT: quantity 47791 for borders
Info : Jul 10 10:43:29 UT: after borders q=47791, downloaded=47791, downed_as_prepaid=0 cost_info size <1>
Info : Jul 10 10:43:29 UT: after borders q=47791, downloaded=47791, downed_as_prepaid=0 cost_info size <1>
Info : Jul 10 10:43:29 UT: added entire 47791
Info : Jul 10 10:43:29 UT: added entire 47791
Info : Jul 10 10:43:29 UT: i==cost_info->end()
Info : Jul 10 10:43:29 UT: i==cost_info->end()
Info : Jul 10 10:43:29 UT: session_timeout <47791> session_limit <72000> old_session_timeout <0>
Info : Jul 10 10:43:29 UT: session_timeout <47791> session_limit <72000> old_session_timeout <0>
Info : Jul 10 10:43:29 UT: t 1152554400, left money 4521.263889
Info : Jul 10 10:43:29 UT: t 1152554400, left money 4521.263889
Info : Jul 10 10:43:29 UT: tr_id 1, mult 10.000000, next 1152626400
Info : Jul 10 10:43:29 UT: tr_id 1, mult 10.000000, next 1152626400
Info : Jul 10 10:43:29 UT: quantity 72000 for borders
Info : Jul 10 10:43:29 UT: quantity 72000 for borders
Info : Jul 10 10:43:29 UT: after borders q=72000, downloaded=119791, downed_as_prepaid=0 cost_info size <1>
Info : Jul 10 10:43:29 UT: after borders q=72000, downloaded=119791, downed_as_prepaid=0 cost_info size <1>
Info : Jul 10 10:43:29 UT: added entire 72000
Info : Jul 10 10:43:29 UT: added entire 72000
Info : Jul 10 10:43:29 UT: i==cost_info->end()
Info : Jul 10 10:43:29 UT: i==cost_info->end()
Info : Jul 10 10:43:29 UT: session_timeout <119791> session_limit <72000> old_session_timeout <47791>
Info : Jul 10 10:43:29 UT: session_timeout <119791> session_limit <72000> old_session_timeout <47791>
Info : Jul 10 10:43:29 UT: session_timeout > session_limit or session_timeout - old_session_timeout too small. Breaking ...
Info : Jul 10 10:43:29 UT: session_timeout > session_limit or session_timeout - old_session_timeout too small. Breaking ...
?Debug : Jul 10 10:43:29 RADIUS Auth: Calculated maximum session time: 72000
?Debug : Jul 10 10:43:29 RADIUS Auth: IP from Named Pool main claimed: 0xac100026
?Debug : Jul 10 10:43:29 RADIUS DBA: dialup_link_update called for slink:3
?Debug : Jul 10 10:43:29 RADIUS DBA: soft dialup_link_update for slink:3 session_count:38
?Debug : Jul 10 10:43:29 RADIUS Auth: Calling fill radius attributes for NAS. Attr storage size <0>
?Debug : Jul 10 10:43:38 RADIUS Auth: Packet from <mail.nurgush.ru>
?Debug : Jul 10 10:43:38 RADIUS Auth: User <test> connecting
?Debug : Jul 10 10:43:38 RADIUS DBA: NAS found. Data size <0>
?Debug : Jul 10 10:43:38 RADIUS DBA: Info for login <test> found. type <2>
?Debug : Jul 10 10:43:38 RADIUS Auth: Auth scheme: MS-CHAPv2
?Debug : Jul 10 10:43:38 RADIUS Auth: MS-CHAPv2: Authorized user <test>
?Debug : Jul 10 10:43:38 RADIUS Auth: MS-CHAPv2: MPPE Keys send
?Debug : Jul 10 10:43:38 RADIUS Auth: Dialup session limit:0 session count:38 for user:test
Info : Jul 10 10:43:38 UT: calc timeout
Info : Jul 10 10:43:38 UT: calc timeout
Info : Jul 10 10:43:38 UT: t 1152506618, left money 4654.016667
Info : Jul 10 10:43:38 UT: t 1152506618, left money 4654.016667
Info : Jul 10 10:43:38 UT: tr_id 1, mult 10.000000, next 1152554400
Info : Jul 10 10:43:38 UT: tr_id 1, mult 10.000000, next 1152554400
Info : Jul 10 10:43:38 UT: quantity 47782 for borders
Info : Jul 10 10:43:38 UT: quantity 47782 for borders
Info : Jul 10 10:43:38 UT: after borders q=47782, downloaded=47782, downed_as_prepaid=0 cost_info size <1>
Info : Jul 10 10:43:38 UT: after borders q=47782, downloaded=47782, downed_as_prepaid=0 cost_info size <1>
Info : Jul 10 10:43:38 UT: added entire 47782
Info : Jul 10 10:43:38 UT: added entire 47782
Info : Jul 10 10:43:38 UT: i==cost_info->end()
Info : Jul 10 10:43:38 UT: i==cost_info->end()
Info : Jul 10 10:43:38 UT: session_timeout <47782> session_limit <72000> old_session_timeout <0>
Info : Jul 10 10:43:38 UT: session_timeout <47782> session_limit <72000> old_session_timeout <0>
Info : Jul 10 10:43:38 UT: t 1152554400, left money 4521.288889
Info : Jul 10 10:43:38 UT: t 1152554400, left money 4521.288889
Info : Jul 10 10:43:38 UT: tr_id 1, mult 10.000000, next 1152626400
Info : Jul 10 10:43:38 UT: tr_id 1, mult 10.000000, next 1152626400
Info : Jul 10 10:43:38 UT: quantity 72000 for borders
Info : Jul 10 10:43:38 UT: quantity 72000 for borders
Info : Jul 10 10:43:38 UT: after borders q=72000, downloaded=119782, downed_as_prepaid=0 cost_info size <1>
Info : Jul 10 10:43:38 UT: after borders q=72000, downloaded=119782, downed_as_prepaid=0 cost_info size <1>
Info : Jul 10 10:43:38 UT: added entire 72000
Info : Jul 10 10:43:38 UT: added entire 72000
Info : Jul 10 10:43:38 UT: i==cost_info->end()
Info : Jul 10 10:43:38 UT: i==cost_info->end()
Info : Jul 10 10:43:38 UT: session_timeout <119782> session_limit <72000> old_session_timeout <47782>
Info : Jul 10 10:43:38 UT: session_timeout <119782> session_limit <72000> old_session_timeout <47782>
Info : Jul 10 10:43:38 UT: session_timeout > session_limit or session_timeout - old_session_timeout too small. Breaking ...
Info : Jul 10 10:43:38 UT: session_timeout > session_limit or session_timeout - old_session_timeout too small. Breaking ...
?Debug : Jul 10 10:43:38 RADIUS Auth: Calculated maximum session time: 72000
?Debug : Jul 10 10:43:38 RADIUS Auth: IP from Named Pool main claimed: 0xac100027
?Debug : Jul 10 10:43:38 RADIUS DBA: dialup_link_update called for slink:3
?Debug : Jul 10 10:43:38 RADIUS DBA: soft dialup_link_update for slink:3 session_count:39
?Debug : Jul 10 10:43:38 RADIUS Auth: Calling fill radius attributes for NAS. Attr storage size <0>
?Debug : Jul 10 10:43:38 RADIUS Acct: Acct packet with session ID: 2506581-link0
?Debug : Jul 10 10:43:38 RADIUS Acct: Acct-Stop packet
?Debug : Jul 10 10:43:38 RADIUS Acct: No h323_disconnect_cause in packet!
Info : Jul 10 10:43:38 UT: session_addon <0>
Info : Jul 10 10:43:38 UT: session_addon <0>
Info : Jul 10 10:43:38 UT: tel tarification for slink 3, tr_id 1, mult 10.000000, next 1152506629
Info : Jul 10 10:43:38 UT: tel tarification for slink 3, tr_id 1, mult 10.000000, next 1152506629
Info : Jul 10 10:43:38 UT: cost info:
Info : Jul 10 10:43:38 UT: cost info:
Info : Jul 10 10:43:38 UT: type 0 deny 0 base_cost 1.000000 size 48 tr_id 1 mult 10.000000 added 0sum 0.000000
Info : Jul 10 10:43:38 UT: type 0 deny 0 base_cost 1.000000 size 48 tr_id 1 mult 10.000000 added 0sum 0.000000
?Debug : Jul 10 10:43:38 RADIUS DBA: Dialup Discount: TR ID 1: 0.133 for 48 sec
?Debug : Jul 10 10:43:38 RADIUS URFA[plugin]: Account <2> with balance <4653.883>
Код: Выделить всё
Jul 10 10:42:50 mail mpd: mpd: PPTP connection from yy.yy.yy.yy:yyy
Jul 10 10:42:50 mail mpd: pptp0: attached to connection with yy.yy.yy.yy:yyy
Jul 10 10:42:50 mail mpd: [pptp0] IFACE: Open event
Jul 10 10:42:50 mail mpd: [pptp0] IPCP: Open event
Jul 10 10:42:50 mail mpd: [pptp0] IPCP: state change Initial --> Starting
Jul 10 10:42:50 mail mpd: [pptp0] IPCP: LayerStart
Jul 10 10:42:50 mail mpd: [pptp0] IPCP: Open event
Jul 10 10:42:50 mail mpd: [pptp0] bundle: OPEN event in state CLOSED
Jul 10 10:42:50 mail mpd: [pptp0] opening link "link0"...
Jul 10 10:42:50 mail mpd: [link0] link: OPEN event
Jul 10 10:42:50 mail mpd: [link0] LCP: Open event
Jul 10 10:42:50 mail mpd: [link0] LCP: state change Initial --> Starting
Jul 10 10:42:50 mail mpd: [link0] LCP: LayerStart
Jul 10 10:42:50 mail mpd: [link0] device: OPEN event in state DOWN
Jul 10 10:42:50 mail mpd: [link0] attaching to peer's outgoing call
Jul 10 10:42:50 mail mpd: [link0] device is now in state OPENING
Jul 10 10:42:50 mail mpd: [link0] device: UP event in state OPENING
Jul 10 10:42:50 mail mpd: [link0] device is now in state UP
Jul 10 10:42:50 mail mpd: [link0] link: UP event
Jul 10 10:42:50 mail mpd: [link0] link: origination is remote
Jul 10 10:42:50 mail mpd: [link0] LCP: Up event
Jul 10 10:42:50 mail mpd: [link0] LCP: state change Starting --> Req-Sent
Jul 10 10:42:50 mail mpd: [link0] LCP: phase shift DEAD --> ESTABLISH
Jul 10 10:42:50 mail mpd: [link0] LCP: SendConfigReq #1
Jul 10 10:42:50 mail mpd: ACFCOMP
Jul 10 10:42:50 mail mpd: PROTOCOMP
Jul 10 10:42:50 mail mpd: MRU 1460
Jul 10 10:42:50 mail mpd: MAGICNUM 0bb32f17
Jul 10 10:42:50 mail mpd: AUTHPROTO CHAP MSOFTv2
Jul 10 10:42:50 mail mpd: [link0] LCP: rec'd Configure Request #0 link 0 (Req-Sent)
Jul 10 10:42:50 mail mpd: MRU 1400
Jul 10 10:42:50 mail mpd: MAGICNUM 5d037b00
Jul 10 10:42:50 mail mpd: PROTOCOMP
Jul 10 10:42:50 mail mpd: ACFCOMP
Jul 10 10:42:50 mail mpd: CALLBACK
Jul 10 10:42:50 mail mpd: Not supported
Jul 10 10:42:50 mail mpd: [link0] LCP: SendConfigRej #0
Jul 10 10:42:50 mail mpd: CALLBACK
Jul 10 10:42:50 mail mpd: pptp0-0: ignoring SetLinkInfo
Jul 10 10:42:50 mail mpd: [link0] LCP: rec'd Configure Request #1 link 0 (Req-Sent)
Jul 10 10:42:50 mail mpd: MRU 1400
Jul 10 10:42:50 mail mpd: MAGICNUM 5d037b00
Jul 10 10:42:50 mail mpd: PROTOCOMP
Jul 10 10:42:50 mail mpd: ACFCOMP
Jul 10 10:42:50 mail mpd: [link0] LCP: SendConfigAck #1
Jul 10 10:42:50 mail mpd: MRU 1400
Jul 10 10:42:50 mail mpd: MAGICNUM 5d037b00
Jul 10 10:42:50 mail mpd: PROTOCOMP
Jul 10 10:42:50 mail mpd: ACFCOMP
Jul 10 10:42:50 mail mpd: [link0] LCP: state change Req-Sent --> Ack-Sent
Jul 10 10:42:52 mail mpd: [link0] LCP: SendConfigReq #2
Jul 10 10:42:52 mail mpd: ACFCOMP
Jul 10 10:42:52 mail mpd: PROTOCOMP
Jul 10 10:42:52 mail mpd: MRU 1460
Jul 10 10:42:52 mail mpd: MAGICNUM 0bb32f17
Jul 10 10:42:52 mail mpd: AUTHPROTO CHAP MSOFTv2
Jul 10 10:42:52 mail mpd: [link0] LCP: rec'd Configure Ack #2 link 0 (Ack-Sent)
Jul 10 10:42:52 mail mpd: ACFCOMP
Jul 10 10:42:52 mail mpd: PROTOCOMP
Jul 10 10:42:52 mail mpd: MRU 1460
Jul 10 10:42:52 mail mpd: MAGICNUM 0bb32f17
Jul 10 10:42:52 mail mpd: AUTHPROTO CHAP MSOFTv2
Jul 10 10:42:52 mail mpd: [link0] LCP: state change Ack-Sent --> Opened
Jul 10 10:42:52 mail mpd: [link0] LCP: phase shift ESTABLISH --> AUTHENTICATE
Jul 10 10:42:52 mail mpd: [link0] LCP: auth: peer wants nothing, I want CHAP
Jul 10 10:42:52 mail mpd: [link0] CHAP: sending CHALLENGE
Jul 10 10:42:52 mail mpd: [link0] LCP: LayerUp
Jul 10 10:42:52 mail mpd: [link0] LCP: rec'd Ident #2 link 0 (Opened)
Jul 10 10:42:52 mail mpd: MESG: MSRASV5.10
Jul 10 10:42:52 mail mpd: pptp0-0: ignoring SetLinkInfo
Jul 10 10:42:52 mail mpd: [link0] LCP: rec'd Ident #3 link 0 (Opened)
Jul 10 10:42:52 mail mpd: MESG: MSRAS-0-CHMK-4014880602
Jul 10 10:42:52 mail mpd: [link0] CHAP: rec'd RESPONSE #1
Jul 10 10:42:52 mail mpd: Name: "test"
Jul 10 10:42:52 mail mpd: [link0] RADIUS: using /etc/radius.conf
Jul 10 10:42:52 mail mpd: [link0] RADIUS: RadiusAddServer Adding 127.0.0.1
Jul 10 10:42:52 mail mpd: [link0] RADIUS: RadiusPutAuth: RADIUS_CHAP (MSOFTv2) peer name: test
Jul 10 10:43:01 mail mpd: [link0] RADIUS: RadiusSendRequest: RAD_ACCESS_ACCEPT for user test
Jul 10 10:43:01 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_FRAMED_PROTOCOL: 2
Jul 10 10:43:01 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_FRAMED_PROTOCOL: 1
Jul 10 10:43:01 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_FRAMED_IP_ADDRESS: 172.16.0.23
Jul 10 10:43:01 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_FRAMED_IP_NETMASK: 255.255.255.255
Jul 10 10:43:01 mail mpd: [link0] RADIUS: RadiusGetParams: Dropping attribute: 10
Jul 10 10:43:01 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_FRAMED_COMPRESSION: 0
Jul 10 10:43:01 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_SESSION_TIMEOUT: 72000
Jul 10 10:43:01 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_MICROSOFT_MS_MPPE_ENCRYPTION_POLICY: 1 (Allowed)
Jul 10 10:43:01 mail mpd: [link0] RADIUS: RadiusGetParams: Dropping MPD vendor specific attribute: 7
Jul 10 10:43:01 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_MICROSOFT_MS_MPPE_ENCRYPTION_TYPES: 6 (40 128 bit)
Jul 10 10:43:01 mail mpd: [link0] RADIUS: RadiusGetParams: Dropping MPD vendor specific attribute: 8
Jul 10 10:43:01 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_MICROSOFT_MS_MPPE_SEND_KEY
Jul 10 10:43:01 mail mpd: [link0] RADIUS: RadiusGetParams: Dropping MPD vendor specific attribute: 16
Jul 10 10:43:01 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_MICROSOFT_MS_MPPE_RECV_KEY
Jul 10 10:43:01 mail mpd: [link0] RADIUS: RadiusGetParams: Dropping MPD vendor specific attribute: 17
Jul 10 10:43:01 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_MICROSOFT_MS_CHAP2_SUCCESS: S=2B83181326785C9BF8D42CE0B12A91E0187EEA79
Jul 10 10:43:01 mail mpd: [link0] RADIUS: RadiusGetParams: Dropping MPD vendor specific attribute: 26
Jul 10 10:43:01 mail mpd: Response is valid
Jul 10 10:43:01 mail mpd: [link0] CHAP: sending SUCCESS
Jul 10 10:43:01 mail mpd: [link0] LCP: authorization successful
Jul 10 10:43:01 mail mpd: [link0] LCP: phase shift AUTHENTICATE --> NETWORK
Jul 10 10:43:01 mail mpd: [pptp0] setting interface ng0 MTU to 1400 bytes
Jul 10 10:43:01 mail mpd: [pptp0] up: 1 link, total bandwidth 64000 bps
Jul 10 10:43:01 mail mpd: [pptp0] IPCP: Up event
Jul 10 10:43:01 mail mpd: [pptp0] IPCP: state change Starting --> Req-Sent
Jul 10 10:43:01 mail mpd: [pptp0] IPCP: SendConfigReq #1
Jul 10 10:43:01 mail mpd: IPADDR 172.16.0.1
Jul 10 10:43:01 mail mpd: COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Jul 10 10:43:01 mail mpd: [pptp0] CCP: Open event
Jul 10 10:43:01 mail mpd: [pptp0] CCP: state change Initial --> Starting
Jul 10 10:43:01 mail mpd: [pptp0] CCP: LayerStart
Jul 10 10:43:01 mail mpd: [pptp0] CCP: Up event
Jul 10 10:43:01 mail mpd: [pptp0] CCP: state change Starting --> Req-Sent
Jul 10 10:43:01 mail mpd: [pptp0] CCP: SendConfigReq #1
Jul 10 10:43:01 mail mpd: [link0] CCP: Checking whether 40 bits are enabled -> yes
Jul 10 10:43:01 mail mpd: [link0] CCP: Checking whether 56 bits are enabled -> yes
Jul 10 10:43:01 mail mpd: [link0] CCP: Checking whether 128 bits are enabled -> yes
Jul 10 10:43:01 mail mpd: MPPC
Jul 10 10:43:01 mail mpd: 0x010000e1: MPPC MPPE, 40 bit, 56 bit, 128 bit, stateless
Jul 10 10:43:01 mail mpd: [link0] RADIUS: RadiusAccount for: test
Jul 10 10:43:01 mail mpd: [link0] RADIUS: using /etc/radius.conf
Jul 10 10:43:01 mail mpd: [link0] RADIUS: RadiusAddServer Adding 127.0.0.1
Jul 10 10:43:01 mail mpd: [link0] RADIUS: RadiusAccount: Sending accounting data (Type: 1)
Jul 10 10:43:01 mail mpd: [link0] RADIUS: RadiusSendRequest: RAD_ACCOUNTING_RESPONSE for user test
Jul 10 10:43:01 mail mpd: [pptp0] IPCP: SendConfigReq #2
Jul 10 10:43:01 mail mpd: IPADDR 172.16.0.1
Jul 10 10:43:01 mail mpd: COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Jul 10 10:43:01 mail mpd: [pptp0] CCP: SendConfigReq #2
Jul 10 10:43:01 mail mpd: [link0] CCP: Checking whether 40 bits are enabled -> yes
Jul 10 10:43:01 mail mpd: [link0] CCP: Checking whether 56 bits are enabled -> yes
Jul 10 10:43:01 mail mpd: [link0] CCP: Checking whether 128 bits are enabled -> yes
Jul 10 10:43:01 mail mpd: MPPC
Jul 10 10:43:01 mail mpd: 0x010000e1: MPPC MPPE, 40 bit, 56 bit, 128 bit, stateless
Jul 10 10:43:01 mail mpd: [link0] CHAP: rec'd RESPONSE #1
Jul 10 10:43:01 mail mpd: Not expected, but that's OK
Jul 10 10:43:01 mail mpd: Name: "test"
Jul 10 10:43:01 mail mpd: [link0] RADIUS: using /etc/radius.conf
Jul 10 10:43:01 mail mpd: [link0] RADIUS: RadiusAddServer Adding 127.0.0.1
Jul 10 10:43:01 mail mpd: [link0] RADIUS: RadiusPutAuth: RADIUS_CHAP (MSOFTv2) peer name: test
Jul 10 10:43:11 mail mpd: [link0] RADIUS: RadiusSendRequest: RAD_ACCESS_ACCEPT for user test
Jul 10 10:43:11 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_FRAMED_PROTOCOL: 2
Jul 10 10:43:11 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_FRAMED_PROTOCOL: 1
Jul 10 10:43:11 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_FRAMED_IP_ADDRESS: 172.16.0.27
Jul 10 10:43:11 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_FRAMED_IP_NETMASK: 255.255.255.255
Jul 10 10:43:11 mail mpd: [link0] RADIUS: RadiusGetParams: Dropping attribute: 10
Jul 10 10:43:11 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_FRAMED_COMPRESSION: 0
Jul 10 10:43:11 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_SESSION_TIMEOUT: 72000
Jul 10 10:43:11 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_MICROSOFT_MS_MPPE_ENCRYPTION_POLICY: 1 (Allowed)
Jul 10 10:43:11 mail mpd: [link0] RADIUS: RadiusGetParams: Dropping MPD vendor specific attribute: 7
Jul 10 10:43:11 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_MICROSOFT_MS_MPPE_ENCRYPTION_TYPES: 6 (40 128 bit)
Jul 10 10:43:11 mail mpd: [link0] RADIUS: RadiusGetParams: Dropping MPD vendor specific attribute: 8
Jul 10 10:43:11 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_MICROSOFT_MS_MPPE_SEND_KEY
Jul 10 10:43:11 mail mpd: [link0] RADIUS: RadiusGetParams: Dropping MPD vendor specific attribute: 16
Jul 10 10:43:11 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_MICROSOFT_MS_MPPE_RECV_KEY
Jul 10 10:43:11 mail mpd: [link0] RADIUS: RadiusGetParams: Dropping MPD vendor specific attribute: 17
Jul 10 10:43:11 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_MICROSOFT_MS_CHAP2_SUCCESS: S=2B83181326785C9BF8D42CE0B12A91E0187EEA79
Jul 10 10:43:11 mail mpd: [link0] RADIUS: RadiusGetParams: Dropping MPD vendor specific attribute: 26
Jul 10 10:43:11 mail mpd: Response is valid
Jul 10 10:43:11 mail mpd: [link0] CHAP: sending SUCCESS
Jul 10 10:43:11 mail mpd: [link0] LCP: authorization successful
Jul 10 10:43:11 mail mpd: [pptp0] IPCP: SendConfigReq #3
Jul 10 10:43:11 mail mpd: IPADDR 172.16.0.1
Jul 10 10:43:11 mail mpd: COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Jul 10 10:43:11 mail mpd: [pptp0] CCP: SendConfigReq #3
Jul 10 10:43:11 mail mpd: [link0] CCP: Checking whether 40 bits are enabled -> yes
Jul 10 10:43:11 mail mpd: [link0] CCP: Checking whether 56 bits are enabled -> yes
Jul 10 10:43:11 mail mpd: [link0] CCP: Checking whether 128 bits are enabled -> yes
Jul 10 10:43:11 mail mpd: MPPC
Jul 10 10:43:11 mail mpd: 0x010000e1: MPPC MPPE, 40 bit, 56 bit, 128 bit, stateless
Jul 10 10:43:11 mail mpd: [link0] CHAP: rec'd RESPONSE #1
Jul 10 10:43:11 mail mpd: Not expected, but that's OK
Jul 10 10:43:11 mail mpd: Name: "test"
Jul 10 10:43:11 mail mpd: [link0] RADIUS: using /etc/radius.conf
Jul 10 10:43:11 mail mpd: [link0] RADIUS: RadiusAddServer Adding 127.0.0.1
Jul 10 10:43:11 mail mpd: [link0] RADIUS: RadiusPutAuth: RADIUS_CHAP (MSOFTv2) peer name: test
Jul 10 10:43:20 mail mpd: [link0] RADIUS: RadiusSendRequest: RAD_ACCESS_ACCEPT for user test
Jul 10 10:43:20 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_FRAMED_PROTOCOL: 2
Jul 10 10:43:20 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_FRAMED_PROTOCOL: 1
Jul 10 10:43:20 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_FRAMED_IP_ADDRESS: 172.16.0.31
Jul 10 10:43:20 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_FRAMED_IP_NETMASK: 255.255.255.255
Jul 10 10:43:20 mail mpd: [link0] RADIUS: RadiusGetParams: Dropping attribute: 10
Jul 10 10:43:20 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_FRAMED_COMPRESSION: 0
Jul 10 10:43:20 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_SESSION_TIMEOUT: 72000
Jul 10 10:43:20 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_MICROSOFT_MS_MPPE_ENCRYPTION_POLICY: 1 (Allowed)
Jul 10 10:43:20 mail mpd: [link0] RADIUS: RadiusGetParams: Dropping MPD vendor specific attribute: 7
Jul 10 10:43:20 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_MICROSOFT_MS_MPPE_ENCRYPTION_TYPES: 6 (40 128 bit)
Jul 10 10:43:20 mail mpd: [link0] RADIUS: RadiusGetParams: Dropping MPD vendor specific attribute: 8
Jul 10 10:43:20 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_MICROSOFT_MS_MPPE_SEND_KEY
Jul 10 10:43:20 mail mpd: [link0] RADIUS: RadiusGetParams: Dropping MPD vendor specific attribute: 16
Jul 10 10:43:20 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_MICROSOFT_MS_MPPE_RECV_KEY
Jul 10 10:43:20 mail mpd: [link0] RADIUS: RadiusGetParams: Dropping MPD vendor specific attribute: 17
Jul 10 10:43:20 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_MICROSOFT_MS_CHAP2_SUCCESS: S=2B83181326785C9BF8D42CE0B12A91E0187EEA79
Jul 10 10:43:20 mail mpd: [link0] RADIUS: RadiusGetParams: Dropping MPD vendor specific attribute: 26
Jul 10 10:43:20 mail mpd: Response is valid
Jul 10 10:43:20 mail mpd: [link0] CHAP: sending SUCCESS
Jul 10 10:43:20 mail mpd: [link0] LCP: authorization successful
Jul 10 10:43:20 mail mpd: [pptp0] IPCP: SendConfigReq #4
Jul 10 10:43:20 mail mpd: IPADDR 172.16.0.1
Jul 10 10:43:20 mail mpd: COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Jul 10 10:43:20 mail mpd: [pptp0] CCP: SendConfigReq #4
Jul 10 10:43:20 mail mpd: [link0] CCP: Checking whether 40 bits are enabled -> yes
Jul 10 10:43:20 mail mpd: [link0] CCP: Checking whether 56 bits are enabled -> yes
Jul 10 10:43:20 mail mpd: [link0] CCP: Checking whether 128 bits are enabled -> yes
Jul 10 10:43:20 mail mpd: MPPC
Jul 10 10:43:20 mail mpd: 0x010000e1: MPPC MPPE, 40 bit, 56 bit, 128 bit, stateless
Jul 10 10:43:20 mail mpd: [link0] CHAP: rec'd RESPONSE #1
Jul 10 10:43:20 mail mpd: Not expected, but that's OK
Jul 10 10:43:20 mail mpd: Name: "test"
Jul 10 10:43:20 mail mpd: [link0] RADIUS: using /etc/radius.conf
Jul 10 10:43:20 mail mpd: [link0] RADIUS: RadiusAddServer Adding 127.0.0.1
Jul 10 10:43:20 mail mpd: [link0] RADIUS: RadiusPutAuth: RADIUS_CHAP (MSOFTv2) peer name: test
Jul 10 10:43:29 mail mpd: [link0] RADIUS: RadiusSendRequest: RAD_ACCESS_ACCEPT for user test
Jul 10 10:43:29 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_FRAMED_PROTOCOL: 2
Jul 10 10:43:29 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_FRAMED_PROTOCOL: 1
Jul 10 10:43:29 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_FRAMED_IP_ADDRESS: 172.16.0.35
Jul 10 10:43:29 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_FRAMED_IP_NETMASK: 255.255.255.255
Jul 10 10:43:29 mail mpd: [link0] RADIUS: RadiusGetParams: Dropping attribute: 10
Jul 10 10:43:29 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_FRAMED_COMPRESSION: 0
Jul 10 10:43:29 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_SESSION_TIMEOUT: 72000
Jul 10 10:43:38 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_MICROSOFT_MS_MPPE_ENCRYPTION_POLICY: 1 (Allowed)
Jul 10 10:43:38 mail mpd: [link0] RADIUS: RadiusGetParams: Dropping MPD vendor specific attribute: 7
Jul 10 10:43:38 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_MICROSOFT_MS_MPPE_ENCRYPTION_TYPES: 6 (40 128 bit)
Jul 10 10:43:38 mail mpd: [link0] RADIUS: RadiusGetParams: Dropping MPD vendor specific attribute: 8
Jul 10 10:43:38 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_MICROSOFT_MS_MPPE_SEND_KEY
Jul 10 10:43:38 mail mpd: [link0] RADIUS: RadiusGetParams: Dropping MPD vendor specific attribute: 16
Jul 10 10:43:38 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_MICROSOFT_MS_MPPE_RECV_KEY
Jul 10 10:43:38 mail mpd: [link0] RADIUS: RadiusGetParams: Dropping MPD vendor specific attribute: 17
Jul 10 10:43:38 mail mpd: [link0] RADIUS: RadiusGetParams: RAD_MICROSOFT_MS_CHAP2_SUCCESS: S=2B83181326785C9BF8D42CE0B12A91E0187EEA79
Jul 10 10:43:38 mail mpd: [link0] RADIUS: RadiusGetParams: Dropping MPD vendor specific attribute: 26
Jul 10 10:43:38 mail mpd: Response is valid
Jul 10 10:43:38 mail mpd: [link0] CHAP: sending SUCCESS
Jul 10 10:43:38 mail mpd: [link0] LCP: authorization successful
Jul 10 10:43:38 mail mpd: [pptp0] IPCP: SendConfigReq #6
Jul 10 10:43:38 mail mpd: IPADDR 172.16.0.1
Jul 10 10:43:38 mail mpd: COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Jul 10 10:43:38 mail mpd: [pptp0] CCP: SendConfigReq #6
Jul 10 10:43:38 mail mpd: [link0] CCP: Checking whether 40 bits are enabled -> yes
Jul 10 10:43:38 mail mpd: [link0] CCP: Checking whether 56 bits are enabled -> yes
Jul 10 10:43:38 mail mpd: [link0] CCP: Checking whether 128 bits are enabled -> yes
Jul 10 10:43:38 mail mpd: MPPC
Jul 10 10:43:38 mail mpd: 0x010000e1: MPPC MPPE, 40 bit, 56 bit, 128 bit, stateless
Jul 10 10:43:38 mail mpd: [pptp0] CCP: rec'd Configure Request #4 link 0 (Req-Sent)
Jul 10 10:43:38 mail mpd: MPPC
Jul 10 10:43:38 mail mpd: 0x01000001: MPPC
Jul 10 10:43:38 mail mpd: [link0] CCP: Checking whether 40 bits are enabled -> yes
Jul 10 10:43:38 mail mpd: [link0] CCP: Checking whether 56 bits are enabled -> yes
Jul 10 10:43:38 mail mpd: [link0] CCP: Checking whether 128 bits are enabled -> yes
Jul 10 10:43:38 mail mpd: [pptp0] CCP: SendConfigNak #4
Jul 10 10:43:38 mail mpd: MPPC
Jul 10 10:43:38 mail mpd: 0x010000e1: MPPC MPPE, 40 bit, 56 bit, 128 bit, stateless
Jul 10 10:43:38 mail mpd: [pptp0] IPCP: rec'd Configure Request #5 link 0 (Req-Sent)
Jul 10 10:43:38 mail mpd: IPADDR 0.0.0.0
Jul 10 10:43:38 mail mpd: NAKing with 172.16.0.2
Jul 10 10:43:38 mail mpd: PRIDNS 0.0.0.0
Jul 10 10:43:38 mail mpd: PRINBNS 0.0.0.0
Jul 10 10:43:38 mail mpd: SECDNS 0.0.0.0
Jul 10 10:43:38 mail mpd: SECNBNS 0.0.0.0
Jul 10 10:43:38 mail mpd: [pptp0] IPCP: SendConfigRej #5
Jul 10 10:43:38 mail mpd: PRIDNS 0.0.0.0
Jul 10 10:43:38 mail mpd: PRINBNS 0.0.0.0
Jul 10 10:43:38 mail mpd: SECDNS 0.0.0.0
Jul 10 10:43:38 mail mpd: SECNBNS 0.0.0.0
Jul 10 10:43:38 mail mpd: [pptp0] IPCP: rec'd Configure Reject #1 link 0 (Req-Sent)
Jul 10 10:43:38 mail mpd: Wrong id#, expecting 6
Jul 10 10:43:38 mail mpd: [pptp0] CCP: rec'd Configure Nak #1 link 0 (Req-Sent)
Jul 10 10:43:38 mail mpd: Wrong id#, expecting 6
Jul 10 10:43:38 mail mpd: [pptp0] IPCP: rec'd Configure Reject #2 link 0 (Req-Sent)
Jul 10 10:43:38 mail mpd: Wrong id#, expecting 6
Jul 10 10:43:38 mail mpd: [pptp0] CCP: rec'd Configure Nak #2 link 0 (Req-Sent)
Jul 10 10:43:38 mail mpd: Wrong id#, expecting 6
Jul 10 10:43:38 mail mpd: [pptp0] IPCP: rec'd Configure Request #6 link 0 (Req-Sent)
Jul 10 10:43:38 mail mpd: IPADDR 0.0.0.0
Jul 10 10:43:38 mail mpd: NAKing with 172.16.0.2
Jul 10 10:43:38 mail mpd: PRIDNS 0.0.0.0
Jul 10 10:43:38 mail mpd: PRINBNS 0.0.0.0
Jul 10 10:43:38 mail mpd: SECDNS 0.0.0.0
Jul 10 10:43:38 mail mpd: SECNBNS 0.0.0.0
Jul 10 10:43:38 mail mpd: [pptp0] IPCP: SendConfigRej #6
Jul 10 10:43:38 mail mpd: PRIDNS 0.0.0.0
Jul 10 10:43:38 mail mpd: PRINBNS 0.0.0.0
Jul 10 10:43:38 mail mpd: SECDNS 0.0.0.0
Jul 10 10:43:38 mail mpd: SECNBNS 0.0.0.0
Jul 10 10:43:38 mail mpd: [pptp0] CCP: rec'd Configure Request #7 link 0 (Req-Sent)
Jul 10 10:43:38 mail mpd: MPPC
Jul 10 10:43:38 mail mpd: 0x01000001: MPPC
Jul 10 10:43:38 mail mpd: [link0] CCP: Checking whether 40 bits are enabled -> yes
Jul 10 10:43:38 mail mpd: [link0] CCP: Checking whether 56 bits are enabled -> yes
Jul 10 10:43:38 mail mpd: [link0] CCP: Checking whether 128 bits are enabled -> yes
Jul 10 10:43:38 mail mpd: [pptp0] CCP: SendConfigNak #7
Jul 10 10:43:38 mail mpd: MPPC
Jul 10 10:43:38 mail mpd: 0x010000e1: MPPC MPPE, 40 bit, 56 bit, 128 bit, stateless
Jul 10 10:43:38 mail mpd: [pptp0] IPCP: rec'd Configure Request #8 link 0 (Req-Sent)
Jul 10 10:43:38 mail mpd: IPADDR 0.0.0.0
Jul 10 10:43:38 mail mpd: NAKing with 172.16.0.2
Jul 10 10:43:38 mail mpd: PRIDNS 0.0.0.0
Jul 10 10:43:38 mail mpd: PRINBNS 0.0.0.0
Jul 10 10:43:38 mail mpd: SECDNS 0.0.0.0
Jul 10 10:43:38 mail mpd: SECNBNS 0.0.0.0
Jul 10 10:43:38 mail mpd: [pptp0] IPCP: SendConfigRej #8
Jul 10 10:43:38 mail mpd: PRIDNS 0.0.0.0
Jul 10 10:43:38 mail mpd: PRINBNS 0.0.0.0
Jul 10 10:43:38 mail mpd: SECDNS 0.0.0.0
Jul 10 10:43:38 mail mpd: SECNBNS 0.0.0.0
Jul 10 10:43:38 mail mpd: [pptp0] CCP: rec'd Configure Request #9 link 0 (Req-Sent)
Jul 10 10:43:38 mail mpd: MPPC
Jul 10 10:43:38 mail mpd: 0x01000001: MPPC
Jul 10 10:43:38 mail mpd: [link0] CCP: Checking whether 40 bits are enabled -> yes
Jul 10 10:43:38 mail mpd: [link0] CCP: Checking whether 56 bits are enabled -> yes
Jul 10 10:43:38 mail mpd: [link0] CCP: Checking whether 128 bits are enabled -> yes
Jul 10 10:43:38 mail mpd: [pptp0] CCP: SendConfigNak #9
Jul 10 10:43:38 mail mpd: MPPC
Jul 10 10:43:38 mail mpd: 0x010000e1: MPPC MPPE, 40 bit, 56 bit, 128 bit, stateless
Jul 10 10:43:38 mail mpd: [pptp0] IPCP: rec'd Configure Reject #3 link 0 (Req-Sent)
Jul 10 10:43:38 mail mpd: Wrong id#, expecting 6
Jul 10 10:43:38 mail mpd: [pptp0] CCP: rec'd Configure Nak #3 link 0 (Req-Sent)
Jul 10 10:43:38 mail mpd: Wrong id#, expecting 6
Jul 10 10:43:38 mail mpd: [pptp0] IPCP: rec'd Configure Request #10 link 0 (Req-Sent)
Jul 10 10:43:38 mail mpd: IPADDR 0.0.0.0
Jul 10 10:43:38 mail mpd: NAKing with 172.16.0.2
Jul 10 10:43:38 mail mpd: PRIDNS 0.0.0.0
Jul 10 10:43:38 mail mpd: PRINBNS 0.0.0.0
Jul 10 10:43:38 mail mpd: SECDNS 0.0.0.0
Jul 10 10:43:38 mail mpd: SECNBNS 0.0.0.0
Jul 10 10:43:38 mail mpd: [pptp0] IPCP: SendConfigRej #10
Jul 10 10:43:38 mail mpd: PRIDNS 0.0.0.0
Jul 10 10:43:38 mail mpd: PRINBNS 0.0.0.0
Jul 10 10:43:38 mail mpd: SECDNS 0.0.0.0
Jul 10 10:43:38 mail mpd: SECNBNS 0.0.0.0
Jul 10 10:43:38 mail mpd: [pptp0] CCP: rec'd Configure Request #11 link 0 (Req-Sent)
Jul 10 10:43:38 mail mpd: MPPC
Jul 10 10:43:38 mail mpd: 0x01000001: MPPC
Jul 10 10:43:38 mail mpd: [link0] CCP: Checking whether 40 bits are enabled -> yes
Jul 10 10:43:38 mail mpd: [link0] CCP: Checking whether 56 bits are enabled -> yes
Jul 10 10:43:38 mail mpd: [link0] CCP: Checking whether 128 bits are enabled -> yes
Jul 10 10:43:38 mail mpd: [pptp0] CCP: SendConfigNak #11
Jul 10 10:43:38 mail mpd: MPPC
Jul 10 10:43:38 mail mpd: 0x010000e1: MPPC MPPE, 40 bit, 56 bit, 128 bit, stateless
Jul 10 10:43:38 mail mpd: [pptp0] IPCP: rec'd Configure Request #12 link 0 (Req-Sent)
Jul 10 10:43:38 mail mpd: IPADDR 0.0.0.0
Jul 10 10:43:38 mail mpd: NAKing with 172.16.0.2
Jul 10 10:43:38 mail mpd: PRIDNS 0.0.0.0
Jul 10 10:43:38 mail mpd: PRINBNS 0.0.0.0
Jul 10 10:43:38 mail mpd: SECDNS 0.0.0.0
Jul 10 10:43:38 mail mpd: SECNBNS 0.0.0.0
Jul 10 10:43:38 mail mpd: [pptp0] IPCP: SendConfigRej #12
Jul 10 10:43:38 mail mpd: PRIDNS 0.0.0.0
Jul 10 10:43:38 mail mpd: PRINBNS 0.0.0.0
Jul 10 10:43:38 mail mpd: SECDNS 0.0.0.0
Jul 10 10:43:38 mail mpd: SECNBNS 0.0.0.0
Jul 10 10:43:38 mail mpd: [pptp0] CCP: rec'd Configure Request #13 link 0 (Req-Sent)
Jul 10 10:43:38 mail mpd: MPPC
Jul 10 10:43:38 mail mpd: 0x01000001: MPPC
Jul 10 10:43:38 mail mpd: [link0] CCP: Checking whether 40 bits are enabled -> yes
Jul 10 10:43:38 mail mpd: [link0] CCP: Checking whether 56 bits are enabled -> yes
Jul 10 10:43:38 mail mpd: [link0] CCP: Checking whether 128 bits are enabled -> yes
Jul 10 10:43:38 mail mpd: [pptp0] CCP: SendConfigNak #13
Jul 10 10:43:38 mail mpd: MPPC
Jul 10 10:43:38 mail mpd: 0x010000e1: MPPC MPPE, 40 bit, 56 bit, 128 bit, stateless
Jul 10 10:43:38 mail mpd: [pptp0] IPCP: rec'd Configure Reject #4 link 0 (Req-Sent)
Jul 10 10:43:38 mail mpd: Wrong id#, expecting 6
Jul 10 10:43:38 mail mpd: [pptp0] CCP: rec'd Configure Nak #4 link 0 (Req-Sent)
Jul 10 10:43:38 mail mpd: Wrong id#, expecting 6
Jul 10 10:43:38 mail mpd: [pptp0] IPCP: rec'd Configure Request #14 link 0 (Req-Sent)
Jul 10 10:43:38 mail mpd: IPADDR 0.0.0.0
Jul 10 10:43:38 mail mpd: NAKing with 172.16.0.2
Jul 10 10:43:38 mail mpd: PRIDNS 0.0.0.0
Jul 10 10:43:38 mail mpd: PRINBNS 0.0.0.0
Jul 10 10:43:38 mail mpd: SECDNS 0.0.0.0
Jul 10 10:43:38 mail mpd: SECNBNS 0.0.0.0
Jul 10 10:43:38 mail mpd: [pptp0] IPCP: not converging
Jul 10 10:43:38 mail mpd: [pptp0] IPCP: parameter negotiation failed
Jul 10 10:43:38 mail mpd: [pptp0] IPCP: state change Req-Sent --> Stopped
Jul 10 10:43:38 mail mpd: [pptp0] IPCP: LayerFinish
Jul 10 10:43:38 mail mpd: [pptp0] bundle: CLOSE event in state OPENED
Jul 10 10:43:38 mail mpd: [pptp0] closing link "link0"...
Jul 10 10:43:38 mail mpd: [pptp0] CCP: rec'd Configure Request #15 link 0 (Req-Sent)
Jul 10 10:43:38 mail mpd: MPPC
Jul 10 10:43:38 mail mpd: 0x01000001: MPPC
Jul 10 10:43:38 mail mpd: [link0] CCP: Checking whether 40 bits are enabled -> yes
Jul 10 10:43:38 mail mpd: [link0] CCP: Checking whether 56 bits are enabled -> yes
Jul 10 10:43:38 mail mpd: [link0] CCP: Checking whether 128 bits are enabled -> yes
Jul 10 10:43:38 mail mpd: [pptp0] CCP: SendConfigNak #15
Jul 10 10:43:38 mail mpd: MPPC
Jul 10 10:43:38 mail mpd: 0x010000e1: MPPC MPPE, 40 bit, 56 bit, 128 bit, stateless
Jul 10 10:43:38 mail mpd: [link0] link: CLOSE event
Jul 10 10:43:38 mail mpd: [link0] LCP: Close event
Jul 10 10:43:38 mail mpd: [link0] LCP: state change Opened --> Closing
Jul 10 10:43:38 mail mpd: [link0] LCP: phase shift NETWORK --> TERMINATE
Jul 10 10:43:38 mail mpd: [link0] RADIUS: RadiusAccount for: test
Jul 10 10:43:38 mail mpd: [link0] RADIUS: using /etc/radius.conf
Jul 10 10:43:38 mail mpd: [link0] RADIUS: RadiusAddServer Adding 127.0.0.1
Jul 10 10:43:38 mail mpd: [link0] RADIUS: Termination cause: Protocol error:PPP layer IPCP failed: parameter negotiation failed, RADIUS: 15
Jul 10 10:43:38 mail mpd: [link0] RADIUS: RadiusAccount: Sending accounting data (Type: 2)
Jul 10 10:43:38 mail mpd: [link0] RADIUS: RadiusSendRequest: RAD_ACCOUNTING_RESPONSE for user test
Jul 10 10:43:38 mail mpd: [pptp0] setting interface ng0 MTU to 1500 bytes
Jul 10 10:43:38 mail mpd: [pptp0] up: 0 links, total bandwidth 9600 bps
Jul 10 10:43:38 mail mpd: [link0] RADIUS: Down Event
Jul 10 10:43:38 mail mpd: [pptp0] IPCP: Down event
Jul 10 10:43:38 mail mpd: [pptp0] IPCP: state change Stopped --> Starting
Jul 10 10:43:38 mail mpd: [pptp0] IPCP: LayerStart
Jul 10 10:43:38 mail mpd: [pptp0] CCP: Down event
Jul 10 10:43:38 mail mpd: [pptp0] CCP: state change Req-Sent --> Starting
Jul 10 10:43:38 mail mpd: [pptp0] CCP: Close event
Jul 10 10:43:38 mail mpd: [pptp0] CCP: state change Starting --> Initial
Jul 10 10:43:38 mail mpd: [pptp0] CCP: LayerFinish
Jul 10 10:43:38 mail mpd: [pptp0] closing link "link0"...
Jul 10 10:43:38 mail mpd: [link0] LCP: SendTerminateReq #3
Jul 10 10:43:38 mail mpd: [link0] LCP: LayerDown
Jul 10 10:43:38 mail mpd: [link0] rec'd proto IPCP during terminate phase
Jul 10 10:43:38 mail mpd: [pptp0] bundle: OPEN event in state CLOSED
Jul 10 10:43:38 mail mpd: [pptp0] opening link "link0"...
Jul 10 10:43:38 mail mpd: [link0] link: CLOSE event
Jul 10 10:43:38 mail mpd: [link0] LCP: Close event
Jul 10 10:43:38 mail mpd: [link0] rec'd proto CCP during terminate phase
Jul 10 10:43:38 mail mpd: [link0] link: OPEN event
Jul 10 10:43:38 mail mpd: [link0] LCP: Open event
Jul 10 10:43:38 mail mpd: [link0] LCP: state change Closing --> Stopping
Jul 10 10:43:38 mail mpd: [link0] rec'd proto IPCP during terminate phase
Jul 10 10:43:38 mail mpd: [link0] rec'd proto CCP during terminate phase
Jul 10 10:43:38 mail mpd: [link0] rec'd proto IPCP during terminate phase
Jul 10 10:43:38 mail mpd: [link0] rec'd proto CCP during terminate phase
Jul 10 10:43:38 mail mpd: [link0] rec'd proto IPCP during terminate phase
Jul 10 10:43:38 mail mpd: [link0] rec'd proto IPCP during terminate phase
Jul 10 10:43:38 mail mpd: [link0] rec'd proto CCP during terminate phase
Jul 10 10:43:38 mail mpd: [link0] rec'd proto CCP during terminate phase
Jul 10 10:43:40 mail mpd: [link0] LCP: SendTerminateReq #4
Jul 10 10:43:40 mail mpd: pptp0-0: ignoring SetLinkInfo
Jul 10 10:43:40 mail mpd: [link0] LCP: rec'd Terminate Ack #4 link 0 (Stopping)
Jul 10 10:43:40 mail mpd: [link0] LCP: state change Stopping --> Stopped
Jul 10 10:43:40 mail mpd: [link0] LCP: phase shift TERMINATE --> ESTABLISH
Jul 10 10:43:40 mail mpd: [link0] LCP: LayerFinish
Jul 10 10:43:40 mail mpd: [link0] device: CLOSE event in state UP
Jul 10 10:43:40 mail mpd: pptp0-0: clearing call
Jul 10 10:43:40 mail mpd: pptp0-0: killing channel
Jul 10 10:43:40 mail mpd: [link0] PPTP call terminated
Jul 10 10:43:40 mail mpd: [pptp0] IFACE: Close event
Jul 10 10:43:40 mail mpd: [pptp0] IPCP: Close event
Jul 10 10:43:40 mail mpd: [pptp0] IPCP: state change Starting --> Initial
Jul 10 10:43:40 mail mpd: [pptp0] IPCP: LayerFinish
Jul 10 10:43:40 mail mpd: [pptp0] IFACE: Close event
Jul 10 10:43:40 mail mpd: pptp0: closing connection with yy.yy.yy.yy:yyy
Jul 10 10:43:40 mail mpd: [pptp0] IFACE: Close event
Jul 10 10:43:40 mail mpd: [link0] device is now in state CLOSING
Jul 10 10:43:40 mail mpd: [pptp0] bundle: CLOSE event in state OPENED
Jul 10 10:43:40 mail mpd: [pptp0] closing link "link0"...
Jul 10 10:43:40 mail mpd: [link0] device: DOWN event in state CLOSING
Jul 10 10:43:40 mail mpd: [link0] device is now in state DOWN
Jul 10 10:43:40 mail mpd: [link0] link: CLOSE event
Jul 10 10:43:40 mail mpd: [link0] LCP: Close event
Jul 10 10:43:40 mail mpd: [link0] LCP: state change Stopped --> Closed
Jul 10 10:43:40 mail mpd: [link0] device: DOWN event in state DOWN
Jul 10 10:43:40 mail mpd: [link0] device is now in state DOWN
Jul 10 10:43:40 mail mpd: [link0] link: DOWN event
Jul 10 10:43:40 mail mpd: [link0] LCP: Down event
Jul 10 10:43:40 mail mpd: [link0] LCP: state change Closed --> Initial
Jul 10 10:43:40 mail mpd: [link0] LCP: phase shift ESTABLISH --> DEAD
Jul 10 10:43:40 mail mpd: [link0] link: DOWN event
Jul 10 10:43:40 mail mpd: [link0] LCP: Down event
Jul 10 10:43:40 mail mpd: pptp0: CID 0x4000 in CallClearRequest not found
Jul 10 10:43:40 mail mpd: pptp0: killing connection with yy.yy.yy.yy:yyy
Код: Выделить всё
default:
load vpn0
vpn0:
new -i ng0 pptp0 link0
set ipcp ranges 172.16.0.1/32 172.16.0.2/32
load vpn_conf
vpn_conf:
set bundle enable multilink
set bundle yes crypt-reqd
set bundle no encryption multilink
set bundle enable compression
set link yes acfcomp protocomp
set link no pap chap
set link enable chap
set link keep-alive 1800 2600
set link mtu 1460
set link mru 1460
set link max-redial -1
set ipcp yes vjcomp
set iface enable proxy-arp
set iface disable on-demand
set iface idle 1800
set iface enable tcpmssfix
set ccp yes mppc
set ccp yes mpp-e40
set ccp yes mpp-e56
set ccp yes mpp-e128
set ccp yes mpp-compress
set ccp yes mpp-stateles
set ppt