1 Topic by andrew88 (edited by andrew88 2017-06-02 04:03:24)

  • andrew88
  • Member
  • Offline
  • Registered: 2017-05-31
  • Posts: 24

Topic: Resolved - Can not send email

==== Required information ====
- iRedMail version (check /etc/iredmail-release): 0.9.6
- Linux/BSD distribution name and version: ubuntu 16.04
- Store mail accounts in which backend (LDAP/MySQL/PGSQL): mariadb
- Web server (Apache or Nginx): apache
- Manage mail accounts with iRedAdmin-Pro? iredadmin
- [IMPORTANT] Related original log or error message is required if you're experiencing an issue.

Hello,

I can receive emails fine, but i can not send.  I had though prehaps there was some sort of grey listing / delay going on but it has been several hours since i first tried sending an e-mail and have still not received it.  The log did not make much sense to me as to what would be causing the error.

/var/log/mail.log

May 30 20:37:23 mail postfix/postscreen[7470]: CONNECT from [127.0.0.1]:39928 to [127.0.0.1]:25
May 30 20:37:23 mail postfix/postscreen[7470]: WHITELISTED [127.0.0.1]:39928
May 30 20:37:23 mail postfix/smtpd[7471]: connect from mail.aperturephoto.ca[127.0.0.1]
May 30 20:37:23 mail postfix/smtpd[7471]: F0D3AA1445: client=mail.aperturephoto.ca[127.0.0.1]
May 30 20:37:24 mail postfix/cleanup[7183]: F0D3AA1445: message-id=<523-592dd800-d-9e58c60@39688310>
May 30 20:37:24 mail postfix/qmgr[1576]: F0D3AA1445: from=<user@aperturephoto.ca>, size=1574, nrcpt=1 (queue active)
May 30 20:37:24 mail postfix/smtpd[7471]: disconnect from mail.aperturephoto.ca[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
May 30 20:37:25 mail postfix/10025/smtpd[5886]: connect from mail.aperturephoto.ca[127.0.0.1]
May 30 20:37:25 mail postfix/10025/smtpd[5886]: 9064CA142B: client=mail.aperturephoto.ca[127.0.0.1]
May 30 20:37:25 mail postfix/cleanup[7182]: 9064CA142B: message-id=<523-592dd800-d-9e58c60@39688310>
May 30 20:37:25 mail postfix/qmgr[1576]: 9064CA142B: from=<user@aperturephoto.ca>, size=2698, nrcpt=1 (queue active)
May 30 20:37:25 mail postfix/10025/smtpd[5886]: disconnect from mail.aperturephoto.ca[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
May 30 20:37:25 mail amavis[7435]: (07435-02) Passed CLEAN {RelayedInternal}, MYNETS LOCAL [127.0.0.1]:39928 <user@aperturephoto.ca> -> <user@boohahaonline.com>, Queue-ID: F0D3AA1445, Message-ID: <523-592dd800-d-9e58c60@39688310>, mail_id: qHAJSVNubiWL, Hits: -1.009$
May 30 20:37:25 mail postfix/smtp-amavis/smtp[7431]: F0D3AA1445: to=<user@boohahaonline.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=1.7, delays=0.11/0/0/1.6, 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 9064CA142B)
May 30 20:37:25 mail postfix/qmgr[1576]: F0D3AA1445: removed
May 30 20:37:44 mail postfix/postscreen[7470]: CONNECT from [127.0.0.1]:39990 to [127.0.0.1]:25
May 30 20:37:44 mail postfix/postscreen[7470]: WHITELISTED [127.0.0.1]:39990
May 30 20:37:44 mail postfix/smtpd[7471]: connect from mail.aperturephoto.ca[127.0.0.1]
May 30 20:37:45 mail postfix/smtpd[7471]: 05AEFA1448: client=mail.aperturephoto.ca[127.0.0.1]
May 30 20:37:45 mail postfix/cleanup[7183]: 05AEFA1448: message-id=<523-592dd800-f-9e58c60@39688343>
May 30 20:37:45 mail postfix/qmgr[1576]: 05AEFA1448: from=<user@aperturephoto.ca>, size=998, nrcpt=1 (queue active)
May 30 20:37:45 mail postfix/smtpd[7471]: disconnect from mail.aperturephoto.ca[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
May 30 20:37:45 mail postfix/10025/smtpd[5886]: connect from mail.aperturephoto.ca[127.0.0.1]
May 30 20:37:45 mail postfix/10025/smtpd[5886]: 392C3A1445: client=mail.aperturephoto.ca[127.0.0.1]
May 30 20:37:45 mail postfix/cleanup[7182]: 392C3A1445: message-id=<523-592dd800-f-9e58c60@39688343>
May 30 20:37:45 mail postfix/qmgr[1576]: 392C3A1445: from=<user@aperturephoto.ca>, size=2110, nrcpt=1 (queue active)
May 30 20:37:45 mail postfix/10025/smtpd[5886]: disconnect from mail.aperturephoto.ca[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
May 30 20:37:45 mail amavis[7435]: (07435-03) Passed CLEAN {RelayedInternal}, MYNETS LOCAL [127.0.0.1]:39990 <user@aperturephoto.ca> -> <user@boohahaonline.com>, Queue-ID: 05AEFA1448, Message-ID: <523-592dd800-f-9e58c60@39688343>, mail_id: eFP4U4EooU_z, Hits: -1.009$
May 30 20:37:45 mail postfix/smtp-amavis/smtp[7431]: 05AEFA1448: to=<user@boohahaonline.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=0.29, delays=0.1/0/0/0.18, 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 392C3A1445)
May 30 20:37:45 mail postfix/qmgr[1576]: 05AEFA1448: removed

----

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: 31,164

Re: Resolved - Can not send email

Pasted log doesn't contain any issue/error/warning. Please try again to reproduce the issue, then extract related log from Postfix log file and paste here.

3 Reply by andrew88

  • andrew88
  • Member
  • Offline
  • Registered: 2017-05-31
  • Posts: 24

Re: Resolved - Can not send email

updated log snippet, tried sending email to 2 different addresses with the same result

May 31 10:33:12 mail postfix/postscreen[2290]: CONNECT from [127.0.0.1]:47978 to [127.0.0.1]:25
May 31 10:33:12 mail postfix/postscreen[2290]: WHITELISTED [127.0.0.1]:47978
May 31 10:33:12 mail postfix/smtpd[2291]: connect from mail.aperturephoto.ca[127.0.0.1]
May 31 10:33:13 mail postfix/smtpd[2291]: F0A51A12D8: client=mail.aperturephoto.ca[127.0.0.1]
May 31 10:33:14 mail postfix/cleanup[2303]: F0A51A12D8: message-id=<50d-592e9c00-3-71653500@114914687>
May 31 10:33:14 mail postfix/smtpd[2291]: disconnect from mail.aperturephoto.ca[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
May 31 10:33:14 mail postfix/qmgr[1599]: F0A51A12D8: from=<user@aperturephoto.ca>, size=1576, nrcpt=1 (queue active)
May 31 10:33:23 mail postfix/10025/smtpd[2312]: connect from mail.aperturephoto.ca[127.0.0.1]
May 31 10:33:23 mail postfix/10025/smtpd[2312]: 602DAA12E8: client=mail.aperturephoto.ca[127.0.0.1]
May 31 10:33:23 mail postfix/cleanup[2303]: 602DAA12E8: message-id=<50d-592e9c00-3-71653500@114914687>
May 31 10:33:23 mail postfix/10025/smtpd[2312]: disconnect from mail.aperturephoto.ca[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
May 31 10:33:23 mail postfix/qmgr[1599]: 602DAA12E8: from=<@aperturephoto.ca>, size=2717, nrcpt=1 (queue active)
May 31 10:33:23 mail amavis[1629]: (01629-01) Passed CLEAN {RelayedInternal}, MYNETS LOCAL [127.0.0.1]:47978 <user@aperturephoto.ca> -> <user@boohahaonline.com>, Queue-ID: F0A51A12D8, Message-ID: <50d-592e9c00-3-71653500@114914687>, mail_id: kFHTqqoNnIqS, Hits: -0.9$
May 31 10:33:23 mail postfix/smtp-amavis/smtp[2308]: F0A51A12D8: to=<user@boohahaonline.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=11, delays=1.3/0.02/1.3/8.3, 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 602DAA12E8)
May 31 10:33:23 mail postfix/qmgr[1599]: F0A51A12D8: removed
May 31 10:33:53 mail postfix/smtp[2313]: connect to boohahaonline-com.mail.protection.outlook.com[23.103.157.42]:25: Connection timed out
May 31 10:34:23 mail postfix/smtp[2313]: connect to boohahaonline-com.mail.protection.outlook.com[23.103.157.10]:25: Connection timed out
May 31 10:34:23 mail postfix/smtp[2313]: 602DAA12E8: to=<user@boohahaonline.com>, relay=none, delay=60, delays=0.03/0.03/60/0, dsn=4.4.1, status=deferred (connect to boohahaonline-com.mail.protection.outlook.com[23.103.157.10]:25: Connection timed out)
May 31 10:37:57 mail postfix/postscreen[2343]: CONNECT from [127.0.0.1]:48066 to [127.0.0.1]:25
May 31 10:37:57 mail postfix/postscreen[2343]: WHITELISTED [127.0.0.1]:48066
May 31 10:37:57 mail postfix/smtpd[2344]: connect from mail.aperturephoto.ca[127.0.0.1]
May 31 10:37:58 mail postfix/smtpd[2344]: 95B9DA12F5: client=mail.aperturephoto.ca[127.0.0.1]
May 31 10:37:58 mail postfix/cleanup[2351]: 95B9DA12F5: message-id=<50d-592e9d00-5-71653500@114914720>
May 31 10:37:58 mail postfix/qmgr[1599]: 95B9DA12F5: from=<user@aperturephoto.ca>, size=1002, nrcpt=1 (queue active)
May 31 10:37:58 mail postfix/smtpd[2344]: disconnect from mail.aperturephoto.ca[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
May 31 10:37:59 mail postfix/10025/smtpd[2360]: connect from mail.aperturephoto.ca[127.0.0.1]
May 31 10:37:59 mail postfix/10025/smtpd[2360]: 1E389A12FA: client=mail.aperturephoto.ca[127.0.0.1]
May 31 10:37:59 mail postfix/cleanup[2351]: 1E389A12FA: message-id=<50d-592e9d00-5-71653500@114914720>
May 31 10:37:59 mail postfix/qmgr[1599]: 1E389A12FA: from=<user@aperturephoto.ca>, size=2133, nrcpt=1 (queue active)
May 31 10:37:59 mail postfix/10025/smtpd[2360]: disconnect fromuser mail.aperturephoto.ca[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
May 31 10:37:59 mail amavis[1629]: (01629-02) Passed CLEAN {RelayedInternal}, MYNETS LOCAL [127.0.0.1]:48066 <user@aperturephoto.ca> -> <web-2e6nh@mail-tester.com>, Queue-ID: 95B9DA12F5, Message-ID: <50d-592e9d00-5-71653500@114914720>, mail_id: pVEXYRDkrJOa, Hits: -0.$
May 31 10:37:59 mail postfix/smtp-amavis/smtp[2356]: 95B9DA12F5: to=<web-2e6nh@mail-tester.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=1.1, delays=0.64/0.02/0.01/0.47, 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 1E389A12$
May 31 10:37:59 mail postfix/qmgr[1599]: 95B9DA12F5: removed
May 31 10:38:29 mail postfix/smtp[2361]: connect to mail-tester.com[94.23.206.89]:25: Connection timed out
May 31 10:38:29 mail postfix/smtp[2361]: 1E389A12FA: to=<web-2e6nh@mail-tester.com>, relay=none, delay=30, delays=0.01/0.03/30/0, dsn=4.4.1, status=deferred (connect to mail-tester.com[94.23.206.89]:25: Connection timed out)
May 31 10:38:29 mail postfix/smtp[2361]: 1E389A12FA: to=<web-2e6nh@mail-tester.com>, relay=none, delay=30, delays=0.01/0.03/30/0, dsn=4.4.1, status=deferred (connect to mail-tester.com[94.23.206.89]:25: Connection timed out)
May 31 10:42:56 mail postfix/postscreen[2437]: CONNECT from [50.104.139.182]:1949 to [74.208.150.106]:25
May 31 10:43:02 mail postfix/postscreen[2437]: PASS NEW [50.104.139.182]:1949
May 31 10:43:02 mail postfix/smtpd[2443]: connect from mail.meyertruckeq.com[50.104.139.182]
May 31 10:43:11 mail postfix/smtpd[2443]: NOQUEUE: reject: RCPT from mail.meyertruckeq.com[50.104.139.182]: 504 5.5.2 <DC01>: Helo command rejected: need fully-qualified hostname; from=<spameri@tiscali.it> to=<spameri@tiscali.it> proto=ESMTP helo=<DC01>
May 31 10:43:12 mail postfix/smtpd[2443]: disconnect from mail.meyertruckeq.com[50.104.139.182] ehlo=1 mail=1 rcpt=0/1 rset=1 quit=1 commands=4/5
May 31 10:43:34 mail postfix/postscreen[2437]: CONNECT from [50.104.139.182]:2361 to [74.208.150.106]:25
May 31 10:43:34 mail postfix/postscreen[2437]: PASS OLD [50.104.139.182]:2361
May 31 10:43:34 mail postfix/smtpd[2443]: connect from mail.meyertruckeq.com[50.104.139.182]
May 31 10:43:35 mail postfix/smtpd[2443]: NOQUEUE: reject: RCPT from mail.meyertruckeq.com[50.104.139.182]: 504 5.5.2 <DC01>: Helo command rejected: need fully-qualified hostname; from=<spameri@tiscali.it> to=<spameri@tiscali.it> proto=ESMTP helo=<DC01>
May 31 10:43:35 mail postfix/smtpd[2443]: disconnect from mail.meyertruckeq.com[50.104.139.182] ehlo=1 mail=1 rcpt=0/1 rset=1 quit=1 commands=4/5

4 Reply by andrew88

  • andrew88
  • Member
  • Offline
  • Registered: 2017-05-31
  • Posts: 24

Re: Resolved - Can not send email

issue resolved.  VPS host 1and1 has a block on port 25 that yo must ask them to remove.