[postfix-users] Zimbra - Postfix - Mailarchiva - 5 Sekunden Verzögerung beim Verschicken von mails

[Helge Hansemann via postfix-users]

Hallo,

ich bereite gerade den Umstieg von Zimbra 7.x unter Ubuntu 8.04 auf Zimbra 8.x auf Ubuntu 12.04 vor und habe jetzt folgendes Problem beobachtet:

Eine Mail die im Webinterface von Zimbra verschickt wird dauert ca 5s (solange kann nicht weiter gearbeitet werden) wenn ich in der main.cf einen milter definiere:

smtpd_milters = inet:server.domain.com:8092

Ohne den milter wird die mail sofort verschickt.
Bei Zimbra 7.x trat dieses Problem nicht auf.

Im Logfile ist das anhand der Timestamps ganz gut zu sehen:

Oct 24 14:48:49 mail postfix/smtpd[25160]: connect from mail.domain.com [192.168.16.27]

<--> ???

Oct 24 14:48:54 mail postfix/smtpd[25160]: NOQUEUE: filter: RCPT from mail.domain.com[192.168.16.27]: <user at domain.com>: Sender address triggers FILTER smtp-amavis:[127.0.0.1]:10026; from=<user at domain.com> to=<user at domain.com> proto=ESMTP helo=<mail.domain.com>
Oct 24 14:48:54 mail postfix/smtpd[25160]: E3EB8FA38BD: client=mail.domain.com[192.168.16.27]
Oct 24 14:48:54 mail postfix/cleanup[25165]: E3EB8FA38BD: message-id=<657406261.8.1382618929724.JavaMail.zimbra at domain.com>
Oct 24 14:48:54 mail postfix/qmgr[28945]: E3EB8FA38BD: from=<user at domain.com>, size=782, nrcpt=1 (queue active)
Oct 24 14:48:54 mail postfix/smtpd[25160]: disconnect from mail.domain.com[192.168.16.27]
Oct 24 14:48:55 mail postfix/dkimmilter/smtpd[25169]: connect from localhost[127.0.0.1]
Oct 24 14:48:55 mail postfix/dkimmilter/smtpd[25169]: 2896EFA38DA: client=localhost[127.0.0.1]
Oct 24 14:48:55 mail postfix/cleanup[25165]: 2896EFA38DA: message-id=<657406261.8.1382618929724.JavaMail.zimbra at domain.com>
Oct 24 14:48:55 mail postfix/qmgr[28945]: 2896EFA38DA: from=<user at domain.com>, size=1223, nrcpt=1 (queue active)
Oct 24 14:48:55 mail postfix/dkimmilter/smtpd[25169]: disconnect from localhost[127.0.0.1]
Oct 24 14:48:55 mail postfix/smtp[25166]: E3EB8FA38BD: to=<user at domain.com>, relay=127.0.0.1[127.0.0.1]:10026, delay=0.31, delays=0.06/0.02/0.01/0.23, dsn=2.0.0, status=sent (250 2.0.0 from MTA(smtp:[127.0.0.1]:10030): 250 2.0.0 Ok: queued as 2896EFA38DA)
Oct 24 14:48:55 mail postfix/qmgr[28945]: E3EB8FA38BD: removed
Oct 24 14:48:55 mail postfix/amavisd/smtpd[25173]: connect from localhost[127.0.0.1]
Oct 24 14:48:55 mail postfix/amavisd/smtpd[25173]: 78E02FA38CB: client=localhost[127.0.0.1]
Oct 24 14:48:55 mail postfix/cleanup[25165]: 78E02FA38CB: message-id=<657406261.8.1382618929724.JavaMail.zimbra at domain.com>
Oct 24 14:48:55 mail postfix/qmgr[28945]: 78E02FA38CB: from=<user at domain.com>, size=1779, nrcpt=1 (queue active)
Oct 24 14:48:55 mail postfix/amavisd/smtpd[25173]: disconnect from localhost[127.0.0.1]
Oct 24 14:48:55 mail postfix/smtp[25171]: 2896EFA38DA: to=<user at domain.com>, relay=127.0.0.1[127.0.0.1]:10032, delay=0.35, delays=0.04/0.02/0.01/0.28, 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 78E02FA38CB)
Oct 24 14:48:55 mail postfix/qmgr[28945]: 2896EFA38DA: removed
Oct 24 14:48:55 mail postfix/lmtp[25174]: 78E02FA38CB: to=<user at domain.com>, relay=mail.domain.com[192.168.16.27]:7025, delay=0.09, delays=0/0.02/0/0.07, dsn=2.1.5, status=sent (250 2.1.5 Delivery OK)
Oct 24 14:48:55 mail postfix/qmgr[28945]: 78E02FA38CB: removed

Die Mail sollte als Kopie an den Mailarchiva Dienst server.domain.com auf Port 8092 gehen. Das funktioniert auch.

Ich suche jetzt nach Ideen, woran diese "Verzögerung" liegen könnte, bzw. wie ich die Sache genauer untersuchen könnte.

Vielen Dank!