1 Topic by kurmet (edited by kurmet 2015-02-24 15:20:56)

  • kurmet
  • Member
  • Offline
  • Registered: 2015-02-24
  • Posts: 7

Topic: postfix sometimes refuses to send large emails

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

Hello ZhangHuangbin,

I have installed iRedMail 0.9.0 on clean CentOS 7 system and configured my
DNS records A, MX entries with my hosting provider and PTR entry with my IP address provider.

In Outlook 2013 and Mozilla Thunderbird it refuses to send large emails, but plain text small emails sends immideatly. I have configured message size limit to 100 MB and mailbox size limit to 10 GB at postfix conf.

However, sometimes, Outlook 2013 is sends large emails, but it takes half an hour to send it, trying again and again.

In the log, I have tried and succeed (after while) to send large email from Outlook 2013 myemail@myhostname.com to testemail@myhostname.com.
Also, I have send plain text email to anotheremail@anotherhost.com, it send immideatly.

Here is the log records for emails that doesn't send:
------------------------------
Feb 24 11:43:37 mail postfix/smtpd[3692]: warning: hostname mail.myhostname.com does not resolve to address XXX.XXX.XXX.XXX
Feb 24 11:43:37 mail postfix/smtpd[3692]: connect from unknown[XXX.XXX.XXX.XXX]
Feb 24 11:43:37 mail cbpolicyd[1647]: module=Core, action=pass, host=XXX.XXX.XXX.XXX, from=[192.168.0.111], to=myemail@myhostname.com, reason=db_failure_bypass
Feb 24 11:43:37 mail postfix/smtpd[3692]: 37E888319415: client=unknown[XXX.XXX.XXX.XXX], sasl_method=PLAIN, sasl_username=myemail@myhostname.com
Feb 24 11:43:38 mail cbpolicyd[1648]: module=Core, action=pass, host=XXX.XXX.XXX.XXX, from=[192.168.0.111], to=myemail@myhostname.com, reason=db_failure_bypass
Feb 24 11:43:38 mail postfix/cleanup[3696]: 37E888319415: message-id=<4chiic5jc87ntcy6cur14080.1424755581861@email.android.com>
Feb 24 11:43:38 mail postfix/qmgr[3279]: 37E888319415: from=<myemail@myhostname.com>, size=1243, nrcpt=1 (queue active)
Feb 24 11:43:39 mail postfix/smtpd[3707]: warning: hostname mail.myhostname.com does not resolve to address XXX.XXX.XXX.XXX
Feb 24 11:43:39 mail postfix/smtpd[3707]: connect from unknown[XXX.XXX.XXX.XXX]
Feb 24 11:43:39 mail cbpolicyd[1649]: module=Core, action=pass, host=XXX.XXX.XXX.XXX, from=Kurmet, to=myemail@myhostname.com, reason=db_failure_bypass
Feb 24 11:43:39 mail postfix/smtpd[3707]: 930008319416: client=unknown[XXX.XXX.XXX.XXX], sasl_method=LOGIN, sasl_username=myemail@myhostname.com
-----------------------------
After a while it sends it:
-----------------------------
Feb 24 11:43:39 mail postfix/cleanup[3696]: 930008319416: message-id=<071901d04ff4$d93a8550$8baf8ff0$@myhostname.com>
Feb 24 11:43:40 mail postfix/smtpd[3713]: connect from mail.myhostname.com[127.0.0.1]
Feb 24 11:43:40 mail postfix/smtpd[3713]: 1BF3F8319417: client=mail.myhostname.com[127.0.0.1]
Feb 24 11:43:40 mail postfix/cleanup[3714]: 1BF3F8319417: message-id=<4chiic5jc87ntcy6cur14080.1424755581861@email.android.com>
Feb 24 11:43:40 mail postfix/smtpd[3713]: disconnect from mail.myhostname.com[127.0.0.1]
Feb 24 11:43:40 mail postfix/qmgr[3279]: 1BF3F8319417: from=<myemail@myhostname.com>, size=2277, nrcpt=1 (queue active)
Feb 24 11:43:40 mail postfix/pipe[3715]: 1BF3F8319417: to=<testemail@myhostname.com>, relay=dovecot, delay=0.17, delays=0.03/0.02/0/0.12, dsn=2.0.0, status=sent (delivered via dovecot service)
Feb 24 11:43:40 mail postfix/qmgr[3279]: 1BF3F8319417: removed
Feb 24 11:43:40 mail amavis[1919]: (01919-01) Passed CLEAN {RelayedInternal}, MYUSERS LOCAL [XXX.XXX.XXX.XXX]:47466 [XXX.XXX.XXX.XXX] <myemail@myhostname.com> -> <testemail@myhostname.com>, Queue-ID: 37E888319415, Message-ID: <4chiic5jc87ntcy6cur14080.1424755581861@email.android.com>, mail_id: muwwXnXgbrRC, Hits: -0.999, size: 1243, queued_as: 1BF3F8319417, dkim_new=dkim:myhostname.com, 1804 ms
Feb 24 11:43:40 mail postfix/smtp[3702]: 37E888319415: to=<testemail@myhostname.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=3.1, delays=1.3/0.03/0.02/1.8, 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 1BF3F8319417)
Feb 24 11:43:40 mail postfix/qmgr[3279]: 37E888319415: removed
Feb 24 11:48:17 mail postfix/smtpd[3552]: timeout after DATA (253926 bytes) from unknown[XXX.XXX.XXX.XXX]
Feb 24 11:48:38 mail postfix/smtpd[3692]: timeout after END-OF-MESSAGE from unknown[XXX.XXX.XXX.XXX]
Feb 24 11:48:39 mail postfix/smtpd[3707]: timeout after DATA (303066 bytes) from unknown[XXX.XXX.XXX.XXX]
Feb 24 11:53:17 mail postfix/smtpd[3552]: disconnect from unknown[XXX.XXX.XXX.XXX]
Feb 24 11:53:18 mail postfix/anvil[3581]: statistics: max connection rate 2/60s for (smtp:XXX.XXX.XXX.XXX) at Feb 24 11:43:39
Feb 24 11:53:18 mail postfix/anvil[3581]: statistics: max connection count 2 for (smtp:XXX.XXX.XXX.XXX) at Feb 24 11:43:39
Feb 24 11:53:18 mail postfix/anvil[3581]: statistics: max cache size 2 at Feb 24 11:43:37
Feb 24 11:53:38 mail postfix/smtpd[3692]: disconnect from unknown[XXX.XXX.XXX.XXX]
-------------------------
Here is log file:
-------------------------
Feb 24 11:43:37 mail postfix/smtpd[3692]: warning: hostname mail.myhostname.com does not resolve to address XXX.XXX.XXX.XXX
Feb 24 11:43:37 mail postfix/smtpd[3692]: connect from unknown[XXX.XXX.XXX.XXX]
Feb 24 11:43:37 mail cbpolicyd[1647]: module=Core, action=pass, host=XXX.XXX.XXX.XXX, from=[192.168.0.111], to=myemail@myhostname.com, reason=db_failure_bypass
Feb 24 11:43:37 mail postfix/smtpd[3692]: 37E888319415: client=unknown[XXX.XXX.XXX.XXX], sasl_method=PLAIN, sasl_username=myemail@myhostname.com
Feb 24 11:43:38 mail cbpolicyd[1648]: module=Core, action=pass, host=XXX.XXX.XXX.XXX, from=[192.168.0.111], to=myemail@myhostname.com, reason=db_failure_bypass
Feb 24 11:43:38 mail postfix/cleanup[3696]: 37E888319415: message-id=<4chiic5jc87ntcy6cur14080.1424755581861@email.android.com>
Feb 24 11:43:38 mail postfix/qmgr[3279]: 37E888319415: from=<myemail@myhostname.com>, size=1243, nrcpt=1 (queue active)
Feb 24 11:43:39 mail postfix/smtpd[3707]: warning: hostname mail.myhostname.com does not resolve to address XXX.XXX.XXX.XXX
Feb 24 11:43:39 mail postfix/smtpd[3707]: connect from unknown[XXX.XXX.XXX.XXX]
Feb 24 11:43:39 mail cbpolicyd[1649]: module=Core, action=pass, host=XXX.XXX.XXX.XXX, from=Kurmet, to=myemail@myhostname.com, reason=db_failure_bypass
Feb 24 11:43:39 mail postfix/smtpd[3707]: 930008319416: client=unknown[XXX.XXX.XXX.XXX], sasl_method=LOGIN, sasl_username=myemail@myhostname.com
Feb 24 11:43:39 mail postfix/cleanup[3696]: 930008319416: message-id=<071901d04ff4$d93a8550$8baf8ff0$@myhostname.com>
Feb 24 11:43:40 mail postfix/smtpd[3713]: connect from mail.myhostname.com[127.0.0.1]
Feb 24 11:43:40 mail postfix/smtpd[3713]: 1BF3F8319417: client=mail.myhostname.com[127.0.0.1]
Feb 24 11:43:40 mail postfix/cleanup[3714]: 1BF3F8319417: message-id=<4chiic5jc87ntcy6cur14080.1424755581861@email.android.com>
Feb 24 11:43:40 mail postfix/smtpd[3713]: disconnect from mail.myhostname.com[127.0.0.1]
Feb 24 11:43:40 mail postfix/qmgr[3279]: 1BF3F8319417: from=<myemail@myhostname.com>, size=2277, nrcpt=1 (queue active)
Feb 24 11:43:40 mail postfix/pipe[3715]: 1BF3F8319417: to=<testemail@myhostname.com>, relay=dovecot, delay=0.17, delays=0.03/0.02/0/0.12, dsn=2.0.0, status=sent (delivered via dovecot service)
Feb 24 11:43:40 mail postfix/qmgr[3279]: 1BF3F8319417: removed
Feb 24 11:43:40 mail amavis[1919]: (01919-01) Passed CLEAN {RelayedInternal}, MYUSERS LOCAL [XXX.XXX.XXX.XXX]:47466 [XXX.XXX.XXX.XXX] <myemail@myhostname.com> -> <testemail@myhostname.com>, Queue-ID: 37E888319415, Message-ID: <4chiic5jc87ntcy6cur14080.1424755581861@email.android.com>, mail_id: muwwXnXgbrRC, Hits: -0.999, size: 1243, queued_as: 1BF3F8319417, dkim_new=dkim:myhostname.com, 1804 ms
Feb 24 11:43:40 mail postfix/smtp[3702]: 37E888319415: to=<testemail@myhostname.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=3.1, delays=1.3/0.03/0.02/1.8, 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 1BF3F8319417)
Feb 24 11:43:40 mail postfix/qmgr[3279]: 37E888319415: removed
Feb 24 11:48:17 mail postfix/smtpd[3552]: timeout after DATA (253926 bytes) from unknown[XXX.XXX.XXX.XXX]
Feb 24 11:48:38 mail postfix/smtpd[3692]: timeout after END-OF-MESSAGE from unknown[XXX.XXX.XXX.XXX]
Feb 24 11:48:39 mail postfix/smtpd[3707]: timeout after DATA (303066 bytes) from unknown[XXX.XXX.XXX.XXX]
Feb 24 11:53:17 mail postfix/smtpd[3552]: disconnect from unknown[XXX.XXX.XXX.XXX]
Feb 24 11:53:18 mail postfix/anvil[3581]: statistics: max connection rate 2/60s for (smtp:XXX.XXX.XXX.XXX) at Feb 24 11:43:39
Feb 24 11:53:18 mail postfix/anvil[3581]: statistics: max connection count 2 for (smtp:XXX.XXX.XXX.XXX) at Feb 24 11:43:39
Feb 24 11:53:18 mail postfix/anvil[3581]: statistics: max cache size 2 at Feb 24 11:43:37
Feb 24 11:53:38 mail postfix/smtpd[3692]: disconnect from unknown[XXX.XXX.XXX.XXX]
Feb 24 11:53:38 mail clamd[1545]: SelfCheck: Database status OK.
Feb 24 11:53:39 mail postfix/smtpd[3707]: disconnect from unknown[XXX.XXX.XXX.XXX]
Feb 24 11:55:14 mail postfix/smtpd[3707]: warning: hostname mail.myhostname.com does not resolve to address XXX.XXX.XXX.XXX
Feb 24 11:55:14 mail postfix/smtpd[3707]: connect from unknown[XXX.XXX.XXX.XXX]
Feb 24 11:55:15 mail postfix/smtpd[3707]: 29B0E8319414: client=unknown[XXX.XXX.XXX.XXX], sasl_method=LOGIN, sasl_username=myemail@myhostname.com
Feb 24 11:55:15 mail postfix/cleanup[3696]: 29B0E8319414: message-id=<071f01d04ff6$77c7db40$675791c0$@myhostname.com>
Feb 24 11:55:15 mail postfix/qmgr[3279]: 29B0E8319414: from=<myemail@myhostname.com>, size=2783, nrcpt=1 (queue active)
Feb 24 11:55:15 mail postfix/smtpd[3707]: 442F58319415: client=unknown[XXX.XXX.XXX.XXX], sasl_method=LOGIN, sasl_username=myemail@myhostname.com
Feb 24 11:55:15 mail postfix/cleanup[3696]: 442F58319415: message-id=<072401d04ff6$77e77130$67b65390$@myhostname.com>
Feb 24 11:55:17 mail postfix/smtpd[4047]: connect from mail.myhostname.com[127.0.0.1]
Feb 24 11:55:17 mail postfix/smtpd[4047]: A174F8319416: client=mail.myhostname.com[127.0.0.1]
Feb 24 11:55:17 mail postfix/cleanup[4052]: A174F8319416: message-id=<071f01d04ff6$77c7db40$675791c0$@myhostname.com>
Feb 24 11:55:17 mail postfix/smtpd[4047]: disconnect from mail.myhostname.com[127.0.0.1]
Feb 24 11:55:17 mail postfix/qmgr[3279]: A174F8319416: from=<myemail@myhostname.com>, size=3859, nrcpt=1 (queue active)
Feb 24 11:55:17 mail amavis[1920]: (01920-01) Passed CLEAN {RelayedInternal}, MYUSERS LOCAL [XXX.XXX.XXX.XXX]:52932 [XXX.XXX.XXX.XXX] <myemail@myhostname.com> -> <testemail@myhostname.com>, Queue-ID: 29B0E8319414, Message-ID: <071f01d04ff6$77c7db40$675791c0$@myhostname.com>, mail_id: KsGw5FqAbQ2o, Hits: -0.998, size: 2783, queued_as: A174F8319416, dkim_new=dkim:myhostname.com, 2448 ms
Feb 24 11:55:17 mail postfix/smtp[4041]: 29B0E8319414: to=<testemail@myhostname.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=2.7, delays=0.25/0.01/0/2.5, 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 A174F8319416)
Feb 24 11:55:17 mail postfix/qmgr[3279]: 29B0E8319414: removed
Feb 24 11:55:17 mail postfix/pipe[4053]: A174F8319416: to=<testemail@myhostname.com>, relay=dovecot, delay=0.12, delays=0.03/0.01/0/0.08, dsn=2.0.0, status=sent (delivered via dovecot service)
Feb 24 11:55:17 mail postfix/qmgr[3279]: A174F8319416: removed
Feb 24 11:58:57 mail postfix/smtpd[4123]: warning: hostname mail.myhostname.com does not resolve to address XXX.XXX.XXX.XXX
Feb 24 11:58:57 mail postfix/smtpd[4123]: connect from unknown[XXX.XXX.XXX.XXX]
Feb 24 11:58:57 mail postfix/smtpd[4123]: C9AFA8319414: client=unknown[XXX.XXX.XXX.XXX], sasl_method=LOGIN, sasl_username=myemail@myhostname.com
Feb 24 11:58:58 mail postfix/cleanup[4134]: C9AFA8319414: message-id=<072a01d04ff6$fc785cc0$f5691640$@myhostname.com>
Feb 24 12:00:15 mail postfix/smtpd[3707]: timeout after DATA (204786 bytes) from unknown[XXX.XXX.XXX.XXX]
Feb 24 12:03:18 mail postfix/anvil[3581]: statistics: max connection rate 1/60s for (smtp:XXX.XXX.XXX.XXX) at Feb 24 11:55:14
Feb 24 12:03:18 mail postfix/anvil[3581]: statistics: max connection count 2 for (smtp:XXX.XXX.XXX.XXX) at Feb 24 11:58:57
Feb 24 12:03:18 mail postfix/anvil[3581]: statistics: max cache size 1 at Feb 24 11:55:14
Feb 24 12:03:58 mail postfix/smtpd[4123]: timeout after DATA (294876 bytes) from unknown[XXX.XXX.XXX.XXX]
Feb 24 12:05:15 mail clamd[1545]: SelfCheck: Database status OK.
Feb 24 12:05:15 mail postfix/smtpd[3707]: disconnect from unknown[XXX.XXX.XXX.XXX]
Feb 24 12:08:58 mail postfix/smtpd[4123]: disconnect from unknown[XXX.XXX.XXX.XXX]
Feb 24 12:15:15 mail clamd[1545]: SelfCheck: Database status OK.
Feb 24 12:20:52 mail postfix/smtpd[5014]: warning: hostname mail.myhostname.com does not resolve to address XXX.XXX.XXX.XXX
Feb 24 12:20:52 mail postfix/smtpd[5014]: connect from unknown[XXX.XXX.XXX.XXX]
Feb 24 12:20:53 mail postfix/smtpd[5014]: 2EA598319414: client=unknown[XXX.XXX.XXX.XXX], sasl_method=LOGIN, sasl_username=myemail@myhostname.com
Feb 24 12:20:53 mail postfix/cleanup[5033]: 2EA598319414: message-id=<073001d04ffa$0c6888a0$253999e0$@myhostname.com>
Feb 24 12:20:53 mail postfix/qmgr[3279]: 2EA598319414: from=<myemail@myhostname.com>, size=4913, nrcpt=1 (queue active)
Feb 24 12:20:53 mail postfix/smtpd[5014]: 5851C8319415: client=unknown[XXX.XXX.XXX.XXX], sasl_method=LOGIN, sasl_username=myemail@myhostname.com
Feb 24 12:20:53 mail postfix/cleanup[5033]: 5851C8319415: message-id=<073501d04ffa$0c8d0090$25a701b0$@myhostname.com>
Feb 24 12:20:55 mail postfix/smtpd[5045]: connect from mail.myhostname.com[127.0.0.1]
Feb 24 12:20:55 mail postfix/smtpd[5045]: 95FD08319416: client=mail.myhostname.com[127.0.0.1]
Feb 24 12:20:55 mail postfix/cleanup[5046]: 95FD08319416: message-id=<073001d04ffa$0c6888a0$253999e0$@myhostname.com>
Feb 24 12:20:55 mail postfix/smtpd[5045]: disconnect from mail.myhostname.com[127.0.0.1]
Feb 24 12:20:55 mail postfix/qmgr[3279]: 95FD08319416: from=<myemail@myhostname.com>, size=5995, nrcpt=1 (queue active)
Feb 24 12:20:55 mail amavis[1919]: (01919-02) Passed CLEAN {RelayedInternal}, MYUSERS LOCAL [XXX.XXX.XXX.XXX]:53221 [XXX.XXX.XXX.XXX] <myemail@myhostname.com> -> <Anotheremail@anotherhost.com>, Queue-ID: 2EA598319414, Message-ID: <073001d04ffa$0c6888a0$253999e0$@myhostname.com>, mail_id: y5VTRUcBwOpF, Hits: -0.999, size: 4913, queued_as: 95FD08319416, dkim_new=dkim:myhostname.com, 2376 ms
Feb 24 12:20:55 mail postfix/smtp[5040]: 2EA598319414: to=<Anotheremail@anotherhost.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=2.7, delays=0.28/0.01/0/2.4, 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 95FD08319416)
Feb 24 12:20:55 mail postfix/qmgr[3279]: 2EA598319414: removed
Feb 24 12:20:56 mail postfix/smtp[5047]: 95FD08319416: to=<Anotheremail@anotherhost.com>, relay=mail.anotherhost.com[YYY.YYY.YYY.YYY]:25, delay=1.1, delays=0.03/0.02/0.78/0.24, dsn=2.0.0, status=sent (250 3678278 message accepted for delivery)
Feb 24 12:20:56 mail postfix/qmgr[3279]: 95FD08319416: removed
------------------------------

Please help, thanks a lot.

I have read about hostname mail.myhostname.com does not resolve to address XXX.XXX.XXX.XXX, but tested the DNS entry and it was fine. I even changed DNS server resolv.conf to 8.8.8.8 google DNS but it did not help

----

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

2 Reply by ZhangHuangbin

  • ZhangHuangbin
  • ZhangHuangbin
  • iRedMail Developers
  • Offline
  • Registered: 2009-05-06
  • Posts: 30,770

Re: postfix sometimes refuses to send large emails

kurmet wrote:

Feb 24 11:43:39 mail cbpolicyd[1649]: module=Core, action=pass, host=XXX.XXX.XXX.XXX, from=Kurmet, to=myemail@myhostname.com, reason=db_failure_bypass

Looks like you don't have correct SQL username/password in Cluebringer config file /etc/policyd/cluebringer.conf, please double check and verify it with SQL command line tool.

kurmet wrote:

Feb 24 12:20:52 mail postfix/smtpd[5014]: warning: hostname mail.myhostname.com does not resolve to address XXX.XXX.XXX.XXX

Do you have correct PTR record in DNS for mail.myhostname.com? and /etc/hosts and /var/spool/postfix/etc/hosts?

3 Reply by kurmet

  • kurmet
  • Member
  • Offline
  • Registered: 2015-02-24
  • Posts: 7

Re: postfix sometimes refuses to send large emails

ZhangHuangbin,

thank you for your reply.

my /etc/hosts and /var/spool/postfix/etc/hosts was
-------------------------------------
127.0.0.1 mail.myhostname.com mx localhost localdomain
-------------------------------------

I changed /etc/hosts and /var/spool/postfix/etc/hosts:
---------
127.0.0.1 mail.myhostname.com localhost localdomain
XXX.XXX.XXX.XXX mail.myhostname.com
---------

After server restart it did work better, but again Outlook sending error of large emails for some period of time happens, then it sends.
Now in maillog it doesn't say anything about resolving XXX.XXX.XXX.XXX and myhostname.com,
but it does show smtpd disconnects, for example when sending from testemail@myhostname.com to myemail@myhostname.com, and mail cbpolicyd (reason=db_failure_bypass)

I did check Cluebringer config file /etc/policyd/cluebringer.conf and found cluebringer username and password, but I don't know how to check and verify it with SQL command line tool. Please can you tell me how should I do this?

Maillog:
-------------------
Feb 24 16:15:23 mail postfix/smtpd[3558]: 72ACB8319418: client=mail.myhostname.com[XXX.XXX.XXX.XXX], sasl_method=LOGIN, sasl_username=testemail@myhostname.com
Feb 24 16:15:23 mail postfix/cleanup[3569]: 72ACB8319418: message-id=<020801d0501a$cd3b9430$67b2bc90$@myhostname.com>
Feb 24 16:16:23 mail postfix/smtpd[3596]: connect from mail.myhostname.com[XXX.XXX.XXX.XXX]
Feb 24 16:16:23 mail postfix/smtpd[3596]: C8BF38319419: client=mail.myhostname.com[XXX.XXX.XXX.XXX], sasl_method=LOGIN, sasl_username=testemail@myhostname.com
Feb 24 16:16:24 mail postfix/cleanup[3607]: C8BF38319419: message-id=<020e01d0501a$f132e280$d398a780$@myhostname.com>
Feb 24 16:17:03 mail postfix/anvil[3045]: statistics: max connection rate 2/60s for (smtp:XXX.XXX.XXX.XXX) at Feb 24 16:10:03
Feb 24 16:17:03 mail postfix/anvil[3045]: statistics: max connection count 5 for (smtp:XXX.XXX.XXX.XXX) at Feb 24 16:16:23
Feb 24 16:17:03 mail postfix/anvil[3045]: statistics: max cache size 4 at Feb 24 16:07:43
Feb 24 16:17:23 mail postfix/smtpd[3634]: connect from mail.myhostname.com[XXX.XXX.XXX.XXX]
Feb 24 16:17:23 mail postfix/smtpd[3634]: 5AEB9831941A: client=mail.myhostname.com[XXX.XXX.XXX.XXX], sasl_method=LOGIN, sasl_username=testemail@myhostname.com
Feb 24 16:17:23 mail postfix/cleanup[3645]: 5AEB9831941A: message-id=<021401d0501b$14b32c10$3e198430$@myhostname.com>
Feb 24 16:17:42 mail postfix/smtpd[3664]: connect from mail.myhostname.com[XXX.XXX.XXX.XXX]
Feb 24 16:17:42 mail postfix/smtpd[3664]: E7750831941B: client=mail.myhostname.com[XXX.XXX.XXX.XXX], sasl_method=LOGIN, sasl_username=testemail@myhostname.com
Feb 24 16:17:43 mail postfix/cleanup[3669]: E7750831941B: message-id=<021a01d0501b$205c18b0$61144a10$@myhostname.com>
Feb 24 16:17:44 mail postfix/smtpd[3125]: disconnect from mail.myhostname.com[XXX.XXX.XXX.XXX]
Feb 24 16:18:11 mail postfix/smtpd[3480]: timeout after DATA (147383 bytes) from mail.myhostname.com[XXX.XXX.XXX.XXX]
Feb 24 16:18:23 mail postfix/smtpd[3683]: connect from mail.myhostname.com[XXX.XXX.XXX.XXX]
Feb 24 16:18:23 mail postfix/smtpd[3683]: 717AC831941C: client=mail.myhostname.com[XXX.XXX.XXX.XXX], sasl_method=LOGIN, sasl_username=testemail@myhostname.com
Feb 24 16:18:24 mail postfix/cleanup[3688]: 717AC831941C: message-id=<022001d0501b$388454c0$a98cfe40$@myhostname.com>
Feb 24 16:18:36 mail postfix/qmgr[1769]: 717AC831941C: from=<testemail@myhostname.com>, size=20666948, nrcpt=1 (queue active)
Feb 24 16:18:37 mail clamd[1520]: No stats for Database check - forcing reload
Feb 24 16:18:37 mail clamd[1520]: Reading databases from /var/lib/clamav
Feb 24 16:18:38 mail postfix/smtpd[3506]: timeout after DATA (139193 bytes) from mail.myhostname.com[XXX.XXX.XXX.XXX]
Feb 24 16:18:39 mail postfix/smtpd[3683]: disconnect from mail.myhostname.com[XXX.XXX.XXX.XXX]
Feb 24 16:18:42 mail clamd[1520]: Database correctly reloaded (3751071 signatures)
Feb 24 16:19:03 mail postfix/smtpd[3721]: connect from mail.myhostname.com[127.0.0.1]
Feb 24 16:19:03 mail postfix/smtpd[3721]: 07C6182DDE76: client=mail.myhostname.com[127.0.0.1]
Feb 24 16:19:03 mail postfix/cleanup[3688]: 07C6182DDE76: message-id=<022001d0501b$388454c0$a98cfe40$@myhostname.com>
Feb 24 16:19:03 mail postfix/smtpd[3721]: disconnect from mail.myhostname.com[127.0.0.1]
Feb 24 16:19:03 mail postfix/qmgr[1769]: 07C6182DDE76: from=<testemail@myhostname.com>, size=20668027, nrcpt=1 (queue active)
Feb 24 16:19:03 mail amavis[1954]: (01954-03) Passed CLEAN {RelayedInternal}, MYUSERS LOCAL [XXX.XXX.XXX.XXX]:53286 [XXX.XXX.XXX.XXX] <testemail@myhostname.com> -> <myemail@myhostname.com>, Queue-ID: 717AC831941C, Message-ID: <022001d0501b$388454c0$a98cfe40$@myhostname.com>, mail_id: aGRqU2MfODYB, Hits: -1.009, size: 20666948, queued_as: 07C6182DDE76, dkim_new=dkim:myhostname.com, 26912 ms
Feb 24 16:19:03 mail postfix/smtp[3702]: 717AC831941C: to=<myemail@myhostname.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=40, delays=13/0.01/0/27, 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 07C6182DDE76)
Feb 24 16:19:03 mail postfix/qmgr[1769]: 717AC831941C: removed
Feb 24 16:19:03 mail postfix/pipe[3722]: 07C6182DDE76: to=<myemail@myhostname.com>, relay=dovecot, delay=0.87, delays=0.5/0.01/0/0.36, dsn=2.0.0, status=sent (delivered via dovecot service)
Feb 24 16:19:03 mail postfix/qmgr[1769]: 07C6182DDE76: removed
Feb 24 16:19:24 mail postfix/smtpd[3526]: timeout after DATA (180221 bytes) from mail.myhostname.com[XXX.XXX.XXX.XXX]
Feb 24 16:20:05 mail postfix/smtpd[3191]: disconnect from mail.myhostname.com[XXX.XXX.XXX.XXX]
Feb 24 16:20:23 mail postfix/smtpd[3558]: timeout after DATA (155573 bytes) from mail.myhostname.com[XXX.XXX.XXX.XXX]
Feb 24 16:21:24 mail postfix/smtpd[3596]: timeout after DATA (229361 bytes) from mail.myhostname.com[XXX.XXX.XXX.XXX]
Feb 24 16:21:36 mail postfix/smtpd[3191]: connect from mail.myhostname.com[127.0.0.1]
Feb 24 16:21:36 mail postfix/smtpd[3191]: 2F1908319414: client=mail.myhostname.com[127.0.0.1], sasl_method=PLAIN, sasl_username=postmaster@myhostname.com
Feb 24 16:21:36 mail postfix/cleanup[3071]: 2F1908319414: message-id=<1424773296.3872.0.camel@mail.myhostname.com>
Feb 24 16:21:36 mail postfix/qmgr[1769]: 2F1908319414: from=<postmaster@myhostname.com>, size=1364761, nrcpt=1 (queue active)
Feb 24 16:21:36 mail postfix/smtpd[3191]: disconnect from mail.myhostname.com[127.0.0.1]
Feb 24 16:22:03 mail postfix/smtpd[4018]: connect from mail.myhostname.com[127.0.0.1]
Feb 24 16:22:03 mail postfix/smtpd[4018]: 50405831941C: client=mail.myhostname.com[127.0.0.1]
Feb 24 16:22:03 mail postfix/cleanup[3071]: 50405831941C: message-id=<1424773296.3872.0.camel@mail.myhostname.com>
Feb 24 16:22:03 mail postfix/smtpd[4018]: disconnect from mail.myhostname.com[127.0.0.1]
Feb 24 16:22:03 mail postfix/qmgr[1769]: 50405831941C: from=<postmaster@myhostname.com>, size=1365784, nrcpt=1 (queue active)
Feb 24 16:22:03 mail amavis[1953]: (01953-02) Passed CLEAN {RelayedInternal}, MYNETS/MYUSERS LOCAL [127.0.0.1]:58328 <postmaster@myhostname.com> -> <myemail@myhostname.com>, Queue-ID: 2F1908319414, Message-ID: <1424773296.3872.0.camel@mail.myhostname.com>, mail_id: yvZOxUtabUYp, Hits: -1.01, size: 1361669, queued_as: 50405831941C, dkim_new=dkim:myhostname.com, 27109 ms
Feb 24 16:22:03 mail postfix/smtp[3987]: 2F1908319414: to=<myemail@myhostname.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=27, delays=0.16/0.01/0/27, 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 50405831941C)
Feb 24 16:22:03 mail postfix/qmgr[1769]: 2F1908319414: removed
Feb 24 16:22:03 mail postfix/pipe[4019]: 50405831941C: to=<myemail@myhostname.com>, relay=dovecot, delay=0.19, delays=0.09/0.01/0/0.09, dsn=2.0.0, status=sent (delivered via dovecot service)
Feb 24 16:22:03 mail postfix/qmgr[1769]: 50405831941C: removed
Feb 24 16:22:23 mail postfix/smtpd[3634]: timeout after DATA (237551 bytes) from mail.myhostname.com[XXX.XXX.XXX.XXX]
Feb 24 16:22:43 mail postfix/smtpd[3664]: timeout after DATA (212981 bytes) from mail.myhostname.com[XXX.XXX.XXX.XXX]
Feb 24 16:22:57 mail postfix/smtpd[3191]: connect from mail.myhostname.com[127.0.0.1]
Feb 24 16:22:57 mail postfix/smtpd[3191]: 658D88319414: client=mail.myhostname.com[127.0.0.1], sasl_method=PLAIN, sasl_username=postmaster@myhostname.com
Feb 24 16:22:57 mail postfix/cleanup[3071]: 658D88319414: message-id=<1424773377.3872.1.camel@mail.myhostname.com>
Feb 24 16:22:57 mail postfix/qmgr[1769]: 658D88319414: from=<postmaster@myhostname.com>, size=1364772, nrcpt=1 (queue active)
----------------

4 Reply by kurmet

  • kurmet
  • Member
  • Offline
  • Registered: 2015-02-24
  • Posts: 7

Re: postfix sometimes refuses to send large emails

ZhangHuangbin, yes, I have correct PTR record in DNS for my mail.myhostname.com

5 Reply by ZhangHuangbin

  • ZhangHuangbin
  • ZhangHuangbin
  • iRedMail Developers
  • Offline
  • Registered: 2009-05-06
  • Posts: 30,770

Re: postfix sometimes refuses to send large emails

kurmet wrote:

I did check Cluebringer config file /etc/policyd/cluebringer.conf and found cluebringer username and password, but I don't know how to check and verify it with SQL command line tool. Please can you tell me how should I do this?

Next time, please do some quick search with Google, it's your homework.

Anyway, to test it with sql command line:

# mysql -u[USERNAME] -p

Replace '[USERNAME]' by the SQL username, then press ENTER, it will ask you to enter password. If login success, it will show you a 'mysql>' prompt and waiting for your SQL commands.

6 Reply by kurmet (edited by kurmet 2015-02-25 17:32:15)

  • kurmet
  • Member
  • Offline
  • Registered: 2015-02-24
  • Posts: 7

Re: postfix sometimes refuses to send large emails

Thank you ZhangHuangbin, for your help. I am sorry, because of I am new to linux, didn't have a clue where to ask information. I will try.

7 Reply by kurmet (edited by kurmet 2015-03-02 17:37:38)

  • kurmet
  • Member
  • Offline
  • Registered: 2015-02-24
  • Posts: 7

Re: postfix sometimes refuses to send large emails

I have tried to do psql commands (because Ive installed PostgreSQL, sorry I mentioned LDAP at first post).

psql -U [USERNAME] -w  didn't pass with users cluebringer, postgres, vmail, vmailadmin,

at every attempt it said that user cannot be autenticated (Peer).

Should I change something in pg_hba.conf? Or, maybe hosts file?

Server is located after the router, router users with DHCP send emails after a lot of attempts. One of users had his email in Outbox with Outlook keeping saying it was not send to me, although his outbox mail was sent to me and received by me several times.

Users from another IP address send emails fine, but sometimes it doesn't autenticate, and email is not sent.

8 Reply by ZhangHuangbin

  • ZhangHuangbin
  • ZhangHuangbin
  • iRedMail Developers
  • Offline
  • Registered: 2009-05-06
  • Posts: 30,770

Re: postfix sometimes refuses to send large emails

Try as root user:

# su - postgres

Now you're user `postgres`. Run command:

$ psql -d vmail