Debian 10 - Postfix/Dovecot/Amavisd Email problem. Can send emails but cannot receive

Discussion in 'Installation/Configuration' started by mezelf, Dec 24, 2021.

Page 1 of 2
  1. mezelf

    mezelf Member

    Hi there,
    Our website was working on Debian 8 with mail etc. We went for a clean Buster install, rather than an upgrade. After we installed Debian 10 (did our best to follow the instructions on https://www.howtoforge.com/perfect-server-debian-10-buster-apache-bind-dovecot-ispconfig-3-1/), the website on the server can send emails but cannot receive any.

    I have read the threads about this issue that I could find here and other places. I tried things but to no avail. I have uninstalled Postfix, Dovecot, Amavisd, Spamassasin, ClamAV and then reinstalled. It did not help. In one of the threads, it was written that ISPConfig3 reinstallation had solved the problem. I did not try it, as it was not clear how ISPConfig3 would cause it and also that the risk that it would make the website offline.

    When I do netstat, I see that amavisd-new is listening to port 10026. When I check the /etc/postfix/mail.log, after I send an email from a gmail account to our websites' email account, I see this in the log
    postfix/smtpd[21570]: connect from localhost[::1]
    postfix/smtpd[21570]: lost connection after CONNECT from localhost[::1]
    postfix/smtpd[21570]: disconnect from localhost[::1] commands=0/0
    dovecot: imap-login: Login: user=<[email protected]>, method=PLAIN, rop::1, lip=::1, mpid=21592, secured, session=<9SeMsdjTHMYAAAAAAAAAAAAAAAAAAAB>
    dovecot: imap([email protected])<21592><9SeMsdjTHMYAAAAAAAAAAAAAAAAAAAB>: Logged out in=123 out=1042 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0

    When I check the Maildir/cur, I can only see the emails sent from another email address of our website, but no external (gmail or so) emails. When I check the deferred with 'qshape deferred' command, I see no deferred emails.
    I have tried this and that and apparently I am missing something. Where else should I be checking? I appreciate your help.
    regards.
     
    Last edited by a moderator: Jan 3, 2022
    mezelf, Dec 24, 2021
    #1
  2. Taleman

    Taleman Well-Known Member HowtoForge Supporter

    So you are running ISPConfig? You should post on ISPConfig forum.
    Start with this: https://www.howtoforge.com/community/threads/please-read-before-posting.58408/
    My signature has link to e-mail setup tutorial, it has instructions on how to troubleshoot e-mail problems.
    You might consider using ISPConfig auto installer if you think problem is with ISPConfig installation. But I would say removing and reinstalling Postfix, Dovecot, Amavisd, Spamassasin, ClamAV makes situation worse. Maybe reinstall the system anew if problems persist.
     
    Taleman, Dec 24, 2021
    #2
    mezelf likes this.
  3. till

    till Super Moderator Staff Member ISPConfig Developer

    According to the log, there was no attempt made to send a message from or to this server, all it shows is the automatic system test from ispconfig and then an imap login from a mail client to check if email is in the inbox. You should check the mail.log again right after you sent an email. the best way to test your system locally is to log into roundcube and then send an email to the same account that you used to log into roundcube, if the email dies not arrive in roundcube after a few seconds, then check the mail.log file and post the lines that got added for this email round trip.
     
    till, Dec 24, 2021
    #3
    mezelf likes this.
  4. mezelf

    mezelf Member

    Thank you for your reply. My apologies for not immediately coming back. I wanted to try a few more things before I come back.
    This is the sequence of events:
    1- Logged-in to roundcube and sent an email to itself ([email protected]). It immediately appeared in the inbox.
    2- From roundcube sent an email to an gmail account. It arrived in gmail inbox
    3- From email, replied to the email. It never arrived in roundcube inbox.
    Below is the mail.log part (please kindly assume t...t.com the same domain name as before).
    For 1 and 2 it seems OK to me. But for action 3 (incoming email from outside), it is as if nothing has ever arrived, unless I miss something.
    Code:
    Dec 25 19:10:04 dhost postfix/smtpd[11184]: disconnect from localhost[::1] commands=0/0
Dec 25 19:10:30 dhost postfix/smtpd[11184]: connect from localhost[::1]
Dec 25 19:10:30 dhost postfix/smtpd[11184]: NOQUEUE: filter: RCPT from localhost[::1]: <[email protected]>: Sender address triggers FILTER lmtp:[127.0.0.1]:10026; from=<tctest@tunne$
Dec 25 19:10:30 dhost postfix/smtpd[11184]: E438F6E7BED: client=localhost[::1], sasl_method=LOGIN, [email protected]
Dec 25 19:10:30 dhost postfix/cleanup[11208]: E438F6E7BED: message-id=<[email protected]>
Dec 25 19:10:30 dhost postfix/qmgr[1528]: E438F6E7BED: from=<[email protected]>, size=609, nrcpt=1 (queue active)
Dec 25 19:10:30 dhost postfix/smtpd[11184]: disconnect from localhost[::1] ehlo=1 auth=1 mail=1 rcpt=1 data=1 quit=1 commands=6
Dec 25 19:10:31 dhost dovecot: imap-login: Login: user=<[email protected]>, method=PLAIN, rip=::1, lip=::1, mpid=11211, secured, session=<BIwTY/zTjpgAAAAAAAAAAAAAAAAAAAAB>
Dec 25 19:10:31 dhost dovecot: imap([email protected])<11211><BIwTY/zTjpgAAAAAAAAAAAAAAAAAAAAB>: Logged out in=456 out=710 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 bod$
Dec 25 19:10:31 dhost postfix/smtpd[11214]: connect from localhost[127.0.0.1]
Dec 25 19:10:31 dhost postfix/smtpd[11214]: 45DFC6E7BEE: client=localhost[127.0.0.1]
Dec 25 19:10:31 dhost postfix/cleanup[11208]: 45DFC6E7BEE: message-id=<[email protected]>
Dec 25 19:10:31 dhost postfix/qmgr[1528]: 45DFC6E7BEE: from=<[email protected]>, size=1079, nrcpt=1 (queue active)
Dec 25 19:10:31 dhost postfix/smtpd[11214]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Dec 25 19:10:31 dhost amavis[1582]: (01582-05) Passed CLEAN {RelayedOutbound}, ORIGINATING LOCAL [127.0.0.1] <[email protected]> -> <[email protected]>, Message-ID: <e98363dae62e42f$
Dec 25 19:10:31 dhost postfix/lmtp[11209]: E438F6E7BED: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:10026, delay=0.39, delays=0.07/0.02/0.01/0.29, dsn=2.0.0, status=sent (250 2.0.0 $
Dec 25 19:10:31 dhost postfix/qmgr[1528]: E438F6E7BED: removed
Dec 25 19:10:31 dhost postfix/smtp[11215]: error: open database /etc/postfix/sasl_passwd: No such file or directory
Dec 25 19:10:31 dhost postfix/smtp[11215]: 45DFC6E7BEE: to=<[email protected]>, relay=gmail-smtp-in.l.google.com[108.177.127.26]:25, delay=0.3, delays=0.01/0.03/0.09/0.16, dsn=2.0.0, statu$
Dec 25 19:10:31 dhost postfix/qmgr[1528]: 45DFC6E7BEE: removed
Dec 25 19:10:31 dhost dovecot: imap-login: Login: user=<[email protected]>, method=PLAIN, rip=::1, lip=::1, mpid=11217, secured, session=<vF0dY/zTmJgAAAAAAAAAAAAAAAAAAAAB>
Dec 25 19:10:31 dhost dovecot:Dec 25 19:10:04 dhost postfix/smtpd[11184]: lost connection after CONNECT from localhost[::1]
Dec 25 19:10:04 dhost postfix/smtpd[11184]: disconnect from localhost[::1] commands=0/0
Dec 25 19:10:30 dhost postfix/smtpd[11184]: connect from localhost[::1]
Dec 25 19:10:30 dhost postfix/smtpd[11184]: NOQUEUE: filter: RCPT from localhost[::1]: <[email protected]>: Sender address triggers FILTER lmtp:[127.0.0.1]:10026; from=<tctest@tunne$
Dec 25 19:10:30 dhost postfix/smtpd[11184]: E438F6E7BED: client=localhost[::1], sasl_method=LOGIN, [email protected]
Dec 25 19:10:30 dhost postfix/cleanup[11208]: E438F6E7BED: message-id=<[email protected]>
Dec 25 19:10:30 dhost postfix/qmgr[1528]: E438F6E7BED: from=<[email protected]>, size=609, nrcpt=1 (queue active)
Dec 25 19:10:30 dhost postfix/smtpd[11184]: disconnect from localhost[::1] ehlo=1 auth=1 mail=1 rcpt=1 data=1 quit=1 commands=6
Dec 25 19:10:31 dhost dovecot: imap-login: Login: user=<[email protected]>, method=PLAIN, rip=::1, lip=::1, mpid=11211, secured, session=<BIwTY/zTjpgAAAAAAAAAAAAAAAAAAAAB>
Dec 25 19:10:31 dhost dovecot: imap([email protected])<11211><BIwTY/zTjpgAAAAAAAAAAAAAAAAAAAAB>: Logged out in=456 out=710 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 bod$
Dec 25 19:10:31 dhost postfix/smtpd[11214]: connect from localhost[127.0.0.1]
Dec 25 19:10:31 dhost postfix/smtpd[11214]: 45DFC6E7BEE: client=localhost[127.0.0.1]
Dec 25 19:10:31 dhost postfix/cleanup[11208]: 45DFC6E7BEE: message-id=<[email protected]>
Dec 25 19:10:31 dhost postfix/qmgr[1528]: 45DFC6E7BEE: from=<[email protected]>, size=1079, nrcpt=1 (queue active)
Dec 25 19:10:31 dhost postfix/smtpd[11214]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Dec 25 19:10:31 dhost amavis[1582]: (01582-05) Passed CLEAN {RelayedOutbound}, ORIGINATING LOCAL [127.0.0.1] <[email protected]> -> <[email protected]>, Message-ID: <e98363dae62e42f$
Dec 25 19:10:31 dhost postfix/lmtp[11209]: E438F6E7BED: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:10026, delay=0.39, delays=0.07/0.02/0.01/0.29, dsn=2.0.0, status=sent (250 2.0.0 $
Dec 25 19:10:31 dhost postfix/qmgr[1528]: E438F6E7BED: removed
Dec 25 19:10:31 dhost postfix/smtp[11215]: error: open database /etc/postfix/sasl_passwd: No such file or directory
Dec 25 19:10:31 dhost postfix/smtp[11215]: 45DFC6E7BEE: to=<[email protected]>, relay=gmail-smtp-in.l.google.com[108.177.127.26]:25, delay=0.3, delays=0.01/0.03/0.09/0.16, dsn=2.0.0, statu$
Dec 25 19:10:31 dhost postfix/qmgr[1528]: 45DFC6E7BEE: removed
Dec 25 19:10:31 dhost dovecot: imap-login: Login: user=<[email protected]>, method=PLAIN, rip=::1, lip=::1, mpid=11217, secured, session=<vF0dY/zTmJgAAAAAAAAAAAAAAAAAAAAB>
Dec 25 19:30:32 dhost dovecot: imap([email protected])<12386><dgOtqvzTIp0AAAAAAAAAAAAAAAAAAAAB>: Logged out in=119 out=1055 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 bo$
Dec 25 19:31:32 dhost dovecot: imap-login: Login: user=<[email protected]>, method=PLAIN, rip=::1, lip=::1, mpid=12439, secured, session=<sts/rvzTXJ0AAAAAAAAAAAAAAAAAAAAB>
Dec 25 19:31:32 dhost dovecot: imap([email protected])<12439><sts/rvzTXJ0AAAAAAAAAAAAAAAAAAAAB>: Logged out in=119 out=1055 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 bo$
Dec 25 19:32:32 dhost dovecot: imap-login: Login: user=<[email protected]>, method=PLAIN, rip=::1, lip=::1, mpid=12473, secured, session=<5eXSsfzTop0AAAAAAAAAAAAAAAAAAAAB>
Dec 25 19:32:32 dhost dovecot: imap([email protected])<12473><5eXSsfzTop0AAAAAAAAAAAAAAAAAAAAB>: Logged out in=119 out=1055 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 bo$
Dec 25 19:33:32 dhost dovecot: imap-login: Login: user=<[email protected]>, method=PLAIN, rip=::1, lip=::1, mpid=12529, secured, session=<qWhmtfzT6p0AAAAAAAAAAAAAAAAAAAAB>
Dec 25 19:33:32 dhost dovecot: imap([email protected])<12529><qWhmtfzT6p0AAAAAAAAAAAAAAAAAAAAB>: Logged out in=119 out=1055 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 bo$
Dec 25 19:34:32 dhost dovecot: imap-login: Login: user=<[email protected]>, method=PLAIN, rip=::1, lip=::1, mpid=12575, secured, session=<t//5uPzTHp4AAAAAAAAAAAAAAAAAAAAB>
Dec 25 19:34:32 dhost dovecot: imap([email protected])<12575><t//5uPzTHp4AAAAAAAAAAAAAAAAAAAAB>: Logged out in=119 out=1055 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 bo$
Dec 25 19:35:03 dhost dovecot: pop3-login: Disconnected (no auth attempts in 0 secs): user=<>, rip=::1, lip=::1, secured, session=<DSPeuvzTuMwAAAAAAAAAAAAAAAAAAAAB>
Dec 25 19:35:03 dhost dovecot: imap-login: Disconnected (no auth attempts in 0 secs): user=<>, rip=::1, lip=::1, secured, session=<P3LeuvzTUp4AAAAAAAAAAAAAAAAAAAAB>
Dec 25 19:35:04 dhost postfix/smtpd[12702]: connect from localhost[::1]
Dec 25 19:35:04 dhost postfix/smtpd[12702]: lost connection after CONNECT from localhost[::1]
Dec 25 19:35:04 dhost postfix/smtpd[12702]: disconnect from localhost[::1] commands=0/0
Dec 25 19:35:32 dhost dovecot: imap-login: Login: user=<[email protected]>, method=PLAIN, rip=::1, lip=::1, mpid=12714, secured, session=<Q0mNvPzTZp4AAAAAAAAAAAAAAAAAAAAB>
Dec 25 19:35:32 dhost dovecot: imap([email protected])<12714><Q0mNvPzTZp4AAAAAAAAAAAAAAAAAAAAB>: Logged out in=119 out=1055 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 bo$
Dec 25 19:36:32 dhost dovecot: imap-login: Login: user=<[email protected]>, method=PLAIN, rip=::1, lip=::1, mpid=12754, secured, session=<feggwPzTlp4AAAAAAAAAAAAAAAAAAAAB>
Dec 25 19:36:32 dhost dovecot: imap([email protected])<12754><feggwPzTlp4AAAAAAAAAAAAAAAAAAAAB>: Logged out in=119 out=1055 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 bo$
Dec 25 19:37:32 dhost dovecot: imap-login: Login: user=<[email protected]>, method=PLAIN, rip=::1, lip=::1, mpid=12797, secured, session=<5Ku0w/zT0J4AAAAAAAAAAAAAAAAAAAAB>
Dec 25 19:37:32 dhost dovecot: imap([email protected])<12797><5Ku0w/zT0J4AAAAAAAAAAAAAAAAAAAAB>: Logged out in=119 out=1055 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 bo$
Dec 25 19:38:32 dhost dovecot: imap-login: Login: user=<[email protected]>, method=PLAIN, rip=::1, lip=::1, mpid=12831, secured, session=<5BhIx/zT9J4AAAAAAAAAAAAAAAAAAAAB>
Dec 25 19:38:32 dhost dovecot: imap([email protected])<12831><5BhIx/zT9J4AAAAAAAAAAAAAAAAAAAAB>: Logged out in=119 out=1055 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 bo$
Dec 25 19:39:32 dhost dovecot: imap-login: Login: user=<[email protected]>, method=PLAIN, rip=::1, lip=::1, mpid=12963, secured, session=<xuPbyvzTFp8AAAAAAAAAAAAAAAAAAAAB>
Dec 25 19:39:32 dhost dovecot: imap([email protected])<12963><xuPbyvzTFp8AAAAAAAAAAAAAAAAAAAAB>: Logged out in=119 out=1055 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 bo$
Dec 25 19:40:04 dhost dovecot: imap-login: Disconnected (no auth attempts in 0 secs): user=<>, rip=::1, lip=::1, secured, session=<kUjQzPzTOJ8AAAAAAAAAAAAAAAAAAAAB>
Dec 25 19:40:04 dhost dovecot: pop3-login: Disconnected (no auth attempts in 0 secs): user=<>, rip=::1, lip=::1, secured, session=<RkrQzPzTns0AAAAAAAAAAAAAAAAAAAAB>
Dec 25 19:40:05 dhost postfix/smtpd[13075]: connect from localhost[::1]
     
    Last edited by a moderator: Dec 25, 2021
    mezelf, Dec 25, 2021
    #4
  5. mezelf

    mezelf Member

    Thank you for your reply. My apologies for not immediately coming back. I wanted to try a few more things before I come back. Regarding your comment over removing/reinstalling software, system software has been restored to pre-update state. At this point in time, I am vary of reinstalling the whole system. Was very careful during installation, I thought and there is a good chance, whatever mistake was made will be made again. Regarding your comment on posting in the wrong forum, I am not very familiar with the rules (even after going through the link you provided), somehow I thought this was not related to ISPConfig, maybe I am wrong, don't know.
     
    mezelf, Dec 25, 2021
    #5
  6. Steini86

    Steini86 Active Member

    You have an error:
    That means you do not have the standard configuration. Can you post the changes you have done to your configuration? If you do not know them, do not care about them or do not need them, you can force an ispconfig upgrade and reconfigure your mail (postfix) services. This should install a working configuration.
    If there is no connection attempt being logged, it can be a firewall issue (port 25 not open from external) or DNS issue (mx entry does not resolve to your server) or not-working but offered IPv6 configuration.
    I would suggest to use one of the many email tester suites on your server (like https://www.internet.nl/test-mail/ )
     
    Steini86, Dec 25, 2021
    #6
    mezelf likes this.
  7. alemarengo

    alemarengo New Member

    Hi everybody.
    I hope you can help me. I have the same exact issue: followed the guide and reinstalled the entire config 3 times.
    Now I have 3 websites running, email sending is ok, NO receiving from external service, only to/from localhost.
    There could be the chance that I am missing something in my config because I just followed the perfect server guide ignoring some crucial requirements. For instance, I cannot set a dns zone for my cloud server in the provider manager, but only for every single domains; no dns zone set in ispconfig; no mx record set for postfix/dovecot; port 25 seems to be closed accordingly to what telnet says.

    mail.log is the following for just the latest few hours:
    Code:
    Dec 30 10:23:51 shcl-0409a dovecot: imap-login: Login: user=<info@a*****.it>, method=PLAIN, rip=::1, lip=::1, mpid=17379, secured, session=<sTHTnFnUTNoAAAAAAAAAAAAAAAAAAAAB>
Dec 30 10:23:51 shcl-0409a dovecot: imap(info@a*****.it)<17379><sTHTnFnUTNoAAAAAAAAAAAAAAAAAAAAB>: Logged out in=82 out=802 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Dec 30 10:23:52 shcl-0409a dovecot: imap-login: Login: user=<info@a*****.it>, method=PLAIN, rip=::1, lip=::1, mpid=17381, secured, session=<oCPWnFnUTtoAAAAAAAAAAAAAAAAAAAAB>
Dec 30 10:23:52 shcl-0409a dovecot: imap(info@a*****.it)<17381><oCPWnFnUTtoAAAAAAAAAAAAAAAAAAAAB>: Logged out in=70 out=754 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Dec 30 10:23:52 shcl-0409a dovecot: imap-login: Login: user=<info@a*****.it>, method=PLAIN, rip=::1, lip=::1, mpid=17385, secured, session=<+/DenFnUUNoAAAAAAAAAAAAAAAAAAAAB>
Dec 30 10:23:52 shcl-0409a dovecot: imap-login: Login: user=<info@a*****.it>, method=PLAIN, rip=::1, lip=::1, mpid=17386, secured, session=<X0rfnFnUUtoAAAAAAAAAAAAAAAAAAAAB>
Dec 30 10:23:52 shcl-0409a dovecot: imap(info@a*****.it)<17386><X0rfnFnUUtoAAAAAAAAAAAAAAAAAAAAB>: Logged out in=233 out=1085 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Dec 30 10:23:52 shcl-0409a dovecot: imap(info@a*****.it)<17385><+/DenFnUUNoAAAAAAAAAAAAAAAAAAAAB>: Logged out in=318 out=2063 deleted=0 expunged=0 trashed=0 hdr_count=2 hdr_bytes=451 body_count=0 body_bytes=0
Dec 30 10:23:55 shcl-0409a dovecot: imap-login: Login: user=<info@a*****.it>, method=PLAIN, rip=::1, lip=::1, mpid=17390, secured, session=<JywLnVnUVNoAAAAAAAAAAAAAAAAAAAAB>
Dec 30 10:23:55 shcl-0409a dovecot: imap(info@a*****.it)<17390><JywLnVnUVNoAAAAAAAAAAAAAAAAAAAAB>: Logged out in=32 out=541 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Dec 30 10:23:55 shcl-0409a dovecot: imap-login: Login: user=<info@a*****.it>, method=PLAIN, rip=::1, lip=::1, mpid=17392, secured, session=<GY8MnVnUVtoAAAAAAAAAAAAAAAAAAAAB>
Dec 30 10:23:55 shcl-0409a dovecot: imap(info@a*****.it)<17392><GY8MnVnUVtoAAAAAAAAAAAAAAAAAAAAB>: Logged out in=44 out=643 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Dec 30 10:24:06 shcl-0409a postfix/smtpd[17407]: connect from localhost.localdomain[127.0.0.1]
Dec 30 10:24:06 shcl-0409a postfix/smtpd[17407]: 51D2F82444: client=localhost.localdomain[127.0.0.1], sasl_method=LOGIN, sasl_username=info@a*****.it
Dec 30 10:24:06 shcl-0409a postfix/cleanup[17410]: 51D2F82444: message-id=<b520b66c9213a808e0f62b14de8f016e@a*****.it>
Dec 30 10:24:06 shcl-0409a postfix/qmgr[1041]: 51D2F82444: from=<info@a*****.it>, size=662, nrcpt=1 (queue active)
Dec 30 10:24:06 shcl-0409a postfix/smtpd[17407]: disconnect from localhost.localdomain[127.0.0.1] ehlo=1 auth=1 mail=1 rcpt=1 data=1 quit=1 commands=6
Dec 30 10:24:06 shcl-0409a dovecot: imap-login: Login: user=<info@a*****.it>, method=PLAIN, rip=::1, lip=::1, mpid=17413, secured, session=<OguynVnUctoAAAAAAAAAAAAAAAAAAAAB>
Dec 30 10:24:06 shcl-0409a dovecot: imap(info@a*****.it)<17413><OguynVnUctoAAAAAAAAAAAAAAAAAAAAB>: Logged out in=477 out=728 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Dec 30 10:24:07 shcl-0409a postfix/smtp[17411]: 51D2F82444: to=<a*****.m*****@gmail.com>, relay=gmail-smtp-in.l.google.com[74.125.133.27]:25, delay=0.72, delays=0.11/0.17/0.24/0.2, dsn=2.0.0, status=sent (250 2.0.0 OK  1640856247 n15si15661043wrf.954 - gsmtp)
Dec 30 10:24:07 shcl-0409a postfix/qmgr[1041]: 51D2F82444: removed
Dec 30 10:24:07 shcl-0409a dovecot: imap-login: Login: user=<info@a*****.it>, method=PLAIN, rip=::1, lip=::1, mpid=17415, secured, session=<x9u9nVnUdtoAAAAAAAAAAAAAAAAAAAAB>
Dec 30 10:24:07 shcl-0409a dovecot: imap(info@a*****.it)<17415><x9u9nVnUdtoAAAAAAAAAAAAAAAAAAAAB>: Logged out in=70 out=754 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Dec 30 10:24:07 shcl-0409a dovecot: imap-login: Login: user=<info@a*****.it>, method=PLAIN, rip=::1, lip=::1, mpid=17417, secured, session=<ta7BnVnUeNoAAAAAAAAAAAAAAAAAAAAB>
Dec 30 10:24:07 shcl-0409a dovecot: imap(info@a*****.it)<17417><ta7BnVnUeNoAAAAAAAAAAAAAAAAAAAAB>: Logged out in=318 out=2047 deleted=0 expunged=0 trashed=0 hdr_count=2 hdr_bytes=451 body_count=0 body_bytes=0
Dec 30 10:24:07 shcl-0409a dovecot: imap-login: Login: user=<info@a*****.it>, method=PLAIN, rip=::1, lip=::1, mpid=17419, secured, session=<oUvCnVnUetoAAAAAAAAAAAAAAAAAAAAB>
Dec 30 10:24:07 shcl-0409a dovecot: imap(info@a*****.it)<17419><oUvCnVnUetoAAAAAAAAAAAAAAAAAAAAB>: Logged out in=233 out=1077 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Dec 30 10:25:02 shcl-0409a postfix/smtpd[17407]: connect from localhost.localdomain[127.0.0.1]
Dec 30 10:25:02 shcl-0409a postfix/smtpd[17407]: lost connection after CONNECT from localhost.localdomain[127.0.0.1]
Dec 30 10:25:02 shcl-0409a postfix/smtpd[17407]: disconnect from localhost.localdomain[127.0.0.1] commands=0/0
Dec 30 10:25:02 shcl-0409a dovecot: imap-login: Disconnected (no auth attempts in 0 secs): user=<>, rip=::1, lip=::1, secured, session=<ORYJoVnUitoAAAAAAAAAAAAAAAAAAAAB>
Dec 30 10:25:02 shcl-0409a dovecot: pop3-login: Disconnected (no auth attempts in 0 secs): user=<>, rip=::1, lip=::1, secured, session=<qToJoVnU3MsAAAAAAAAAAAAAAAAAAAAB>
Dec 30 10:25:08 shcl-0409a dovecot: imap-login: Login: user=<info@a*****.it>, method=PLAIN, rip=::1, lip=::1, mpid=17524, secured, session=<xz1foVnUjtoAAAAAAAAAAAAAAAAAAAAB>
Dec 30 10:25:08 shcl-0409a dovecot: imap(info@a*****.it)<17524><xz1foVnUjtoAAAAAAAAAAAAAAAAAAAAB>: Logged out in=119 out=1055 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Dec 30 10:26:07 shcl-0409a dovecot: imap-login: Login: user=<info@a*****.it>, method=PLAIN, rip=::1, lip=::1, mpid=17544, secured, session=<kZrvpFnUlNoAAAAAAAAAAAAAAAAAAAAB>
Dec 30 10:26:07 shcl-0409a dovecot: imap(info@a*****.it)<17544><kZrvpFnUlNoAAAAAAAAAAAAAAAAAAAAB>: Logged out in=119 out=1055 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Dec 30 10:27:09 shcl-0409a dovecot: imap-login: Login: user=<info@a*****.it>, method=PLAIN, rip=::1, lip=::1, mpid=17582, secured, session=<Lz6eqFnUmNoAAAAAAAAAAAAAAAAAAAAB>
Dec 30 10:27:09 shcl-0409a dovecot: imap(info@a*****.it)<17582><Lz6eqFnUmNoAAAAAAAAAAAAAAAAAAAAB>: Logged out in=119 out=1055 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Dec 30 10:28:07 shcl-0409a dovecot: imap-login: Login: user=<info@a*****.it>, method=PLAIN, rip=::1, lip=::1, mpid=17598, secured, session=<z5sWrFnUnNoAAAAAAAAAAAAAAAAAAAAB>
Dec 30 10:28:07 shcl-0409a dovecot: imap(info@a*****.it)<17598><z5sWrFnUnNoAAAAAAAAAAAAAAAAAAAAB>: Logged out in=119 out=1055 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Dec 30 10:29:08 shcl-0409a dovecot: imap-login: Login: user=<info@a*****.it>, method=PLAIN, rip=::1, lip=::1, mpid=17625, secured, session=<RgWtr1nUoNoAAAAAAAAAAAAAAAAAAAAB>
Dec 30 10:29:08 shcl-0409a dovecot: imap(info@a*****.it)<17625><RgWtr1nUoNoAAAAAAAAAAAAAAAAAAAAB>: Logged out in=119 out=1055 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Dec 30 10:30:02 shcl-0409a dovecot: imap-login: Disconnected (no auth attempts in 0 secs): user=<>, rip=::1, lip=::1, secured, session=<U27xslnUsNoAAAAAAAAAAAAAAAAAAAAB>
Dec 30 10:30:02 shcl-0409a dovecot: pop3-login: Disconnected (no auth attempts in 0 secs): user=<>, rip=::1, lip=::1, secured, session=<g2/xslnUAswAAAAAAAAAAAAAAAAAAAAB>
Dec 30 10:30:02 shcl-0409a postfix/smtpd[17731]: connect from localhost.localdomain[127.0.0.1]
Dec 30 10:30:02 shcl-0409a postfix/smtpd[17731]: lost connection after CONNECT from localhost.localdomain[127.0.0.1]
Dec 30 10:30:02 shcl-0409a postfix/smtpd[17731]: disconnect from localhost.localdomain[127.0.0.1] commands=0/0
Dec 30 10:30:08 shcl-0409a dovecot: imap-login: Login: user=<info@a*****.it>, method=PLAIN, rip=::1, lip=::1, mpid=17737, secured, session=<mQ5Ls1nUtNoAAAAAAAAAAAAAAAAAAAAB>
Dec 30 10:30:08 shcl-0409a dovecot: imap(info@a*****.it)<17737><mQ5Ls1nUtNoAAAAAAAAAAAAAAAAAAAAB>: Logged out in=119 out=1055 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Dec 30 10:31:09 shcl-0409a dovecot: imap-login: Login: user=<info@a*****.it>, method=PLAIN, rip=::1, lip=::1, mpid=17754, secured, session=<NQDutlnUuNoAAAAAAAAAAAAAAAAAAAAB>
Dec 30 10:31:09 shcl-0409a dovecot: imap(info@a*****.it)<17754><NQDutlnUuNoAAAAAAAAAAAAAAAAAAAAB>: Logged out in=119 out=1055 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Dec 30 10:32:10 shcl-0409a dovecot: imap-login: Login: user=<info@a*****.it>, method=PLAIN, rip=::1, lip=::1, mpid=17773, secured, session=<tqCQulnUvNoAAAAAAAAAAAAAAAAAAAAB>
Dec 30 10:32:10 shcl-0409a dovecot: imap(info@a*****.it)<17773><tqCQulnUvNoAAAAAAAAAAAAAAAAAAAAB>: Logged out in=119 out=1055 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Dec 30 10:33:12 shcl-0409a dovecot: imap-login: Login: user=<info@a*****.it>, method=PLAIN, rip=::1, lip=::1, mpid=17790, secured, session=<xAFAvlnUwNoAAAAAAAAAAAAAAAAAAAAB>
Dec 30 10:33:12 shcl-0409a dovecot: imap(info@a*****.it)<17790><xAFAvlnUwNoAAAAAAAAAAAAAAAAAAAAB>: Logged out in=119 out=1063 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
     
    alemarengo, Dec 30, 2021
    #7
  8. till

    till Super Moderator Staff Member ISPConfig Developer

    Ok, so you don't have an issue with your ispconfig server setup then and that#s why reinstalling can't help as there was no installation problem. If you can send and receive emails locally, then your server is set up correctly. To run an email server, port 25 must be open as external mail servers will deliver emails via port 25 to your server and your server will use port 25 to communicate with other servers. Then you must have a DNS MX-Record for the domain you like to receive email for which must point to your server hostname. Without a MX record, no external mail system knows where to send emails for this domain to, so no mails will ever get sent to your server. So you must gtet port v25 opened to run your mail system and you must create a MX record pointing to your server hostname and the server hostname must exist in DNS and must have an A-Record pointing to your server IP (and the server IP should have a valid reverse record too). If that's not possible at your current cloud provider, then use a different provider or don't try to run a mail system. Personally, I e.g. use hetzner.com and their cloud servers have no such restrictions.
     
    till, Dec 30, 2021
    #8
  9. alemarengo

    alemarengo New Member

    Thanks Till.
    Should I create a MX record for each domain pointing to my server hostname? I set up this one, is it correct?
    MX @ shcl-0409a.myserver.com 10 3600
     
    alemarengo, Dec 30, 2021
    #9
  10. Taleman

    Taleman Well-Known Member HowtoForge Supporter

    My signature has link to e-mail setup Tutorial with ISPConfig.
     
    Taleman, Dec 30, 2021
    #10
  11. alemarengo

    alemarengo New Member

    I did everything you suggested:
    • created an MX record pointing to server hostname in every dns zone for each domain, value @
    • server hostname exist in any dns zone for each domain, IP address
    • created an A record pointing to the server IP
    I think that the ISP is blocking port 25. Even if it's quite strange though and I cannot have confirmation about this: this cloud instance cannot be reached by telnet on port 25 and the support keeps telling me port 25 is ok; I have another cloud instance by the same supplier with plesk installed and there port 25 is open and ok.
    So... I have a doubt: is it possible that my cloud server, configured from scratch by following the perfect server guide has a blocked port 25, whereas the same cloud server featuring plesk + roundcube has not? Is it possible?
    Ip lookup of my cloud server tells me that is OVH, even if my supplier is italian.
     
    alemarengo, Jan 1, 2022
    #11
  12. till

    till Super Moderator Staff Member ISPConfig Developer

    till, Jan 1, 2022
    #12
  13. alemarengo

    alemarengo New Member

    Let me say: I noticed that, after following your guide step by step, I found the following lines in master.cf: why? Are they expected to be there?
    Plus: I replaced 127.0.0.1:10025 with 127.0.0.1:25 to make roundcube to send mails. The problem to receive mail is still there. I think they shouldn't be there and are useless.
     
    alemarengo, Jan 1, 2022
    #13
  14. till

    till Super Moderator Staff Member ISPConfig Developer

    They are an essential part of the mail system and are used by amavis to communicate with postfix.

    Ok, so you changed essential parts of the mail setup in a way that it must stop working. Your setup must fail now after the changes you made.

    They have to be there of course.

    Due to your manual changes you completely broke the mail setup in a way that it must fail. Reinstall the server by starting with an empty and clean base system, the easiest way in doing this is to use the auto installer https://www.howtoforge.com/ispconfig-autoinstall-debian-ubuntu/. And don not alter the mail setup in any way manually.
     
    till, Jan 1, 2022
    #14
  15. alemarengo

    alemarengo New Member

    Ok... Thanks for your reply.
    1st: I find very strange to have completely broken an entire mail setup. Very sorry for that. No solution to avoid to reinstall?
    2nd: roundcube didn't work at all before replacing 127.0.01:10025 to 127.0.0.1: why? At least NOW I can actually send email and send copies to another email address.
     
    alemarengo, Jan 1, 2022
    #15
  16. alemarengo

    alemarengo New Member

    Just made a fresh reinstallation from scratch of Debian 10, then launched your script for ispconfig autoinstall.
    Here's the thing:
    • port 25 is blocked
    • phpmyadmin and roundcube are reachable on 8080 instead of 8081
    • email not sending - SMTP Error (-1) - nor receiving
    Result: the same as previous configuration, even worse, since I cannot send emails - that's why I replaced 127.0.0.1:10025 to 127.0.0.1:25 in master.cf
    Any suggestion at this point?
    Here's the log you may need to read:
     
    alemarengo, Jan 2, 2022
    #16
  17. Jesse Norell

    Jesse Norell Well-Known Member Staff Member Howtoforge Staff

    Your master.cf is bad:
    [WARN] I found no "smtp" entry in your postfix master.cf

    What does it contain?

    That is normal for an apache system.
     
    Jesse Norell, Jan 2, 2022
    #17
  18. alemarengo

    alemarengo New Member

    Consider I did not edit master.cf, this is how it looks like after ispconfig installation launched from your script (which is the same I had in my previous config):
     
    alemarengo, Jan 2, 2022
    #18
  19. mezelf

    mezelf Member

    Hi there,
    I have been trying out things and spent some time in better understanding the mail log file entries. Not to much success though.
    - About the error you have pointed out with sasl_passwd : When I first had the email problems after Debian 10 installation, the first instinct was to use the external smtp server that the hosting company provides. In that process, I had to update the sasl_passwd via an application (it is an encrypted file). Later when I rolled back all changes again to use own server and tackle the email problem, this is/was the only part that was not undone. Could not find a way to undo it. In any case, there are no any DNS references to the external smtp server, I do not understand why this sasl_passwd file comes into picture.
    - I have followed your advice and I have upgraded the ISPCONFIG3 to the latest version. It did not change the situation for this sasl_passwd problem.
    - When I do "sudo ss -tulpn | grep smtp", I can see that the 'master' deamon of postfix listens to port 25. I understand that firewall may still be blocking it. How do I check it?
    - As before, when an email is sent from the local email address to itself, it is received without any problem. Also when an email is sent from this local email address to an external email address (e.g. a gmail.com address), the email is received at the gmail address. But when an email is sent from an gmail address to the t...t.com domain email address, when I check the mail.log, I see basically nothing related (only some entries which indicate that roundcube is polling the incoming email).
    Code:
    Jan  3 00:06:42 dhost dovecot: imap([email protected])<3174><XlYMdaHUxqkAAAAAAAAAAAAAAAAAAAAB>: Logged out in=121 out=1359 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 bod$
Jan  3 00:07:42 dhost dovecot: imap-login: Login: user=<[email protected]>, method=PLAIN, rip=::1, lip=::1, mpid=3267, secured, session=<Ai+feKHU6qkAAAAAAAAAAAAAAAAAAAAB>
Jan  3 00:07:42 dhost dovecot: imap([email protected])<3267><Ai+feKHU6qkAAAAAAAAAAAAAAAAAAAAB>: Logged out in=121 out=1351 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 bod$
Jan  3 00:08:43 dhost dovecot: imap-login: Login: user=<[email protected]>, method=PLAIN, rip=::1, lip=::1, mpid=3364, secured, session=<HupBfKHUEKoAAAAAAAAAAAAAAAAAAAAB>
Jan  3 00:08:43 dhost dovecot: imap([email protected])<3364><HupBfKHUEKoAAAAAAAAAAAAAAAAAAAAB>: Logged out in=121 out=1351 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 bod$
Jan  3 00:09:43 dhost dovecot: imap-login: Login: user=<[email protected]>, method=PLAIN, rip=::1, lip=::1, mpid=3500, secured, session=</q7Vf6HUMKoAAAAAAAAAAAAAAAAAAAAB>
Jan  3 00:09:43 dhost dovecot: imap([email protected])<3500></q7Vf6HUMKoAAAAAAAAAAAAAAAAAAAAB>: Logged out in=121 out=1351 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 bod$
Jan  3 00:10:43 dhost dovecot: imap-login: Login: user=<[email protected]>, method=PLAIN, rip=::1, lip=::1, mpid=3824, secured, session=<A8Zpg6HUWqoAAAAAAAAAAAAAAAAAAAAB>
Jan  3 00:10:43 dhost dovecot: imap([email protected])<3824><A8Zpg6HUWqoAAAAAAAAAAAAAAAAAAAAB>: Logged out in=121 out=1351 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 bod$
Jan  3 00:11:44 dhost dovecot: imap-login: Login: user=<[email protected]>, method=PLAIN, rip=::1, lip=::1, mpid=4138, secured, session=<dSUMh6HUbqoAAAAAAAAAAAAAAAAAAAAB>
Jan  3 00:11:44 dhost dovecot: imap([email protected])<4138><dSUMh6HUbqoAAAAAAAAAAAAAAAAAAAAB>: Logged out in=121 out=1351 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 bod$
Jan  3 00:12:44 dhost dovecot: imap-login: Login: user=<[email protected]>, method=PLAIN, rip=::1, lip=::1, mpid=4268, secured, session=<2hOgiqHUnKoAAAAAAAAAAAAAAAAAAAAB>
Jan  3 00:12:44 dhost dovecot: imap([email protected])<4268><2hOgiqHUnKoAAAAAAAAAAAAAAAAAAAAB>: Logged out in=121 out=1359 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 bod$
    This makes me wonder if the MX record for the mail is wrong in the DNS entries. it is currently mail.t...t.com whereas, when I configured the postfix, I did not enter any mail.t...t.com in the prompts but instead with hostname like dhost.t...t.com. But then, this MX record has not changed since Debian 8... It has always been mail.t...t.com...
    Kind Regards.
     
    Last edited by a moderator: Jan 3, 2022
    mezelf, Jan 3, 2022
    #19
  20. till

    till Super Moderator Staff Member ISPConfig Developer

    The problem is not from our script nor from you using it then. Our script expects to find a master.cf file as it's delivered by Debian and Ubuntu, means a correct and working master.cf. Your master.cf has been modified though, most likely by the person or company that provided you with the install image. What they did is basically disable an installed postfix instance so that it can't be used for email receiving at all. To fix their base config, edit master.cf file and remove the # in front of this line:

    Code:
    #smtp inet n - n - - smtpd
    so that it looks like this:

    Code:
    smtp inet n - n - - smtpd
    and then restart postfix.
     
    till, Jan 3, 2022
    #20
    mezelf likes this.
Page 1 of 2

Share This Page