Thread Info
  • State Not Answered
  • Locked Locked
  • Replies 1 reply
  • Subscribers 1 subscriber
  • Views 710 views
  • Users 0 members are here
Options
Suggested
This discussion has been locked.
You can no longer post new replies to this discussion. If you have a question you can start a new discussion

Problems establishing a PPTP VPN on V7.301

Markus_Hahn
I use a ASG 320 as HA currently on version 7.300. I have PPTP VPN enabled. I have a problem with on user who is trying to connect his Vista laptop via his home ADSL connection. Sometimes it works, sometimes not. If it doesn't work it does so for hours. This is a part of the PPTP daemon log when it fails:

2008:09:03-19:30:44 (none) pptpd[32370]: CTRL: Client 192.168.5.225 control connection started
2008:09:03-19:30:44 (none) pptpd[32370]: CTRL: Starting call (launching pppd, opening GRE)
2008:09:03-19:30:44 (none) pppd-pptp[32371]: Plugin /usr/sbin/aua.so loaded.
2008:09:03-19:30:44 (none) pppd-pptp[32371]: AUA plugin initialized.
2008:09:03-19:30:44 (none) pppd-pptp[32371]: Plugin /usr/sbin/aua.so loaded.
2008:09:03-19:30:44 (none) pppd-pptp[32371]: AUA plugin initialized.
2008:09:03-19:30:44 (none) pppd-pptp[32371]: pppd 2.4.3 started by (unknown), uid 0
2008:09:03-19:30:44 (none) pppd-pptp[32371]: Starting negotiation on /dev/ttyp0
2008:09:03-19:30:44 (none) pptpd[32370]: GRE: Bad checksum from pppd.
2008:09:03-19:31:14 (none) pppd-pptp[32371]: LCP: timeout sending Config-Requests
2008:09:03-19:31:14 (none) pppd-pptp[32371]: Connection terminated.
2008:09:03-19:31:14 (none) pppd-pptp[32371]: Starting negotiation on /dev/ttyp0
2008:09:03-19:31:14 (none) pppd-pptp[32371]: tcflush failed: Bad file descriptor
2008:09:03-19:31:14 (none) pppd-pptp[32371]: tcsetattr: Invalid argument (line 1001)
2008:09:03-19:31:14 (none) pppd-pptp[32371]: Exit.
2008:09:03-19:31:14 (none) pptpd[32370]: GRE: read(fd=6,buffer=8058640,len=8196) from PTY failed: status = -1 error = Input/output error, usually caused by unexpected termination of pppd, check option syntax and pppd logs
2008:09:03-19:31:14 (none) pptpd[32370]: CTRL: PTY read or GRE write failed (pty,gre)=(6,7)
2008:09:03-19:31:14 (none) pptpd[32370]: CTRL: Reaping child PPP[32371]
2008:09:03-19:31:14 (none) pptpd[32370]: CTRL: Client 192.168.5.225 control connection finished


And this is when it works:

2008:09:03-20:21:02 (none) pptpd[1351]: CTRL: Client 192.168.5.225 control connection started
2008:09:03-20:21:02 (none) pptpd[1351]: CTRL: Starting call (launching pppd, opening GRE)
2008:09:03-20:21:02 (none) pppd-pptp[1352]: Plugin /usr/sbin/aua.so loaded.
2008:09:03-20:21:02 (none) pppd-pptp[1352]: AUA plugin initialized.
2008:09:03-20:21:02 (none) pppd-pptp[1352]: Plugin /usr/sbin/aua.so loaded.
2008:09:03-20:21:02 (none) pppd-pptp[1352]: AUA plugin initialized.
2008:09:03-20:21:02 (none) pppd-pptp[1352]: pppd 2.4.3 started by (unknown), uid 0
2008:09:03-20:21:02 (none) pppd-pptp[1352]: Starting negotiation on /dev/ttyp0
2008:09:03-20:21:02 (none) pptpd[1351]: GRE: Bad checksum from pppd.
2008:09:03-20:21:05 (none) pptpd[1351]: CTRL: Ignored a SET LINK INFO packet with real ACCMs!
2008:09:03-20:21:07 (none) pppd-pptp[1352]: Using interface ppp0
2008:09:03-20:21:07 (none) pppd-pptp[1352]: MPPE 128-bit stateless compression enabled
2008:09:03-20:21:07 (none) pppd-pptp[1352]: Cannot determine ethernet address for proxy ARP
2008:09:03-20:21:07 (none) pppd-pptp[1352]: local  IP address 192.168.5.65
2008:09:03-20:21:07 (none) pppd-pptp[1352]: remote IP address 192.168.5.66
2008:09:03-20:21:08 (none) pppd-pptp[1355]: id="2201" severity="info" sys="SecureNet" sub="vpn" event="Connection started" username="Sigma" variant="pptp" srcip="192.168.5.225" virtual_ip="192.168.5.66"
2008:09:03-20:27:38 (none) pppd-pptp[1352]: LCP terminated by peer (+M-V6[^@ 2008:09:03-19:31:14 (none) pppd-pptp[32371]: LCP: timeout sending Config-Requests

I only have this problem with that particular user. Somebody else using the same account does not report such issues. What can I do about it ?


This thread was automatically locked due to age.
Parents
  • 0
    Please let me know as you get some solution I am also having same issue, PPTP VPN connection error with ASL7.

    When ever some one tries to connect to VPN from some office LAN or DSL connection it fails with Error 619. But at the same time users are able to connect with same user name when they are on dial up or have a static live IP. 

    Following are ASL7 logs of the event when VPN not working.

    2008:09:17-09:35:34 (none) pptpd[26299]: GRE: read(fd=6,buffer=8058640,len=8196) from PTY failed: status = -1 error = Input/output error, usually caused by unexpected termination of pppd, check option syntax and pppd logs
    2008:09:17-09:35:34 (none) pptpd[26299]: CTRL: PTY read or GRE write failed (pty,gre)=(6,7)
    2008:09:17-09:35:34 (none) pptpd[26299]: CTRL: Reaping child PPP[26300]
    2008:09:17-09:35:34 (none) pptpd[26299]: CTRL: Client 202.142.166.172 control connection finished
    2008:09:17-09:35:34 (none) pptpd[26299]: CTRL: Exiting now
    2008:09:17-09:35:34 (none) pptpd[9246]: MGR: Reaped child 26299
    2008:09:17-09:36:35 (none) pptpd[26315]: MGR: Launching /usr/local/sbin/pptpctrl to handle client
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: local address = 10.242.1.1
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: remote address = 10.242.1.2
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: pppd options file = /etc/ppp/options
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: Client 202.142.166.172 control connection started
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: Received PPTP Control Message (type: 1)
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: Made a START CTRL CONN RPLY packet
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: I wrote 156 bytes to the client.
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: Sent packet to client
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: Received PPTP Control Message (type: 7)
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: Set parameters to 100000000 maxbps, 64 window size
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: Made a OUT CALL RPLY packet
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: Starting call (launching pppd, opening GRE)
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: pty_fd = 6
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: tty_fd = 7
    2008:09:17-09:36:35 (none) pptpd[26316]: CTRL (PPPD Launcher): program binary = /usr/sbin/pppd-pptp
    2008:09:17-09:36:35 (none) pptpd[26316]: CTRL (PPPD Launcher): local address = 10.242.1.1
    2008:09:17-09:36:35 (none) pptpd[26316]: CTRL (PPPD Launcher): remote address = 10.242.1.2
    2008:09:17-09:36:35 (none) pppd-pptp[26316]: Plugin /usr/sbin/aua.so loaded.
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: I wrote 32 bytes to the client.
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: Sent packet to client
    2008:09:17-09:36:35 (none) pppd-pptp[26316]: AUA plugin initialized.
    2008:09:17-09:36:35 (none) pppd-pptp[26316]: Plugin /usr/sbin/aua.so loaded.
    2008:09:17-09:36:35 (none) pppd-pptp[26316]: AUA plugin initialized.
    2008:09:17-09:36:35 (none) pppd-pptp[26316]: pppd 2.4.3 started by (unknown), uid 0
    2008:09:17-09:36:35 (none) pppd-pptp[26316]: using channel 62
    2008:09:17-09:36:35 (none) pppd-pptp[26316]: Starting negotiation on /dev/ttyp0
    2008:09:17-09:36:35 (none) pppd-pptp[26316]: sent [LCP ConfReq id=0x1 ]
    2008:09:17-09:36:35 (none) pptpd[26315]: GRE: Bad checksum from pppd.
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: Received PPTP Control Message (type: 15)
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: Got a SET LINK INFO packet with standard ACCMs
    2008:09:17-09:36:38 (none) pppd-pptp[26316]: sent [LCP ConfReq id=0x1 ]
    2008:09:17-09:36:41 (none) pppd-pptp[26316]: sent [LCP ConfReq id=0x1 ]
    2008:09:17-09:36:44 (none) pppd-pptp[26316]: sent [LCP ConfReq id=0x1 ]
    2008:09:17-09:36:47 (none) pppd-pptp[26316]: sent [LCP ConfReq id=0x1 ]
    2008:09:17-09:36:50 (none) pppd-pptp[26316]: sent [LCP ConfReq id=0x1 ]
    2008:09:17-09:36:53 (none) pppd-pptp[26316]: sent [LCP ConfReq id=0x1 ]
    2008:09:17-09:36:56 (none) pppd-pptp[26316]: sent [LCP ConfReq id=0x1 ]
    2008:09:17-09:36:59 (none) pppd-pptp[26316]: sent [LCP ConfReq id=0x1 ]
    2008:09:17-09:37:02 (none) pppd-pptp[26316]: sent [LCP ConfReq id=0x1 ]
    2008:09:17-09:37:05 (none) pppd-pptp[26316]: LCP: timeout sending Config-Requests
    2008:09:17-09:37:05 (none) pppd-pptp[26316]: Connection terminated.
    2008:09:17-09:37:05 (none) pppd-pptp[26316]: using channel 63
    2008:09:17-09:37:05 (none) pppd-pptp[26316]: Starting negotiation on /dev/ttyp0
    2008:09:17-09:37:05 (none) pppd-pptp[26316]: sent [LCP ConfReq id=0x2 ]
    2008:09:17-09:37:05 (none) pppd-pptp[26316]: tcflush failed: Bad file descriptor
    2008:09:17-09:37:05 (none) pppd-pptp[26316]: tcsetattr: Invalid argument (line 1001)
    2008:09:17-09:37:05 (none) pppd-pptp[26316]: Exit.
Reply
  • 0
    Please let me know as you get some solution I am also having same issue, PPTP VPN connection error with ASL7.

    When ever some one tries to connect to VPN from some office LAN or DSL connection it fails with Error 619. But at the same time users are able to connect with same user name when they are on dial up or have a static live IP. 

    Following are ASL7 logs of the event when VPN not working.

    2008:09:17-09:35:34 (none) pptpd[26299]: GRE: read(fd=6,buffer=8058640,len=8196) from PTY failed: status = -1 error = Input/output error, usually caused by unexpected termination of pppd, check option syntax and pppd logs
    2008:09:17-09:35:34 (none) pptpd[26299]: CTRL: PTY read or GRE write failed (pty,gre)=(6,7)
    2008:09:17-09:35:34 (none) pptpd[26299]: CTRL: Reaping child PPP[26300]
    2008:09:17-09:35:34 (none) pptpd[26299]: CTRL: Client 202.142.166.172 control connection finished
    2008:09:17-09:35:34 (none) pptpd[26299]: CTRL: Exiting now
    2008:09:17-09:35:34 (none) pptpd[9246]: MGR: Reaped child 26299
    2008:09:17-09:36:35 (none) pptpd[26315]: MGR: Launching /usr/local/sbin/pptpctrl to handle client
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: local address = 10.242.1.1
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: remote address = 10.242.1.2
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: pppd options file = /etc/ppp/options
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: Client 202.142.166.172 control connection started
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: Received PPTP Control Message (type: 1)
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: Made a START CTRL CONN RPLY packet
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: I wrote 156 bytes to the client.
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: Sent packet to client
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: Received PPTP Control Message (type: 7)
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: Set parameters to 100000000 maxbps, 64 window size
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: Made a OUT CALL RPLY packet
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: Starting call (launching pppd, opening GRE)
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: pty_fd = 6
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: tty_fd = 7
    2008:09:17-09:36:35 (none) pptpd[26316]: CTRL (PPPD Launcher): program binary = /usr/sbin/pppd-pptp
    2008:09:17-09:36:35 (none) pptpd[26316]: CTRL (PPPD Launcher): local address = 10.242.1.1
    2008:09:17-09:36:35 (none) pptpd[26316]: CTRL (PPPD Launcher): remote address = 10.242.1.2
    2008:09:17-09:36:35 (none) pppd-pptp[26316]: Plugin /usr/sbin/aua.so loaded.
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: I wrote 32 bytes to the client.
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: Sent packet to client
    2008:09:17-09:36:35 (none) pppd-pptp[26316]: AUA plugin initialized.
    2008:09:17-09:36:35 (none) pppd-pptp[26316]: Plugin /usr/sbin/aua.so loaded.
    2008:09:17-09:36:35 (none) pppd-pptp[26316]: AUA plugin initialized.
    2008:09:17-09:36:35 (none) pppd-pptp[26316]: pppd 2.4.3 started by (unknown), uid 0
    2008:09:17-09:36:35 (none) pppd-pptp[26316]: using channel 62
    2008:09:17-09:36:35 (none) pppd-pptp[26316]: Starting negotiation on /dev/ttyp0
    2008:09:17-09:36:35 (none) pppd-pptp[26316]: sent [LCP ConfReq id=0x1 ]
    2008:09:17-09:36:35 (none) pptpd[26315]: GRE: Bad checksum from pppd.
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: Received PPTP Control Message (type: 15)
    2008:09:17-09:36:35 (none) pptpd[26315]: CTRL: Got a SET LINK INFO packet with standard ACCMs
    2008:09:17-09:36:38 (none) pppd-pptp[26316]: sent [LCP ConfReq id=0x1 ]
    2008:09:17-09:36:41 (none) pppd-pptp[26316]: sent [LCP ConfReq id=0x1 ]
    2008:09:17-09:36:44 (none) pppd-pptp[26316]: sent [LCP ConfReq id=0x1 ]
    2008:09:17-09:36:47 (none) pppd-pptp[26316]: sent [LCP ConfReq id=0x1 ]
    2008:09:17-09:36:50 (none) pppd-pptp[26316]: sent [LCP ConfReq id=0x1 ]
    2008:09:17-09:36:53 (none) pppd-pptp[26316]: sent [LCP ConfReq id=0x1 ]
    2008:09:17-09:36:56 (none) pppd-pptp[26316]: sent [LCP ConfReq id=0x1 ]
    2008:09:17-09:36:59 (none) pppd-pptp[26316]: sent [LCP ConfReq id=0x1 ]
    2008:09:17-09:37:02 (none) pppd-pptp[26316]: sent [LCP ConfReq id=0x1 ]
    2008:09:17-09:37:05 (none) pppd-pptp[26316]: LCP: timeout sending Config-Requests
    2008:09:17-09:37:05 (none) pppd-pptp[26316]: Connection terminated.
    2008:09:17-09:37:05 (none) pppd-pptp[26316]: using channel 63
    2008:09:17-09:37:05 (none) pppd-pptp[26316]: Starting negotiation on /dev/ttyp0
    2008:09:17-09:37:05 (none) pppd-pptp[26316]: sent [LCP ConfReq id=0x2 ]
    2008:09:17-09:37:05 (none) pppd-pptp[26316]: tcflush failed: Bad file descriptor
    2008:09:17-09:37:05 (none) pppd-pptp[26316]: tcsetattr: Invalid argument (line 1001)
    2008:09:17-09:37:05 (none) pppd-pptp[26316]: Exit.
Children
No Data
Cookie Information Banner