26 Reply by nevsehiredu (edited by nevsehiredu 2015-01-12 22:00:20)

  • nevsehiredu
  • Member
  • Offline
  • Registered: 2013-07-16
  • Posts: 27

Re: problems after upgrading 0.9.0 from 0.8.7

I sent 2 mail from hotmail to nevsehir.edu.tr. In first log  "content_filter = smtp-amavis:[127.0.0.1]:10024" is not commented on main.cf. The second log, the row is commented.

[root@mail ~]# tail -f /var/log/maillog | grep nuh


Jan 12 15:52:01 mail postfix/qmgr[5672]: BA95B155409B7: from=<nuh.87@hotmail.com>, size=1956, nrcpt=1 (queue active)
Jan 12 15:52:03 mail postfix/error[5702]: BA95B155409B7: to=<nuh@nevsehir.edu.tr>, relay=none, delay=1.5, delays=0.42/1.1/0/0, dsn=4.3.0, status=deferred (unknown mail transport error)




Jan 12 15:52:37 mail postfix/qmgr[5868]: 80A73155409FF: from=<nuh.87@hotmail.com>, size=1952, nrcpt=1 (queue active)
Jan 12 15:52:37 mail postfix/pipe[5889]: 80A73155409FF: to=<nuh@nevsehir.edu.tr>, relay=dovecot, delay=0.43, delays=0.38/0.01/0/0.04, dsn=2.0.0, status=sent (delivered via dovecot service)

----

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

27 Reply by ZhangHuangbin

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

Re: problems after upgrading 0.9.0 from 0.8.7

Dear nevsehiredu,

Please show me FULL log of this testing email in Postfix log file, not just the line contain error message.

Full log means starts with 'connect from xxx', ends with 'disconnect from xxx'. For example:

Jan 12 06:08:45 d7 postfix/smtpd[3880]: connect from localhost[127.0.0.1]
Jan 12 06:08:46 d7 postfix/smtpd[3880]: 31E58FFF50: client=localhost[127.0.0.1], sasl_method=LOGIN, sasl_username=postmaster@a.cn
Jan 12 06:08:47 d7 postfix/cleanup[3842]: 31E58FFF50: message-id=<e10cb3a571fd5c7888e9f556f379883e@a.cn>
Jan 12 06:08:47 d7 postfix/qmgr[3470]: 31E58FFF50: from=<postmaster@a.cn>, size=694, nrcpt=1 (queue active)
Jan 12 06:08:47 d7 roundcube: User postmaster@a.cn [172.16.244.1]; Message for postmaster@a.cn; 250: 2.0.0 Ok: queued as 31E58FFF50
Jan 12 06:08:47 d7 postfix/smtpd[3880]: disconnect from localhost[127.0.0.1]

28 Reply by nevsehiredu

  • nevsehiredu
  • Member
  • Offline
  • Registered: 2013-07-16
  • Posts: 27

Re: problems after upgrading 0.9.0 from 0.8.7

If amavis works;

Jan 12 16:13:59 mail postfix/smtpd[5886]: connect from dub004-omc1s24.hotmail.com[157.55.0.223]
Jan 12 16:13:59 mail postfix/smtpd[5886]: E14BB15540A08: client=dub004-omc1s24.hotmail.com[157.55.0.223]
Jan 12 16:14:00 mail postfix/cleanup[6855]: E14BB15540A08: message-id=<DUB119-W10E73C319406BEA5D3765D9D430@phx.gbl>
Jan 12 16:14:00 mail postfix/qmgr[5868]: E14BB15540A08: from=<nuh.87@hotmail.com>, size=1961, nrcpt=1 (queue active)
Jan 12 16:14:00 mail postfix/pipe[6040]: E14BB15540A08: to=<nuh@nevsehir.edu.tr>, relay=dovecot, delay=0.42, delays=0.37/0.01/0/0.04, dsn=2.0.0, status=sent (delivered via dovecot service)
Jan 12 16:14:00 mail postfix/qmgr[5868]: E14BB15540A08: removed
Jan 12 16:14:00 mail postfix/smtpd[5886]: disconnect from dub004-omc1s24.hotmail.com[157.55.0.223]

if amavis row commented;

Jan 12 16:16:02 mail postfix/smtpd[10729]: connect from dub004-omc1s35.hotmail.com[157.55.0.234]
Jan 12 16:16:03 mail postfix/smtpd[10729]: 19B6C15540A0C: client=dub004-omc1s35.hotmail.com[157.55.0.234]
Jan 12 16:16:03 mail postfix/cleanup[10730]: 19B6C15540A0C: message-id=<DUB119-W5181686D109DC4E6E605029D430@phx.gbl>
Jan 12 16:16:03 mail postfix/qmgr[10723]: 19B6C15540A0C: from=<nuh.87@hotmail.com>, size=1959, nrcpt=1 (queue active)
Jan 12 16:16:03 mail postfix/error[10736]: 19B6C15540A0C: to=<nuh@nevsehir.edu.tr>, relay=none, delay=0.38, delays=0.38/0/0/0, dsn=4.3.0, status=deferred (unknown mail transport error)
Jan 12 16:16:03 mail postfix/smtpd[10729]: disconnect from dub004-omc1s35.hotmail.com[157.55.0.234]

29 Reply by stannicek

  • stannicek
  • Member
  • Offline
  • Registered: 2015-01-06
  • Posts: 35

Re: problems after upgrading 0.9.0 from 0.8.7

Can you also check with more verbose messages as per http://www.postfix.org/DEBUG_README.html#verbose ?

master.cf :
"smtpd -v" + "qmgr -v"

main.cf :
debug_peer_level=5
debug_peer_list = hotmail.com

+service postfix reload/restart

30 Reply by ZhangHuangbin

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

Re: problems after upgrading 0.9.0 from 0.8.7

Pasted log doesn't contain amavis log at all. Could you please turn on debug mode in Amavisd and try again?
http://www.iredmail.org/docs/debug.amavisd.html

If there's something wrong with Amavisd, there must be some error log in Amavisd log file (same as Postfix log file).

31 Reply by nevsehiredu

  • nevsehiredu
  • Member
  • Offline
  • Registered: 2013-07-16
  • Posts: 27

Re: problems after upgrading 0.9.0 from 0.8.7

Jan 13 08:58:34 mail postfix/smtpd[13723]: connect from dub004-omc1s9.hotmail.com[157.55.0.208]
Jan 13 08:58:35 mail postfix/smtpd[13723]: 3A91D15540994: client=dub004-omc1s9.hotmail.com[157.55.0.208]
Jan 13 08:58:35 mail postfix/cleanup[13730]: 3A91D15540994: message-id=<DUB119-W2661C6A43D94F275A52F509D400@phx.gbl>
Jan 13 08:58:35 mail postfix/qmgr[13701]: 3A91D15540994: from=<nuh.87@hotmail.com>, size=1953, nrcpt=1 (queue active)
Jan 13 08:58:35 mail postfix/smtp[13731]: fatal: garbage after numerical service in server description: [127.0.0.1]:10024    #kapat??ld??
Jan 13 08:58:35 mail postfix/smtpd[13723]: disconnect from dub004-omc1s9.hotmail.com[157.55.0.208]
Jan 13 08:58:36 mail postfix/qmgr[13701]: warning: private/smtp-amavis socket: malformed response
Jan 13 08:58:36 mail postfix/qmgr[13701]: warning: transport smtp-amavis failure -- see a previous warning/fatal/panic logfile record for the problem description
Jan 13 08:58:36 mail postfix/master[13699]: warning: process /usr/libexec/postfix/smtp pid 13731 exit status 1
Jan 13 08:58:36 mail postfix/master[13699]: warning: /usr/libexec/postfix/smtp: bad command startup -- throttling
Jan 13 08:58:36 mail postfix/error[13734]: 3A91D15540994: to=<nuh@nevsehir.edu.tr>, relay=none, delay=1.6, delays=0.44/1.1/0/0.05, dsn=4.3.0, status=deferred (unknown mail transport error)
ZhangHuangbin wrote:

Pasted log doesn't contain amavis log at all. Could you please turn on debug mode in Amavisd and try again?
http://www.iredmail.org/docs/debug.amavisd.html

If there's something wrong with Amavisd, there must be some error log in Amavisd log file (same as Postfix log file).

32 Reply by nevsehiredu

  • nevsehiredu
  • Member
  • Offline
  • Registered: 2013-07-16
  • Posts: 27

Re: problems after upgrading 0.9.0 from 0.8.7

I dont know what happened, but working now. I deleted the comment after content_filter row, maybe the problem was that. Thanks for your help Stannicek and Zhang.

33 Reply by stannicek

  • stannicek
  • Member
  • Offline
  • Registered: 2015-01-06
  • Posts: 35

Re: problems after upgrading 0.9.0 from 0.8.7

nevsehiredu wrote:

I dont know what happened, but working now. I deleted the comment after content_filter row, maybe the problem was that. Thanks for your help Stannicek and Zhang.

This is the cause: 

Jan 13 08:58:35 mail postfix/smtp[13731]: fatal: garbage after numerical service in server description: [127.0.0.1]:10024    #kapat??ld??

So your main.cf was :

content_filter = smtp-amavis:[127.0.0.1]:10024  #kapat??ld??

which is garbage ... comments are allowed only on the beginning of the line.

34 Reply by stannicek

  • stannicek
  • Member
  • Offline
  • Registered: 2015-01-06
  • Posts: 35

Re: problems after upgrading 0.9.0 from 0.8.7

I'm wondering why this error was not reported by postfix correctly, since it is fatal error.

"fatal" is the result of missing files, incorrect permissions, incorrect configuration file settings that you can fix. Postfix cannot proceed until this is fixed.

"error" reports an error condition. For safety reasons, a Postfix process will terminate when more than 13 of these happen.

35 Reply by nevsehiredu

  • nevsehiredu
  • Member
  • Offline
  • Registered: 2013-07-16
  • Posts: 27

Re: problems after upgrading 0.9.0 from 0.8.7

Actually I didnt suspect when I didnt see any error since opened debug mode.

Thank you both of you. smile