1 (edited by Marcel F. 2013-11-08 21:18:41)

Topic: Slow mail processing in cause of Amavis

======== Required information ====
- iRedMail version: 0.8.5
- Store mail accounts in which backend (LDAP/MySQL/PGSQL): LDAP
- Linux/BSD distribution name and version:  CentOS 6.4
- Related log if you're reporting an issue:
====

Hi,

i've installed iRedMail on an VM.
Today morning i moved this VM to an other Server. This included an change of the IP.

Since that, it takes up to one minute till mails are being processed and delivered to INBOX.
When i turn off Amavis (remove content_filter = smtp-amavis:[127.0.0.1]:10024 from /etc/postfix/main.cf) mails will be deliverd immediately into INBOX.

Here is an snip from /var/log/maillog when Amavis is turned on.

Nov  8 13:33:01 iredmailer01 postfix/smtpd[2323]: connect from unknown[XXX.XXX.XXX.XXX]
Nov  8 13:33:01 iredmailer01 postfix/smtpd[2323]: 4772A540024: client=unknown[XXX.XXX.XXX.XXX]
Nov  8 13:33:01 iredmailer01 postfix/cleanup[2330]: 4772A540024: message-id=<527CD9FB.9010800@example.de>
Nov  8 13:33:01 iredmailer01 postfix/qmgr[2174]: 4772A540024: from=<user.name@example.de>, size=1318, nrcpt=1 (queue active)
Nov  8 13:33:01 iredmailer01 postfix/smtpd[2323]: disconnect from unknown[XXX.XXX.XXX.XXX]

Nov  8 13:33:59 iredmailer01 postfix/smtpd[2334]: connect from iredmailer01.example.com[127.0.0.1]
Nov  8 13:33:59 iredmailer01 postfix/smtpd[2334]: A4664540A5E: client=iredmailer01.example.com[127.0.0.1]
Nov  8 13:33:59 iredmailer01 postfix/cleanup[2330]: A4664540A5E: message-id=<527CD9FB.9010800@example.de>
Nov  8 13:33:59 iredmailer01 postfix/smtpd[2334]: disconnect from iredmailer01.example.com[127.0.0.1]
Nov  8 13:33:59 iredmailer01 postfix/qmgr[2174]: A4664540A5E: from=<user.name@example.de>, size=1993, nrcpt=1 (queue active)
Nov  8 13:33:59 iredmailer01 amavis[2199]: (02199-01) Passed CLEAN {RelayedInternal}, LOCAL [XXX.XXX.XXX.XXX]:56292 [YYY.YYY.YYY.YYY] <user.name@example.de> -> <mf@test.iredmail.example.com>, Message-ID: <527CD9FB.9010800@example.de>, mail_id: JYC_h0mwbrpG, Hits: 2.273, size: 1318, queued_as: A4664540A5E, 58366 ms
Nov  8 13:33:59 iredmailer01 postfix/smtp[2331]: 4772A540024: to=<mf@test.iredmail.example.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=58, delays=0.06/0.01/0.01/58, 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 A4664540A5E)
Nov  8 13:33:59 iredmailer01 postfix/qmgr[2174]: 4772A540024: removed
Nov  8 13:33:59 iredmailer01 postfix/pipe[2338]: A4664540A5E: to=<mf@test.iredmail.example.com>, relay=dovecot, delay=0.09, delays=0.01/0.03/0/0.06, dsn=2.0.0, status=sent (delivered via dovecot service)
Nov  8 13:33:59 iredmailer01 postfix/qmgr[2174]: A4664540A5E: removed

Any Idea? :-)

Thx,
Marcel

----

Spider Email Archiver: On-Premises, lightweight email archiving software developed by iRedMail team. Supports Amazon S3 compatible storage and custom branding.

2

Re: Slow mail processing in cause of Amavis

Maybe its related, but i also have the behavior that the login into iRedAdmin also takes up to one minute.
Login to Roundcube is fine.

3

Re: Slow mail processing in cause of Amavis

*) Did you hardcode IP address of old VM in Amavisd config file?
*) Do you have current IP address listed in "@mynetworks" in Amavisd config file?

4

Re: Slow mail processing in cause of Amavis

ZhangHuangbin wrote:

*) Did you hardcode IP address of old VM in Amavisd config file?

NO

ZhangHuangbin wrote:

*) Do you have current IP address listed in "@mynetworks" in Amavisd config file?

The private one, which is bind on eth0, YES

5

Re: Slow mail processing in cause of Amavis

No idea yet, sorry. Amavisd shows normal log, no unusual log at all.

6

Re: Slow mail processing in cause of Amavis

Found the problem.

I forgot to update /etc/resolv.conf with name-servers that are reachable from the new network. :-)