LCSAM startet nicht automatisch

janwego · 3. Oktober 2014

Hallo zusammen,
Ich hab seit Tagen keine Mails mehr bekommen und heute mal einen Test gestartet und selbst verschickte Mails kamen ebenfalls nicht an. Nachdem ich dann das Forum durchsucht habe scheint das Problem zu sein, dass der LCSAM-Milter sich nicht automatisch nach Neustart des Systems startet. Ich habe ihn dann manuell gestartet. Im Mail.Log war in vielen Zeilen zu lesen:

Code

Oct  1 16:25:58 ns312862 postfix/smtpd[30339]: connect from HOSTNAME[IP]
Oct  1 16:25:58 ns312862 postfix/smtpd[30339]: warning: connect to Milter service unix:/var/run/lcsam.sock: No such file or directory
Oct  1 16:25:58 ns312862 postfix/smtpd[30339]: NOQUEUE: milter-reject: CONNECT from HOSTNAME[IP]: 451 4.7.1 Service unavailable - try again late$
Oct  1 16:25:58 ns312862 postfix/smtpd[30339]: NOQUEUE: milter-reject: EHLO from HOSTNAME[IP]: 451 4.7.1 Service unavailable - try again later; $
Oct  1 16:25:58 ns312862 postfix/smtpd[30339]: disconnect from HOSTNAME[IP]

Ich werde nun häufiger danach schauen und regelmäßig Testmails senden. Kann jemand das Problem nachvollziehen oder bestätigen?
Habe Ubuntu 12.04 .

LG,

Jan

solidius · 17. November 2014

Hallo janwego,

unter Ubuntu 12.04 beobachte ich gerade ein ähnliches verhalten. Bei mir steigt der Dienst regelmäßig aus. Dann kommen einfach keine
Mails mehr rein.

Code

Nov 17 11:02:19 system01 postfix/smtpd[28806]: connect from gmy2-mh873.smtproutes.com[94.186.192.202]
Nov 17 11:02:19 system01 postfix/smtpd[28806]: warning: connect to Milter service unix:/var/run/lcsam.sock: No such file or directory
Nov 17 11:02:19 system01 postfix/smtpd[28806]: NOQUEUE: milter-reject: CONNECT from gmy2-mh873.smtproutes.com[94.186.192.202]: 451 4.7.1 Service unavailable - try again later; proto=SMTP
Nov 17 11:02:19 system01 postfix/smtpd[28806]: NOQUEUE: milter-reject: EHLO from gmy2-mh873.smtproutes.com[94.186.192.202]: 451 4.7.1 Service unavailable - try again later; proto=SMTP helo=<gmy2-mh873.smtproutes.com>
Nov 17 11:02:19 system01 postfix/smtpd[28806]: NOQUEUE: milter-reject: MAIL from gmy2-mh873.smtproutes.com[94.186.192.202]: 451 4.7.1 Service unavailable - try again later; from=<YG-20141117-fa6df6c7213338@epal.com> proto=ESMTP helo=<gmy2-mh873.smtproutes.com>
Nov 17 11:02:20 system01 postfix/smtpd[28806]: disconnect from gmy2-mh873.smtproutes.com[94.186.192.202]

@Keppler: Was könnte hier das Problem sein ? Anbei mal die LC Diag

Code

root@system01:~# liveconfig --diag
Running OS diagnostics...
FILE SYSTEMS:
  / [/dev/simfs] (total: 34359738368, free: 18188832768)
  /run [none] (total: 858996736, free: 858886144)
  /run/lock [none] (total: 5242880, free: 5242880)
  /run/shm [none] (total: 4294967296, free: 4294967296)
CPUs:
  CPU: 'cpu' user=1285170 nice=79090 system=504416 idle=150978583 wait=771688 irq=0 sirq=0 steal=0
  CPU: 'cpu0' user=1285170 nice=79090 system=504416 idle=150978583 wait=771688 irq=0 sirq=0 steal=0
  CPU: 'cpu1' user=1285170 nice=79090 system=504416 idle=150978583 wait=771688 irq=0 sirq=0 steal=0
  CPU: 'cpu2' user=1285170 nice=79090 system=504416 idle=150978583 wait=771688 irq=0 sirq=0 steal=0
  CPU: 'cpu3' user=1285170 nice=79090 system=504416 idle=150978583 wait=771688 irq=0 sirq=0 steal=0
CPU IDENTIFICATION:
  Physical CPUs: 1
  CPU Vendor: GenuineIntel
  CPU Model: Intel(R) Xeon(R) CPU E3-1245 V2 @ 3.40GHz
  Physical Core Count: 4
  Cache: 8192 KB
  Intel Hyperthreading is enabled!
    Logical (HT) Core Count: 4
LOAD AVERAGE:
  Load: 1min=0.020000, 5min=0.170000, 15min=0.150000
MEMORY:
  Total Memory: 8589934592
   Free Memory: 7111540736
    Total Swap: 8589934592
     Free Swap: 8473079808
INTERFACES:
  Name: 'lo'
       MAC: 00:00:00:00:00:00
       IPv4: '127.0.0.1'/8
       IPv6: '::1'/128
       IN: 3381909311 bytes (11057325 packets)
       OUT: 3381909311 bytes (11057325 packets)
       FLAGS: UP LOOPBACK
  Name: 'venet0'
       IPv4: '127.0.0.2'/32
       IN: 2113014491 bytes (6168558 packets)
       OUT: 144303989 bytes (7045365 packets)
       FLAGS: UP BROADCAST NOARP
  Name: 'venet0:0'
       IPv4: '5.196.200.120'/32
       FLAGS: UP BROADCAST NOARP
  Name: 'venet0:1'
       IPv4: '5.196.143.102'/32
       FLAGS: UP BROADCAST NOARP
  Name: 'venet0:2'
       IPv4: '5.196.143.101'/32
       FLAGS: UP BROADCAST NOARP
  Name: 'venet0:3'
       IPv4: '5.196.200.119'/32
       FLAGS: UP BROADCAST NOARP
  Name: 'venet0:4'
       IPv4: '5.196.200.118'/32
       FLAGS: UP BROADCAST NOARP
  Name: 'venet0:5'
       IPv4: '5.196.200.117'/32
       FLAGS: UP BROADCAST NOARP
  Name: 'venet0:6'
       IPv4: '5.196.200.116'/32
       FLAGS: UP BROADCAST NOARP
  Name: 'venet0:7'
       IPv4: '5.196.164.213'/32
       FLAGS: UP BROADCAST NOARP
  Name: 'venet0:8'
       IPv4: '5.196.164.212'/32
       FLAGS: UP BROADCAST NOARP
  Name: 'venet0:9'
       IPv4: '5.196.164.211'/32
       FLAGS: UP BROADCAST NOARP
  Name: 'venet0:10'
       IPv4: '5.135.196.105'/32
       FLAGS: UP BROADCAST NOARP
HOSTNAME:
  Hostname: 'system01'
      FQDN: 'system01.isp-serverfarm.de'
UPTIME:
  Uptime: 4 days, 11:38:01
DMI:
  ERROR: open(/dev/mem) failed: Operation not permitted
IPMI:
  ERROR: IPMI not supported in this build
QUOTA for group 'root' at path /var/www: OK - kb_used=1937948, inodes_used=57287
Running Lua diagnostics...
[INFO] Detected 'Ubuntu 12.04.5 LTS'
[DEBUG] running web.detect()
[DEBUG] Found package 'apache2-mpm-prefork' (Version 2.2.22-1ubuntu1.7)
[DEBUG] binver 2.2.22
[DEBUG] Found package 'nginx' (Version 1.1.19-1ubuntu0.6)
[DEBUG] binver 1.1.19
       Distribution name: 'Ubuntu'
   Distribution codename: 'precise'
     Distribution family: 'Debian'
    Distribution version: '12.04'
Distribution description: 'Ubuntu 12.04.5 LTS'
Checking for web server software:
 - Found 'apache' web server
   Version: '2.2.22'
   Package version: '2.2.22-1ubuntu1.7'
   SNI support: yes
   Modules: core log_config logio mpm_prefork http so actions alias auth_basic authn_file authz_default authz_groupfile authz_host authz_user autoindex cgi cloudflare deflate dir env fcgid geoip headers include mime negotiation perl php5 proxy proxy_http python reqtimeout rewrite setenvif spdy ssl suexec suphp
 - Found 'nginx' web server
   Version: '1.1.19'
   Package version: '1.1.19-1ubuntu0.6'
   SNI support: yes
 - PHP 5.3.10 (code='php5', bin='/usr/bin/php-cgi')
   default php.ini: '/etc/php5/cgi/php.ini'
Checking for ftp server software:
[DEBUG] running ftp.detect()
[DEBUG] Found package 'proftpd-basic' (Version 1.3.4a-1)
 - Found 'proftpd' FTP server
   Version: '1.3.4a'
   Package version: '1.3.4a-1'
Checking for SMTP server software:
[DEBUG] running smtp.detect()
[DEBUG] Found package 'postfix' (Version 2.9.6-1~12.04.2)
[DEBUG] Found package 'spamassassin' (Version 3.3.2-2ubuntu1)
 - Found 'postfix' smtp server
   Version: '2.9.6'
   Package version: '2.9.6-1~12.04.2'
 - found SpamAssassin (package version: '3.3.2-2ubuntu1')
   configured: yes
Checking for POP/IMAP server software:
[DEBUG] running popimap.detect()
[DEBUG] Found package 'dovecot-imapd' (Version 1:2.0.19-0ubuntu2.2)
 - Found 'dovecot' POP/IMAP server
   Version: '2.0.19'
   Package version: '1:2.0.19-0ubuntu2.2'
Checking for database server software:
[DEBUG] running db.detect()
[DEBUG] Found package 'mysql-server' (Version 5.5.40-0ubuntu0.12.04.1)
 - Found 'mysql' database server
   Version: '5.5.40'
   Package version: '5.5.40-0ubuntu0.12.04.1'
Checking for DNS server software:
[DEBUG] running dns.detect()
Done.

Alles anzeigen

kk · 17. November 2014

Suchen Sie bitte mal im /var/log/mail.log, was da so zum lcsam-Prozess drin steht (am besten per grep). Wenn lcsam nicht mehr läuft, müsste es sich natürlich irgendwann beendet haben. Vielleicht gibt das Log mehr Auskünfte dazu.

Ich gehe davon aus, dass Sie die aktuelle LC-Version einsetzen? (v1.7.5-r3127)

solidius · 17. November 2014

Moin,

ja wir haben die aktuelle Version r3127 installiert.

Hier der Auszug aus der Maillog.

Code

Nov 12 22:19:14 system01 postfix/smtpd[16874]: warning: connect to Milter service unix:/var/run/lcsam.sock: No such file or directory
Nov 12 22:20:15 system01 postfix/smtpd[16874]: warning: connect to Milter service unix:/var/run/lcsam.sock: No such file or directory
Nov 12 22:20:36 system01 postfix/smtpd[16874]: warning: connect to Milter service unix:/var/run/lcsam.sock: No such file or directory
Nov 12 22:20:36 system01 postfix/smtpd[16874]: warning: connect to Milter service unix:/var/run/lcsam.sock: No such file or directory
Nov 12 22:21:14 system01 postfix/smtpd[16874]: warning: connect to Milter service unix:/var/run/lcsam.sock: No such file or directory
Nov 12 22:22:00 system01 lcsam[17737]: LiveConfig SpamAssassin Milter (lcsam) started
Nov 12 22:22:14 system01 lcsam[17737]: 5.83.128.154: lcsam_helo('node-fra')
Nov 12 22:22:14 system01 lcsam[17737]: 5.83.128.154: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 22:22:14 system01 lcsam[17737]: 5.83.128.154: lcsam_abort()
Nov 12 22:22:14 system01 lcsam[17737]: : lcsam_close()
Nov 12 22:23:14 system01 lcsam[17737]: 213.133.109.71: lcsam_helo('node-nur')
Nov 12 22:23:14 system01 lcsam[17737]: 213.133.109.71: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 22:23:14 system01 lcsam[17737]: 213.133.109.71: lcsam_abort()
Nov 12 22:23:14 system01 lcsam[17737]: : lcsam_close()
Nov 12 22:24:15 system01 lcsam[17737]: 81.169.152.104: lcsam_helo('node-ber.serverguard24.de')
Nov 12 22:24:15 system01 lcsam[17737]: 81.169.152.104: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 22:24:15 system01 lcsam[17737]: 81.169.152.104: lcsam_abort()
Nov 12 22:24:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 22:42:15 system01 lcsam[17737]: 81.169.152.104: lcsam_helo('node-ber.serverguard24.de')
Nov 12 22:42:15 system01 lcsam[17737]: 81.169.152.104: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 22:42:15 system01 lcsam[17737]: 81.169.152.104: lcsam_abort()
Nov 12 22:42:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 22:43:14 system01 lcsam[17737]: 212.72.183.125: lcsam_helo('node-ham.monitoring-serverguard24.net')
Nov 12 22:43:14 system01 lcsam[17737]: 212.72.183.125: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 22:43:14 system01 lcsam[17737]: 212.72.183.125: lcsam_abort()
Nov 12 22:43:14 system01 lcsam[17737]: : lcsam_close()
Nov 12 22:43:36 system01 lcsam[17737]: 94.186.192.151: lcsam_helo('gmy2-mh822.smtproutes.com')
Nov 12 22:43:36 system01 lcsam[17737]: 94.186.192.151: lcsam_envfrom('<service@paypal.de>')
Nov 12 22:43:36 system01 lcsam[17737]: 94.186.192.151: lcsam_envrcpt('paypal@isp-serverfarm.de')
Nov 12 22:43:36 system01 lcsam[17737]: lcsam_lookup(paypal@isp-serverfarm.de): not found
Nov 12 22:43:37 system01 lcsam[17737]: 94.186.192.151: lcsam_close()
Nov 12 22:44:15 system01 lcsam[17737]: 193.164.132.46: lcsam_helo('node-mun')
Nov 12 22:44:15 system01 lcsam[17737]: 193.164.132.46: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 22:44:15 system01 lcsam[17737]: 193.164.132.46: lcsam_abort()
Nov 12 22:44:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 22:45:14 system01 lcsam[17737]: 85.25.132.182: lcsam_helo('node-str.serverguard24.de')
Nov 12 22:45:14 system01 lcsam[17737]: 85.25.132.182: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 22:45:14 system01 lcsam[17737]: 85.25.132.182: lcsam_abort()
Nov 12 22:45:14 system01 lcsam[17737]: : lcsam_close()
Nov 12 22:45:37 system01 lcsam[17737]: 178.32.53.38: lcsam_close()
Nov 12 22:45:37 system01 lcsam[17737]: 178.32.53.38: lcsam_close()
Nov 12 22:46:15 system01 lcsam[17737]: 5.83.128.154: lcsam_helo('node-fra')
Nov 12 22:46:15 system01 lcsam[17737]: 5.83.128.154: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 22:46:15 system01 lcsam[17737]: 5.83.128.154: lcsam_abort()
Nov 12 22:46:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 22:47:15 system01 lcsam[17737]: 213.133.109.71: lcsam_helo('node-nur')
Nov 12 22:47:15 system01 lcsam[17737]: 213.133.109.71: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 22:47:15 system01 lcsam[17737]: 213.133.109.71: lcsam_abort()
Nov 12 22:47:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 22:48:15 system01 lcsam[17737]: 81.169.152.104: lcsam_helo('node-ber.serverguard24.de')
Nov 12 22:48:15 system01 lcsam[17737]: 81.169.152.104: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 22:48:15 system01 lcsam[17737]: 81.169.152.104: lcsam_abort()
Nov 12 22:48:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 22:49:15 system01 lcsam[17737]: 212.72.183.125: lcsam_helo('node-ham.monitoring-serverguard24.net')
Nov 12 22:49:15 system01 lcsam[17737]: 212.72.183.125: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 22:49:15 system01 lcsam[17737]: 212.72.183.125: lcsam_abort()
Nov 12 22:49:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 22:50:15 system01 lcsam[17737]: 193.164.132.46: lcsam_helo('node-mun')
Nov 12 22:50:15 system01 lcsam[17737]: 193.164.132.46: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 22:50:15 system01 lcsam[17737]: 193.164.132.46: lcsam_abort()
Nov 12 22:50:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 22:50:37 system01 lcsam[17737]: 178.32.53.38: lcsam_close()
Nov 12 22:50:37 system01 lcsam[17737]: 178.32.53.38: lcsam_close()
Nov 12 22:51:15 system01 lcsam[17737]: 85.25.132.182: lcsam_helo('node-str.serverguard24.de')
Nov 12 22:51:15 system01 lcsam[17737]: 85.25.132.182: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 22:51:15 system01 lcsam[17737]: 85.25.132.182: lcsam_abort()
Nov 12 22:51:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 22:52:15 system01 lcsam[17737]: 5.83.128.154: lcsam_helo('node-fra')
Nov 12 22:52:15 system01 lcsam[17737]: 5.83.128.154: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 22:52:15 system01 lcsam[17737]: 5.83.128.154: lcsam_abort()
Nov 12 22:52:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 22:53:14 system01 lcsam[17737]: 213.133.109.71: lcsam_helo('node-nur')
Nov 12 22:53:14 system01 lcsam[17737]: 213.133.109.71: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 22:53:14 system01 lcsam[17737]: 213.133.109.71: lcsam_abort()
Nov 12 22:53:14 system01 lcsam[17737]: : lcsam_close()
Nov 12 22:54:14 system01 lcsam[17737]: 81.169.152.104: lcsam_helo('node-ber.serverguard24.de')
Nov 12 22:54:14 system01 lcsam[17737]: 81.169.152.104: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 22:54:14 system01 lcsam[17737]: 81.169.152.104: lcsam_abort()
Nov 12 22:54:14 system01 lcsam[17737]: : lcsam_close()
Nov 12 22:55:14 system01 lcsam[17737]: 212.72.183.125: lcsam_helo('node-ham.monitoring-serverguard24.net')
Nov 12 22:55:14 system01 lcsam[17737]: 212.72.183.125: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 22:55:14 system01 lcsam[17737]: 212.72.183.125: lcsam_abort()
Nov 12 22:55:14 system01 lcsam[17737]: : lcsam_close()
Nov 12 22:55:36 system01 lcsam[17737]: 178.32.53.38: lcsam_close()
Nov 12 22:55:36 system01 lcsam[17737]: 178.32.53.38: lcsam_close()
Nov 12 22:56:15 system01 lcsam[17737]: 193.164.132.46: lcsam_helo('node-mun')
Nov 12 22:56:15 system01 lcsam[17737]: 193.164.132.46: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 22:56:15 system01 lcsam[17737]: 193.164.132.46: lcsam_abort()
Nov 12 22:56:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 22:57:15 system01 lcsam[17737]: 85.25.132.182: lcsam_helo('node-str.serverguard24.de')
Nov 12 22:57:15 system01 lcsam[17737]: 85.25.132.182: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 22:57:15 system01 lcsam[17737]: 85.25.132.182: lcsam_abort()
Nov 12 22:57:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 22:58:15 system01 lcsam[17737]: 5.83.128.154: lcsam_helo('node-fra')
Nov 12 22:58:15 system01 lcsam[17737]: 5.83.128.154: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 22:58:15 system01 lcsam[17737]: 5.83.128.154: lcsam_abort()
Nov 12 22:58:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 22:59:15 system01 lcsam[17737]: 213.133.109.71: lcsam_helo('node-nur')
Nov 12 22:59:15 system01 lcsam[17737]: 213.133.109.71: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 22:59:15 system01 lcsam[17737]: 213.133.109.71: lcsam_abort()
Nov 12 22:59:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:00:15 system01 lcsam[17737]: 81.169.152.104: lcsam_helo('node-ber.serverguard24.de')
Nov 12 23:00:15 system01 lcsam[17737]: 81.169.152.104: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:00:15 system01 lcsam[17737]: 81.169.152.104: lcsam_abort()
Nov 12 23:00:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:00:37 system01 lcsam[17737]: 178.32.53.38: lcsam_close()
Nov 12 23:00:37 system01 lcsam[17737]: 178.32.53.38: lcsam_close()
Nov 12 23:01:15 system01 lcsam[17737]: 212.72.183.125: lcsam_helo('node-ham.monitoring-serverguard24.net')
Nov 12 23:01:15 system01 lcsam[17737]: 212.72.183.125: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:01:15 system01 lcsam[17737]: 212.72.183.125: lcsam_abort()
Nov 12 23:01:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:02:14 system01 lcsam[17737]: 193.164.132.46: lcsam_helo('node-mun')
Nov 12 23:02:14 system01 lcsam[17737]: 193.164.132.46: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:02:14 system01 lcsam[17737]: 193.164.132.46: lcsam_abort()
Nov 12 23:02:14 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:03:14 system01 lcsam[17737]: 85.25.132.182: lcsam_helo('node-str.serverguard24.de')
Nov 12 23:03:14 system01 lcsam[17737]: 85.25.132.182: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:03:14 system01 lcsam[17737]: 85.25.132.182: lcsam_abort()
Nov 12 23:03:14 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:04:15 system01 lcsam[17737]: 5.83.128.154: lcsam_helo('node-fra')
Nov 12 23:04:15 system01 lcsam[17737]: 5.83.128.154: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:04:15 system01 lcsam[17737]: 5.83.128.154: lcsam_abort()
Nov 12 23:04:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:05:15 system01 lcsam[17737]: 213.133.109.71: lcsam_helo('node-nur')
Nov 12 23:05:15 system01 lcsam[17737]: 213.133.109.71: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:05:15 system01 lcsam[17737]: 213.133.109.71: lcsam_abort()
Nov 12 23:05:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:05:37 system01 lcsam[17737]: 178.32.53.38: lcsam_close()
Nov 12 23:05:37 system01 lcsam[17737]: 178.32.53.38: lcsam_close()
Nov 12 23:06:15 system01 lcsam[17737]: 81.169.152.104: lcsam_helo('node-ber.serverguard24.de')
Nov 12 23:06:15 system01 lcsam[17737]: 81.169.152.104: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:06:15 system01 lcsam[17737]: 81.169.152.104: lcsam_abort()
Nov 12 23:06:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:07:14 system01 lcsam[17737]: 212.72.183.125: lcsam_helo('node-ham.monitoring-serverguard24.net')
Nov 12 23:07:14 system01 lcsam[17737]: 212.72.183.125: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:07:14 system01 lcsam[17737]: 212.72.183.125: lcsam_abort()
Nov 12 23:07:14 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:08:15 system01 lcsam[17737]: 193.164.132.46: lcsam_helo('node-mun')
Nov 12 23:08:15 system01 lcsam[17737]: 193.164.132.46: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:08:15 system01 lcsam[17737]: 193.164.132.46: lcsam_abort()
Nov 12 23:08:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:09:15 system01 lcsam[17737]: 85.25.132.182: lcsam_helo('node-str.serverguard24.de')
Nov 12 23:09:15 system01 lcsam[17737]: 85.25.132.182: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:09:15 system01 lcsam[17737]: 85.25.132.182: lcsam_abort()
Nov 12 23:09:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:10:14 system01 lcsam[17737]: 5.83.128.154: lcsam_helo('node-fra')
Nov 12 23:10:14 system01 lcsam[17737]: 5.83.128.154: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:10:14 system01 lcsam[17737]: 5.83.128.154: lcsam_abort()
Nov 12 23:10:14 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:10:37 system01 lcsam[17737]: 178.32.53.38: lcsam_close()
Nov 12 23:10:37 system01 lcsam[17737]: 178.32.53.38: lcsam_close()
Nov 12 23:11:15 system01 lcsam[17737]: 213.133.109.71: lcsam_helo('node-nur')
Nov 12 23:11:15 system01 lcsam[17737]: 213.133.109.71: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:11:15 system01 lcsam[17737]: 213.133.109.71: lcsam_abort()
Nov 12 23:11:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:12:15 system01 lcsam[17737]: 81.169.152.104: lcsam_helo('node-ber.serverguard24.de')
Nov 12 23:12:15 system01 lcsam[17737]: 81.169.152.104: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:12:15 system01 lcsam[17737]: 81.169.152.104: lcsam_abort()
Nov 12 23:12:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:13:14 system01 lcsam[17737]: 212.72.183.125: lcsam_helo('node-ham.monitoring-serverguard24.net')
Nov 12 23:13:14 system01 lcsam[17737]: 212.72.183.125: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:13:14 system01 lcsam[17737]: 212.72.183.125: lcsam_abort()
Nov 12 23:13:14 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:14:15 system01 lcsam[17737]: 193.164.132.46: lcsam_helo('node-mun')
Nov 12 23:14:15 system01 lcsam[17737]: 193.164.132.46: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:14:15 system01 lcsam[17737]: 193.164.132.46: lcsam_abort()
Nov 12 23:14:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:15:15 system01 lcsam[17737]: 85.25.132.182: lcsam_helo('node-str.serverguard24.de')
Nov 12 23:15:15 system01 lcsam[17737]: 85.25.132.182: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:15:15 system01 lcsam[17737]: 85.25.132.182: lcsam_abort()
Nov 12 23:15:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:15:36 system01 lcsam[17737]: 178.32.53.38: lcsam_close()
Nov 12 23:15:37 system01 lcsam[17737]: 178.32.53.38: lcsam_close()
Nov 12 23:16:14 system01 lcsam[17737]: 5.83.128.154: lcsam_helo('node-fra')
Nov 12 23:16:14 system01 lcsam[17737]: 5.83.128.154: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:16:14 system01 lcsam[17737]: 5.83.128.154: lcsam_abort()
Nov 12 23:16:14 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:17:15 system01 lcsam[17737]: 213.133.109.71: lcsam_helo('node-nur')
Nov 12 23:17:15 system01 lcsam[17737]: 213.133.109.71: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:17:15 system01 lcsam[17737]: 213.133.109.71: lcsam_abort()
Nov 12 23:17:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:18:14 system01 lcsam[17737]: 81.169.152.104: lcsam_helo('node-ber.serverguard24.de')
Nov 12 23:18:14 system01 lcsam[17737]: 81.169.152.104: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:18:14 system01 lcsam[17737]: 81.169.152.104: lcsam_abort()
Nov 12 23:18:14 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:19:07 system01 lcsam[17737]: 94.186.192.197: lcsam_helo('gmy2-mh868.smtproutes.com')
Nov 12 23:19:07 system01 lcsam[17737]: 94.186.192.197: lcsam_envfrom('<bjoern@strausmann.net>')
Nov 12 23:19:07 system01 lcsam[17737]: 94.186.192.197: lcsam_envrcpt('info@support.nexus-service.de')
Nov 12 23:19:08 system01 lcsam[17737]: lcsam_lookup(info@support.nexus-service.de): not found
Nov 12 23:19:08 system01 lcsam[17737]: 94.186.192.197: lcsam_close()
Nov 12 23:19:15 system01 lcsam[17737]: 212.72.183.125: lcsam_helo('node-ham.monitoring-serverguard24.net')
Nov 12 23:19:15 system01 lcsam[17737]: 212.72.183.125: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:19:15 system01 lcsam[17737]: 212.72.183.125: lcsam_abort()
Nov 12 23:19:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:20:14 system01 lcsam[17737]: 193.164.132.46: lcsam_helo('node-mun')
Nov 12 23:20:14 system01 lcsam[17737]: 193.164.132.46: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:20:14 system01 lcsam[17737]: 193.164.132.46: lcsam_abort()
Nov 12 23:20:14 system01 lcsam[17737]: : lcsam_abort()
Nov 12 23:20:14 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:20:37 system01 lcsam[17737]: 178.32.53.38: lcsam_close()
Nov 12 23:20:37 system01 lcsam[17737]: 178.32.53.38: lcsam_close()
Nov 12 23:21:15 system01 lcsam[17737]: 85.25.132.182: lcsam_helo('node-str.serverguard24.de')
Nov 12 23:21:15 system01 lcsam[17737]: 85.25.132.182: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:21:15 system01 lcsam[17737]: 85.25.132.182: lcsam_abort()
Nov 12 23:21:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:22:14 system01 lcsam[17737]: 5.83.128.154: lcsam_helo('node-fra')
Nov 12 23:22:14 system01 lcsam[17737]: 5.83.128.154: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:22:14 system01 lcsam[17737]: 5.83.128.154: lcsam_abort()
Nov 12 23:22:14 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:23:14 system01 lcsam[17737]: 213.133.109.71: lcsam_helo('node-nur')
Nov 12 23:23:14 system01 lcsam[17737]: 213.133.109.71: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:23:14 system01 lcsam[17737]: 213.133.109.71: lcsam_abort()
Nov 12 23:23:14 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:24:15 system01 lcsam[17737]: 81.169.152.104: lcsam_helo('node-ber.serverguard24.de')
Nov 12 23:24:15 system01 lcsam[17737]: 81.169.152.104: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:24:15 system01 lcsam[17737]: 81.169.152.104: lcsam_abort()
Nov 12 23:24:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:25:14 system01 lcsam[17737]: 212.72.183.125: lcsam_helo('node-ham.monitoring-serverguard24.net')
Nov 12 23:25:14 system01 lcsam[17737]: 212.72.183.125: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:25:14 system01 lcsam[17737]: 212.72.183.125: lcsam_abort()
Nov 12 23:25:14 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:26:15 system01 lcsam[17737]: 193.164.132.46: lcsam_helo('node-mun')
Nov 12 23:26:15 system01 lcsam[17737]: 193.164.132.46: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:26:15 system01 lcsam[17737]: 193.164.132.46: lcsam_abort()
Nov 12 23:26:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:27:15 system01 lcsam[17737]: 85.25.132.182: lcsam_helo('node-str.serverguard24.de')
Nov 12 23:27:15 system01 lcsam[17737]: 85.25.132.182: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:27:15 system01 lcsam[17737]: 85.25.132.182: lcsam_abort()
Nov 12 23:27:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:28:15 system01 lcsam[17737]: 5.83.128.154: lcsam_helo('node-fra')
Nov 12 23:28:15 system01 lcsam[17737]: 5.83.128.154: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:28:15 system01 lcsam[17737]: 5.83.128.154: lcsam_abort()
Nov 12 23:28:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:31:35 system01 postfix/smtpd[4579]: warning: connect to Milter service unix:/var/run/lcsam.sock: No such file or directory
Nov 12 23:32:35 system01 postfix/smtpd[4579]: warning: connect to Milter service unix:/var/run/lcsam.sock: No such file or directory
Nov 12 23:33:35 system01 postfix/smtpd[4579]: warning: connect to Milter service unix:/var/run/lcsam.sock: No such file or directory

Alles anzeigen

Der Dienst war nach dem letzten Auswahl und Neustart am 12.11. um 22:22, nach nur etwas mehr als 24 Stunden wieder ausgestiegen.

kk · 17. November 2014

Zitat von solidius

Code

Nov 12 23:28:15 system01 lcsam[17737]: 5.83.128.154: lcsam_helo('node-fra')
Nov 12 23:28:15 system01 lcsam[17737]: 5.83.128.154: lcsam_envfrom('monitoring@isp-serverfarm.de')
Nov 12 23:28:15 system01 lcsam[17737]: 5.83.128.154: lcsam_abort()
Nov 12 23:28:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:31:35 system01 postfix/smtpd[4579]: warning: connect to Milter service unix:/var/run/lcsam.sock: No such file or directory

Prüfen Sie bitte mal im mail.log bzw. syslog, was zwischen 23:28:15 und 23:31:35 eventuell so alles passiert ist.

Zitat

Der Dienst war nach dem letzten Auswahl und Neustart am 12.11. um 22:22, nach nur etwas mehr als 24 Stunden wieder ausgestiegen.

Das Blöde an der Sache ist, dass der Milter-Prozess komplett durch das Milter-Framework gesteuert wird - man registriert quasi nur die eigenen Handler-Funktionen. Wenn sich das bei Ihnen aber so schnell reproduzieren lässt, bauen wir mal weitere Debug-Informationen ein, die vielleicht einen Hinweis darauf liefern könnten wo genau Milter da aussteigt.

solidius · 17. November 2014

Scheinbar ist die Logrotation schuld an dem ganzen Problem.

Code

Nov 12 23:28:15 system01 lcsam[17737]: : lcsam_close()
Nov 12 23:28:15 system01 dovecot: imap-login: Aborted login (no auth attempts): rip=193.164.132.46, lip=5.196.200.120
Nov 12 23:28:27 system01 dovecot: master: Warning: Killed with signal 15 (by pid=1 uid=0 code=kill)
Nov 12 23:28:27 system01 dovecot: log: Warning: Killed with signal 15 (by pid=1 uid=0 code=kill)
Nov 12 23:29:14 system01 postfix/master[9782]: terminating on signal 15
Nov 12 23:29:14 system01 spamd[792]: spamd: server killed by SIGTERM, shutting down 
Nov 12 23:29:15 system01 exiting on signal 15
Nov 12 23:31:00 system01 syslogd 1.5.0#6ubuntu1: restart.
Nov 12 23:31:03 system01 postgrey[903]: Process Backgrounded 
Nov 12 23:31:03 system01 postgrey[903]: 2014/11/12-23:31:02 postgrey (type Net::Server::Multiplex) starting! pid(903) 
Nov 12 23:31:03 system01 postgrey[903]: Using default listen value of 128 
Nov 12 23:31:03 system01 postgrey[903]: Binding to TCP port 10023 on host 127.0.0.1  
Nov 12 23:31:03 system01 postgrey[903]: Setting gid to "120 120" 
Nov 12 23:31:03 system01 postgrey[903]: Setting uid to "117" 
Nov 12 23:31:04 system01 spamd[907]: logger: removing stderr method 
Nov 12 23:31:06 system01 spamd[933]: spamd: server started on UNIX domain socket /var/run/spamd.sock (running version 3.3.2) 
Nov 12 23:31:06 system01 spamd[933]: spamd: server pid: 933 
Nov 12 23:31:06 system01 spamd[933]: spamd: server successfully spawned child process, pid 944 
Nov 12 23:31:06 system01 spamd[933]: spamd: server successfully spawned child process, pid 946 
Nov 12 23:31:06 system01 spamd[933]: prefork: child states: IS 
Nov 12 23:31:06 system01 spamd[933]: prefork: child states: II 
Nov 12 23:31:12 system01 ntpdate[246]: step-systime: Operation not permitted
Nov 12 23:31:14 system01 postfix/master[1845]: daemon started -- version 2.9.6, configuration /etc/postfix
Nov 12 23:31:14 system01 dovecot: pop3-login: Aborted login (no auth attempts): rip=85.25.132.182, lip=5.196.200.120
Nov 12 23:31:15 system01 dovecot: imap-login: Aborted login (no auth attempts): rip=212.72.183.125, lip=5.196.200.120
Nov 12 23:31:22 system01 ntpdate[1754]: step-systime: Operation not permitted
Nov 12 23:31:35 system01 postfix/smtpd[4579]: connect from node-fra.serverguard24.de[5.83.128.154]
Nov 12 23:31:35 system01 postfix/smtpd[4579]: warning: connect to Milter service unix:/var/run/lcsam.sock: No such file or directory
Nov 12 23:31:35 system01 postfix/smtpd[4579]: NOQUEUE: milter-reject: CONNECT from node-fra.serverguard24.de[5.83.128.154]: 451 4.7.1 Service unavailable - try again later; proto=SMTP
Nov 12 23:31:35 system01 postfix/smtpd[4579]: NOQUEUE: milter-reject: HELO from node-fra.serverguard24.de[5.83.128.154]: 451 4.7.1 Service unavailable - try again later; proto=SMTP helo=<node-fra>
Nov 12 23:31:35 system01 postfix/smtpd[4579]: NOQUEUE: milter-reject: MAIL from node-fra.serverguard24.de[5.83.128.154]: 451 4.7.1 Service unavailable - try again later; from=<monitoring@isp-serverfarm.de> proto=SMTP

Alles anzeigen

kk · 28. November 2014

Hallo,

was da geschehen ist war kein Logrotate, sondern ein Server-Neustart (ein Kill-Signal 15 vom init-Prozess deutet zumindest stark darauf hin Außerdem gibt es eine Zeitlücke von über 1,5 Minuten zwischen den Service-Stopps und -Starts.

Das hat die Fehlerquelle dann auch eingegrenzt: unter Ubuntu 12 konnte LiveConfig das init-Script für lcsam nicht korrekt installieren. Mit folgendem Befehl kann das nachgeholt werden:

Code

update-rc.d lcsam defaults

Mit dem nächsten Update (v1.8) holt LiveConfig das ggf. selbst nach.

Viele Grüße

-Klaus Keppler

solidius · 6. Dezember 2014

Ich habe das Problem nun eine längere Zeit beobachtet und es ist tatsächlich nicht mehr vorgekommen, seit dem Update der init-Scripte.

Vielen dank an Herrn Keppler

Jetzt mitmachen!