My YunoHost server
Hardware: RPi
YunoHost version: 4.3.3
I have access to my server : Through SSH | through the webadmin
Are you in a special context or did you perform some particular tweaking on your YunoHost instance ? : yes
If yes, please explain:
SMTP relay was set up in 2020, before the feature was integrated. Worked fine until a few days ago.
Description of my issue
Like I said above, I have a manual SMTP relay config I’ve been running for ages using SendGrid. I just noticed today none of my outgoing was working. After checking all the settings in both Sendgrid and postfix and confirming that nothing changed. I then looked at my mail logs etc. and saw a righteous mess:
LOGFILE: journalctl
-- Logs begin at Mon 2021-11-15 17:47:26 EST, end at Mon 2021-11-15 19:11:06 EST. --
Nov 15 19:06:38 postmulti[4786]: /usr/sbin/postconf: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 postmulti[4786]: /usr/sbin/postconf: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 postmulti[4786]: /usr/sbin/postconf: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 postmulti[4786]: /usr/sbin/postconf: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 postmulti[4786]: /usr/sbin/postconf: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 postmulti[4786]: /usr/sbin/postconf: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 postmulti[4786]: /usr/sbin/postconf: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 postmulti[4786]: /usr/sbin/postconf: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 postmulti[4786]: /usr/sbin/postconf: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 postmulti[4786]: /usr/sbin/postconf: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 postmulti[4786]: postsuper: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 postfix/postsuper[4832]: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 postmulti[4786]: /usr/sbin/postconf: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 postfix/postlog[4845]: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 postfix/postfix-script[4845]: warning: not owned by root: /etc/postfix/./sasl_passwd
Nov 15 19:06:38 postfix/postlog[4846]: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 postfix/postfix-script[4846]: warning: not owned by root: /etc/postfix/./sasl_passwd.db
Nov 15 19:06:38 postfix/postlog[4852]: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 postfix/postfix-script[4852]: warning: symlink leaves directory: /etc/postfix/./makedefs.out
Nov 15 19:06:38 postfix/postlog[4902]: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 postfix/postfix-script[4902]: starting the Postfix mail system
Nov 15 19:06:38 postfix/master[4904]: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 postfix/master[4904]: daemon started -- version 3.4.14, configuration /etc/postfix
Nov 15 19:06:38 systemd[1]: Started Postfix Mail Transport Agent (instance -).
Nov 15 19:06:38 postfix/qmgr[4906]: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 postfix/pickup[4905]: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 postfix/qmgr[4906]: 0069CAAE: from=<user@domain.tld>, size=1122, nrcpt=1 (queue active)
Nov 15 19:06:38 postfix/trivial-rewrite[4908]: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 postfix/qmgr[4906]: 664FFB59: from=<user@domain.tld>, size=2802, nrcpt=1 (queue active)
Nov 15 19:06:38 postfix/qmgr[4906]: A38862E: from=<user@domain.tld>, size=65884, nrcpt=1 (queue active)
Nov 15 19:06:38 postfix/qmgr[4906]: C04BE198D: from=<user@domain.tld>, size=5263, nrcpt=1 (queue active)
Nov 15 19:06:39 postfix/qmgr[4906]: F161D153F: from=<user@domain.tld>, size=4347, nrcpt=1 (queue active)
Nov 15 19:06:39 postfix/qmgr[4906]: DD789B13: from=<user@domain.tld>, size=4778, nrcpt=1 (queue active)
Nov 15 19:06:39 postfix/qmgr[4906]: 1B3B0700: from=<user2@domain.tld>, size=1037, nrcpt=1 (queue active)
Nov 15 19:06:39 postfix/qmgr[4906]: 4D90C1972: from=<user@domain.tld>, size=11613, nrcpt=1 (queue active)
Nov 15 19:06:39 postfix/qmgr[4906]: 72A57CA4: from=<user@domain.tld>, size=12564, nrcpt=2 (queue active)
Nov 15 19:06:39 postfix/qmgr[4906]: 0B1701931: from=<user@domain.tld>, size=2044, nrcpt=1 (queue active)
Nov 15 19:06:39 postfix/qmgr[4906]: 85E0F1141: from=<user@domain.tld>, size=13059, nrcpt=2 (queue active)
Nov 15 19:06:39 postfix/master[4904]: warning: process /usr/lib/postfix/sbin/smtp pid 4909 killed by signal 11
Nov 15 19:06:39 postfix/master[4904]: warning: /usr/lib/postfix/sbin/smtp: bad command startup -- throttling
Nov 15 19:06:39 postfix/qmgr[4906]: 9361F1824: from=<user@domain.tld>, size=3973184, nrcpt=2 (queue active)
Nov 15 19:06:39 postfix/qmgr[4906]: 85912CC5: from=<user@domain.tld>, size=1635, nrcpt=1 (queue active)
Nov 15 19:07:39 postfix/master[4904]: warning: process /usr/lib/postfix/sbin/smtp pid 4924 killed by signal 11
Nov 15 19:07:39 postfix/master[4904]: warning: /usr/lib/postfix/sbin/smtp: bad command startup -- throttling
Nov 15 19:08:39 postfix/master[4904]: warning: process /usr/lib/postfix/sbin/smtp pid 4948 killed by signal 11
Nov 15 19:08:39 postfix/master[4904]: warning: /usr/lib/postfix/sbin/smtp: bad command startup -- throttling
Nov 15 19:09:39 postfix/master[4904]: warning: process /usr/lib/postfix/sbin/smtp pid 5016 killed by signal 11
Nov 15 19:09:39 postfix/master[4904]: warning: /usr/lib/postfix/sbin/smtp: bad command startup -- throttling
Nov 15 19:10:39 postfix/master[4904]: warning: process /usr/lib/postfix/sbin/smtp pid 5022 killed by signal 11
Nov 15 19:10:39 postfix/master[4904]: warning: /usr/lib/postfix/sbin/smtp: bad command startup -- throttling
LOGFILE: /var/log/mail.err
Nov 8 16:22:13 domain postfix/postqueue[10276]: fatal: malformed showq server response
Nov 8 17:36:45 domain postfix/qmgr[14945]: fatal: timeout connecting to transport: smtp
Nov 8 19:07:23 domain postfix/postqueue[13454]: fatal: malformed showq server response
Nov 8 22:36:47 domain postfix/qmgr[12111]: fatal: timeout connecting to transport: smtp
Nov 9 03:36:48 domain postfix/qmgr[17502]: fatal: timeout connecting to transport: smtp
Nov 9 07:11:42 domain postfix/postqueue[24552]: fatal: malformed showq server response
Nov 9 08:36:49 domain postfix/qmgr[21114]: fatal: timeout connecting to transport: smtp
Nov 9 13:36:51 domain postfix/qmgr[25909]: fatal: timeout connecting to transport: smtp
Nov 9 18:36:56 domain postfix/qmgr[8650]: fatal: timeout connecting to transport: smtp
Nov 9 19:13:10 domain postfix/postqueue[1714]: fatal: malformed showq server response
Nov 9 23:41:52 domain postfix/qmgr[1035]: fatal: timeout connecting to transport: smtp
Nov 10 04:41:53 domain postfix/qmgr[11989]: fatal: timeout connecting to transport: smtp
Nov 10 07:17:50 domain postfix/postqueue[17746]: fatal: malformed showq server response
Nov 10 09:46:53 domain postfix/qmgr[15588]: fatal: timeout connecting to transport: smtp
Nov 10 14:46:54 domain postfix/qmgr[20885]: fatal: timeout connecting to transport: smtp
Nov 10 19:08:58 domain postfix/postqueue[32547]: fatal: malformed showq server response
Nov 10 19:46:57 domain postfix/qmgr[26578]: fatal: timeout connecting to transport: smtp
Nov 11 00:46:59 domain postfix/qmgr[1026]: fatal: timeout connecting to transport: smtp
Nov 11 05:47:00 domain postfix/qmgr[4547]: fatal: timeout connecting to transport: smtp
Nov 11 07:05:12 domain postfix/postqueue[9829]: fatal: malformed showq server response
Nov 11 10:47:01 domain postfix/qmgr[8424]: fatal: timeout connecting to transport: smtp
Nov 11 15:47:05 domain postfix/qmgr[12831]: fatal: timeout connecting to transport: smtp
Nov 11 19:18:32 domain postfix/postqueue[22230]: fatal: malformed showq server response
Nov 11 20:47:06 domain postfix/qmgr[18946]: fatal: timeout connecting to transport: smtp
Nov 12 01:47:07 domain postfix/qmgr[23633]: fatal: timeout connecting to transport: smtp
Nov 12 06:47:08 domain postfix/qmgr[27246]: fatal: timeout connecting to transport: smtp
Nov 12 07:16:55 domain postfix/postqueue[31471]: fatal: malformed showq server response
Nov 12 11:52:07 domain postfix/qmgr[31079]: fatal: timeout connecting to transport: smtp
Nov 12 16:52:08 domain postfix/qmgr[5427]: fatal: timeout connecting to transport: smtp
Nov 12 19:05:32 domain postfix/postqueue[24129]: fatal: malformed showq server response
Nov 12 21:52:09 domain postfix/qmgr[22046]: fatal: timeout connecting to transport: smtp
Nov 13 02:52:11 domain postfix/qmgr[26484]: fatal: timeout connecting to transport: smtp
Nov 13 07:19:17 domain postfix/postqueue[1607]: fatal: malformed showq server response
Nov 13 07:52:12 domain postfix/qmgr[30034]: fatal: timeout connecting to transport: smtp
Nov 13 12:52:15 domain postfix/qmgr[2358]: fatal: timeout connecting to transport: smtp
Nov 13 17:52:16 domain postfix/qmgr[8147]: fatal: timeout connecting to transport: smtp
Nov 13 19:03:07 domain postfix/postqueue[12922]: fatal: malformed showq server response
Nov 13 22:57:15 domain postfix/qmgr[12057]: fatal: timeout connecting to transport: smtp
Nov 14 03:57:19 domain postfix/qmgr[18408]: fatal: timeout connecting to transport: smtp
Nov 14 07:12:15 domain postfix/postqueue[2705]: fatal: malformed showq server response
Nov 14 08:57:20 domain postfix/qmgr[29361]: fatal: timeout connecting to transport: smtp
Nov 14 13:57:21 domain postfix/qmgr[6609]: fatal: timeout connecting to transport: smtp
Nov 14 18:57:22 domain postfix/qmgr[27987]: fatal: timeout connecting to transport: smtp
Nov 14 19:18:03 domain postfix/postqueue[1591]: fatal: malformed showq server response
Nov 14 23:57:24 domain postfix/qmgr[982]: fatal: timeout connecting to transport: smtp
Nov 15 04:57:26 domain postfix/qmgr[6811]: fatal: timeout connecting to transport: smtp
Nov 15 07:15:36 domain postfix/postqueue[12619]: fatal: malformed showq server response
Nov 15 09:57:28 domain postfix/qmgr[10537]: fatal: timeout connecting to transport: smtp
Nov 15 14:57:29 domain postfix/qmgr[14821]: fatal: timeout connecting to transport: smtp
Nov 15 16:05:10 domain postfix/postqueue[25049]: fatal: malformed showq server response
LOGFILE: /var/log/mail.log
Nov 15 19:06:37 domain postmulti[4786]: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:37 domain postfix[4787]: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:37 domain postfix[4787]: Postfix is running with backwards-compatible default settings
Nov 15 19:06:37 domain postfix[4787]: See http://www.postfix.org/COMPATIBILITY_README.html for details
Nov 15 19:06:37 domain postfix[4787]: To disable backwards compatibility use "postconf compatibility_level=2" and "postfix reload"
Nov 15 19:06:37 domain postfix/master[4792]: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 domain postfix/postsuper[4832]: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 domain postfix/postlog[4845]: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 domain postfix/postfix-script[4845]: warning: not owned by root: /etc/postfix/./sasl_passwd
Nov 15 19:06:38 domain postfix/postlog[4846]: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 domain postfix/postfix-script[4846]: warning: not owned by root: /etc/postfix/./sasl_passwd.db
Nov 15 19:06:38 domain postfix/postlog[4852]: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 domain postfix/postfix-script[4852]: warning: symlink leaves directory: /etc/postfix/./makedefs.out
Nov 15 19:06:38 domain postfix/postlog[4902]: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 domain postfix/postfix-script[4902]: starting the Postfix mail system
Nov 15 19:06:38 domain postfix/master[4904]: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 domain postfix/master[4904]: daemon started -- version 3.4.14, configuration /etc/postfix
Nov 15 19:06:38 domain postfix/qmgr[4906]: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 domain postfix/pickup[4905]: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 domain postfix/qmgr[4906]: 0069CAAE: from=<user@domain.tld>, size=1122, nrcpt=1 (queue active)
Nov 15 19:06:38 domain postfix/trivial-rewrite[4908]: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:06:38 domain postfix/qmgr[4906]: 664FFB59: from=<user@domain.tld>, size=2802, nrcpt=1 (queue active)
Nov 15 19:06:38 domain postfix/qmgr[4906]: A38862E: from=<user@domain.tld>, size=65884, nrcpt=1 (queue active)
Nov 15 19:06:38 domain postfix/qmgr[4906]: C04BE198D: from=<user@domain.tld>, size=5263, nrcpt=1 (queue active)
Nov 15 19:06:39 domain postfix/qmgr[4906]: F161D153F: from=<user@domain.tld>, size=4347, nrcpt=1 (queue active)
Nov 15 19:06:39 domain postfix/qmgr[4906]: DD789B13: from=<user@domain.tld>, size=4778, nrcpt=1 (queue active)
Nov 15 19:06:39 domain postfix/qmgr[4906]: 1B3B0700: from=<user2@domain.tld>, size=1037, nrcpt=1 (queue active)
Nov 15 19:06:39 domain postfix/qmgr[4906]: 4D90C1972: from=<user@domain.tld>, size=11613, nrcpt=1 (queue active)
Nov 15 19:06:39 domain postfix/qmgr[4906]: 72A57CA4: from=<user@domain.tld>, size=12564, nrcpt=2 (queue active)
Nov 15 19:06:39 domain postfix/qmgr[4906]: 0B1701931: from=<user@domain.tld>, size=2044, nrcpt=1 (queue active)
Nov 15 19:06:39 domain postfix/qmgr[4906]: 85E0F1141: from=<user@domain.tld>, size=13059, nrcpt=2 (queue active)
Nov 15 19:06:39 domain postfix/master[4904]: warning: process /usr/lib/postfix/sbin/smtp pid 4909 killed by signal 11
Nov 15 19:06:39 domain postfix/master[4904]: warning: /usr/lib/postfix/sbin/smtp: bad command startup -- throttling
Nov 15 19:06:39 domain postfix/qmgr[4906]: 9361F1824: from=<user@domain.tld>, size=3973184, nrcpt=2 (queue active)
Nov 15 19:06:39 domain postfix/qmgr[4906]: 85912CC5: from=<user@domain.tld>, size=1635, nrcpt=1 (queue active)
Nov 15 19:06:41 domain postfix/postqueue[4910]: warning: /etc/postfix/main.cf, line 179: overriding earlier entry: smtp_tls_security_level=may
Nov 15 19:07:39 domain postfix/master[4904]: warning: process /usr/lib/postfix/sbin/smtp pid 4924 killed by signal 11
Nov 15 19:07:39 domain postfix/master[4904]: warning: /usr/lib/postfix/sbin/smtp: bad command startup -- throttling
Nov 15 19:07:53 domain dovecot: master: Warning: Killed with signal 15 (by pid=4928 uid=0 code=kill)
Nov 15 19:07:53 domain dovecot: imap(user)<4362><SvQ8mdzQIzbAqFYO>: Server shutting down. in=1068 out=7861 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Nov 15 19:07:53 domain dovecot: master: Dovecot v2.3.4.1 (f79e8e7e4) starting up for imap, sieve (core dumps disabled)
Nov 15 19:08:25 domain dovecot: imap-login: Login: user=<user>, method=PLAIN, rip=192.168.86.14, lip=192.168.86.26, mpid=4945, TLS, session=<jwlqudzQGDfAqFYO>
Nov 15 19:08:39 domain postfix/master[4904]: warning: process /usr/lib/postfix/sbin/smtp pid 4948 killed by signal 11
Nov 15 19:08:39 domain postfix/master[4904]: warning: /usr/lib/postfix/sbin/smtp: bad command startup -- throttling
Nov 15 19:09:39 domain postfix/master[4904]: warning: process /usr/lib/postfix/sbin/smtp pid 5016 killed by signal 11
Nov 15 19:09:39 domain postfix/master[4904]: warning: /usr/lib/postfix/sbin/smtp: bad command startup -- throttling
Nov 15 19:10:39 domain postfix/master[4904]: warning: process /usr/lib/postfix/sbin/smtp pid 5022 killed by signal 11
Nov 15 19:10:39 domain postfix/master[4904]: warning: /usr/lib/postfix/sbin/smtp: bad command startup -- throttling
Nov 15 19:10:52 domain dovecot: imap-login: Login: user=<user>, method=PLAIN, rip=192.168.86.14, lip=192.168.86.26, mpid=5027, TLS, session=<RFolwtzQTJLAqFYO>
Nov 15 19:10:54 domain dovecot: imap(user)<5027><RFolwtzQTJLAqFYO>: Connection closed (UID FETCH finished 1.624 secs ago) in=555 out=5282 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
No clue where to even start–any help would be appreciated.
EDIT: I reset all the config files to be managed by Yunohost and did the SMTP relay through the new functionality. Result is the same (minus the nags about root ownership etc.).
EDIT 2: A simple apt reinstall got everything working, thanks!