[Poptop-server] pptpd server connection problem

SourceForge Headquarters 225 Broadway Suite 1600 San Diego, CA 92101 +1 (858) 454-5900

Hi,

I hope someone here would be willing to assist me, could someone please tell me what is happening here in this log? And what would be a likely cause of this error, here I am trying to connect to a Poptop server (on OpenBSD 4.2 amd64, kernel rebuilt from release sources) from a Windows XP Professional client (standard Windows XP settings).

Also, the configuration needs to work primarly on Windows Vista and Mac OS X Leopard, but Windows XP is also necessary for backwards-compatibility.

One more thing, is it possible to configure Poptop to assign IP addresses (to both server and client) using DHCP? I already have DHCP running on this specific computer.

I've set the syslog level to debug, so my apologies for any unnecessary information, unfortunately, I'm not sure which is necessary. This is rather urgent, so if anybody has some free time to look at it, I would really appreciate it. Configuration is included in the log file, so I haven't included dumps of the config files, if this is necessary, please request.

Thanks for your help!

Regards,

Lionel

Feb 18 19:10:15 server pptpd[2807]: CTRL: Exiting now
Feb 18 19:10:15 server pptpd[4613]: MGR: Reaped child 2807
Feb 18 19:10:15 server ppp[19428]: Phase: deflink: Connect time: 16 secs: 0 octets in, 300 octets out 
Feb 18 19:10:15 server ppp[19428]: Phase: deflink: 0 packets in, 5 packets out 
Feb 18 19:10:15 server ppp[19428]: Phase:  total 18 bytes/sec, peak 24 bytes/sec on Mon Feb 18 19:10:03 2008 
Feb 18 19:10:15 server ppp[19428]: Phase: deflink: lcp -> closed 
Feb 18 19:10:15 server ppp[19428]: Phase: bundle: Dead 
Feb 18 19:10:15 server ppp[19428]: Phase: Caught signal 2, abort connection(s) 
Feb 18 19:10:15 server ppp[19428]: Phase: PPP Terminated (normal). 
Feb 18 19:10:50 server pptpd[19964]: MGR: Launching /usr/local/sbin/pptpctrl to handle client
Feb 18 19:10:50 server pptpd[19964]: CTRL: Client 10.0.0.2 control connection started
Feb 18 19:10:50 server pptpd[19964]: CTRL: Received PPTP Control Message (type: 1)
Feb 18 19:10:50 server pptpd[19964]: CTRL: Made a START CTRL CONN RPLY packet
Feb 18 19:10:50 server pptpd[19964]: CTRL: I wrote 156 bytes to the client.
Feb 18 19:10:50 server pptpd[19964]: CTRL: Sent packet to client
Feb 18 19:10:50 server pptpd[19964]: CTRL: Received PPTP Control Message (type: 7)
Feb 18 19:10:50 server pptpd[19964]: CTRL: Set parameters to 1525 maxbps, 64 window size
Feb 18 19:10:50 server pptpd[19964]: CTRL: Made a OUT CALL RPLY packet
Feb 18 19:10:50 server pptpd[19964]: CTRL: Starting call (launching pppd, opening GRE)
Feb 18 19:10:50 server pptpd[19964]: CTRL: pty_fd = 5
Feb 18 19:10:50 server pptpd[19964]: CTRL: tty_fd = 4
Feb 18 19:10:50 server pptpd[19964]: CTRL: I wrote 32 bytes to the client.
Feb 18 19:10:50 server pptpd[19964]: CTRL: Sent packet to client
Feb 18 19:10:50 server ppp[17661]: Phase: Using interface: tun0 
Feb 18 19:10:50 server ppp[17661]: Phase: deflink: Created in closed state 
Feb 18 19:10:50 server ppp[17661]: Command: loop: set device localhost:pptp 
Feb 18 19:10:50 server ppp[17661]: Command: loop: set dial 
Feb 18 19:10:50 server ppp[17661]: Command: loop: set login 
Feb 18 19:10:50 server ppp[17661]: Command: loop: set mppe * stateful 
Feb 18 19:10:50 server ppp[17661]: Command: loop: set ifaddr 10.0.1.1 10.0.1.2-10.0.1.253 255.255.255.255 
Feb 18 19:10:50 server ppp[17661]: IPCP: Selected IP address 10.0.1.200 
Feb 18 19:10:50 server ppp[17661]: Command: loop: set server /tmp/loop ******** 0177 
Feb 18 19:10:50 server ppp[17661]: Phase: Listening at local socket /tmp/loop. 
Feb 18 19:10:50 server ppp[17661]: Command: pptp: disable pap 
Feb 18 19:10:50 server ppp[17661]: Command: pptp: disable chap 
Feb 18 19:10:50 server ppp[17661]: Command: pptp: enable mschapv2 
Feb 18 19:10:50 server ppp[17661]: Command: pptp: disable deflate pred1 
Feb 18 19:10:50 server ppp[17661]: Command: pptp: deny deflate pred1 
Feb 18 19:10:50 server ppp[17661]: Command: pptp: disable ipv6 
Feb 18 19:10:50 server ppp[17661]: Command: pptp: accept mppe 
Feb 18 19:10:50 server ppp[17661]: Command: pptp: enable proxy 
Feb 18 19:10:50 server ppp[17661]: Command: pptp: accept dns 
Feb 18 19:10:50 server ppp[17661]: Command: pptp: set dns 10.0.0.1 
Feb 18 19:10:50 server ppp[17661]: Command: pptp: set nbns 10.0.0.1 
Feb 18 19:10:50 server ppp[17661]: Command: pptp: set device !/etc/ppp/secure 
Feb 18 19:10:50 server ppp[17661]: Phase: PPP Started (direct mode). 
Feb 18 19:10:50 server ppp[17661]: Phase: bundle: Establish 
Feb 18 19:10:50 server ppp[17661]: Phase: deflink: closed -> opening 
Feb 18 19:10:50 server ppp[17661]: Phase: deflink: Connected! 
Feb 18 19:10:50 server ppp[17661]: Phase: deflink: opening -> carrier 
Feb 18 19:10:50 server ppp[17661]: Phase: deflink: carrier -> lcp 
Feb 18 19:10:50 server ppp[17661]: LCP: FSM: Using "deflink" as a transport 
Feb 18 19:10:50 server ppp[17661]: LCP: deflink: State change Initial --> Closed 
Feb 18 19:10:50 server ppp[17661]: LCP: deflink: State change Closed --> Stopped 
Feb 18 19:10:50 server pptpd[19964]: CTRL: Received PPTP Control Message (type: 15)
Feb 18 19:10:50 server pptpd[19964]: CTRL: Got a SET LINK INFO packet with standard ACCMs
Feb 18 19:10:51 server ppp[17661]: LCP: deflink: LayerStart 
Feb 18 19:10:51 server ppp[17661]: LCP: deflink: SendConfigReq(1) state = Stopped 
Feb 18 19:10:51 server ppp[17661]: LCP:  ACFCOMP[2] 
Feb 18 19:10:51 server ppp[17661]: LCP:  PROTOCOMP[2] 
Feb 18 19:10:51 server ppp[17661]: LCP:  ACCMAP[6] 0x00000000 
Feb 18 19:10:51 server ppp[17661]: LCP:  MRU[4] 1500 
Feb 18 19:10:51 server ppp[17661]: LCP:  MAGICNUM[6] 0x1ae1fc76 
Feb 18 19:10:51 server ppp[17661]: LCP:  AUTHPROTO[5] 0xc223 (CHAP 0x81) 
Feb 18 19:10:51 server ppp[17661]: LCP: deflink: State change Stopped --> Req-Sent 
Feb 18 19:10:54 server ppp[17661]: LCP: deflink: SendConfigReq(1) state = Req-Sent 
Feb 18 19:10:54 server ppp[17661]: LCP:  ACFCOMP[2] 
Feb 18 19:10:54 server ppp[17661]: LCP:  PROTOCOMP[2] 
Feb 18 19:10:54 server ppp[17661]: LCP:  ACCMAP[6] 0x00000000 
Feb 18 19:10:54 server ppp[17661]: LCP:  MRU[4] 1500 
Feb 18 19:10:54 server ppp[17661]: LCP:  MAGICNUM[6] 0x1ae1fc76 
Feb 18 19:10:54 server ppp[17661]: LCP:  AUTHPROTO[5] 0xc223 (CHAP 0x81) 
Feb 18 19:10:57 server ppp[17661]: LCP: deflink: SendConfigReq(1) state = Req-Sent 
Feb 18 19:10:57 server ppp[17661]: LCP:  ACFCOMP[2] 
Feb 18 19:10:57 server ppp[17661]: LCP:  PROTOCOMP[2] 
Feb 18 19:10:57 server ppp[17661]: LCP:  ACCMAP[6] 0x00000000 
Feb 18 19:10:57 server ppp[17661]: LCP:  MRU[4] 1500 
Feb 18 19:10:57 server ppp[17661]: LCP:  MAGICNUM[6] 0x1ae1fc76 
Feb 18 19:10:57 server ppp[17661]: LCP:  AUTHPROTO[5] 0xc223 (CHAP 0x81) 
Feb 18 19:11:00 server ppp[17661]: LCP: deflink: SendConfigReq(1) state = Req-Sent 
Feb 18 19:11:00 server ppp[17661]: LCP:  ACFCOMP[2] 
Feb 18 19:11:00 server ppp[17661]: LCP:  PROTOCOMP[2] 
Feb 18 19:11:00 server ppp[17661]: LCP:  ACCMAP[6] 0x00000000 
Feb 18 19:11:00 server ppp[17661]: LCP:  MRU[4] 1500 
Feb 18 19:11:00 server ppp[17661]: LCP:  MAGICNUM[6] 0x1ae1fc76 
Feb 18 19:11:00 server ppp[17661]: LCP:  AUTHPROTO[5] 0xc223 (CHAP 0x81) 
Feb 18 19:11:03 server ppp[17661]: LCP: deflink: SendConfigReq(1) state = Req-Sent 
Feb 18 19:11:03 server ppp[17661]: LCP:  ACFCOMP[2] 
Feb 18 19:11:03 server ppp[17661]: LCP:  PROTOCOMP[2] 
Feb 18 19:11:03 server ppp[17661]: LCP:  ACCMAP[6] 0x00000000 
Feb 18 19:11:03 server ppp[17661]: LCP:  MRU[4] 1500 
Feb 18 19:11:03 server ppp[17661]: LCP:  MAGICNUM[6] 0x1ae1fc76 
Feb 18 19:11:03 server ppp[17661]: LCP:  AUTHPROTO[5] 0xc223 (CHAP 0x81) 
Feb 18 19:11:06 server ppp[17661]: LCP: deflink: LayerFinish 
Feb 18 19:11:06 server ppp[17661]: LCP: deflink: State change Req-Sent --> Stopped 
Feb 18 19:11:06 server ppp[17661]: LCP: deflink: State change Stopped --> Closed 
Feb 18 19:11:06 server ppp[17661]: LCP: deflink: State change Closed --> Initial 
Feb 18 19:11:06 server ppp[17661]: Phase: deflink: Disconnected! 
Feb 18 19:11:06 server pptpd[19964]: GRE: read(fd=5,buffer=805c40,len=8196) from PTY failed: status = 0 error = No error
Feb 18 19:11:06 server pptpd[19964]: CTRL: PTY read or GRE write failed (pty,gre)=(5,4)
Feb 18 19:11:06 server pptpd[19964]: CTRL: Closing child ppp with pid 17661
Feb 18 19:11:06 server pptpd[19964]: CTRL: Client 10.0.0.2 control connection finished
Feb 18 19:11:06 server pptpd[19964]: CTRL: Exiting now
Feb 18 19:11:06 server pptpd[4613]: MGR: Reaped child 19964
Feb 18 19:11:06 server ppp[17661]: Phase: deflink: Connect time: 16 secs: 0 octets in, 300 octets out 
Feb 18 19:11:06 server ppp[17661]: Phase: deflink: 0 packets in, 5 packets out 
Feb 18 19:11:06 server ppp[17661]: Phase:  total 18 bytes/sec, peak 24 bytes/sec on Mon Feb 18 19:10:54 2008 
Feb 18 19:11:06 server ppp[17661]: Phase: deflink: lcp -> closed 
Feb 18 19:11:06 server ppp[17661]: Phase: bundle: Dead 
Feb 18 19:11:06 server ppp[17661]: Phase: Caught signal 2, abort connection(s) 
Feb 18 19:11:06 server ppp[17661]: Phase: PPP Terminated (normal). 
Feb 18 19:11:18 server pptpd[12274]: MGR: Launching /usr/local/sbin/pptpctrl to handle client
Feb 18 19:11:18 server pptpd[12274]: CTRL: Client 10.0.0.2 control connection started
Feb 18 19:11:18 server pptpd[12274]: CTRL: Received PPTP Control Message (type: 1)
Feb 18 19:11:18 server pptpd[12274]: CTRL: Made a START CTRL CONN RPLY packet
Feb 18 19:11:18 server pptpd[12274]: CTRL: I wrote 156 bytes to the client.
Feb 18 19:11:18 server pptpd[12274]: CTRL: Sent packet to client
Feb 18 19:11:18 server pptpd[12274]: CTRL: Received PPTP Control Message (type: 7)
Feb 18 19:11:18 server pptpd[12274]: CTRL: Set parameters to 1525 maxbps, 64 window size
Feb 18 19:11:18 server pptpd[12274]: CTRL: Made a OUT CALL RPLY packet
Feb 18 19:11:18 server pptpd[12274]: CTRL: Starting call (launching pppd, opening GRE)
Feb 18 19:11:18 server pptpd[12274]: CTRL: pty_fd = 5
Feb 18 19:11:18 server pptpd[12274]: CTRL: tty_fd = 4
Feb 18 19:11:18 server pptpd[12274]: CTRL: I wrote 32 bytes to the client.
Feb 18 19:11:18 server pptpd[12274]: CTRL: Sent packet to client
Feb 18 19:11:18 server ppp[25271]: Phase: Using interface: tun0 
Feb 18 19:11:18 server ppp[25271]: Phase: deflink: Created in closed state 
Feb 18 19:11:18 server ppp[25271]: Command: loop: set device localhost:pptp 
Feb 18 19:11:18 server ppp[25271]: Command: loop: set dial 
Feb 18 19:11:18 server ppp[25271]: Command: loop: set login 
Feb 18 19:11:18 server ppp[25271]: Command: loop: set mppe * stateful 
Feb 18 19:11:18 server ppp[25271]: Command: loop: set ifaddr 10.0.1.1 10.0.1.2-10.0.1.253 255.255.255.255 
Feb 18 19:11:18 server ppp[25271]: IPCP: Selected IP address 10.0.1.143 
Feb 18 19:11:18 server ppp[25271]: Command: loop: set server /tmp/loop ******** 0177 
Feb 18 19:11:18 server ppp[25271]: Phase: Listening at local socket /tmp/loop. 
Feb 18 19:11:18 server ppp[25271]: Command: pptp: disable pap 
Feb 18 19:11:18 server ppp[25271]: Command: pptp: disable chap 
Feb 18 19:11:18 server ppp[25271]: Command: pptp: enable mschapv2 
Feb 18 19:11:18 server ppp[25271]: Command: pptp: disable deflate pred1 
Feb 18 19:11:18 server ppp[25271]: Command: pptp: deny deflate pred1 
Feb 18 19:11:18 server ppp[25271]: Command: pptp: disable ipv6 
Feb 18 19:11:18 server ppp[25271]: Command: pptp: accept mppe 
Feb 18 19:11:18 server ppp[25271]: Command: pptp: enable proxy 
Feb 18 19:11:18 server ppp[25271]: Command: pptp: accept dns 
Feb 18 19:11:18 server ppp[25271]: Command: pptp: set dns 10.0.0.1 
Feb 18 19:11:18 server ppp[25271]: Command: pptp: set nbns 10.0.0.1 
Feb 18 19:11:18 server ppp[25271]: Command: pptp: set device !/etc/ppp/secure 
Feb 18 19:11:18 server ppp[25271]: Phase: PPP Started (direct mode). 
Feb 18 19:11:18 server ppp[25271]: Phase: bundle: Establish 
Feb 18 19:11:18 server ppp[25271]: Phase: deflink: closed -> opening 
Feb 18 19:11:18 server ppp[25271]: Phase: deflink: Connected! 
Feb 18 19:11:18 server ppp[25271]: Phase: deflink: opening -> carrier 
Feb 18 19:11:18 server ppp[25271]: Phase: deflink: carrier -> lcp 
Feb 18 19:11:18 server ppp[25271]: LCP: FSM: Using "deflink" as a transport 
Feb 18 19:11:18 server ppp[25271]: LCP: deflink: State change Initial --> Closed 
Feb 18 19:11:18 server ppp[25271]: LCP: deflink: State change Closed --> Stopped 
Feb 18 19:11:18 server pptpd[12274]: CTRL: Received PPTP Control Message (type: 15)
Feb 18 19:11:18 server pptpd[12274]: CTRL: Got a SET LINK INFO packet with standard ACCMs
Feb 18 19:11:19 server ppp[25271]: LCP: deflink: LayerStart 
Feb 18 19:11:19 server ppp[25271]: LCP: deflink: SendConfigReq(1) state = Stopped 
Feb 18 19:11:19 server ppp[25271]: LCP:  ACFCOMP[2] 
Feb 18 19:11:19 server ppp[25271]: LCP:  PROTOCOMP[2] 
Feb 18 19:11:19 server ppp[25271]: LCP:  ACCMAP[6] 0x00000000 
Feb 18 19:11:19 server ppp[25271]: LCP:  MRU[4] 1500 
Feb 18 19:11:19 server ppp[25271]: LCP:  MAGICNUM[6] 0x66519a51 
Feb 18 19:11:19 server ppp[25271]: LCP:  AUTHPROTO[5] 0xc223 (CHAP 0x81) 
Feb 18 19:11:19 server ppp[25271]: LCP: deflink: State change Stopped --> Req-Sent 
Feb 18 19:11:22 server ppp[25271]: LCP: deflink: SendConfigReq(1) state = Req-Sent 
Feb 18 19:11:22 server ppp[25271]: LCP:  ACFCOMP[2] 
Feb 18 19:11:22 server ppp[25271]: LCP:  PROTOCOMP[2] 
Feb 18 19:11:22 server ppp[25271]: LCP:  ACCMAP[6] 0x00000000 
Feb 18 19:11:22 server ppp[25271]: LCP:  MRU[4] 1500 
Feb 18 19:11:22 server ppp[25271]: LCP:  MAGICNUM[6] 0x66519a51 
Feb 18 19:11:22 server ppp[25271]: LCP:  AUTHPROTO[5] 0xc223 (CHAP 0x81) 
Feb 18 19:11:25 server ppp[25271]: LCP: deflink: SendConfigReq(1) state = Req-Sent 
Feb 18 19:11:25 server ppp[25271]: LCP:  ACFCOMP[2] 
Feb 18 19:11:25 server ppp[25271]: LCP:  PROTOCOMP[2] 
Feb 18 19:11:25 server ppp[25271]: LCP:  ACCMAP[6] 0x00000000 
Feb 18 19:11:25 server ppp[25271]: LCP:  MRU[4] 1500 
Feb 18 19:11:25 server ppp[25271]: LCP:  MAGICNUM[6] 0x66519a51 
Feb 18 19:11:25 server ppp[25271]: LCP:  AUTHPROTO[5] 0xc223 (CHAP 0x81) 
Feb 18 19:11:28 server ppp[25271]: LCP: deflink: SendConfigReq(1) state = Req-Sent 
Feb 18 19:11:28 server ppp[25271]: LCP:  ACFCOMP[2] 
Feb 18 19:11:28 server ppp[25271]: LCP:  PROTOCOMP[2] 
Feb 18 19:11:28 server ppp[25271]: LCP:  ACCMAP[6] 0x00000000 
Feb 18 19:11:28 server ppp[25271]: LCP:  MRU[4] 1500 
Feb 18 19:11:28 server ppp[25271]: LCP:  MAGICNUM[6] 0x66519a51 
Feb 18 19:11:28 server ppp[25271]: LCP:  AUTHPROTO[5] 0xc223 (CHAP 0x81) 
Feb 18 19:11:31 server ppp[25271]: LCP: deflink: SendConfigReq(1) state = Req-Sent 
Feb 18 19:11:31 server ppp[25271]: LCP:  ACFCOMP[2] 
Feb 18 19:11:31 server ppp[25271]: LCP:  PROTOCOMP[2] 
Feb 18 19:11:31 server ppp[25271]: LCP:  ACCMAP[6] 0x00000000 
Feb 18 19:11:31 server ppp[25271]: LCP:  MRU[4] 1500 
Feb 18 19:11:31 server ppp[25271]: LCP:  MAGICNUM[6] 0x66519a51 
Feb 18 19:11:31 server ppp[25271]: LCP:  AUTHPROTO[5] 0xc223 (CHAP 0x81) 
Feb 18 19:11:34 server ppp[25271]: LCP: deflink: LayerFinish 
Feb 18 19:11:34 server ppp[25271]: LCP: deflink: State change Req-Sent --> Stopped 
Feb 18 19:11:34 server ppp[25271]: LCP: deflink: State change Stopped --> Closed 
Feb 18 19:11:34 server ppp[25271]: LCP: deflink: State change Closed --> Initial 
Feb 18 19:11:34 server ppp[25271]: Phase: deflink: Disconnected! 
Feb 18 19:11:34 server pptpd[12274]: GRE: read(fd=5,buffer=805c40,len=8196) from PTY failed: status = 0 error = No error
Feb 18 19:11:34 server pptpd[12274]: CTRL: PTY read or GRE write failed (pty,gre)=(5,4)
Feb 18 19:11:34 server pptpd[12274]: CTRL: Closing child ppp with pid 25271
Feb 18 19:11:34 server pptpd[12274]: CTRL: Client 10.0.0.2 control connection finished
Feb 18 19:11:34 server pptpd[12274]: CTRL: Exiting now
Feb 18 19:11:34 server pptpd[4613]: MGR: Reaped child 12274
Feb 18 19:11:34 server ppp[25271]: Phase: deflink: Connect time: 16 secs: 0 octets in, 295 octets out 
Feb 18 19:11:34 server ppp[25271]: Phase: deflink: 0 packets in, 5 packets out 
Feb 18 19:11:34 server ppp[25271]: Phase:  total 18 bytes/sec, peak 23 bytes/sec on Mon Feb 18 19:11:22 2008 
Feb 18 19:11:34 server ppp[25271]: Phase: deflink: lcp -> closed 
Feb 18 19:11:34 server ppp[25271]: Phase: bundle: Dead 
Feb 18 19:11:34 server ppp[25271]: Phase: Caught signal 2, abort connection(s) 
Feb 18 19:11:34 server ppp[25271]: Phase: PPP Terminated (normal).

      __________________________________________________________________ 
Yahoo! Singapore Answers 
Real people. Real questions. Real answers. Share what you know at http://answers.yahoo.com.sg