Spamassassin logging

Ho un Postfix, Postgrey, Spamassassin, amavisd-new, setup del colombaio.

Quando invio un messaggio di posta elettronica tramite il sistema, il file di registro viene completamente danneggiato.

Non riesco a trovare informazioni per spiegare quali sono le linee di log, quindi non so quale sia la cosa migliore da submit qui. Sembra che sta tornando ad alcune informazioni da quando ho installato i server 6 mesi fa e fornendo linee di registro per un enorme numero di messaggi precedentemente processati.

Ciò port a enormi file di log (la mia mail.log.1 è stata commutata questa mattina ed è 331 Gb.) Mail.log è attualmente fino a 34 Gb.

Ecco un esempio delle righe che vengono registrate. Ci sono migliaia di righe come questa con la data dopo "spamfilter:" incrementandolo fino a raggiungere la data corrente.

Apr 2 10:31:26 oss2 spamfilter: Sat Oct 15 13:11:34 2016 [2758] info: spamd: connection from ip6-localhost [::1]:54602 to port 783, fd 5 Apr 2 10:31:26 oss2 spamfilter: Sat Oct 15 13:11:34 2016 [2758] info: spamd: processing message (unknown) for spamd:5001 Apr 2 10:31:26 oss2 spamfilter: Sat Oct 15 13:11:36 2016 [2758] info: spamd: clean message (2.8/5.0) for spamd:5001 in 2.2 seconds, 52500 bytes. Apr 2 10:31:26 oss2 spamfilter: Sat Oct 15 13:11:36 2016 [2758] info: spamd: result: . 2 - BAYES_00,DKIM_SIGNED,DKIM_VALID,DKIM_VALID_AU,HTML_MESSAGE,MISSING_MID,NORMAL_HTTP_TO_IP,NO_RECEIVED,NO_RELAYS,RAZOR2_CF_RANGE_51_100,RAZOR2_CF_RANGE_E8_51_100,RAZOR2_CHECK,URIBL_BLOCKED,URI_DQ_UNSUB scantime=2.2,size=52500,user=spamd,uid=5001,required_score=5.0,rhost=ip6-localhost,raddr=::1,rport=54602,mid=(unknown),bayes=0.000000,autolearn=no autolearn_force=no Apr 2 10:31:26 oss2 spamfilter: Sat Oct 15 13:11:36 2016 [2756] info: prefork: child states: II Apr 2 10:31:26 oss2 spamfilter: Sat Oct 15 13:16:39 2016 [2758] info: spamd: connection from ip6-localhost [::1]:54668 to port 783, fd 5 Apr 2 10:31:26 oss2 spamfilter: Sat Oct 15 13:16:39 2016 [2758] info: spamd: processing message <0.0.4C.42D.1D226E3EE36EFD0.0@smtp9.valisetour.com> for spamd:5001 Apr 2 10:31:26 oss2 spamfilter: Sat Oct 15 13:16:41 2016 [2758] info: spamd: clean message (-1.8/5.0) for spamd:5001 in 1.8 seconds, 11325 bytes. Apr 2 10:31:26 oss2 spamfilter: Sat Oct 15 13:16:41 2016 [2758] info: spamd: result: . -1 - BAYES_00,DKIM_SIGNED,DKIM_VALID,DKIM_VALID_AU,HTML_MESSAGE,NO_RECEIVED,NO_RELAYS,PP_MIME_FAKE_ASCII_TEXT,URIBL_BLOCKED scantime=1.8,size=11325,user=spamd,uid=5001,required_score=5.0,rhost=ip6-localhost,raddr=::1,rport=54668,mid=<0.0.4C.42D.1D226E3EE36EFD0.0@smtp9.valisetour.com>,bayes=0.000000,autolearn=no autolearn_force=no Apr 2 10:31:26 oss2 spamfilter: Sat Oct 15 13:16:41 2016 [2756] info: prefork: child states: II Apr 2 10:31:26 oss2 spamfilter: Sat Oct 15 13:17:12 2016 [2758] info: spamd: connection from ip6-localhost [::1]:54698 to port 783, fd 5 Apr 2 10:31:26 oss2 spamfilter: Sat Oct 15 13:17:12 2016 [2758] info: spamd: processing message <0.0.61.22E.1D226E4025142E0.0@smtp9.valisetour.com> for spamd:5001 Apr 2 10:31:26 oss2 spamfilter: Sat Oct 15 13:17:12 2016 [2759] info: spamd: connection from ip6-localhost [::1]:54700 to port 783, fd 5 Apr 2 10:31:26 oss2 spamfilter: Sat Oct 15 13:17:12 2016 [2759] info: spamd: processing message <0.0.61.22E.1D226E4025142E0.0@smtp9.valisetour.com> for spamd:5001 Apr 2 10:31:26 oss2 spamfilter: Sat Oct 15 13:17:14 2016 [2758] info: spamd: clean message (-1.8/5.0) for spamd:5001 in 1.6 seconds, 11288 bytes. Apr 2 10:31:26 oss2 spamfilter: Sat Oct 15 13:17:14 2016 [2758] info: spamd: result: . -1 - BAYES_00,DKIM_SIGNED,DKIM_VALID,DKIM_VALID_AU,HTML_MESSAGE,NO_RECEIVED,NO_RELAYS,PP_MIME_FAKE_ASCII_TEXT,URIBL_BLOCKED scantime=1.6,size=11288,user=spamd,uid=5001,required_score=5.0,rhost=ip6-localhost,raddr=::1,rport=54698,mid=<0.0.61.22E.1D226E4025142E0.0@smtp9.valisetour.com>,bayes=0.000000,autolearn=no autolearn_force=no Apr 2 10:31:26 oss2 spamfilter: Sat Oct 15 13:17:14 2016 [2756] info: prefork: child states: IB Apr 2 10:31:26 oss2 spamfilter: Sat Oct 15 13:17:14 2016 [2759] info: spamd: clean message (-1.8/5.0) for spamd:5001 in 1.7 seconds, 11288 bytes. Apr 2 10:31:26 oss2 spamfilter: Sat Oct 15 13:17:14 2016 [2759] info: spamd: result: . -1 - BAYES_00,DKIM_SIGNED,DKIM_VALID,DKIM_VALID_AU,HTML_MESSAGE,NO_RECEIVED,NO_RELAYS,PP_MIME_FAKE_ASCII_TEXT,URIBL_BLOCKED scantime=1.7,size=11288,user=spamd,uid=5001,required_score=5.0,rhost=ip6-localhost,raddr=::1,rport=54700,mid=<0.0.61.22E.1D226E4025142E0.0@smtp9.valisetour.com>,bayes=0.000000,autolearn=no autolearn_force=no Apr 2 10:31:26 oss2 spamfilter: Sat Oct 15 13:17:14 2016 [2756] info: prefork: child states: II Apr 2 10:31:26 oss2 spamfilter: Sat Oct 15 13:20:21 2016 [2758] info: spamd: connection from ip6-localhost [::1]:54760 to port 783, fd 5 Apr 2 10:31:26 oss2 spamfilter: Sat Oct 15 13:20:21 2016 [2758] info: spamd: processing message <000601d2269a$90c7d4c0$c93956a9@LocalHost4dh> for spamd:5001 Apr 2 10:31:26 oss2 spamfilter: Sat Oct 15 13:20:23 2016 [2758] info: spamd: clean message (3.8/5.0) for spamd:5001 in 2.0 seconds, 5992 bytes. Apr 2 10:31:26 oss2 spamfilter: Sat Oct 15 13:20:23 2016 [2758] info: spamd: result: . 3 - BAYES_05,HTML_MESSAGE,MIME_HTML_ONLY,NO_RECEIVED,NO_RELAYS,RATWARE_OUTLOOK_NONAME,URIBL_BLOCKED,URIBL_PH_SURBL scantime=2.0,size=5992,user=spamd,uid=5001,required_score=5.0,rhost=ip6-localhost,raddr=::1,rport=54760,mid=<000601d2269a$90c7d4c0$c93956a9@LocalHost4dh>,bayes=0.029870,autolearn=no autolearn_force=no Apr 2 10:31:26 oss2 spamfilter: Sat Oct 15 13:20:23 2016 [2756] info: prefork: child states: II 

Sono sicuro che questo comportmento è sbagliato, ma non ho idea del motivo per cui sta facendo questo. Ho guardato attraverso diversi tutorial di configuration di Spamassassin, ma nulla mi dà un indizio su where guardare.

Se qualcuno potesse dirmi come risolvere questo problema, sarebbe fantastico. In caso contrario, mi potrebbe far sapere perché si ripeterebbe queste linee di registro per each e-mail in fase di elaborazione e where potrei iniziare a cercare una soluzione.

Se è richiesto più informazioni, fai solo sapere quello che ti serve. Non voglio inondare questo post con i file di configuration e altre informazioni di log che non sono necessarie.

One Solution collect form web for “Spamassassin logging”

Con enorme gratitudine ai ragazzi esperti della mailing list degli utenti di Spamassassin, ho finalmente trovato in fondo questo problema e ordinato.

Attraverso il mio process di configuration del server di posta, ho finito con uno script di helper /usr/bin/spamfilter.sh

Questo script stava scrivendo a un file di registro (/var/log/spamassassin/spamd.log) che non era incluso nel sistema logrotate. La directory logrotate.d contiene un file di configuration per ruotare /var/log/spamd.log. Questo (typo?) Significava che stava crescendo per sempre.

Ogni volta che un nuovo messaggio è stato elaborato dal sistema, tutto il contenuto di quel file di registro stava facendo lettura e riaccredito a mail.log e syslog.

Ho risolto il problema fissando il riferimento in spamfilter.sh per utilizzare il file /var/log/spamd.log, che verrà ruotato quotidianamente, impedendo così un grosso accumulo di messaggi per la re-logging.

Spero che questo sia utile per gli altri, ma desidero ringraziare i seguenti utenti della mailing list degli utenti Spamassassin specificamente per il loro aiuto e suggerimenti esperti:

  • Kevin McGrail
  • John Hardin
  • Dave Wreski
  • ap-ml
  • Tom Hendrikx

Fantastici ragazzi di lavoro. Grazie mille.

Suggerimenti per Linux e Windows Server, quali Ubuntu, Centos, Apache, Nginx, Debian e argomenti di rete.