У меня есть настройки Postfix, Postgrey, Spamassassin, amavisd-new, dovecot.
Когда я отправляю электронное письмо через систему, файл журнала сходит с ума.
Я не могу найти никакой информации, чтобы объяснить, что означают строки журнала, поэтому я не уверен, что лучше всего разместить здесь. Похоже, это возвращается к некоторой информации, полученной при установке серверов 6 месяцев назад, и предоставляет строки журнала для огромного количества сообщений, которые были ранее обработаны.
Это приводит к огромным файлам журналов (мой mail.log.1 был переключен сегодня утром и имеет размер 331 ГБ.) Mail.log в настоящее время имеет размер до 34 ГБ.
Вот образец записываемых строк. Есть тысячи таких строк с датой после "spamfilter:", увеличивающейся до текущей даты.
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
Я уверен, что это неправильное поведение, но я понятия не имею, почему это происходит. Я просмотрел несколько руководств по настройке Spamassassin, но ничто не дает мне подсказки, где искать.
Если бы кто-нибудь мог сказать мне, как это исправить, это было бы здорово. В противном случае я мог бы сообщить мне, почему он будет повторять эти строки журнала для каждого обрабатываемого электронного письма, и где я мог бы начать поиск решения.
Если требуется дополнительная информация, просто дайте мне знать, что вам нужно. Я не хочу забивать этот пост файлами конфигурации и дополнительной информацией журнала, которая не нужна.
С огромной благодарностью знающим ребятам из списка рассылки пользователей Spamassassin я, наконец, дошел до сути этой проблемы и разобрался с ней.
В процессе настройки почтового сервера я получил вспомогательный скрипт /usr/bin/spamfilter.sh
Этот сценарий записывал в файл журнала (/var/log/spamassassin/spamd.log), который не был включен в систему logrotate. Каталог logrotate.d содержит файл конфигурации для ротации /var/log/spamd.log. Эта (опечатка?) Означала, что он постоянно растет.
Каждый раз, когда система обрабатывала новое сообщение, все содержимое этого файла журнала считывалось и повторно регистрировалось как в mail.log, так и в syslog.
Я решил проблему, исправив ссылку в spamfilter.sh на использование файла /var/log/spamd.log, который будет обновляться ежедневно, что предотвратит накопление большого количества сообщений для повторной регистрации.
Я надеюсь, что это окажется полезным для других, но я хотел бы поблагодарить следующих пользователей из списка рассылки Spamassassin за их помощь и советы экспертов:
Отличная работа, ребята. Огромное спасибо.