PPTP causes Postfix reload

[fpizzinini]

Hi folks,
lately I set up a ubuntu hardy 8.04 server with postfix, clamav, spamassassing with the help of this tutorial (http://www.howtoforge.com/virtual-us...ail-ubuntu8.04) what I found very useful. As a perfect newbi, i could setup a complete mail server in no time. Thank you Falko.
Now it comes my question:
on the same server i also configured a PPTP VPN but every time someone connects to that VPN I get this message in the syslog:

linux postfix/master[5219]: reload configuration /etc/postfix

and on the servers screen I can read: reloading postfix configuration .... done.

I think that there is something conflicting between those programs but i cannot find out where i should look.

Any help is very welcome
Franco

[falko]

Are there any errors in the mail log?
What are the outputs of

netstat -tap

and

ifconfig

?

[fpizzinini]

Hi Falko,

I thank you very much for taking your time at my issue
As you can see from the messages ouput below, I connected to the server using PPTP VPN from 08.48 to 08.50

At login time the server screen showed these messages:
[329654.615881] padlock: VIA PadLock Hash Engine not detected.
Reloading postfix configuration ... ...done.
and after logout:
Reloading postfix configuration ... ...done.

The syslog also contains a warning about padlock_sha, what I also cannot understand. Could be that this is the source of the other issue ?

Now here are the outputs of the log files

messages ouput:

Oct  2 08:48:01 linux pppd[17890]: found interface eth0 for proxy arp
Oct  2 08:48:01 linux pppd[17890]: local  IP address 192.168.0.100
Oct  2 08:48:01 linux pppd[17890]: remote IP address 192.168.0.200
Oct  2 08:50:05 linux pppd[17890]: Modem hangup
Oct  2 08:50:05 linux pppd[17890]: Connect time 2.1 minutes.
Oct  2 08:50:05 linux pppd[17890]: Sent 31072 bytes, received 42288 bytes.
Oct  2 08:50:05 linux pppd[17890]: Connection terminated.
Oct  2 08:50:05 linux pppd[17890]: Exit.

the mail.log output looks like this:

Oct  2 08:48:01 linux postfix/master[5201]: reload configuration /etc/postfix
Oct  2 08:50:01 linux getmail: Initializing SimplePOP3Retriever:test@test.eu@pop3.test.eu:110:
Oct  2 08:50:01 linux getmail: Initializing SimplePOP3Retriever:test@test.eu@pop3.test.eu:110:
Oct  2 08:50:05 linux postfix/master[5201]: reload configuration /etc/postfix

ifconfig output:

eth0      Link encap:Ethernet  HWaddr 00:19:99:42:30:95  
          inet addr:192.168.0.100  Bcast:192.168.0.255  Mask:255.255.255.0
          inet6 addr: fe80::219:99ff:fe42:3095/64 Scope:Link
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:194577 errors:0 dropped:0 overruns:0 frame:0
          TX packets:223860 errors:0 dropped:0 overruns:0 carrier:0
          collisions:1564 txqueuelen:100 
          RX bytes:21421865 (20.4 MB)  TX bytes:39529424 (37.6 MB)
          Memory:d8300000-d8320000 

lo        Link encap:Local Loopback  
          inet addr:127.0.0.1  Mask:255.0.0.0
          inet6 addr: ::1/128 Scope:Host
          UP LOOPBACK RUNNING  MTU:16436  Metric:1
          RX packets:541063 errors:0 dropped:0 overruns:0 frame:0
          TX packets:541063 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:0 
          RX bytes:63358040 (60.4 MB)  TX bytes:63358040 (60.4 MB)

netstat -tap output:

Active Internet connections (servers and established)
Proto Recv-Q Send-Q Local Address           Foreign Address         State       PID/Program name
tcp        0      0 *:5443                  *:*                     LISTEN      5238/restore_push
tcp        0      0 localhost.localdo:10024 *:*                     LISTEN      4543/amavisd (maste
tcp        0      0 localhost.localdo:10025 *:*                     LISTEN      5201/master     
tcp        0      0 localhost.localdo:mysql *:*                     LISTEN      4617/mysqld     
tcp        0      0 *:gds_db                *:*                     LISTEN      4487/fbserver   
tcp        0      0 *:netbios-ssn           *:*                     LISTEN      5258/smbd       
tcp        0      0 *:5901                  *:*                     LISTEN      5398/xinetd     
tcp        0      0 localhost.localdo:spamd *:*                     LISTEN      4688/spamd.pid  
tcp        0      0 *:www                   *:*                     LISTEN      3138/apache2    
tcp        0      0 localhost.localdo:37684 *:*                     LISTEN      5221/restore_backen
tcp        0      0 *:munin                 *:*                     LISTEN      5516/munin-node 
tcp        0      0 localhost.localdoma:ipp *:*                     LISTEN      5132/cupsd      
tcp        0      0 *:smtp                  *:*                     LISTEN      5201/master     
tcp        0      0 *:1723                  *:*                     LISTEN      5207/pptpd      
tcp        0      0 *:microsoft-ds          *:*                     LISTEN      5258/smbd       
tcp        0      0 *:3551                  *:*                     LISTEN      4702/apcupsd    
tcp        0      0 linux.serv:microsoft-ds 192.168.0.20:1799       ESTABLISHED 16744/smbd      
tcp        0      0 linux.server.c:38801 linux.serv:microsoft-ds TIME_WAIT   -               
tcp        0      0 linux.server.c:38805 linux.serv:microsoft-ds TIME_WAIT   -               
tcp        0      0 linux.server.c:36070 linux.serve:netbios-ssn TIME_WAIT   -               
tcp        0      0 linux.server.c:36068 linux.serve:netbios-ssn TIME_WAIT   -               
tcp        0      0 localhost.localdo:53931 localhost.localdo:mysql TIME_WAIT   -               
tcp        0      0 linux.server.c:38799 linux.serv:microsoft-ds TIME_WAIT   -               
tcp        0      0 linux.server.c:36074 linux.serve:netbios-ssn TIME_WAIT   -               
tcp        0      0 linux.serv:microsoft-ds 192.168.0.200:proofd    ESTABLISHED 17974/smbd      
tcp        0      0 localhost.localdo:53929 localhost.localdo:mysql TIME_WAIT   -               
tcp6       0      0 [::]:imaps              [::]:*                  LISTEN      5075/couriertcpd
tcp6       0      0 [::]:pop3s              [::]:*                  LISTEN      5109/couriertcpd
tcp6       0      0 [::]:pop3               [::]:*                  LISTEN      5089/couriertcpd
tcp6       0      0 [::]:imap2              [::]:*                  LISTEN      5050/couriertcpd
tcp6       0      0 [::]:ftp                [::]:*                  LISTEN      25840/proftpd: (acc
tcp6       0      0 [::]:ssh                [::]:*                  LISTEN      4508/sshd       
tcp6       0     52 linux.server.com:ssh 192.168.0.20%8191:2592  ESTABLISHED 18332/sshd: adminis

Syslog ouput:

Oct  2 08:47:45 linux pptpd[17889]: CTRL: Client 194.242.217.81 control connection started
Oct  2 08:47:46 linux pptpd[17889]: CTRL: Starting call (launching pppd, opening GRE)
Oct  2 08:47:46 linux pppd[17890]: Plugin /usr/lib/pptpd/pptpd-logwtmp.so loaded.
Oct  2 08:47:46 linux kernel: [329649.091835] PPP generic driver version 2.4.2
Oct  2 08:47:46 linux pppd[17890]: pppd 2.4.4 started by root, uid 0
Oct  2 08:47:46 linux pppd[17890]: Using interface ppp0
Oct  2 08:47:46 linux pppd[17890]: Connect: ppp0 <--> /dev/pts/0
Oct  2 08:47:46 linux pptpd[17889]: GRE: Bad checksum from pppd.
Oct  2 08:47:52 linux pptpd[17889]: CTRL: Ignored a SET LINK INFO packet with real ACCMs!
Oct  2 08:47:52 linux kernel: [329654.615881] padlock: VIA PadLock Hash Engine not detected.
Oct  2 08:47:52 linux modprobe: WARNING: Error inserting padlock_sha (/lib/modules/2.6.24-19-server/kernel/drivers/crypto/padlock-sha.ko): No such device 
Oct  2 08:47:52 linux kernel: [329654.682556] PPP MPPE Compression module registered
Oct  2 08:47:53 linux pppd[17890]: MPPE 128-bit stateless compression enabled
Oct  2 08:48:01 linux pppd[17890]: found interface eth0 for proxy arp
Oct  2 08:48:01 linux pppd[17890]: local  IP address 192.168.0.100
Oct  2 08:48:01 linux pppd[17890]: remote IP address 192.168.0.200
Oct  2 08:48:01 linux postfix/master[5201]: reload configuration /etc/postfix
Oct  2 08:48:34 linux nmbd[5253]: [2008/10/02 08:48:34, 0] nmbd/nmbd_packets.c:process_browse_packet(1067)

mail.info output:

Oct  2 08:48:01 linux postfix/master[5201]: reload configuration /etc/postfix

I thank you very much for your help
Franco

[falko]

Hm, good question why Postfix is reloaded, but I see here: http://marc.info/?l=linux-netdev&m=121157849601121&w=2 that this happens here as well, so maybe this is normal.

The good thing is that it's just a reload, not a restart, so Postfix is not interrupted.

[fpizzinini]

Oh, I can see.
I thank you for taking your time.
Next week this server will go live.
All the tests went without problems so far, so I hope that there will not be bad surprises.