Имеется сервер с pppd и pptpd, работающий как точка доступа. При подключении VPN клиента Windows XP sp2, процесс установки соединения замораживается на "Проверки имени пользователя и пароля", и сообщает об ошибке 619. Что может быть ума не приложу, пожалуйста помогите найти грабли!
pppd версии 2.4.3 и pptpd версии 1.2.3. pppd собран с поддержкой mppe и ms-chap. В ядро вкомпилены модули CONFIG_PPP, CONFIG_PPP_SYNC_TTY; поддержки mppe в ядре нет.
Конфигурационные файлы:
# cat /etc/pptpd.conf
ppp /usr/local/sbin/pppd
option /etc/ppp/options.pptpd
debug
localip 192.168.50.1
remoteip 192.168.50.10-100
listen 192.168.1.1
# cat /etc/ppp/options.pptpd
sync
lock
debug
name pptpd
nodeflate
nobsdcomp
auth
+chap
-mschap-v2
-mschap
nomppe
ms-dns 192.168.50.1
Логи при попытке подключения клиента:
/var/log/messages
Dec 2 09:37:48 server pptpd[3784]: MGR: Manager process started
Dec 2 09:37:48 server pptpd[3784]: MGR: Maximum of 91 connections available
Dec 2 09:39:18 server pptpd[3790]: CTRL: Client 192.168.1.10 control connection started
Dec 2 09:39:18 server pptpd[3790]: CTRL: Starting call (launching pppd, opening GRE)
Dec 2 09:39:18 server pppd[3791]: pppd 2.4.3 started by root, uid 0
Dec 2 09:39:18 server pppd[3791]: Using interface ppp1
Dec 2 09:39:18 server pppd[3791]: Connect: ppp1 <--> /dev/pts/1
Dec 2 09:39:48 server pppd[3791]: Connection terminated.
Dec 2 09:39:48 server pppd[3791]: Using interface ppp1
Dec 2 09:39:48 server pppd[3791]: Connect: ppp1 <--> /dev/pts/1
Dec 2 09:39:48 server pppd[3791]: Exit.
Dec 2 09:39:48 server pptpd[3790]: CTRL: Client 192.168.1.10 control connection finished
/var/log/syslog
Dec 2 09:39:48 server pppd[3791]: LCP: timeout sending Config-Requests
Dec 2 09:39:48 server pppd[3791]: tcflush failed: Bad file descriptor
Dec 2 09:39:48 server pppd[3791]: tcsetattr: Invalid argument (line 1001)
Dec 2 09:39:48 server pptpd[3790]: GRE: read(fd=6,buffer=804e620,len=8196) from PTY failed: status = -1 error = Input/output error, usually caused by unexpected termination of pppd, check option syntax and pppd logs
Dec 2 09:39:48 server pptpd[3790]: CTRL: PTY read or GRE write failed (pty,gre)=(6,7)
/var/log/debug
Dec 2 09:39:18 server pptpd[3790]: MGR: Launching /usr/local/sbin/pptpctrl to handle client
Dec 2 09:39:18 server pptpd[3790]: CTRL: local address = 192.168.50.1
Dec 2 09:39:18 server pptpd[3790]: CTRL: remote address = 192.168.50.10
Dec 2 09:39:18 server pptpd[3790]: CTRL: pppd options file = /etc/ppp/options.pptpd
Dec 2 09:39:18 server pptpd[3790]: CTRL: Received PPTP Control Message (type: 1)
Dec 2 09:39:18 server pptpd[3790]: CTRL: Made a START CTRL CONN RPLY packet
Dec 2 09:39:18 server pptpd[3790]: CTRL: I wrote 156 bytes to the client.
Dec 2 09:39:18 server pptpd[3790]: CTRL: Sent packet to client
Dec 2 09:39:18 server pptpd[3790]: CTRL: Received PPTP Control Message (type: 7)
Dec 2 09:39:18 server pptpd[3790]: CTRL: Set parameters to 100000000 maxbps, 64 window size
Dec 2 09:39:18 server pptpd[3790]: CTRL: Made a OUT CALL RPLY packet
Dec 2 09:39:18 server pptpd[3790]: CTRL: pty_fd = 6
Dec 2 09:39:18 server pptpd[3790]: CTRL: tty_fd = 7
Dec 2 09:39:18 server pptpd[3791]: CTRL (PPPD Launcher): program binary = /usr/local/sbin/pppd
Dec 2 09:39:18 server pptpd[3791]: CTRL (PPPD Launcher): local address = 192.168.50.1
Dec 2 09:39:18 server pptpd[3791]: CTRL (PPPD Launcher): remote address = 192.168.50.10
Dec 2 09:39:18 server pptpd[3790]: CTRL: I wrote 32 bytes to the client.
Dec 2 09:39:18 server pptpd[3790]: CTRL: Sent packet to client
Dec 2 09:39:18 server pppd[3791]: using channel 2
Dec 2 09:39:18 server pppd[3791]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap MD5> <magic 0xaca64307> <pcomp> <accomp>]
Dec 2 09:39:18 server pptpd[3790]: CTRL: Received PPTP Control Message (type: 15)
Dec 2 09:39:18 server pptpd[3790]: CTRL: Got a SET LINK INFO packet with standard ACCMs
Dec 2 09:39:18 server pppd[3791]: rcvd [proto=0x7eff] 7d 23 c0 21 7d 21 7d 20 7d 20 7d 35 7d 21 7d 24 7d 25 78 7d 25 7d 26 27 fc 4f 7d 37 7d 27 7d 22 ...
Dec 2 09:39:18 server pppd[3791]: Discarded non-LCP packet when LCP not open
Dec 2 09:39:20 server pppd[3791]: rcvd [proto=0x7eff] 7d 23 c0 21 7d 21 7d 21 7d 20 7d 35 7d 21 7d 24 7d 25 78 7d 25 7d 26 27 fc 4f 7d 37 7d 27 7d 22 ...
Dec 2 09:39:20 server pppd[3791]: Discarded non-LCP packet when LCP not open
Dec 2 09:39:21 server pppd[3791]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap MD5> <magic 0xaca64307> <pcomp> <accomp>]
Dec 2 09:39:23 server pppd[3791]: rcvd [proto=0x7eff] 7d 23 c0 21 7d 21 7d 22 7d 20 7d 35 7d 21 7d 24 7d 25 78 7d 25 7d 26 27 fc 4f 7d 37 7d 27 7d 22 ...
Dec 2 09:39:23 server pppd[3791]: Discarded non-LCP packet when LCP not open
Dec 2 09:39:24 server pppd[3791]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap MD5> <magic 0xaca64307> <pcomp> <accomp>]
Dec 2 09:39:27 server pppd[3791]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap MD5> <magic 0xaca64307> <pcomp> <accomp>]
Dec 2 09:39:27 server pppd[3791]: rcvd [proto=0x7eff] 7d 23 c0 21 7d 21 7d 23 7d 20 7d 35 7d 21 7d 24 7d 25 78 7d 25 7d 26 27 fc 4f 7d 37 7d 27 7d 22 ...
Dec 2 09:39:27 server pppd[3791]: Discarded non-LCP packet when LCP not open
Dec 2 09:39:30 server pppd[3791]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap MD5> <magic 0xaca64307> <pcomp> <accomp>]
Dec 2 09:39:31 server pppd[3791]: rcvd [proto=0x7eff] 7d 23 c0 21 7d 21 7d 24 7d 20 7d 35 7d 21 7d 24 7d 25 78 7d 25 7d 26 27 fc 4f 7d 37 7d 27 7d 22 ...
Dec 2 09:39:31 server pppd[3791]: Discarded non-LCP packet when LCP not open
Dec 2 09:39:33 server pppd[3791]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap MD5> <magic 0xaca64307> <pcomp> <accomp>]
Dec 2 09:39:35 server pppd[3791]: rcvd [proto=0x7eff] 7d 23 c0 21 7d 21 7d 25 7d 20 7d 35 7d 21 7d 24 7d 25 78 7d 25 7d 26 27 fc 4f 7d 37 7d 27 7d 22 ...
Dec 2 09:39:35 server pppd[3791]: Discarded non-LCP packet when LCP not open
Dec 2 09:39:36 server pppd[3791]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap MD5> <magic 0xaca64307> <pcomp> <accomp>]
Dec 2 09:39:39 server pppd[3791]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap MD5> <magic 0xaca64307> <pcomp> <accomp>]
Dec 2 09:39:39 server pppd[3791]: rcvd [proto=0x7eff] 7d 23 c0 21 7d 21 7d 26 7d 20 7d 35 7d 21 7d 24 7d 25 78 7d 25 7d 26 27 fc 4f 7d 37 7d 27 7d 22 ...
Dec 2 09:39:39 server pppd[3791]: Discarded non-LCP packet when LCP not open
Dec 2 09:39:42 server pppd[3791]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap MD5> <magic 0xaca64307> <pcomp> <accomp>]
Dec 2 09:39:43 server pppd[3791]: rcvd [proto=0x7eff] 7d 23 c0 21 7d 21 7d 27 7d 20 7d 35 7d 21 7d 24 7d 25 78 7d 25 7d 26 27 fc 4f 7d 37 7d 27 7d 22 ...
Dec 2 09:39:43 server pppd[3791]: Discarded non-LCP packet when LCP not open
Dec 2 09:39:45 server pppd[3791]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap MD5> <magic 0xaca64307> <pcomp> <accomp>]
Dec 2 09:39:47 server pppd[3791]: rcvd [proto=0x7eff] 7d 23 c0 21 7d 21 7d 28 7d 20 7d 35 7d 21 7d 24 7d 25 78 7d 25 7d 26 27 fc 4f 7d 37 7d 27 7d 22 ...
Dec 2 09:39:47 server pppd[3791]: Discarded non-LCP packet when LCP not open
Dec 2 09:39:48 server pppd[3791]: using channel 3
Dec 2 09:39:48 server pppd[3791]: sent [LCP ConfReq id=0x2 <asyncmap 0x0> <auth chap MD5> <magic 0x76cf023> <pcomp> <accomp>]
Dec 2 09:39:48 server pptpd[3790]: CTRL: Reaping child PPP[3791]
Dec 2 09:39:48 server pptpd[3790]: CTRL: Exiting now
Dec 2 09:39:48 server pptpd[3784]: MGR: Reaped child 3790