Os logs acima são o comportamento esperado do postfix e do amavis. Para o filtro de conteúdo após a fila, como o amavis, o filtro precisa reinjetar o email para postfix . Para mais informações, consulte a documentação oficial . Aqui algumas explicações da ação postfix e amavis.
Jul 19 07:59:06 mail postfix/smtpd[15492]: connect from unknown[37.215.63.185]
O cliente de 37.215.63.185 se conecta ao seu servidor de e-mail.
Jul 19 07:59:07 mail postfix/smtpd[15492]: NOQUEUE: reject_warning: RCPT from unknown[37.215.63.185]: 504 5.5.2 <37.215.63.185>: Helo command rejected: need fully-qualified hostname; from=<> to=<[email protected]> proto=SMTP helo=<37.215.63.185>
O postfix avisa porque o comando HELO contém um nome de host não totalmente qualificado, mas ele ainda passa o email porque você especifica warn_if_reject em main.cf
.
Jul 19 07:59:07 mail postgrey[13086]: action=pass, reason=triplet found, delay=316, client_name=unknown, client_address=37.215.63.185, [email protected]
Postgrey não rejeita o e-mail porque o cliente já passou no cheque do postgrey antes.
Jul 19 07:59:07 mail postfix/smtpd[15492]: B8D5D20BDD: client=unknown[37.215.63.185]
Jul 19 07:59:09 mail postfix/cleanup[15500]: B8D5D20BDD: message-id=<>
Jul 19 07:59:09 mail postfix/qmgr[3224]: B8D5D20BDD: from=<>, size=493, nrcpt=1 (queue active)
O postfix atribui o id-da-fila ao e-mail porque o passe para todas as restrições. Neste estágio, posfix ACEITOU o email.
Jul 19 07:59:09 mail amavis[11960]: (11960-14) NOTICE: reconnecting in response to: err=2006, HY000, DBD::mysql::st execute failed: MySQL server has gone away at (eval 111) line 170, <GEN39> line 9463.
O Postfix começou a transferir seu e-mail para o amavis, então o amavis está notificando que a conexão do mysql não foi bem-sucedida.
Jul 19 07:59:10 mail postfix/smtpd[15492]: disconnect from unknown[37.215.63.185]
O cliente de 37.215.63.185 desconecta do seu servidor porque o Postfix aceita o email.
Jul 19 07:59:11 mail postfix/smtpd[15505]: connect from localhost[127.0.0.1]
O Amavis está tentando reinjetar o e-mail na porta 10025. Por padrão, o Amavis APENAS reinicia a mensagem se o status do e-mail for PASSED CLEAN (com base na linha de log abaixo).
Jul 19 07:59:11 mail postfix/smtpd[15505]: 96E1820C16: client=localhost[127.0.0.1]
Jul 19 07:59:11 mail postfix/cleanup[15500]: 96E1820C16: message-id=<[email protected]>
Jul 19 07:59:11 mail postfix/qmgr[3224]: 96E1820C16: from=<>, size=1166, nrcpt=1 (queue active)
Obviamente, o Postfix aceitou e-mail da amavis.
Jul 19 07:59:11 mail postfix/smtpd[15505]: disconnect from localhost[127.0.0.1]
O Amavis terminou o processo de envio
Jul 19 07:59:11 mail amavis[11960]: (11960-14) Passed CLEAN {RelayedInbound}, [37.215.63.185]:55743 <> -> <[email protected]>, Queue-ID: B8D5D20BDD, mail_id: d6hKjzEiMtex, Hits: 1.296, size: 493, queued_as: 96E1820C16, 2128 ms
Amavis registra sua ação no maillog. A pontuação do SpamAssasin é relativamente baixa (1.296) e o email passou em todos os cheques do amavis.
Jul 19 07:59:11 mail postfix/smtp[15502]: B8D5D20BDD: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:10024, delay=4.2, delays=2.1/0.01/0.01/2.1, dsn=2.0.0, status=sent (250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 96E1820C16)
O postfix notifica que a varredura de e-mail foi bem-sucedida.
Jul 19 07:59:11 mail postfix/qmgr[3224]: B8D5D20BDD: removed
A fila foi removida. Então, e-mail entregue ao dovecot abaixo.
Jul 19 07:59:11 mail dovecot: auth-worker(15508): mysql(localhost): Connected to database vimbadmin
Jul 19 07:59:11 mail dovecot: lda([email protected]): sieve: msgid=<[email protected]>: stored mail into mailbox 'INBOX'
Jul 19 07:59:11 mail postfix/pipe[15506]: 96E1820C16: to=<[email protected]>, relay=dovecot, delay=0.05, delays=0/0/0/0.04, dsn=2.0.0, status=sent (delivered via dovecot service)
Jul 19 07:59:11 mail postfix/qmgr[3224]: 96E1820C16: removed