Страница 1 из 1
mpd - LCP: parameter negotiation failed
Добавлено: 2010-11-10 17:10:42
pyrius
Здравтсвуйте
Настроил на сервере MPD
Пользователи, используя 3G, подключаются к VPN серверу и работают нормально.
Пользователи, которые находятся в некоторых кафе и дома подключаясь по Wi-Fi к интернету, при подключении к VPN серверу получают ошибку о "сбое коммуникационного оборудования".
в логе следующие строки:
Код: Выделить всё
Nov 10 18:00:10 serv mpd: [L-1] PROTOCOMP
Nov 10 18:00:10 serv mpd: [L-1] MRU 1500
Nov 10 18:00:10 serv mpd: [L-1] MAGICNUM 90000319
Nov 10 18:00:10 serv mpd: [L-1] AUTHPROTO CHAP MSOFTv2
Nov 10 18:00:12 serv mpd: [L-1] LCP: SendConfigReq #9
Nov 10 18:00:12 serv mpd: [L-1] ACFCOMP
Nov 10 18:00:12 serv mpd: [L-1] PROTOCOMP
Nov 10 18:00:12 serv mpd: [L-1] MRU 1500
Nov 10 18:00:12 serv mpd: [L-1] MAGICNUM 90000319
Nov 10 18:00:12 serv mpd: [L-1] AUTHPROTO CHAP MSOFTv2
Nov 10 18:00:14 serv mpd: [L-1] LCP: SendConfigReq #10
Nov 10 18:00:14 serv mpd: [L-1] ACFCOMP
Nov 10 18:00:14 serv mpd: [L-1] PROTOCOMP
Nov 10 18:00:14 serv mpd: [L-1] MRU 1500
Nov 10 18:00:14 serv mpd: [L-1] MAGICNUM 90000319
Nov 10 18:00:14 serv mpd: [L-1] AUTHPROTO CHAP MSOFTv2
Nov 10 18:00:16 serv mpd: [L-1] LCP: parameter negotiation failed
Nov 10 18:00:16 serv mpd: [L-1] LCP: state change Req-Sent --> Stopped
Nov 10 18:00:16 serv mpd: [L-1] LCP: LayerFinish
Nov 10 18:00:16 serv mpd: [L-1] PPTP call terminated
Nov 10 18:00:16 serv mpd: [L-1] Link: DOWN event
Nov 10 18:00:16 serv mpd: [L-1] LCP: Close event
Nov 10 18:00:16 serv mpd: [L-1] LCP: state change Stopped --> Closed
Nov 10 18:00:16 serv mpd: [L-1] LCP: Down event
Nov 10 18:00:16 serv mpd: [L-1] LCP: state change Closed --> Initial
Nov 10 18:00:16 serv mpd: [L-1] Link: SHUTDOWN event
Nov 10 18:00:16 serv mpd: [L-1] Link: Shutdown
Подскажите что делать.
Re: mpd - LCP: parameter negotiation failed
Добавлено: 2010-11-10 17:16:17
hizel
мало лога
Re: mpd - LCP: parameter negotiation failed
Добавлено: 2010-11-10 17:35:22
pyrius
Nov 10 19:32:14 serv mpd: PPTP: Incoming control connection from CLIENT_EXTERNAL_IP 28305 to SERVER_EXTERNAL_IP 1723
Nov 10 19:32:14 serv mpd: pptp0: ctrl state FREE --> IDLE
Nov 10 19:32:14 serv mpd: pptp0: attached to connection with CLIENT_EXTERNAL_IP 28305
Nov 10 19:32:14 serv mpd: pptp0: recv StartCtrlConnRequest
Nov 10 19:32:14 serv mpd: vers=0x100 frameCap=1 bearCap=1 maxChan=0 firm=0 host="" vend=""
Nov 10 19:32:14 serv mpd: pptp0: ctrl state IDLE --> ESTABLISHED
Nov 10 19:32:14 serv mpd: pptp0: send StartCtrlConnReply msg
Nov 10 19:32:14 serv mpd: len=0x9c msgType=1 magic=0x1a2b3c4d type=2
Nov 10 19:32:14 serv mpd: vers=0x100 result=1 err=0 frameCap=3 bearCap=3 maxChan=0
Nov 10 19:32:14 serv mpd: firm=0x101 host="serv.ltd" vend="FreeBSD MPD"
Nov 10 19:32:14 serv mpd: pptp0: recv OutCallRequest
Nov 10 19:32:14 serv mpd: cid=0xef serno=0 minBPS=0x12c maxBPS=0x5f5e100 frameType=3
Nov 10 19:32:14 serv mpd: bearType=3 recvWin=64 ppd=0 numLen=0 phone="" subaddr=""
Nov 10 19:32:14 serv mpd: pptp0-0: chan state FREE --> WAIT_ANSWER
Nov 10 19:32:14 serv mpd: [L-1] Accepting PPTP connection
Nov 10 19:32:14 serv mpd: [L-1] Link: OPEN event
Nov 10 19:32:14 serv mpd: [L-1] LCP: Open event
Nov 10 19:32:14 serv mpd: [L-1] LCP: state change Initial --> Starting
Nov 10 19:32:14 serv mpd: [L-1] LCP: LayerStart
Nov 10 19:32:14 serv mpd: [L-1] device: OPEN event
Nov 10 19:32:14 serv mpd: [L-1] PPTP: attaching to peer's outgoing call
Nov 10 19:32:14 serv mpd: pptp0-0: chan state WAIT_ANSWER --> ESTABLISHED
Nov 10 19:32:14 serv mpd: pptp0: send OutCallReply msg
Nov 10 19:32:14 serv mpd: len=32 msgType=1 magic=0x1a2b3c4d type=8
Nov 10 19:32:14 serv mpd: cid=0x228f peerCid=0xef result=1 err=0 cause=0 speed=0xfa00
Nov 10 19:32:14 serv mpd: recvWin=16 ppd=1 channel=0
Nov 10 19:32:14 serv mpd: [L-1] device: UP event
Nov 10 19:32:14 serv mpd: [L-1] Link: UP event
Nov 10 19:32:14 serv mpd: [L-1] Link: origination is remote
Nov 10 19:32:14 serv mpd: [L-1] LCP: Up event
Nov 10 19:32:14 serv mpd: [L-1] LCP: state change Starting --> Req-Sent
Nov 10 19:32:14 serv mpd: [L-1] LCP: phase shift DEAD --> ESTABLISH
Nov 10 19:32:14 serv mpd: [L-1] LCP: SendConfigReq #1
Nov 10 19:32:14 serv mpd: [L-1] ACFCOMP
Nov 10 19:32:14 serv mpd: [L-1] PROTOCOMP
Nov 10 19:32:14 serv mpd: [L-1] MRU 1500
Nov 10 19:32:14 serv mpd: [L-1] MAGICNUM 85b10524
Nov 10 19:32:14 serv mpd: [L-1] AUTHPROTO CHAP MSOFTv2
Nov 10 19:32:14 serv mpd: pptp0: recv SetLinkInfo
Nov 10 19:32:14 serv mpd: cid=0x228f sendAccm=0xffffffff recvAccm=0xffffffff
Nov 10 19:32:16 serv mpd: [L-1] LCP: SendConfigReq #2
Nov 10 19:32:16 serv mpd: [L-1] ACFCOMP
Nov 10 19:32:16 serv mpd: [L-1] PROTOCOMP
Nov 10 19:32:16 serv mpd: [L-1] MRU 1500
Nov 10 19:32:16 serv mpd: [L-1] MAGICNUM 85b10524
Nov 10 19:32:16 serv mpd: [L-1] AUTHPROTO CHAP MSOFTv2
Nov 10 19:32:18 serv mpd: [L-1] LCP: SendConfigReq #3
Nov 10 19:32:18 serv mpd: [L-1] ACFCOMP
Nov 10 19:32:18 serv mpd: [L-1] PROTOCOMP
Nov 10 19:32:18 serv mpd: [L-1] MRU 1500
Nov 10 19:32:18 serv mpd: [L-1] MAGICNUM 85b10524
Nov 10 19:32:18 serv mpd: [L-1] AUTHPROTO CHAP MSOFTv2
Nov 10 19:32:20 serv mpd: [L-1] LCP: SendConfigReq #4
Nov 10 19:32:20 serv mpd: [L-1] ACFCOMP
Nov 10 19:32:20 serv mpd: [L-1] PROTOCOMP
Nov 10 19:32:20 serv mpd: [L-1] MRU 1500
Nov 10 19:32:20 serv mpd: [L-1] MAGICNUM 85b10524
Nov 10 19:32:20 serv mpd: [L-1] AUTHPROTO CHAP MSOFTv2
Nov 10 19:32:22 serv mpd: [L-1] LCP: SendConfigReq #5
Nov 10 19:32:22 serv mpd: [L-1] ACFCOMP
Nov 10 19:32:22 serv mpd: [L-1] PROTOCOMP
Nov 10 19:32:22 serv mpd: [L-1] MRU 1500
Nov 10 19:32:22 serv mpd: [L-1] MAGICNUM 85b10524
Nov 10 19:32:22 serv mpd: [L-1] AUTHPROTO CHAP MSOFTv2
Nov 10 19:32:24 serv mpd: [L-1] LCP: SendConfigReq #6
Nov 10 19:32:24 serv mpd: [L-1] ACFCOMP
Nov 10 19:32:24 serv mpd: [L-1] PROTOCOMP
Nov 10 19:32:24 serv mpd: [L-1] MRU 1500
Nov 10 19:32:24 serv mpd: [L-1] MAGICNUM 85b10524
Nov 10 19:32:24 serv mpd: [L-1] AUTHPROTO CHAP MSOFTv2
Nov 10 19:32:26 serv mpd: [L-1] LCP: SendConfigReq #7
Nov 10 19:32:26 serv mpd: [L-1] ACFCOMP
Nov 10 19:32:26 serv mpd: [L-1] PROTOCOMP
Nov 10 19:32:26 serv mpd: [L-1] MRU 1500
Nov 10 19:32:26 serv mpd: [L-1] MAGICNUM 85b10524
Nov 10 19:32:26 serv mpd: [L-1] AUTHPROTO CHAP MSOFTv2
Nov 10 19:32:28 serv mpd: [L-1] LCP: SendConfigReq #8
Nov 10 19:32:28 serv mpd: [L-1] ACFCOMP
Nov 10 19:32:28 serv mpd: [L-1] PROTOCOMP
Nov 10 19:32:28 serv mpd: [L-1] MRU 1500
Nov 10 19:32:28 serv mpd: [L-1] MAGICNUM 85b10524
Nov 10 19:32:28 serv mpd: [L-1] AUTHPROTO CHAP MSOFTv2
Nov 10 19:32:30 serv mpd: [L-1] LCP: SendConfigReq #9
Nov 10 19:32:30 serv mpd: [L-1] ACFCOMP
Nov 10 19:32:30 serv mpd: [L-1] PROTOCOMP
Nov 10 19:32:30 serv mpd: [L-1] MRU 1500
Nov 10 19:32:30 serv mpd: [L-1] MAGICNUM 85b10524
Nov 10 19:32:30 serv mpd: [L-1] AUTHPROTO CHAP MSOFTv2
Nov 10 19:32:32 serv mpd: [L-1] LCP: SendConfigReq #10
Nov 10 19:32:32 serv mpd: [L-1] ACFCOMP
Nov 10 19:32:32 serv mpd: [L-1] PROTOCOMP
Nov 10 19:32:32 serv mpd: [L-1] MRU 1500
Nov 10 19:32:32 serv mpd: [L-1] MAGICNUM 85b10524
Nov 10 19:32:32 serv mpd: [L-1] AUTHPROTO CHAP MSOFTv2
Nov 10 19:32:34 serv mpd: [L-1] LCP: parameter negotiation failed
Nov 10 19:32:34 serv mpd: [L-1] LCP: state change Req-Sent --> Stopped
Nov 10 19:32:34 serv mpd: [L-1] LCP: LayerFinish
Nov 10 19:32:34 serv mpd: [L-1] device: CLOSE event
Nov 10 19:32:34 serv mpd: pptp0-0: clearing call
Nov 10 19:32:34 serv mpd: pptp0: send CallDiscNotify msg
Nov 10 19:32:34 serv mpd: len=0x94 msgType=1 magic=0x1a2b3c4d type=13
Nov 10 19:32:34 serv mpd: cid=0x228f result=3 err=0 cause=0 stats=""
Nov 10 19:32:34 serv mpd: pptp0-0: killing channel
Nov 10 19:32:34 serv mpd: [L-1] PPTP call terminated
Nov 10 19:32:34 serv mpd: [L-1] device: DOWN event
Nov 10 19:32:34 serv mpd: [L-1] Link: DOWN event
Nov 10 19:32:34 serv mpd: [L-1] LCP: Close event
Nov 10 19:32:34 serv mpd: [L-1] LCP: state change Stopped --> Closed
Nov 10 19:32:34 serv mpd: [L-1] LCP: Down event
Nov 10 19:32:34 serv mpd: [L-1] LCP: state change Closed --> Initial
Nov 10 19:32:34 serv mpd: [L-1] LCP: phase shift ESTABLISH --> DEAD
Nov 10 19:32:34 serv mpd: pptp0-0: chan state ESTABLISHED --> DYING
Nov 10 19:32:34 serv mpd: [L-1] Link: SHUTDOWN event
Nov 10 19:32:34 serv mpd: [L-1] Link: Shutdown
Nov 10 19:32:44 serv mpd: pptp0: closing connection with CLIENT_EXTERNAL_IP 28305
Nov 10 19:32:44 serv mpd: pptp0: ctrl state ESTABLISHED --> WAIT_STOP_REPLY
Nov 10 19:32:44 serv mpd: pptp0: send StopCtrlConnRequest msg
Nov 10 19:32:44 serv mpd: len=16 msgType=1 magic=0x1a2b3c4d type=3
Nov 10 19:32:44 serv mpd: reason=3
Nov 10 19:32:47 serv mpd: pptp0: no reply to StopCtrlConnRequest after 3 sec
Nov 10 19:32:47 serv mpd: pptp0: ctrl state WAIT_STOP_REPLY --> DYING
Nov 10 19:32:47 serv mpd: pptp0: killing connection with CLIENT_EXTERNAL_IP 28305
Re: mpd - LCP: parameter negotiation failed
Добавлено: 2010-11-10 17:41:35
pyrius
Вот часть лога - когда прерывается соединение:
Nov 10 19:39:11 serv mpd: [L-1] LCP: SendConfigReq #8
Nov 10 19:39:11 serv mpd: [L-1] ACFCOMP
Nov 10 19:39:11 serv mpd: [L-1] PROTOCOMP
Nov 10 19:39:11 serv mpd: [L-1] MRU 1500
Nov 10 19:39:11 serv mpd: [L-1] MAGICNUM 9bd834d8
Nov 10 19:39:11 serv mpd: [L-1] AUTHPROTO CHAP MSOFTv2
Nov 10 19:39:11 serv mpd: [L-1] xmit frame to link proto=0xc021
Nov 10 19:39:11 serv mpd: ff 03 c0 21 01 08 00 17 08 02 07 02 01 04 05 dc ...!............
Nov 10 19:39:11 serv mpd: 05 06 9b d8 34 d8 03 05 c2 23 81 ....4....#.
Nov 10 19:39:11 serv mpd: EVENT: Starting timer "LCP" FsmTimeout() for 2000 ms at fsm.c:426
Nov 10 19:39:11 serv mpd: EVENT: Registering event EVENT_TIMEOUT TimerExpires() at timer.c:50
Nov 10 19:39:11 serv mpd: EVENT: Registering event EVENT_TIMEOUT TimerExpires() done at timer.c:50
Nov 10 19:39:11 serv mpd: EVENT: Processing timer "LCP" FsmTimeout() done
Nov 10 19:39:11 serv mpd: EVENT: Processing event EVENT_TIMEOUT TimerExpires() done
Nov 10 19:39:13 serv mpd: EVENT: Processing event EVENT_TIMEOUT TimerExpires()
Nov 10 19:39:13 serv mpd: EVENT: Processing timer "LCP" FsmTimeout()
Nov 10 19:39:13 serv mpd: [L-1] LCP: SendConfigReq #9
Nov 10 19:39:13 serv mpd: [L-1] ACFCOMP
Nov 10 19:39:13 serv mpd: [L-1] PROTOCOMP
Nov 10 19:39:13 serv mpd: [L-1] MRU 1500
Nov 10 19:39:13 serv mpd: [L-1] MAGICNUM 9bd834d8
Nov 10 19:39:13 serv mpd: [L-1] AUTHPROTO CHAP MSOFTv2
Nov 10 19:39:13 serv mpd: [L-1] xmit frame to link proto=0xc021
Nov 10 19:39:13 serv mpd: ff 03 c0 21 01 09 00 17 08 02 07 02 01 04 05 dc ...!............
Nov 10 19:39:13 serv mpd: 05 06 9b d8 34 d8 03 05 c2 23 81 ....4....#.
Nov 10 19:39:13 serv mpd: EVENT: Starting timer "LCP" FsmTimeout() for 2000 ms at fsm.c:426
Nov 10 19:39:13 serv mpd: EVENT: Registering event EVENT_TIMEOUT TimerExpires() at timer.c:50
Nov 10 19:39:13 serv mpd: EVENT: Registering event EVENT_TIMEOUT TimerExpires() done at timer.c:50
Nov 10 19:39:13 serv mpd: EVENT: Processing timer "LCP" FsmTimeout() done
Nov 10 19:39:13 serv mpd: EVENT: Processing event EVENT_TIMEOUT TimerExpires() done
Nov 10 19:39:15 serv mpd: EVENT: Processing event EVENT_TIMEOUT TimerExpires()
Nov 10 19:39:15 serv mpd: EVENT: Processing timer "LCP" FsmTimeout()
Nov 10 19:39:15 serv mpd: [L-1] LCP: SendConfigReq #10
Nov 10 19:39:15 serv mpd: [L-1] ACFCOMP
Nov 10 19:39:15 serv mpd: [L-1] PROTOCOMP
Nov 10 19:39:15 serv mpd: [L-1] MRU 1500
Nov 10 19:39:15 serv mpd: [L-1] MAGICNUM 9bd834d8
Nov 10 19:39:15 serv mpd: [L-1] AUTHPROTO CHAP MSOFTv2
Nov 10 19:39:15 serv mpd: [L-1] xmit frame to link proto=0xc021
Nov 10 19:39:15 serv mpd: ff 03 c0 21 01 0a 00 17 08 02 07 02 01 04 05 dc ...!............
Nov 10 19:39:15 serv mpd: 05 06 9b d8 34 d8 03 05 c2 23 81 ....4....#.
Nov 10 19:39:15 serv mpd: EVENT: Starting timer "LCP" FsmTimeout() for 2000 ms at fsm.c:426
Nov 10 19:39:15 serv mpd: EVENT: Registering event EVENT_TIMEOUT TimerExpires() at timer.c:50
Nov 10 19:39:15 serv mpd: EVENT: Registering event EVENT_TIMEOUT TimerExpires() done at timer.c:50
Nov 10 19:39:15 serv mpd: EVENT: Processing timer "LCP" FsmTimeout() done
Nov 10 19:39:15 serv mpd: EVENT: Processing event EVENT_TIMEOUT TimerExpires() done
Nov 10 19:39:17 serv mpd: EVENT: Processing event EVENT_TIMEOUT TimerExpires()
Nov 10 19:39:17 serv mpd: EVENT: Processing timer "LCP" FsmTimeout()
Nov 10 19:39:17 serv mpd: [L-1] LCP: parameter negotiation failed
Nov 10 19:39:17 serv mpd: [L-1] LCP: state change Req-Sent --> Stopped
Nov 10 19:39:17 serv mpd: EVENT: Stopping timer "LCP" FsmTimeout() at fsm.c:190
Nov 10 19:39:17 serv mpd: [L-1] LCP: LayerFinish
Nov 10 19:39:17 serv mpd: EVENT: Stopping timer "(null)" (null)() at auth.c:682
Nov 10 19:39:17 serv mpd: EVENT: Stopping timer "(null)" (null)() at pap.c:64
Nov 10 19:39:17 serv mpd: EVENT: Stopping timer "(null)" (null)() at chap.c:93
Nov 10 19:39:17 serv mpd: EVENT: Stopping timer "(null)" (null)() at chap.c:94
Nov 10 19:39:17 serv mpd: EVENT: Stopping timer "(null)" (null)() at eap.c:146
Nov 10 19:39:17 serv mpd: EVENT: Stopping timer "(null)" (null)() at eap.c:147
Nov 10 19:39:17 serv mpd: EVENT: Message 2 to PhysMsg() sent
Nov 10 19:39:17 serv mpd: EVENT: Processing timer "LCP" FsmTimeout() done
Nov 10 19:39:17 serv mpd: EVENT: Processing event EVENT_TIMEOUT TimerExpires() done
Nov 10 19:39:17 serv mpd: EVENT: Processing event EVENT_READ MsgEvent()
Nov 10 19:39:17 serv mpd: EVENT: Message 2 to PhysMsg() received
Nov 10 19:39:17 serv mpd: [L-1] device: CLOSE event
Nov 10 19:39:17 serv mpd: pptp0-0: clearing call
Nov 10 19:39:17 serv mpd: pptp0: send CallDiscNotify msg
Re: mpd - LCP: parameter negotiation failed
Добавлено: 2010-11-11 0:32:10
mav
PPTP плохо ходит через NAT. Так-же возможно что фаервол в тех местах режет GRE пакеты. Как вариант - можно подумать от L2TP без IPSec - дн должен ходить лучше.
Re: mpd - LCP: parameter negotiation failed
Добавлено: 2010-11-11 0:39:29
hizel
несовсем так, gre протокол без магии не пройдет через nat вообще
Re: mpd - LCP: parameter negotiation failed
Добавлено: 2010-11-11 1:07:08
mav
NAT - вообще магия. Но если за натом только один клиент подключается к конкретному серверу, то магия нужна не особо хитрая.
Re: mpd - LCP: parameter negotiation failed
Добавлено: 2010-11-11 2:02:42
Alex Keda
а у меня дома ходит...
без магии - просто файрволл, просто после диверта gre разрешил...
Re: mpd - LCP: parameter negotiation failed
Добавлено: 2010-11-11 14:32:11
hizel
волшебство в деталях, ты просто их не видишь ;]
Re: mpd - LCP: parameter negotiation failed
Добавлено: 2010-11-14 21:48:33
Alex Keda
тогда тем более не понимаю
)