Topic: VPN problem after manual update SOLVED

[jeppefugl]

I am running SME 7.2, just updated, but have a problem with the VPN connection. Because I am running php/mysql 5 I had to make the update a little different because of dependency issues (maybe I broke something):

Code: [Select]

rpm -e -nodeps xorg-x11-libs
rpm -e -nodeps krb5-libs
rpm -e -nodeps php-common
rpm -e -nodeps libtool-libs
(if you have more missing packages, just continue to remove)

yum clean all
yum install xorg-x11-libs
yum install krb5-libs
yum install php-common
yum install libtool-libs
(if you had more missing packages, just continue to install)
yum update , see http://forums.contribs.org/index.php?topic=38043.0.

After this update I was unable to connect through the vpn connection. When I try to connect from Windows XP I get an Error 718. Everything else is working fine, smb, ftp, http, email, webmail etc. If anybody have a clue I would appreciate it very much. More info below.


These are the current installed ppp packages and kernel:

Code: [Select]

kmod-ppp-1.0.2-2.2.6.9_55.0.2.EL
ppp-2.4.4-1.3.el4.sme
rp-pppoe-3.5-22.2.RHEL4.1
kernel-2.6.9-55.0.2.EL

These where the last packages I updated before I broke the vpn:

Code: [Select]

httpd.i386                               2.0.52-32.3.ent.centos updates        
httpd-suexec.i386                        2.0.52-32.3.ent.centos updates        
ingo-h3.noarch                           1.1.3-2.el4.sme        smeos          
mod_auth_tkt.i386                        2.0.0rc2-1.el4.sme     smeos          
mod_ssl.i386                             1:2.0.52-32.3.ent.cent updates        
oidentd.i386                             2.0.8-1.el4.rf         smeos          
pcre.i386                                6.7-1                  dungog          
perl-Clone.i386                          0.27-1.el4             smeupdates      
php-domxml.i386                          4.3.9-3.22.5           updates        
smeserver-locale-de.noarch               1.2.0-27.el4.sme       smeos          
smeserver-locale-es.noarch               1.2.0-27.el4.sme       smeos          
smeserver-locale-fr.noarch               1.2.0-27.el4.sme       smeos          
smeserver-locale-it.noarch               1.2.0-27.el4.sme       smeos          
smeserver-support.noarch                 1.6.0-39.el4.sme       smeos          
spamassassin.i386                        3.2.2-43.el4           smeupdates      
vixie-cron.i386                          4:4.1-49.EL4           smeupdates


This is from the /var/log/message on the server, when I try to connect:

Code: [Select]

Aug  7 17:00:20 server pptpd[23775]: MGR: Launching /usr/sbin/pptpctrl to handle client
Aug  7 17:00:20 server pptpd[23775]: CTRL: local address = 192.168.xxx.xxx
Aug  7 17:00:20 server pptpd[23775]: CTRL: remote address = 192.168.xxx.xxx
Aug  7 17:00:20 server pptpd[23775]: CTRL: pppd speed = 460800
Aug  7 17:00:20 server pptpd[23775]: CTRL: pppd options file = /etc/ppp/options.pptpd
Aug  7 17:00:20 server pptpd[23775]: CTRL: Client xxx.xxx.184.104 control connection started
Aug  7 17:00:20 server pptpd[23775]: CTRL: Received PPTP Control Message (type: 1)
Aug  7 17:00:20 server pptpd[23775]: CTRL: Made a START CTRL CONN RPLY packet
Aug  7 17:00:20 server pptpd[23775]: CTRL: I wrote 156 bytes to the client.
Aug  7 17:00:20 server pptpd[23775]: CTRL: Sent packet to client
Aug  7 17:00:20 server pptpd[23775]: CTRL: Received PPTP Control Message (type: 7)
Aug  7 17:00:20 server pptpd[23775]: CTRL: Set parameters to 100000000 maxbps, 64 window size
Aug  7 17:00:20 server pptpd[23775]: CTRL: Made a OUT CALL RPLY packet
Aug  7 17:00:20 server pptpd[23775]: CTRL: Starting call (launching pppd, opening GRE)
Aug  7 17:00:20 server pptpd[23775]: CTRL: pty_fd = 6
Aug  7 17:00:20 server pptpd[23775]: CTRL: tty_fd = 7
Aug  7 17:00:20 server pptpd[23776]: CTRL (PPPD Launcher): program binary = /usr/sbin/pppd
Aug  7 17:00:20 server pptpd[23776]: CTRL (PPPD Launcher): local address = 192.168.xxx.xxx
Aug  7 17:00:20 server pptpd[23776]: CTRL (PPPD Launcher): remote address = 192.168.xxx.xxx
Aug  7 17:00:20 server pptpd[23775]: CTRL: I wrote 32 bytes to the client.
Aug  7 17:00:20 server pptpd[23775]: CTRL: Sent packet to client
Aug  7 17:00:20 server pppd[23776]: Plugin radius.so loaded.
Aug  7 17:00:20 server pppd[23776]: RADIUS plugin initialized.
Aug  7 17:00:20 server pppd[23776]: pppd 2.4.4 started by root, uid 0
Aug  7 17:00:20 server pppd[23776]: using channel 26
Aug  7 17:00:20 server kernel: divert: not allocating divert_blk for non-ethernet device ppp0
Aug  7 17:00:20 server pppd[23776]: Using interface ppp0
Aug  7 17:00:20 server pptpd[23775]: CTRL: Received PPTP Control Message (type: 15)
Aug  7 17:00:20 server pptpd[23775]: CTRL: Got a SET LINK INFO packet with standard ACCMs
Aug  7 17:00:20 server pppd[23776]: Connect: ppp0 <--> /dev/pts/0
Aug  7 17:00:20 server pppd[23776]: sent [LCP ConfReq id=0x1 <asyncmap 0x0> <auth chap MS-v2> <magic 0xf9ade420> <pcomp> <accomp>]
Aug  7 17:00:20 server pptpd[23775]: GRE: accepting packet #0
Aug  7 17:00:20 server pppd[23776]: rcvd [LCP ConfReq id=0x0 <mru 1400> <magic 0x37092965> <pcomp> <accomp> <callback CBCP>]
Aug  7 17:00:20 server pppd[23776]: sent [LCP ConfRej id=0x0 <callback CBCP>]
Aug  7 17:00:20 server pptpd[23775]: GRE: accepting packet #1
Aug  7 17:00:20 server pptpd[23775]: GRE: accepting packet #2
Aug  7 17:00:20 server pppd[23776]: rcvd [LCP ConfAck id=0x1 <asyncmap 0x0> <auth chap MS-v2> <magic 0xf9ade420> <pcomp> <accomp>]
Aug  7 17:00:20 server pppd[23776]: rcvd [LCP ConfReq id=0x1 <mru 1400> <magic 0x37092965> <pcomp> <accomp>]
Aug  7 17:00:20 server pppd[23776]: sent [LCP ConfAck id=0x1 <mru 1400> <magic 0x37092965> <pcomp> <accomp>]
Aug  7 17:00:20 server pppd[23776]: sent [LCP EchoReq id=0x0 magic=0xf9ade420]
Aug  7 17:00:20 server pppd[23776]: sent [CHAP Challenge id=0xe0 <9cf74c9c45f395cd94c63a0dddd8ca97>, name = "server"]
Aug  7 17:00:20 server pptpd[23775]: CTRL: Received PPTP Control Message (type: 15)
Aug  7 17:00:20 server pptpd[23775]: CTRL: Ignored a SET LINK INFO packet with real ACCMs!
Aug  7 17:00:20 server pptpd[23775]: GRE: accepting packet #3
Aug  7 17:00:20 server pptpd[23775]: GRE: accepting packet #4
Aug  7 17:00:20 server pppd[23776]: rcvd [LCP Ident id=0x2 magic=0x37092965 "MSRASV5.10"]
Aug  7 17:00:20 server pppd[23776]: rcvd [LCP Ident id=0x3 magic=0x37092965 "MSRAS-0-NNE-PC4497"]
Aug  7 17:00:20 server pptpd[23775]: GRE: accepting packet #5
Aug  7 17:00:20 server pppd[23776]: rcvd [LCP EchoRep id=0x0 magic=0x37092965]
Aug  7 17:00:20 server pptpd[23775]: GRE: accepting packet #6
Aug  7 17:00:20 server pppd[23776]: rcvd [CHAP Response id=0xe0 <8f32a03fcd324cc4e90e6b441a845fc90000000000000000362df527686a8b6acd4480ee3549eb291b9d7a6690da2e4800>, name = "user1"]
Aug  7 17:00:22 server pptpd[23775]: GRE: accepting packet #7
Aug  7 17:00:24 server pptpd[23775]: GRE: accepting packet #8
Aug  7 17:00:26 server pptpd[23775]: GRE: accepting packet #9
Aug  7 17:00:28 server pptpd[23775]: GRE: accepting packet #10
Aug  7 17:00:30 server pptpd[23775]: GRE: accepting packet #11
Aug  7 17:00:32 server pptpd[23775]: GRE: accepting packet #12
Aug  7 17:00:34 server pptpd[23775]: GRE: accepting packet #13
Aug  7 17:00:36 server pptpd[23775]: GRE: accepting packet #14
Aug  7 17:00:38 server pptpd[23775]: GRE: accepting packet #15
Aug  7 17:00:40 server pptpd[23775]: CTRL: Received PPTP Control Message (type: 15)
Aug  7 17:00:40 server pptpd[23775]: CTRL: Got a SET LINK INFO packet with standard ACCMs
Aug  7 17:00:40 server pptpd[23775]: GRE: accepting packet #16
Aug  7 17:00:42 server pptpd[23775]: GRE: accepting packet #17
Aug  7 17:00:44 server pptpd[23775]: CTRL: Received PPTP Control Message (type: 12)
Aug  7 17:00:44 server pptpd[23775]: CTRL: Made a CALL DISCONNECT RPLY packet
Aug  7 17:00:44 server pptpd[23775]: CTRL: Received CALL CLR request (closing call)
Aug  7 17:00:44 server pptpd[23775]: CTRL: Reaping child PPP[23776]
Aug  7 17:00:50 server pppd[23776]: rc_send_server: no reply from RADIUS server localhost:1812
Aug  7 17:00:50 server pppd[23776]: Peer user1 failed CHAP authentication
Aug  7 17:00:50 server pppd[23776]: sent [CHAP Failure id=0xe0 ""]
Aug  7 17:00:50 server pppd[23776]: sent [LCP TermReq id=0x2 "Authentication failed"]
Aug  7 17:00:50 server pppd[23776]: rcvd [CHAP Response id=0xe0 <8f32a03fcd324cc4e90e6b441a845fc90000000000000000362df527686a8b6acd4480ee3549eb291b9d7a6690da2e4800>, name = "user1"]
Aug  7 17:00:50 server pppd[23776]: Discarded non-LCP packet when LCP not open
Aug  7 17:00:50 server pppd[23776]: rcvd [CHAP Response id=0xe0 <8f32a03fcd324cc4e90e6b441a845fc90000000000000000362df527686a8b6acd4480ee3549eb291b9d7a6690da2e4800>, name = "user1"]
Aug  7 17:00:50 server pppd[23776]: Discarded non-LCP packet when LCP not open
Aug  7 17:00:50 server pppd[23776]: rcvd [CHAP Response id=0xe0 <8f32a03fcd324cc4e90e6b441a845fc90000000000000000362df527686a8b6acd4480ee3549eb291b9d7a6690da2e4800>, name = "user1"]
Aug  7 17:00:50 server pppd[23776]: Discarded non-LCP packet when LCP not open
Aug  7 17:00:50 server pppd[23776]: rcvd [CHAP Response id=0xe0 <8f32a03fcd324cc4e90e6b441a845fc90000000000000000362df527686a8b6acd4480ee3549eb291b9d7a6690da2e4800>, name = "user1"]
Aug  7 17:00:50 server pppd[23776]: Discarded non-LCP packet when LCP not open
Aug  7 17:00:50 server pppd[23776]: rcvd [CHAP Response id=0xe0 <8f32a03fcd324cc4e90e6b441a845fc90000000000000000362df527686a8b6acd4480ee3549eb291b9d7a6690da2e4800>, name = "user1"]
Aug  7 17:00:50 server pppd[23776]: Discarded non-LCP packet when LCP not open
Aug  7 17:00:50 server pppd[23776]: rcvd [CHAP Response id=0xe0 <8f32a03fcd324cc4e90e6b441a845fc90000000000000000362df527686a8b6acd4480ee3549eb291b9d7a6690da2e4800>, name = "user1"]
Aug  7 17:00:50 server pppd[23776]: Discarded non-LCP packet when LCP not open
Aug  7 17:00:50 server pppd[23776]: rcvd [CHAP Response id=0xe0 <8f32a03fcd324cc4e90e6b441a845fc90000000000000000362df527686a8b6acd4480ee3549eb291b9d7a6690da2e4800>, name = "user1"]
Aug  7 17:00:50 server pppd[23776]: Discarded non-LCP packet when LCP not open
Aug  7 17:00:50 server pppd[23776]: rcvd [CHAP Response id=0xe0 <8f32a03fcd324cc4e90e6b441a845fc90000000000000000362df527686a8b6acd4480ee3549eb291b9d7a6690da2e4800>, name = "user1"]
Aug  7 17:00:50 server pppd[23776]: Discarded non-LCP packet when LCP not open
Aug  7 17:00:50 server pppd[23776]: rcvd [CHAP Response id=0xe0 <8f32a03fcd324cc4e90e6b441a845fc90000000000000000362df527686a8b6acd4480ee3549eb291b9d7a6690da2e4800>, name = "user1"]
Aug  7 17:00:50 server pppd[23776]: Discarded non-LCP packet when LCP not open
Aug  7 17:00:50 server pppd[23776]: rcvd [LCP TermReq id=0x4 "7\t)e\000<\37777777715t\000\000\002\37777777716"]
Aug  7 17:00:50 server pppd[23776]: sent [LCP TermAck id=0x4]
Aug  7 17:00:50 server pppd[23776]: rcvd [LCP TermReq id=0x5 "7\t)e\000<\37777777715t\000\000\002\37777777716"]
Aug  7 17:00:50 server pppd[23776]: sent [LCP TermAck id=0x5]
Aug  7 17:00:50 server pppd[23776]: Modem hangup
Aug  7 17:00:50 server pppd[23776]: Connection terminated.
Aug  7 17:00:50 server kernel: divert: no divert_blk to free, ppp0 not ethernet
Aug  7 17:00:50 server pppd[23776]: Exit.
Aug  7 17:00:50 server pptpd[23775]: CTRL: Client xxx.xxx.184.104 control connection finished
Aug  7 17:00:50 server pptpd[23775]: CTRL: Exiting now
Aug  7 17:00:50 server pptpd[7591]: MGR: Reaped child 23775

[pfloor]

The answer is in your log file here:

Code: [Select]

Aug  7 17:00:50 server pppd[23776]: Peer user1 failed CHAP authentication
Aug  7 17:00:50 server pppd[23776]: sent [CHAP Failure id=0xe0 ""]
Aug  7 17:00:50 server pppd[23776]: sent [LCP TermReq id=0x2 "Authentication failed"]

Possible problems:

1-The user you are trying to log in with must have vpn enabled in their settings in the user panel of the server-manager.
2-You must enable at least one pptp client connection in the remote access panel.
3-You must use the correct username/password for the user(s) in step #1.

[jeppefugl]

Thanks for you reply

According to your suggestions:
1: I have enabled the vpn for the user I am using.
2: I have enabled more than 1 pptp client connections
3: I can log on to the ftp with the same user/pass I use for vpn connection. (Do I have to add domain when connecting, with the new release?)

The line above says:

Code: [Select]

Aug  7 17:00:50 server pppd[23776]: rc_send_server: no reply from RADIUS server localhost:1812

Does this say anything?

[pfloor]

What do the following return?:

Code: [Select]

rpm -q freeradius radiusclient e-smith-radiusd
/etc/rc7.d/S90radiusd status

[jeppefugl]

rpm -q freeradius radiusclient e-smith-radiusd

Code: [Select]


freeradius-1.0.1-3.RHEL4.5
radiusclient-0.3.2-0.2.el4.rf
e-smith-radiusd-1.0.0-16.el4.sme

/etc/rc7.d/S90radiusd status changes, for each run:

Code: [Select]


run: /service/radiusd: (pid 18859) 1s, normally down; run: log: (pid 2633) 189833s

[root@server ~]# /etc/rc7.d/S90radiusd status
down: /service/radiusd: 0s, want up; run: log: (pid 2633) 189846s

[root@server ~]# /etc/rc7.d/S90radiusd status
run: /service/radiusd: (pid 19026) 1s, normally down; run: log: (pid 2633) 189854s

[root@server ~]# /etc/rc7.d/S90radiusd status
down: /service/radiusd: 1s, want up; run: log: (pid 2633) 189855s


[pfloor]

radiusd is not running.  Show output of:

Code: [Select]

config show radiusd

[jeppefugl]

Just checked my /var/log/radiusd

Found:

Code: [Select]

@4000000046b887b20da7d3a4 /usr/sbin/radiusd: error while loading shared libraries: libltdl.so.3: cannot open shared object file: No such file or directory

must have broken something with libltdl.so.3


Any ideas?

[jeppefugl]

Code: [Select]

[root@server ~]# config show radiusd
radiusd=service
    status=enabled

[pfloor]

Just checked my /var/log/radiusd

Found:

Code: [Select]

@4000000046b887b20da7d3a4 /usr/sbin/radiusd: error while loading shared libraries: libltdl.so.3: cannot open shared object file: No such file or directory

must have broken something with libltdl.so.3


Any ideas?

Code: [Select]

cd /
rpm -qa libtool*

[jeppefugl]

Found the problem...  

I was missing to install libtool-libs and now everything works.

Thanks a lot for your help. Great work.