PPTP Patch

Dear Astaro Users,

first of all I want to apologize for the long waiting time but this error
was a tough one and it took us a longer to solve the problem.

Don't worry this won't become the standard way to publish patches [;)]
but the PPTP error became such a substantial problem that we think it might
be useful to publish it before the next schedulded up2date will be released.

Note, this is not an official release!

README
http://www.n-zsolt.de/downloads/readme_pptp_patch.txt

PATCH
http://www.n-zsolt.de/downloads/pptp_patch.tar.gz

Your feedback about its functionality is really appreciated.

read you
o|iver

Parents

0 Coty over 22 years ago
Thanks for getting this patch out. Alas, it hasn't fixed things for us. Here is the transcript from my install:

Code:

fw01:/root # cd /
fw01:/ # tar xvzf /tmp/pptp_patch.tar.gz
usr/local/fw/firewall_on.sh
usr/local/fw/firewall_off.sh
root/replace_pptp.sh
root/iptable_nat.o
fw01:/ # cd root
fw01:/root # ./replace_pptp.sh
iptable_nat: Device or resource busy
ip_conntrack: Device or resource busy
ip_tables: Device or resource busy
:: Stopping MiddleWare
-> ok
:: Starting MiddleWare
-> ok
:: Restarting MiddleWare
-> ok
fw01:/root #

Are the "Device or resource busy" errors expected? After the install, I switched from local users to RADIUS for PPTP authentication and got the same errors I was getting before. I haven't done any further investigation, but I was wondering about the error messages. I'll report back any additional info.
Cancel
Vote Up 0 Vote Down

Sign in to reply

Verify Answer

Cancel
0 Stephan_Scholz over 22 years ago in reply to Coty

Hi Coty,

the error messages indicate that the kernel modules could not be unloaded, and therefore the new module is not active. Could you reboot the device ? Afterwards everything should work fine. Let me know how things work out.

Stephan
Cancel
Vote Up 0 Vote Down

Sign in to reply

Verify Answer

Cancel
0 Coty over 22 years ago in reply to Stephan_Scholz

Stephan,

I've rebooted, but I am still unable to authenticate successfully with our RADIUS server. Do you have any suggestions for diagnosing the issue further?

Thanks,
Coty
Cancel
Vote Up 0 Vote Down

Sign in to reply

Verify Answer

Cancel
0 JimmyM over 22 years ago in reply to Coty

I applied the patch and got the module busy errors. I rebooted my ASL machine and am now able to connect on the first attempt. Will the next Up2Date include this fix. I need to be sure that all patches are applied if the ASL machine needs to be rebuilt.
Cancel
Vote Up 0 Vote Down

Sign in to reply

Verify Answer

Cancel
0 oliver.desch over 22 years ago in reply to JimmyM

Hello again,

meanwhile there were about 300 downloads of the patch but almost no reply [:(]

The patch didn't address the passthrough problem, means that you access
a remote pptp server through a masquerading ASL - this is another issue which
will be addressed shortly.

There are no known problems with RADIUS so I suggest to go on in the VPN
section - I have no idea from the given description what the problem may be.

The patch is supposed to fix the problem with the general connection problem
where a client connected to the internet uses ASL as its PPTP server.

On some machines/installations a reboot of the machine seemed to be nescesary
after applying the patch.

So again, please post your qualified feedback!

Looking forward reading your postings
o|iver
Cancel
Vote Up 0 Vote Down

Sign in to reply

Verify Answer

Cancel
0 Jan_Rusche over 22 years ago in reply to oliver.desch

hi oliver, how can the upload of the patch-file to the asl be done? is ftp possible? how can i enable it? jan
Cancel
Vote Up 0 Vote Down

Sign in to reply

Verify Answer

Cancel
0 John Agnew over 22 years ago in reply to Jan_Rusche

To upload the patch file to the Astaro server uses "scp" either from another Unix/Linux server or from a Windows PC using something like "PuTTY" - which I think has an scp client - or you can use the scp client that comes with the Cygnus Toolkit (free Unix/Linux tools for Windows). Astaro does not have an ftp server so it is not possible to use ftp to transfer files to an Astaro server.
Cancel
Vote Up 0 Vote Down

Sign in to reply

Verify Answer

Cancel
0 cyclops over 22 years ago in reply to John Agnew

putty is a terminal client only but winscp does the trick - use loginuser
to transfer files to /tmp

http://cedric.rucus.net/WinSCP2/

Greetings
cyclops
Cancel
Vote Up 0 Vote Down

Sign in to reply

Verify Answer

Cancel
0 _curious_ over 22 years ago in reply to cyclops

I applied the patch and got the module busy errors. I rebooted my ASL machine. Now my ASL box does not respond to pptp at all [:(]
Cancel
Vote Up 0 Vote Down

Sign in to reply

Verify Answer

Cancel
0 Stephan_Scholz over 22 years ago in reply to _curious_

Hi curious,

could you enable debugging for PPTP and post an excerpt of the log file, so that we can see what's happening ? Could you also give some more information about your ASL version, client software, etc. ?

Thanks,
Stephan
Cancel
Vote Up 0 Vote Down

Sign in to reply

Verify Answer

Cancel
0 AlexCrusius over 22 years ago in reply to Stephan_Scholz

Hi!
I also have this problem. I installed the patch, rebooted the machine and tried again. It still doesn't work.

This is an excerpt from my log file:

Aug  4 15:24:05 localhost pptpd[24648]: CTRL: Closing child ppp with pid 24653
Aug  4 15:24:05 localhost pppd[24653]: rcvd [CHAP Response id=0x1

ɘc4c931cfbcd8971ddca88e6e21a80a00000000000000000da23183c71bdef4564a83887e70cd36a36b53d99d9f42fac00>, name = "***\*****"]
Aug  4 15:24:05 localhost pppd[24653]: No CHAP secret found for authenticating ***\****, trying aua now.
Aug  4 15:24:05 localhost pppd[24653]: sent [CHAP Success id=0x1 "S=3D70EE3AFCC07D732770FAB4AC320ADC9E4645C4 M=Welcome to

gatekeeper.****.****."]
Aug  4 15:24:05 localhost pppd[24653]: Using interface ppp0
Aug  4 15:24:05 localhost pppd[24653]: sent [CCP ConfReq id=0x1 ]
Aug  4 15:24:05 localhost pppd[24653]: CHAP peer authentication succeeded for ***\*****
Aug  4 15:24:05 localhost pppd[24653]: rcvd [LCP TermReq id=0x5 19 a6 78 ad 00 3c cd 74 00 00 02 d0]
Aug  4 15:24:05 localhost pppd[24653]: LCP terminated by peer (^YM-&xM--^@

  ]
Aug  4 15:34:14 localhost pptpd[27909]: CTRL: remote address = 10.0.5.3
Aug  4 15:34:14 (none) pptpd[27909]: CTRL: I wrote 32 bytes to the client.
Aug  4 15:34:14 (none) pptpd[27909]: CTRL: Sent packet to client
Aug  4 15:34:14 (none) pptpd[27909]: GRE: Discarding duplicate packet
Aug  4 15:34:14 (none) pptpd[27909]: CTRL: Received PPTP Control Message (type: 15)
Aug  4 15:34:14 (none) pptpd[27909]: CTRL: Got a SET LINK INFO packet with standard ACCMs
Aug  4 15:34:14 localhost pptpd[27909]: CTRL: pppd options file = /etc/ppp/options
Aug  4 15:34:14 localhost pptpd[27909]: CTRL: Client ***.**.189.115 control connection started
Aug  4 15:34:14 localhost pptpd[27909]: CTRL: Received PPTP Control Message (type: 1)
Aug  4 15:34:14 localhost pptpd[27909]: CTRL: Made a START CTRL CONN RPLY packet
Aug  4 15:34:14 localhost pptpd[27909]: CTRL: I wrote 156 bytes to the client.
Aug  4 15:34:14 localhost pptpd[27909]: CTRL: Sent packet to client
Aug  4 15:34:14 localhost pptpd[27909]: CTRL: Received PPTP Control Message (type: 7)
Aug  4 15:34:14 localhost pptpd[27909]: CTRL: Set parameters to 1525 maxbps, 64 window size
Aug  4 15:34:14 localhost pptpd[27909]: CTRL: Made a OUT CALL RPLY packet
Aug  4 15:34:14 localhost pptpd[27909]: CTRL: Starting call (launching pppd, opening GRE)
Aug  4 15:34:15 localhost pptpd[27909]: CTRL: pty_fd = 5
Aug  4 15:34:15 localhost pptpd[27909]: CTRL: tty_fd = 6
Aug  4 15:34:15 localhost pptpd[27910]: CTRL (PPPD Launcher): Connection speed = 115200
Aug  4 15:34:15 localhost pptpd[27910]: CTRL (PPPD Launcher): local address = 10.0.5.1
Aug  4 15:34:15 localhost pptpd[27910]: CTRL (PPPD Launcher): remote address = 10.0.5.3
Aug  4 15:34:15 localhost pppd[27910]: pppd 2.4.2b1 started by (unknown), uid 0
Aug  4 15:34:15 localhost pppd[27910]: using channel 11
Aug  4 15:34:15 localhost pppd[27910]: Starting negotiation on /dev/ttyp0
Aug  4 15:34:15 localhost pppd[27910]: sent [LCP ConfReq id=0x1

   ]
Aug  4 15:34:15 localhost pptpd[27909]: CTRL: I wrote 32 bytes to the client.
Aug  4 15:34:15 localhost pptpd[27909]: CTRL: Sent packet to client
Aug  4 15:34:15 localhost pptpd[27909]: GRE: Discarding duplicate packet
Aug  4 15:34:15 localhost pptpd[27909]: CTRL: Received PPTP Control Message (type: 15)
Aug  4 15:34:15 localhost pptpd[27909]: CTRL: Got a SET LINK INFO packet with standard ACCMs
Aug  4 15:34:16 (none) pppd[27910]: rcvd [LCP ConfReq id=0x1

]
Aug  4 15:34:16 (none) pppd[27910]: sent [LCP ConfRej id=0x1 ]
Aug  4 15:34:16 localhost pppd[27910]: rcvd [LCP ConfReq id=0x1

]
Aug  4 15:34:16 localhost pppd[27910]: sent [LCP ConfRej id=0x1 ]
Aug  4 15:34:16 (none) pppd[27910]: rcvd [LCP ConfReq id=0x2     ]
Aug  4 15:34:16 (none) pppd[27910]: sent [LCP ConfAck id=0x2     ]
Aug  4 15:34:16 localhost pppd[27910]: rcvd [LCP ConfReq id=0x2     ]
Aug  4 15:34:16 localhost pppd[27910]: sent [LCP ConfAck id=0x2     ]
Aug  4 15:34:17 (none) pppd[27910]: sent [LCP ConfReq id=0x1

  ]
Aug  4 15:34:17 localhost pppd[27910]: sent [LCP ConfReq id=0x1

   ]
Aug  4 15:34:17 (none) pppd[27910]: rcvd [LCP ConfNak id=0x1  ]
Aug  4 15:34:17 (none) pppd[27910]: sent [LCP ConfReq id=0x2

]
Aug  4 15:34:17 localhost pppd[27910]: rcvd [LCP ConfNak id=0x1  ]
Aug  4 15:34:17 (none) pppd[27910]: rcvd [LCP ConfNak id=0x2 ]
Aug  4 15:34:17 (none) pppd[27910]: sent [LCP ConfReq id=0x3

]
Aug  4 15:34:17 localhost pppd[27910]: sent [LCP ConfReq id=0x2

]
Aug  4 15:34:17 (none) pppd[27910]: rcvd [LCP ConfNak id=0x3 ]
Aug  4 15:34:17 (none) pppd[27910]: sent [LCP ConfReq id=0x4

]
Aug  4 15:34:17 localhost pppd[27910]: rcvd [LCP ConfNak id=0x2 ]
Aug  4 15:34:17 (none) pppd[27910]: rcvd [LCP ConfNak id=0x4 ]
Aug  4 15:34:17 (none) pppd[27910]: sent [LCP ConfReq id=0x5

]
Aug  4 15:34:17 localhost pppd[27910]: sent [LCP ConfReq id=0x3

]
Aug  4 15:34:17 (none) pppd[27910]: rcvd [LCP ConfNak id=0x5 ]
Aug  4 15:34:17 (none) pppd[27910]: sent [LCP ConfReq id=0x6

]
Aug  4 15:34:17 localhost pppd[27910]: rcvd [LCP ConfNak id=0x3 ]
Aug  4 15:34:17 (none) pppd[27910]: rcvd [LCP ConfNak id=0x6 ]
Aug  4 15:34:17 (none) pppd[27910]: sent [LCP ConfReq id=0x7

]
Aug  4 15:34:17 localhost pppd[27910]: sent [LCP ConfReq id=0x4

]
Aug  4 15:34:17 (none) pppd[27910]: rcvd [LCP ConfNak id=0x7 ]
Aug  4 15:34:17 (none) pppd[27910]: sent [LCP ConfReq id=0x8

]
Aug  4 15:34:17 localhost pppd[27910]: rcvd [LCP ConfNak id=0x4 ]
Aug  4 15:34:17 (none) pppd[27910]: rcvd [LCP ConfNak id=0x8 ]
Aug  4 15:34:17 (none) pppd[27910]: sent [LCP ConfReq id=0x9

]
Aug  4 15:34:17 localhost pppd[27910]: sent [LCP ConfReq id=0x5

]
Aug  4 15:34:17 (none) pppd[27910]: rcvd [LCP ConfNak id=0x9 ]
Aug  4 15:34:17 (none) pppd[27910]: sent [LCP ConfReq id=0xa

]
Aug  4 15:34:17 localhost pppd[27910]: rcvd [LCP ConfNak id=0x5 ]
Aug  4 15:34:17 (none) pppd[27910]: rcvd [LCP ConfNak id=0xa ]
Aug  4 15:34:17 (none) pppd[27910]: sent [LCP ConfReq id=0xb

]
Aug  4 15:34:18 localhost pppd[27910]: sent [LCP ConfReq id=0x6

]
Aug  4 15:34:17 (none) pppd[27910]: rcvd [LCP ConfRej id=0xb ]
Aug  4 15:34:17 (none) pppd[27910]: sent [LCP ConfReq id=0xc

]
Aug  4 15:34:18 localhost pppd[27910]: rcvd [LCP ConfNak id=0x6 ]
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Received PPTP Control Message (type: 15)
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Ignored a SET LINK INFO packet with real ACCMs!
Aug  4 15:34:18 (none) pppd[27910]: rcvd [LCP ConfAck id=0xc

]
Aug  4 15:34:18 (none) pppd[27910]: sent [LCP EchoReq id=0x0 magic=0xfe01bed7]
Aug  4 15:34:18 (none) pppd[27910]: sent [CHAP Challenge id=0x1 , name = "pptp"]
Aug  4 15:34:18 (none) pppd[27910]: rcvd [LCP code=0xc id=0x3 40 8a 77 8c 4d 53 52 41 53 56 35 2e 30 30]
Aug  4 15:34:18 (none) pppd[27910]: sent [LCP CodeRej id=0xd 0c 03 00 12 40 8a 77 8c 4d 53 52 41 53 56 35 2e 30 30]
Aug  4 15:34:18 (none) pppd[27910]: rcvd [LCP code=0xc id=0x4 40 8a 77 8c 4d 53 52 41 53 2d 30 2d 4d 41 54 54 48 49 41 53]
Aug  4 15:34:18 (none) pppd[27910]: sent [LCP CodeRej id=0xe 0c 04 00 18 40 8a 77 8c 4d 53 52 41 53 2d 30 2d 4d 41 54 54 48 49 41

53]
Aug  4 15:34:18 (none) pppd[27910]: rcvd [LCP EchoRep id=0x0 magic=0x408a778c]
Aug  4 15:34:18 localhost pppd[27910]: sent [LCP ConfReq id=0x7

]
Aug  4 15:34:18 (none) aua[27930]: truncated domain part from username
Aug  4 15:34:18 (none) aua[27930]: Returning password hash for user ***\***** [9E3EA769040A92DEDA85F62A5A3BA5CA:10.0.20.2]
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Received PPTP Control Message (type: 15)
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Got a SET LINK INFO packet with standard ACCMs
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Received PPTP Control Message (type: 12)
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Made a CALL DISCONNECT RPLY packet
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Received CALL CLR request (closing call)
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: I wrote 148 bytes to the client.
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Sent packet to client
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Received PPTP Control Message (type: 3)
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Made a STOP CTRL CONN RPLY packet
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Received STOP CTRL CONN request (disconnecting)
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: I wrote 16 bytes to the client.
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Sent packet to client
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Closing child ppp with pid 27910
Aug  4 15:34:18 (none) pppd[27910]: rcvd [CHAP Response id=0x1

ឞbac23b228abd50e2da5b0150b055c30000000000000000679957efc28425e59e985dc9a658d5b1878e7ced6225937a00>, name = "***\*****"]
Aug  4 15:34:18 (none) pppd[27910]: No CHAP secret found for authenticating ***\*****, trying aua now.
Aug  4 15:34:18 (none) pppd[27910]: sent [CHAP Success id=0x1 "S=2F16EA9506A753209C4C7EB2BFD24BC4FD50D8C7 M=Welcome to

gatekeeper.******.****."]
Aug  4 15:34:18 (none) pppd[27910]: Using interface ppp0
Aug  4 15:34:18 (none) pppd[27910]: sent [CCP ConfReq id=0x1 ]
Aug  4 15:34:18 (none) pppd[27910]: CHAP peer authentication succeeded for ***\*****
Aug  4 15:34:18 (none) pppd[27910]: Modem hangup
Aug  4 15:34:18 (none) pppd[27910]: Connection terminated.
Aug  4 15:34:18 (none) pppd[27910]: Connect time 0.1 minutes.
Aug  4 15:34:18 (none) pppd[27910]: Sent 10 bytes, received 0 bytes.
Aug  4 15:34:18 (none) pppd[27910]: Connect time 0.1 minutes.
Aug  4 15:34:18 (none) pppd[27910]: Sent 10 bytes, received 0 bytes.
Aug  4 15:34:19 localhost pppd[27910]: rcvd [LCP ConfNak id=0x7 ]
Aug  4 15:34:18 (none) pptpd[12157]: MGR: Reaped child 27909
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Client ***.**.189.115 control connection finished
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Exiting now
Aug  4 15:34:18 (none) pppd[27910]: Exit.
Aug  4 15:34:19 localhost pppd[27910]: sent [LCP ConfReq id=0x8

]
Aug  4 15:34:19 localhost pppd[27910]: rcvd [LCP ConfNak id=0x8 ]
Aug  4 15:34:19 localhost pppd[27910]: sent [LCP ConfReq id=0x9

]
Aug  4 15:34:19 localhost pppd[27910]: rcvd [LCP ConfNak id=0x9 ]
Aug  4 15:34:19 localhost pppd[27910]: sent [LCP ConfReq id=0xa

]
Aug  4 15:34:19 localhost pppd[27910]: rcvd [LCP ConfNak id=0xa ]
Aug  4 15:34:19 localhost pppd[27910]: sent [LCP ConfReq id=0xb

]
Aug  4 15:34:19 localhost pppd[27910]: rcvd [LCP ConfRej id=0xb ]
Aug  4 15:34:19 localhost pppd[27910]: sent [LCP ConfReq id=0xc

]
Aug  4 15:34:19 localhost pptpd[27909]: CTRL: Received PPTP Control Message (type: 15)
Aug  4 15:34:19 localhost pptpd[27909]: CTRL: Ignored a SET LINK INFO packet with real ACCMs!
Aug  4 15:34:19 localhost pppd[27910]: rcvd [LCP ConfAck id=0xc

]
Aug  4 15:34:19 localhost pppd[27910]: sent [LCP EchoReq id=0x0 magic=0xfe01bed7]
Aug  4 15:34:19 localhost pppd[27910]: sent [CHAP Challenge id=0x1 , name = "pptp"]
Aug  4 15:34:19 localhost pppd[27910]: rcvd [LCP code=0xc id=0x3 40 8a 77 8c 4d 53 52 41 53 56 35 2e 30 30]
Aug  4 15:34:19 localhost pppd[27910]: sent [LCP CodeRej id=0xd 0c 03 00 12 40 8a 77 8c 4d 53 52 41 53 56 35 2e 30 30]
Aug  4 15:34:19 localhost pppd[27910]: rcvd [LCP code=0xc id=0x4 40 8a 77 8c 4d 53 52 41 53 2d 30 2d 4d 41 54 54 48 49 41 53]
Aug  4 15:34:19 localhost pppd[27910]: sent [LCP CodeRej id=0xe 0c 04 00 18 40 8a 77 8c 4d 53 52 41 53 2d 30 2d 4d 41 54 54 48 49 41

53]
Aug  4 15:34:19 localhost pppd[27910]: rcvd [LCP EchoRep id=0x0 magic=0x408a778c]
Aug  4 15:34:19 localhost aua[27930]: truncated domain part from username
Aug  4 15:34:19 localhost aua[27930]: Returning password hash for user ***\***** [9E3EA769040A92DEDA85F62A5A3BA5CA:10.0.20.2]
Aug  4 15:34:19 localhost pptpd[27909]: CTRL: Received PPTP Control Message (type: 15)
Aug  4 15:34:19 localhost pptpd[27909]: CTRL: Got a SET LINK INFO packet with standard ACCMs
Aug  4 15:34:19 localhost pptpd[27909]: CTRL: Received PPTP Control Message (type: 12)
Aug  4 15:34:19 localhost pptpd[27909]: CTRL: Made a CALL DISCONNECT RPLY packet
Aug  4 15:34:19 localhost pptpd[27909]: CTRL: Received CALL CLR request (closing call)
Aug  4 15:34:19 localhost pptpd[27909]: CTRL: I wrote 148 bytes to the client.
Aug  4 15:34:20 localhost pptpd[27909]: CTRL: Sent packet to client
Aug  4 15:34:20 localhost pptpd[27909]: CTRL: Received PPTP Control Message (type: 3)
Aug  4 15:34:20 localhost pptpd[27909]: CTRL: Made a STOP CTRL CONN RPLY packet
Aug  4 15:34:20 localhost pptpd[27909]: CTRL: Received STOP CTRL CONN request (disconnecting)
Aug  4 15:34:20 localhost pptpd[27909]: CTRL: I wrote 16 bytes to the client.
Aug  4 15:34:20 localhost pptpd[27909]: CTRL: Sent packet to client
Aug  4 15:34:20 localhost pptpd[27909]: CTRL: Closing child ppp with pid 27910
Aug  4 15:34:20 localhost pppd[27910]: rcvd [CHAP Response id=0x1

ឞbac23b228abd50e2da5b0150b055c30000000000000000679957efc28425e59e985dc9a658d5b1878e7ced6225937a00>, name = "***\*****"]
Aug  4 15:34:20 localhost pppd[27910]: No CHAP secret found for authenticating ***\*****, trying aua now.
Aug  4 15:34:20 localhost pppd[27910]: sent [CHAP Success id=0x1 "S=2F16EA9506A753209C4C7EB2BFD24BC4FD50D8C7 M=Welcome to

gatekeeper..******.****."]
Aug  4 15:34:20 localhost pppd[27910]: Using interface ppp0
Aug  4 15:34:20 localhost pppd[27910]: sent [CCP ConfReq id=0x1 ]
Aug  4 15:34:20 localhost pppd[27910]: CHAP peer authentication succeeded for ***\*****
Aug  4 15:34:20 localhost pppd[27910]: Modem hangup
Aug  4 15:34:20 localhost pppd[27910]: Connection terminated.
Aug  4 15:34:20 localhost pppd[27910]: Connect time 0.1 minutes.
Aug  4 15:34:20 localhost pppd[27910]: Sent 10 bytes, received 0 bytes.
Aug  4 15:34:20 localhost pppd[27910]: Connect time 0.1 minutes.
Aug  4 15:34:20 localhost pppd[27910]: Sent 10 bytes, received 0 bytes.
Aug  4 15:34:20 localhost pptpd[12157]: MGR: Reaped child 27909
Aug  4 15:34:20 localhost pptpd[27909]: CTRL: Client ***.**.189.115 control connection finished
Aug  4 15:34:20 localhost pptpd[27909]: CTRL: Exiting now
Aug  4 15:34:20 localhost pppd[27910]: Exit.

I hope that's not too much text, but I don't know what the important lines are [;)]

Alex
Cancel
Vote Up 0 Vote Down

Sign in to reply

Verify Answer

Cancel

Reply

0 AlexCrusius over 22 years ago in reply to Stephan_Scholz

Hi!
I also have this problem. I installed the patch, rebooted the machine and tried again. It still doesn't work.

This is an excerpt from my log file:

Aug  4 15:24:05 localhost pptpd[24648]: CTRL: Closing child ppp with pid 24653
Aug  4 15:24:05 localhost pppd[24653]: rcvd [CHAP Response id=0x1

ɘc4c931cfbcd8971ddca88e6e21a80a00000000000000000da23183c71bdef4564a83887e70cd36a36b53d99d9f42fac00>, name = "***\*****"]
Aug  4 15:24:05 localhost pppd[24653]: No CHAP secret found for authenticating ***\****, trying aua now.
Aug  4 15:24:05 localhost pppd[24653]: sent [CHAP Success id=0x1 "S=3D70EE3AFCC07D732770FAB4AC320ADC9E4645C4 M=Welcome to

gatekeeper.****.****."]
Aug  4 15:24:05 localhost pppd[24653]: Using interface ppp0
Aug  4 15:24:05 localhost pppd[24653]: sent [CCP ConfReq id=0x1 ]
Aug  4 15:24:05 localhost pppd[24653]: CHAP peer authentication succeeded for ***\*****
Aug  4 15:24:05 localhost pppd[24653]: rcvd [LCP TermReq id=0x5 19 a6 78 ad 00 3c cd 74 00 00 02 d0]
Aug  4 15:24:05 localhost pppd[24653]: LCP terminated by peer (^YM-&xM--^@

  ]
Aug  4 15:34:14 localhost pptpd[27909]: CTRL: remote address = 10.0.5.3
Aug  4 15:34:14 (none) pptpd[27909]: CTRL: I wrote 32 bytes to the client.
Aug  4 15:34:14 (none) pptpd[27909]: CTRL: Sent packet to client
Aug  4 15:34:14 (none) pptpd[27909]: GRE: Discarding duplicate packet
Aug  4 15:34:14 (none) pptpd[27909]: CTRL: Received PPTP Control Message (type: 15)
Aug  4 15:34:14 (none) pptpd[27909]: CTRL: Got a SET LINK INFO packet with standard ACCMs
Aug  4 15:34:14 localhost pptpd[27909]: CTRL: pppd options file = /etc/ppp/options
Aug  4 15:34:14 localhost pptpd[27909]: CTRL: Client ***.**.189.115 control connection started
Aug  4 15:34:14 localhost pptpd[27909]: CTRL: Received PPTP Control Message (type: 1)
Aug  4 15:34:14 localhost pptpd[27909]: CTRL: Made a START CTRL CONN RPLY packet
Aug  4 15:34:14 localhost pptpd[27909]: CTRL: I wrote 156 bytes to the client.
Aug  4 15:34:14 localhost pptpd[27909]: CTRL: Sent packet to client
Aug  4 15:34:14 localhost pptpd[27909]: CTRL: Received PPTP Control Message (type: 7)
Aug  4 15:34:14 localhost pptpd[27909]: CTRL: Set parameters to 1525 maxbps, 64 window size
Aug  4 15:34:14 localhost pptpd[27909]: CTRL: Made a OUT CALL RPLY packet
Aug  4 15:34:14 localhost pptpd[27909]: CTRL: Starting call (launching pppd, opening GRE)
Aug  4 15:34:15 localhost pptpd[27909]: CTRL: pty_fd = 5
Aug  4 15:34:15 localhost pptpd[27909]: CTRL: tty_fd = 6
Aug  4 15:34:15 localhost pptpd[27910]: CTRL (PPPD Launcher): Connection speed = 115200
Aug  4 15:34:15 localhost pptpd[27910]: CTRL (PPPD Launcher): local address = 10.0.5.1
Aug  4 15:34:15 localhost pptpd[27910]: CTRL (PPPD Launcher): remote address = 10.0.5.3
Aug  4 15:34:15 localhost pppd[27910]: pppd 2.4.2b1 started by (unknown), uid 0
Aug  4 15:34:15 localhost pppd[27910]: using channel 11
Aug  4 15:34:15 localhost pppd[27910]: Starting negotiation on /dev/ttyp0
Aug  4 15:34:15 localhost pppd[27910]: sent [LCP ConfReq id=0x1

   ]
Aug  4 15:34:15 localhost pptpd[27909]: CTRL: I wrote 32 bytes to the client.
Aug  4 15:34:15 localhost pptpd[27909]: CTRL: Sent packet to client
Aug  4 15:34:15 localhost pptpd[27909]: GRE: Discarding duplicate packet
Aug  4 15:34:15 localhost pptpd[27909]: CTRL: Received PPTP Control Message (type: 15)
Aug  4 15:34:15 localhost pptpd[27909]: CTRL: Got a SET LINK INFO packet with standard ACCMs
Aug  4 15:34:16 (none) pppd[27910]: rcvd [LCP ConfReq id=0x1

]
Aug  4 15:34:16 (none) pppd[27910]: sent [LCP ConfRej id=0x1 ]
Aug  4 15:34:16 localhost pppd[27910]: rcvd [LCP ConfReq id=0x1

]
Aug  4 15:34:16 localhost pppd[27910]: sent [LCP ConfRej id=0x1 ]
Aug  4 15:34:16 (none) pppd[27910]: rcvd [LCP ConfReq id=0x2     ]
Aug  4 15:34:16 (none) pppd[27910]: sent [LCP ConfAck id=0x2     ]
Aug  4 15:34:16 localhost pppd[27910]: rcvd [LCP ConfReq id=0x2     ]
Aug  4 15:34:16 localhost pppd[27910]: sent [LCP ConfAck id=0x2     ]
Aug  4 15:34:17 (none) pppd[27910]: sent [LCP ConfReq id=0x1

  ]
Aug  4 15:34:17 localhost pppd[27910]: sent [LCP ConfReq id=0x1

   ]
Aug  4 15:34:17 (none) pppd[27910]: rcvd [LCP ConfNak id=0x1  ]
Aug  4 15:34:17 (none) pppd[27910]: sent [LCP ConfReq id=0x2

]
Aug  4 15:34:17 localhost pppd[27910]: rcvd [LCP ConfNak id=0x1  ]
Aug  4 15:34:17 (none) pppd[27910]: rcvd [LCP ConfNak id=0x2 ]
Aug  4 15:34:17 (none) pppd[27910]: sent [LCP ConfReq id=0x3

]
Aug  4 15:34:17 localhost pppd[27910]: sent [LCP ConfReq id=0x2

]
Aug  4 15:34:17 (none) pppd[27910]: rcvd [LCP ConfNak id=0x3 ]
Aug  4 15:34:17 (none) pppd[27910]: sent [LCP ConfReq id=0x4

]
Aug  4 15:34:17 localhost pppd[27910]: rcvd [LCP ConfNak id=0x2 ]
Aug  4 15:34:17 (none) pppd[27910]: rcvd [LCP ConfNak id=0x4 ]
Aug  4 15:34:17 (none) pppd[27910]: sent [LCP ConfReq id=0x5

]
Aug  4 15:34:17 localhost pppd[27910]: sent [LCP ConfReq id=0x3

]
Aug  4 15:34:17 (none) pppd[27910]: rcvd [LCP ConfNak id=0x5 ]
Aug  4 15:34:17 (none) pppd[27910]: sent [LCP ConfReq id=0x6

]
Aug  4 15:34:17 localhost pppd[27910]: rcvd [LCP ConfNak id=0x3 ]
Aug  4 15:34:17 (none) pppd[27910]: rcvd [LCP ConfNak id=0x6 ]
Aug  4 15:34:17 (none) pppd[27910]: sent [LCP ConfReq id=0x7

]
Aug  4 15:34:17 localhost pppd[27910]: sent [LCP ConfReq id=0x4

]
Aug  4 15:34:17 (none) pppd[27910]: rcvd [LCP ConfNak id=0x7 ]
Aug  4 15:34:17 (none) pppd[27910]: sent [LCP ConfReq id=0x8

]
Aug  4 15:34:17 localhost pppd[27910]: rcvd [LCP ConfNak id=0x4 ]
Aug  4 15:34:17 (none) pppd[27910]: rcvd [LCP ConfNak id=0x8 ]
Aug  4 15:34:17 (none) pppd[27910]: sent [LCP ConfReq id=0x9

]
Aug  4 15:34:17 localhost pppd[27910]: sent [LCP ConfReq id=0x5

]
Aug  4 15:34:17 (none) pppd[27910]: rcvd [LCP ConfNak id=0x9 ]
Aug  4 15:34:17 (none) pppd[27910]: sent [LCP ConfReq id=0xa

]
Aug  4 15:34:17 localhost pppd[27910]: rcvd [LCP ConfNak id=0x5 ]
Aug  4 15:34:17 (none) pppd[27910]: rcvd [LCP ConfNak id=0xa ]
Aug  4 15:34:17 (none) pppd[27910]: sent [LCP ConfReq id=0xb

]
Aug  4 15:34:18 localhost pppd[27910]: sent [LCP ConfReq id=0x6

]
Aug  4 15:34:17 (none) pppd[27910]: rcvd [LCP ConfRej id=0xb ]
Aug  4 15:34:17 (none) pppd[27910]: sent [LCP ConfReq id=0xc

]
Aug  4 15:34:18 localhost pppd[27910]: rcvd [LCP ConfNak id=0x6 ]
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Received PPTP Control Message (type: 15)
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Ignored a SET LINK INFO packet with real ACCMs!
Aug  4 15:34:18 (none) pppd[27910]: rcvd [LCP ConfAck id=0xc

]
Aug  4 15:34:18 (none) pppd[27910]: sent [LCP EchoReq id=0x0 magic=0xfe01bed7]
Aug  4 15:34:18 (none) pppd[27910]: sent [CHAP Challenge id=0x1 , name = "pptp"]
Aug  4 15:34:18 (none) pppd[27910]: rcvd [LCP code=0xc id=0x3 40 8a 77 8c 4d 53 52 41 53 56 35 2e 30 30]
Aug  4 15:34:18 (none) pppd[27910]: sent [LCP CodeRej id=0xd 0c 03 00 12 40 8a 77 8c 4d 53 52 41 53 56 35 2e 30 30]
Aug  4 15:34:18 (none) pppd[27910]: rcvd [LCP code=0xc id=0x4 40 8a 77 8c 4d 53 52 41 53 2d 30 2d 4d 41 54 54 48 49 41 53]
Aug  4 15:34:18 (none) pppd[27910]: sent [LCP CodeRej id=0xe 0c 04 00 18 40 8a 77 8c 4d 53 52 41 53 2d 30 2d 4d 41 54 54 48 49 41

53]
Aug  4 15:34:18 (none) pppd[27910]: rcvd [LCP EchoRep id=0x0 magic=0x408a778c]
Aug  4 15:34:18 localhost pppd[27910]: sent [LCP ConfReq id=0x7

]
Aug  4 15:34:18 (none) aua[27930]: truncated domain part from username
Aug  4 15:34:18 (none) aua[27930]: Returning password hash for user ***\***** [9E3EA769040A92DEDA85F62A5A3BA5CA:10.0.20.2]
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Received PPTP Control Message (type: 15)
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Got a SET LINK INFO packet with standard ACCMs
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Received PPTP Control Message (type: 12)
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Made a CALL DISCONNECT RPLY packet
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Received CALL CLR request (closing call)
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: I wrote 148 bytes to the client.
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Sent packet to client
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Received PPTP Control Message (type: 3)
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Made a STOP CTRL CONN RPLY packet
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Received STOP CTRL CONN request (disconnecting)
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: I wrote 16 bytes to the client.
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Sent packet to client
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Closing child ppp with pid 27910
Aug  4 15:34:18 (none) pppd[27910]: rcvd [CHAP Response id=0x1

ឞbac23b228abd50e2da5b0150b055c30000000000000000679957efc28425e59e985dc9a658d5b1878e7ced6225937a00>, name = "***\*****"]
Aug  4 15:34:18 (none) pppd[27910]: No CHAP secret found for authenticating ***\*****, trying aua now.
Aug  4 15:34:18 (none) pppd[27910]: sent [CHAP Success id=0x1 "S=2F16EA9506A753209C4C7EB2BFD24BC4FD50D8C7 M=Welcome to

gatekeeper.******.****."]
Aug  4 15:34:18 (none) pppd[27910]: Using interface ppp0
Aug  4 15:34:18 (none) pppd[27910]: sent [CCP ConfReq id=0x1 ]
Aug  4 15:34:18 (none) pppd[27910]: CHAP peer authentication succeeded for ***\*****
Aug  4 15:34:18 (none) pppd[27910]: Modem hangup
Aug  4 15:34:18 (none) pppd[27910]: Connection terminated.
Aug  4 15:34:18 (none) pppd[27910]: Connect time 0.1 minutes.
Aug  4 15:34:18 (none) pppd[27910]: Sent 10 bytes, received 0 bytes.
Aug  4 15:34:18 (none) pppd[27910]: Connect time 0.1 minutes.
Aug  4 15:34:18 (none) pppd[27910]: Sent 10 bytes, received 0 bytes.
Aug  4 15:34:19 localhost pppd[27910]: rcvd [LCP ConfNak id=0x7 ]
Aug  4 15:34:18 (none) pptpd[12157]: MGR: Reaped child 27909
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Client ***.**.189.115 control connection finished
Aug  4 15:34:18 (none) pptpd[27909]: CTRL: Exiting now
Aug  4 15:34:18 (none) pppd[27910]: Exit.
Aug  4 15:34:19 localhost pppd[27910]: sent [LCP ConfReq id=0x8

]
Aug  4 15:34:19 localhost pppd[27910]: rcvd [LCP ConfNak id=0x8 ]
Aug  4 15:34:19 localhost pppd[27910]: sent [LCP ConfReq id=0x9

]
Aug  4 15:34:19 localhost pppd[27910]: rcvd [LCP ConfNak id=0x9 ]
Aug  4 15:34:19 localhost pppd[27910]: sent [LCP ConfReq id=0xa

]
Aug  4 15:34:19 localhost pppd[27910]: rcvd [LCP ConfNak id=0xa ]
Aug  4 15:34:19 localhost pppd[27910]: sent [LCP ConfReq id=0xb

]
Aug  4 15:34:19 localhost pppd[27910]: rcvd [LCP ConfRej id=0xb ]
Aug  4 15:34:19 localhost pppd[27910]: sent [LCP ConfReq id=0xc

]
Aug  4 15:34:19 localhost pptpd[27909]: CTRL: Received PPTP Control Message (type: 15)
Aug  4 15:34:19 localhost pptpd[27909]: CTRL: Ignored a SET LINK INFO packet with real ACCMs!
Aug  4 15:34:19 localhost pppd[27910]: rcvd [LCP ConfAck id=0xc

]
Aug  4 15:34:19 localhost pppd[27910]: sent [LCP EchoReq id=0x0 magic=0xfe01bed7]
Aug  4 15:34:19 localhost pppd[27910]: sent [CHAP Challenge id=0x1 , name = "pptp"]
Aug  4 15:34:19 localhost pppd[27910]: rcvd [LCP code=0xc id=0x3 40 8a 77 8c 4d 53 52 41 53 56 35 2e 30 30]
Aug  4 15:34:19 localhost pppd[27910]: sent [LCP CodeRej id=0xd 0c 03 00 12 40 8a 77 8c 4d 53 52 41 53 56 35 2e 30 30]
Aug  4 15:34:19 localhost pppd[27910]: rcvd [LCP code=0xc id=0x4 40 8a 77 8c 4d 53 52 41 53 2d 30 2d 4d 41 54 54 48 49 41 53]
Aug  4 15:34:19 localhost pppd[27910]: sent [LCP CodeRej id=0xe 0c 04 00 18 40 8a 77 8c 4d 53 52 41 53 2d 30 2d 4d 41 54 54 48 49 41

53]
Aug  4 15:34:19 localhost pppd[27910]: rcvd [LCP EchoRep id=0x0 magic=0x408a778c]
Aug  4 15:34:19 localhost aua[27930]: truncated domain part from username
Aug  4 15:34:19 localhost aua[27930]: Returning password hash for user ***\***** [9E3EA769040A92DEDA85F62A5A3BA5CA:10.0.20.2]
Aug  4 15:34:19 localhost pptpd[27909]: CTRL: Received PPTP Control Message (type: 15)
Aug  4 15:34:19 localhost pptpd[27909]: CTRL: Got a SET LINK INFO packet with standard ACCMs
Aug  4 15:34:19 localhost pptpd[27909]: CTRL: Received PPTP Control Message (type: 12)
Aug  4 15:34:19 localhost pptpd[27909]: CTRL: Made a CALL DISCONNECT RPLY packet
Aug  4 15:34:19 localhost pptpd[27909]: CTRL: Received CALL CLR request (closing call)
Aug  4 15:34:19 localhost pptpd[27909]: CTRL: I wrote 148 bytes to the client.
Aug  4 15:34:20 localhost pptpd[27909]: CTRL: Sent packet to client
Aug  4 15:34:20 localhost pptpd[27909]: CTRL: Received PPTP Control Message (type: 3)
Aug  4 15:34:20 localhost pptpd[27909]: CTRL: Made a STOP CTRL CONN RPLY packet
Aug  4 15:34:20 localhost pptpd[27909]: CTRL: Received STOP CTRL CONN request (disconnecting)
Aug  4 15:34:20 localhost pptpd[27909]: CTRL: I wrote 16 bytes to the client.
Aug  4 15:34:20 localhost pptpd[27909]: CTRL: Sent packet to client
Aug  4 15:34:20 localhost pptpd[27909]: CTRL: Closing child ppp with pid 27910
Aug  4 15:34:20 localhost pppd[27910]: rcvd [CHAP Response id=0x1

ឞbac23b228abd50e2da5b0150b055c30000000000000000679957efc28425e59e985dc9a658d5b1878e7ced6225937a00>, name = "***\*****"]
Aug  4 15:34:20 localhost pppd[27910]: No CHAP secret found for authenticating ***\*****, trying aua now.
Aug  4 15:34:20 localhost pppd[27910]: sent [CHAP Success id=0x1 "S=2F16EA9506A753209C4C7EB2BFD24BC4FD50D8C7 M=Welcome to

gatekeeper..******.****."]
Aug  4 15:34:20 localhost pppd[27910]: Using interface ppp0
Aug  4 15:34:20 localhost pppd[27910]: sent [CCP ConfReq id=0x1 ]
Aug  4 15:34:20 localhost pppd[27910]: CHAP peer authentication succeeded for ***\*****
Aug  4 15:34:20 localhost pppd[27910]: Modem hangup
Aug  4 15:34:20 localhost pppd[27910]: Connection terminated.
Aug  4 15:34:20 localhost pppd[27910]: Connect time 0.1 minutes.
Aug  4 15:34:20 localhost pppd[27910]: Sent 10 bytes, received 0 bytes.
Aug  4 15:34:20 localhost pppd[27910]: Connect time 0.1 minutes.
Aug  4 15:34:20 localhost pppd[27910]: Sent 10 bytes, received 0 bytes.
Aug  4 15:34:20 localhost pptpd[12157]: MGR: Reaped child 27909
Aug  4 15:34:20 localhost pptpd[27909]: CTRL: Client ***.**.189.115 control connection finished
Aug  4 15:34:20 localhost pptpd[27909]: CTRL: Exiting now
Aug  4 15:34:20 localhost pppd[27910]: Exit.

I hope that's not too much text, but I don't know what the important lines are [;)]

Alex
Cancel
Vote Up 0 Vote Down

Sign in to reply

Verify Answer

Cancel

Children

0 seb-zrh over 22 years ago in reply to AlexCrusius

hoi oliver,

as described here: https://community.sophos.com/products/unified-threat-management/astaroorg/f/53/t/32484

i installed the patch a long time ago by hand (no script available at this time) and it runs fine till today.

thanks!

seb
Cancel
Vote Up 0 Vote Down

Sign in to reply

Verify Answer

Cancel