PDA

View Full Version : WL520gc - не подключается к VPN серверу

timurkh
11-05-2008, 13:18
Довольно часто роутер Wl520gc (прошивка - 0.0.0.16) не может подключиться к VPN серверу.

При этом:

1. Если установить WAN Connection Type 'Static IP' и подключаться к VPN из windows - всё работает отлично.

2. Если просто несколько раз (иногда достаточно и одного), попереключать PPTP -> Static IP -> PPTP то роутер всё-таки подключается.

3. Настройки роутера:

WAN Connection Type: PPTP
Choose IPTV STB Port: None

Get IP automatically? No
IP Address: 10.0.2.170
Subnet Mask: 255.255.255.0
Default Gateway: 10.0.2.1

Get DNS Server automatically? Yes

User Name: username
Password: *******

Heart-Beat or PPTP/L2TP (VPN) Server: 10.0.4.1

LAN IP Setting
IP Address: 192.168.1.1
Subnet Mask: 255.255.255.0


4. В случае неудачи, пишет в лог следующее:

Jan 1 03:00:04 pppd[61]: pppd 2.4.2 started by (unknown), uid 0
Jan 1 03:00:04 pppd[61]: Serial connection established.
Jan 1 03:00:04 pppd[61]: Using interface ppp0
Jan 1 03:00:04 pppd[61]: Connect: ppp0 <--> /dev/pts/0
Jan 1 03:00:35 pppd[61]: LCP: timeout sending Config-Requests
Jan 1 03:00:35 pppd[61]: Connection terminated.
Jan 1 03:00:46 udhcpd: sending OFFER of 192.168.1.2
Jan 1 03:00:46 udhcpd: sending ACK to 192.168.1.2
Jan 1 03:00:53 udhcpd: sending ACK to 192.168.1.2
Jan 1 03:01:06 pppd[61]: Serial connection established.
Jan 1 03:01:06 pppd[61]: Using interface ppp0
Jan 1 03:01:06 pppd[61]: Connect: ppp0 <--> /dev/pts/1
Jan 1 03:01:07 pptp[156]: Call manager for 10.0.4.1 is already running.
Jan 1 03:01:07 pptp[156]: Could not open unix socket for 10.0.4.1
Jan 1 03:01:07 pptp[151]: Call manager exited with error 256
Jan 1 03:01:07 pppd[61]: Modem hangup
Jan 1 03:01:07 pppd[61]: Connection terminated.
Jan 1 03:01:37 pppd[61]: Serial connection established.
Jan 1 03:01:37 pppd[61]: Using interface ppp0
Jan 1 03:01:37 pppd[61]: Connect: ppp0 <--> /dev/pts/1
Jan 1 03:01:38 pptp[199]: Call manager for 10.0.4.1 is already running.
Jan 1 03:01:38 pptp[199]: Could not open unix socket for 10.0.4.1
Jan 1 03:01:38 pptp[194]: Call manager exited with error 256
Jan 1 03:01:38 pppd[61]: Modem hangup
Jan 1 03:01:38 pppd[61]: Connection terminated.

(10.0.4.1 при этом пингуется, и с самого роутера, и с компьютера подключённого через wifi к роутеру).

5. В случае удачи, пишет в лог следующее:

Jan 1 03:00:04 pppd[61]: pppd 2.4.2 started by (unknown), uid 0
Jan 1 03:00:04 pppd[61]: Serial connection established.
Jan 1 03:00:04 pppd[61]: Using interface ppp0
Jan 1 03:00:04 pppd[61]: Connect: ppp0 <--> /dev/pts/0
Jan 1 03:00:10 pppd[61]: CHAP authentication failed: Invalid!!
Jan 1 03:00:10 pppd[61]: Modem hangup
Jan 1 03:00:10 pppd[61]: Connection terminated.
Jan 1 03:00:21 udhcpd: sending OFFER of 192.168.1.2
Jan 1 03:00:21 udhcpd: sending ACK to 192.168.1.2
Jan 1 03:00:26 udhcpd: sending ACK to 192.168.1.2
Jan 1 03:00:40 pppd[61]: Serial connection established.
Jan 1 03:00:40 pppd[61]: Using interface ppp0
Jan 1 03:00:40 pppd[61]: Connect: ppp0 <--> /dev/pts/0
Jan 1 03:00:46 pppd[61]: CHAP authentication succeeded: Welcome!!
Jan 1 03:00:46 pppd[61]: Received bad configure-ack:
Jan 1 03:00:46 pppd[61]: local IP address 62.63.69.75
Jan 1 03:00:46 pppd[61]: remote IP address 62.63.65.146
Jan 1 03:00:46 pppd[61]: primary DNS address 80.73.163.60
Jan 1 03:00:46 pppd[61]: secondary DNS address 80.73.164.146
Jan 1 03:00:47 PPTP: connect to ISP
Jan 1 03:00:49 pppd[61]: Received bad configure-ack:
Jan 1 03:00:52 pppd[61]: Received bad configure-ack:
May 11 14:58:45 pppd[61]: Received bad configure-ack:
May 11 14:58:48 pppd[61]: Received bad configure-ack:
May 11 14:58:51 pppd[61]: Received bad configure-ack:
May 11 14:58:54 pppd[61]: Received bad configure-ack:
May 11 14:58:54 ntp client: time is synchronized to time.nist.gov pool.ntp.org
May 11 14:58:57 pppd[61]: Received bad configure-ack:
May 11 14:59:00 pppd[61]: Received bad configure-ack:
May 11 14:59:03 pppd[61]: Received bad configure-ack:
May 11 14:59:06 pppd[61]: CCP: timeout sending Config-Requests


Что делать???
timurkh
12-05-2008, 13:40
А, забыл на написать. Если роутер подключается, то работает от дня до недели, а потом отключается и не может присоединиться. Чтобы восстановить подключение, надо несколько раз переключить из Static IP в PPTP (при этом из Static IP к VPN средствами ОС можно подключится сразу), иногда достаточно одного.
Spacesoft
13-05-2008, 04:09
После того, как перепрошили - в default скидывали настройки?
timurkh
24-05-2008, 11:11
да, скидывал, не помогает
timurkh
24-05-2008, 11:58
Добавил debug к pppd опциям, получил такой лог:

Jan 1 03:00:04 pppd[61]: pppd 2.4.2 started by (unknown), uid 0
Jan 1 03:00:04 pppd[61]: Serial connection established.
Jan 1 03:00:04 pppd[61]: using channel 1
Jan 1 03:00:04 pppd[61]: Using interface ppp0
Jan 1 03:00:04 pppd[61]: Connect: ppp0 <--> /dev/pts/0
Jan 1 03:00:05 pppd[61]: sent [LCP ConfReq id=0x1 <magic 0xdc74c694>]
Jan 1 03:00:08 pppd[61]: sent [LCP ConfReq id=0x1 <magic 0xdc74c694>]
Jan 1 03:00:11 pppd[61]: sent [LCP ConfReq id=0x1 <magic 0xdc74c694>]
Jan 1 03:00:14 pppd[61]: sent [LCP ConfReq id=0x1 <magic 0xdc74c694>]
Jan 1 03:00:16 udhcpd: sending OFFER of 192.168.1.2
Jan 1 03:00:16 udhcpd: sending ACK to 192.168.1.2
Jan 1 03:00:17 pppd[61]: sent [LCP ConfReq id=0x1 <magic 0xdc74c694>]
Jan 1 03:00:20 pppd[61]: sent [LCP ConfReq id=0x1 <magic 0xdc74c694>]
Jan 1 03:00:23 pppd[61]: sent [LCP ConfReq id=0x1 <magic 0xdc74c694>]
Jan 1 03:00:26 pppd[61]: sent [LCP ConfReq id=0x1 <magic 0xdc74c694>]
Jan 1 03:00:29 pppd[61]: sent [LCP ConfReq id=0x1 <magic 0xdc74c694>]
Jan 1 03:00:32 pppd[61]: sent [LCP ConfReq id=0x1 <magic 0xdc74c694>]
Jan 1 03:00:35 pppd[61]: LCP: timeout sending Config-Requests
Jan 1 03:00:35 pppd[61]: Connection terminated.
Jan 1 03:01:06 pppd[61]: Serial connection established.
Jan 1 03:01:06 pppd[61]: using channel 2
Jan 1 03:01:06 pppd[61]: Using interface ppp0
Jan 1 03:01:06 pppd[61]: Connect: ppp0 <--> /dev/pts/1
Jan 1 03:01:07 pptp[154]: Call manager for 10.0.4.1 is already running.
Jan 1 03:01:07 pptp[154]: Could not open unix socket for 10.0.4.1
Jan 1 03:01:07 pppd[61]: Script /usr/sbin/pptp 10.0.4.1 --nolaunchpppd --nobuffer --sync finished (pid 148), status = 0x1
Jan 1 03:01:07 pppd[61]: Modem hangup
Jan 1 03:01:07 pppd[61]: Connection terminated.
Jan 1 03:01:07 pptp[149]: Call manager exited with error 256
Jan 1 03:01:37 pppd[61]: Serial connection established.
Jan 1 03:01:37 pppd[61]: using channel 3
Jan 1 03:01:37 pppd[61]: Using interface ppp0
Jan 1 03:01:37 pppd[61]: Connect: ppp0 <--> /dev/pts/1
Jan 1 03:01:38 pptp[197]: Call manager for 10.0.4.1 is already running.
Jan 1 03:01:38 pptp[197]: Could not open unix socket for 10.0.4.1
Jan 1 03:01:38 pptp[192]: Call manager exited with error 256
Jan 1 03:01:38 pppd[61]: Script /usr/sbin/pptp 10.0.4.1 --nolaunchpppd --nobuffer --sync finished (pid 191), status = 0x1
Jan 1 03:01:38 pppd[61]: Modem hangup
Jan 1 03:01:38 pppd[61]: Connection terminated.
Jan 1 03:02:08 pppd[61]: Serial connection established.
Jan 1 03:02:08 pppd[61]: using channel 4
Jan 1 03:02:08 pppd[61]: Using interface ppp0
Jan 1 03:02:08 pppd[61]: Connect: ppp0 <--> /dev/pts/1
Jan 1 03:02:08 pptp[240]: Call manager for 10.0.4.1 is already running.
Jan 1 03:02:08 pptp[240]: Could not open unix socket for 10.0.4.1
Jan 1 03:02:08 pptp[235]: Call manager exited with error 256
Jan 1 03:02:08 pppd[61]: Script /usr/sbin/pptp 10.0.4.1 --nolaunchpppd --nobuffer --sync finished (pid 234), status = 0x1
Jan 1 03:02:08 pppd[61]: Modem hangup
Jan 1 03:02:08 pppd[61]: Connection terminated.
Jan 1 03:02:39 pppd[61]: Serial connection established.
Jan 1 03:02:39 pppd[61]: using channel 5
Jan 1 03:02:39 pppd[61]: Using interface ppp0
Jan 1 03:02:39 pppd[61]: Connect: ppp0 <--> /dev/pts/1
Jan 1 03:02:40 pppd[61]: sent [LCP ConfReq id=0x2 <magic 0x7887a12c>]
Jan 1 03:02:41 pptp[285]: Call manager for 10.0.4.1 is already running.
Jan 1 03:02:41 pptp[285]: Could not open unix socket for 10.0.4.1
Jan 1 03:02:41 pppd[61]: Script /usr/sbin/pptp 10.0.4.1 --nolaunchpppd --nobuffer --sync finished (pid 66), status = 0x1
Jan 1 03:02:41 pptp[71]: Call manager exited with error 256
Jan 1 03:02:43 pppd[61]: sent [LCP ConfReq id=0x2 <magic 0x7887a12c>]
Jan 1 03:02:46 pppd[61]: sent [LCP ConfReq id=0x2 <magic 0x7887a12c>]
Jan 1 03:02:49 pppd[61]: sent [LCP ConfReq id=0x2 <magic 0x7887a12c>]
Jan 1 03:02:52 pppd[61]: sent [LCP ConfReq id=0x2 <magic 0x7887a12c>]
Jan 1 03:02:55 pppd[61]: sent [LCP ConfReq id=0x2 <magic 0x7887a12c>]
Jan 1 03:02:58 pppd[61]: sent [LCP ConfReq id=0x2 <magic 0x7887a12c>]
Jan 1 03:03:01 pppd[61]: sent [LCP ConfReq id=0x2 <magic 0x7887a12c>]
Jan 1 03:03:04 pppd[61]: sent [LCP ConfReq id=0x2 <magic 0x7887a12c>]
Jan 1 03:03:07 pppd[61]: sent [LCP ConfReq id=0x2 <magic 0x7887a12c>]
Jan 1 03:03:10 pppd[61]: LCP: timeout sending Config-Requests
Jan 1 03:03:10 pppd[61]: Connection terminated.


Что ещё можно сделать или посмотреть?
timurkh
24-05-2008, 15:56
А сейчас роутер подключился, в логе следующее:

Jan 1 03:00:04 pppd[61]: pppd 2.4.2 started by (unknown), uid 0
Jan 1 03:00:04 pppd[61]: Serial connection established.
Jan 1 03:00:04 pppd[61]: using channel 1
Jan 1 03:00:04 pppd[61]: Using interface ppp0
Jan 1 03:00:04 pppd[61]: Connect: ppp0 <--> /dev/pts/0
Jan 1 03:00:05 pppd[61]: sent [LCP ConfReq id=0x1 <magic 0xba84771c>]
Jan 1 03:00:08 pppd[61]: sent [LCP ConfReq id=0x1 <magic 0xba84771c>]
Jan 1 03:00:08 pppd[61]: rcvd [LCP ConfAck id=0x1 <magic 0xba84771c>]
Jan 1 03:00:10 pppd[61]: rcvd [LCP ConfReq id=0x1 <accomp> <asyncmap 0x0> <mru 1500> <magic 0x7a29950d> <quality lqr 00 00 8c a0> <auth chap MD5>]
Jan 1 03:00:10 pppd[61]: sent [LCP ConfRej id=0x1 <accomp> <asyncmap 0x0> <quality lqr 00 00 8c a0>]
Jan 1 03:00:10 pppd[61]: rcvd [LCP ConfReq id=0x2 <mru 1500> <magic 0x7a29950d> <auth chap MD5>]
Jan 1 03:00:10 pppd[61]: sent [LCP ConfAck id=0x2 <mru 1500> <magic 0x7a29950d> <auth chap MD5>]
Jan 1 03:00:10 pppd[61]: sent [LCP EchoReq id=0x0 magic=0xba84771c]
Jan 1 03:00:10 pppd[61]: rcvd [CHAP Challenge id=0x1 <33393733383637353231313934363030>, name = ""]
Jan 1 03:00:10 pppd[61]: sent [CHAP Response id=0x1 <1606b9f20369ad758d7814a09e778243>, name = "zencom0774"]
Jan 1 03:00:10 pppd[61]: rcvd [LCP EchoRep id=0x0 magic=0x7a29950d]
Jan 1 03:00:10 pppd[61]: rcvd [CHAP Success id=0x1 "Welcome!!"]
Jan 1 03:00:10 pppd[61]: CHAP authentication succeeded: Welcome!!
Jan 1 03:00:10 pppd[61]: sent [CCP ConfReq id=0x1 <mppe -H -M -S -L -D +C>]
Jan 1 03:00:10 pppd[61]: sent [IPCP ConfReq id=0x1 <addr 0.0.0.0> <ms-dns1 0.0.0.0> <ms-dns3 0.0.0.0>]
Jan 1 03:00:10 pppd[61]: rcvd [CCP ConfReq id=0x1]
Jan 1 03:00:10 pppd[61]: sent [CCP ConfAck id=0x1]
Jan 1 03:00:10 pppd[61]: rcvd [IPCP ConfReq id=0x1 <addr 62.63.65.146> <compress VJ 0f 01>]
Jan 1 03:00:10 pppd[61]: sent [IPCP ConfRej id=0x1 <compress VJ 0f 01>]
Jan 1 03:00:10 pppd[61]: rcvd [CCP ConfRej id=0x1 <mppe -H -M -S -L -D +C>]
Jan 1 03:00:10 pppd[61]: sent [CCP ConfReq id=0x2]
Jan 1 03:00:10 pppd[61]: rcvd [IPCP ConfNak id=0x1 <addr 62.63.69.116> <ms-dns1 80.73.163.60> <ms-dns3 80.73.164.146>]
Jan 1 03:00:10 pppd[61]: sent [IPCP ConfReq id=0x2 <addr 62.63.69.116> <ms-dns1 80.73.163.60> <ms-dns3 80.73.164.146>]
Jan 1 03:00:10 pppd[61]: rcvd [IPCP ConfReq id=0x2 <addr 62.63.65.146>]
Jan 1 03:00:10 pppd[61]: sent [IPCP ConfAck id=0x2 <addr 62.63.65.146>]
Jan 1 03:00:11 pppd[61]: rcvd [CCP ConfAck id=0x2]
Jan 1 03:00:11 pppd[61]: Received bad configure-ack:
Jan 1 03:00:11 pppd[61]: rcvd [IPCP ConfAck id=0x2 <addr 62.63.69.116> <ms-dns1 80.73.163.60> <ms-dns3 80.73.164.146>]
Jan 1 03:00:11 pppd[61]: local IP address 62.63.69.116
Jan 1 03:00:11 pppd[61]: remote IP address 62.63.65.146
Jan 1 03:00:11 pppd[61]: primary DNS address 80.73.163.60
Jan 1 03:00:11 pppd[61]: secondary DNS address 80.73.164.146
Jan 1 03:00:11 pppd[61]: Script /tmp/ppp/ip-up started (pid 76)
Jan 1 03:00:11 PPTP: connect to ISP
Jan 1 03:00:11 pppd[61]: Script /tmp/ppp/ip-up finished (pid 76), status = 0x0
Jan 1 03:00:13 pppd[61]: sent [CCP ConfReq id=0x2]
Jan 1 03:00:13 pppd[61]: rcvd [CCP ConfReq id=0x2]
Jan 1 03:00:13 pppd[61]: sent [CCP ConfAck id=0x2]
Jan 1 03:00:13 pppd[61]: rcvd [CCP ConfAck id=0x2]
Jan 1 03:00:13 pppd[61]: Received bad configure-ack:
May 24 17:47:24 udhcpd: sending OFFER of 192.168.1.2
May 24 17:47:24 udhcpd: sending ACK to 192.168.1.2
May 24 17:47:25 pppd[61]: sent [CCP ConfReq id=0x2]
May 24 17:47:25 pppd[61]: rcvd [CCP ConfReq id=0x3]
May 24 17:47:25 pppd[61]: sent [CCP ConfAck id=0x3]
May 24 17:47:25 pppd[61]: rcvd [CCP ConfAck id=0x2]
May 24 17:47:25 pppd[61]: Received bad configure-ack:
May 24 17:47:28 pppd[61]: sent [CCP ConfReq id=0x2]
May 24 17:47:28 pppd[61]: rcvd [CCP ConfReq id=0x4]
May 24 17:47:28 pppd[61]: sent [CCP ConfAck id=0x4]
May 24 17:47:28 pppd[61]: rcvd [CCP ConfAck id=0x2]
May 24 17:47:28 pppd[61]: Received bad configure-ack:
May 24 17:47:31 pppd[61]: sent [CCP ConfReq id=0x2]
May 24 17:47:31 pppd[61]: rcvd [CCP ConfReq id=0x5]
May 24 17:47:31 pppd[61]: sent [CCP ConfAck id=0x5]
May 24 17:47:31 pppd[61]: rcvd [CCP ConfAck id=0x2]
May 24 17:47:31 pppd[61]: Received bad configure-ack:
May 24 17:47:33 ntp client: time is synchronized to time.nist.gov pool.ntp.org
May 24 17:47:34 pppd[61]: sent [CCP ConfReq id=0x2]
May 24 17:47:34 pppd[61]: rcvd [CCP ConfReq id=0x6]
May 24 17:47:34 pppd[61]: sent [CCP ConfAck id=0x6]
May 24 17:47:34 pppd[61]: rcvd [CCP ConfAck id=0x2]
May 24 17:47:34 pppd[61]: Received bad configure-ack:
May 24 17:47:37 pppd[61]: sent [CCP ConfReq id=0x2]
May 24 17:47:37 pppd[61]: rcvd [CCP ConfReq id=0x7]
May 24 17:47:37 pppd[61]: sent [CCP ConfAck id=0x7]
May 24 17:47:37 pppd[61]: rcvd [CCP ConfAck id=0x2]
May 24 17:47:37 pppd[61]: Received bad configure-ack:
May 24 17:47:40 pppd[61]: sent [CCP ConfReq id=0x2]
May 24 17:47:40 pppd[61]: rcvd [CCP ConfReq id=0x8]
May 24 17:47:40 pppd[61]: sent [CCP ConfAck id=0x8]
May 24 17:47:40 pppd[61]: rcvd [CCP ConfAck id=0x2]
May 24 17:47:40 pppd[61]: Received bad configure-ack:
May 24 17:47:43 pppd[61]: sent [CCP ConfReq id=0x2]
May 24 17:47:43 pppd[61]: rcvd [CCP ConfReq id=0x9]
May 24 17:47:43 pppd[61]: sent [CCP ConfAck id=0x9]
May 24 17:47:43 pppd[61]: rcvd [CCP ConfAck id=0x2]
May 24 17:47:43 pppd[61]: Received bad configure-ack:
May 24 17:47:46 pppd[61]: sent [CCP ConfReq id=0x2]
May 24 17:47:46 pppd[61]: rcvd [CCP ConfReq id=0xa]
May 24 17:47:46 pppd[61]: sent [CCP ConfAck id=0xa]
May 24 17:47:46 pppd[61]: rcvd [CCP ConfAck id=0x2]
May 24 17:47:46 pppd[61]: Received bad configure-ack:
May 24 17:47:49 pppd[61]: CCP: timeout sending Config-Requests


может кому-то это что-то скажет?