Hello all, i just tried to configure autoresponder for some of my email accounts and it seems that the vacation message is placed in the postfix queue. I see nothing special in maill.log: Code: 2024-01-12T23:13:28.367284+01:00 s2 postfix/smtpd[49275]: connect from mail-ej1-f46.google.com[209.85.218.46] 2024-01-12T23:13:28.889993+01:00 s2 postfix/smtpd[49275]: D938D1362236: client=mail-ej1-f46.google.com[209.85.218.46] 2024-01-12T23:13:28.903862+01:00 s2 postfix/cleanup[52398]: D938D1362236: message-id=<[email protected]> 2024-01-12T23:13:30.083213+01:00 s2 postfix/qmgr[49140]: D938D1362236: from=<***@gmail.com>, size=3156, nrcpt=1 (queue active) 2024-01-12T23:13:30.094526+01:00 s2 postfix/smtpd[49275]: disconnect from mail-ej1-f46.google.com[209.85.218.46] ehlo=2 starttls=1 mail=1 rcpt=1 bdat=1 quit=1 commands=7 2024-01-12T23:13:30.141290+01:00 s2 dovecot: lmtp(52403): Connect from local 2024-01-12T23:13:30.201300+01:00 s2 dovecot: lmtp(***@***.pl)<52403><2HViCIq5oWWzzAAApXXQdg>: sieve: msgid=<[email protected]>: vacation action: sent vacation response to <***@gmail.com> 2024-01-12T23:13:30.211096+01:00 s2 postfix/lmtp[52402]: D938D1362236: to=<***@*.pl>, relay=s2.pietkunkancelaria.pl[private/dovecot-lmtp], delay=1.7, delays=1.5/0.04/0.02/0.07, dsn=2.0.0, status=sent (250 2.0 .0 <***@***.pl> 2HViCIq5oWWzzAAApXXQdg Saved) 2024-01-12T23:13:30.211258+01:00 s2 dovecot: lmtp(***@***.pl)<52403><2HViCIq5oWWzzAAApXXQdg>: sieve: msgid=<[email protected]>: stored mail into mailbox 'INBOX' 2024-01-12T23:13:30.211315+01:00 s2 postfix/qmgr[49140]: D938D1362236: removed And the message is placed into the queue bu its not processed: Code: 2DFCB1362756 675 Fri Jan 12 23:13:30 MAILER-DAEMON ***@gmail.com postcat -q 2DFCB1362756 *** ENVELOPE RECORDS maildrop/2DFCB1362756 *** message_arrival_time: Fri Jan 12 23:13:30 2024 named_attribute: rewrite_context=local sender_fullname: sender: recipient: ***@gmail.com *** MESSAGE CONTENTS maildrop/2DFCB1362756 *** X-Sieve: Pigeonhole Sieve 0.5.19 (4eae2f79) Message-ID: <dovecot-sieve-1705097610-149575-0@s2.***.pl> Date: Fri, 12 Jan 2024 23:13:30 +0100 From: <***@***.pl> To: "***" <***@gmail.com> Subject: Out of office reply In-Reply-To: <[email protected]> References: <[email protected]> Auto-Submitted: auto-replied (vacation) Precedence: bulk X-Auto-Response-Suppress: All MIME-Version: 1.0 Content-Type: text/plain; charset=utf-8 Content-Transfer-Encoding: 8bit niemamnie *** HEADER EXTRACTED maildrop/2DFCB1362756 *** *** MESSAGE FILE END maildrop/2DFCB1362756 *** I'm on debian 12 with ISPConfig 3.2.11p1, dovecot, postfix, rspamd. Can anybody help me debug this issue? I'v spent more that a day trying to solve this.
What shows command mailq? If the message is in mail queue, it should get delivered, unless the receiving e-mail server can not or refuses to accept the e-mail. Is postfix running on your server? Code: systemctl --state=failed
yes, the postifix seems to be running: Code: systemctl --state=failed UNIT LOAD ACTIVE SUB DESCRIPTION ● clamav-clamonacc.service loaded failed failed ClamAV On-Access Scanner ● networking.service loaded failed failed Raise network interfaces LOAD = Reflects whether the unit definition was properly loaded. ACTIVE = The high-level unit activation state, i.e. generalization of SUB. SUB = The low-level unit activation state, values depend on unit type. 2 loaded units listed. root@s2:~# systemctl status [email protected] ● [email protected] - Postfix Mail Transport Agent (instance -) Loaded: loaded (/lib/systemd/system/[email protected]; enabled-runtime; preset: enabled) Active: active (running) since Sat 2024-01-13 13:36:20 CET; 34min ago Docs: man:postfix(1) Process: 317050 ExecStartPre=/usr/lib/postfix/configure-instance.sh - (code=exited, status=0/SUCCESS) Process: 317112 ExecStart=/usr/sbin/postmulti -i - -p start (code=exited, status=0/SUCCESS) Tasks: 7 (limit: 19137) Memory: 19.3M CPU: 3.233s CGroup: /system.slice/system-postfix.slice/[email protected] ├─317369 /usr/lib/postfix/sbin/master -w ├─317370 qmgr -l -t unix -u ├─317450 tlsmgr -l -t unix -u -c ├─317451 anvil -l -t unix -u -c ├─320368 smtpd -n smtp -t inet -u -c -o stress= -s 2 ├─322511 smtpd -n smtp -t inet -u -c -o stress= -s 2 └─323201 showq -t unix -u -c i really have no idea wht is going on here. Perhaps i should clarify thhis in the 1st post: the rest of the email communictation is working fine. The only problem there is is with the autoresponders. The mailq prints message queue as it should. The message in question looks like this: Code: 2DFCB1362756 675 Fri Jan 12 23:13:30 MAILER-DAEMON ***@gmail.com
The ID from your log lines and the one in your mailq are different. So please share the log lines surrounding 2DFCB1362756.
the lines in log that mentions 2DFCB1362756: Code: 2024-01-13T12:20:08.241998+01:00 s2 postfix/postsuper[304608]: Requeued: 0 messages 2024-01-13T12:20:14.398140+01:00 s2 postfix/postsuper[304615]: fatal: invalid directory name: 2DFCB1362756 [..] 2024-01-13T12:24:04.759180+01:00 s2 postfix/postqueue[305135]: input attribute value: 2DFCB1362756 2024-01-13T12:24:04.759208+01:00 s2 postfix/postqueue[305135]: public/showq socket: wanted attribute: time 2024-01-13T12:24:04.759234+01:00 s2 postfix/postqueue[305135]: input attribute name: time 2024-01-13T12:24:04.759287+01:00 s2 postfix/postqueue[305135]: input attribute value: 1705097610 2024-01-13T12:24:04.759317+01:00 s2 postfix/postqueue[305135]: public/showq socket: wanted attribute: size 2024-01-13T12:24:04.759343+01:00 s2 postfix/postqueue[305135]: input attribute name: size 2024-01-13T12:24:04.759369+01:00 s2 postfix/postqueue[305135]: input attribute value: 675 2024-01-13T12:24:04.759396+01:00 s2 postfix/postqueue[305135]: public/showq socket: wanted attribute: forced_expire 2024-01-13T12:24:04.759422+01:00 s2 postfix/postqueue[305135]: input attribute name: forced_expire 2024-01-13T12:24:04.759449+01:00 s2 postfix/postqueue[305135]: input attribute value: 0 2024-01-13T12:24:04.759475+01:00 s2 postfix/postqueue[305135]: public/showq socket: wanted attribute: sender 2024-01-13T12:24:04.759502+01:00 s2 postfix/postqueue[305135]: input attribute name: sender 2024-01-13T12:24:04.759529+01:00 s2 postfix/postqueue[305135]: input attribute value: MAILER-DAEMON 2024-01-13T12:24:04.759555+01:00 s2 postfix/postqueue[305135]: public/showq socket: non-terminator 'r' (lookahead) 2024-01-13T12:24:04.759581+01:00 s2 postfix/postqueue[305135]: public/showq socket: wanted attribute: recipient 2024-01-13T12:24:04.759775+01:00 s2 postfix/postqueue[305135]: input attribute name: recipient 2024-01-13T12:24:04.759809+01:00 s2 postfix/postqueue[305135]: input attribute value: ***@gmail.com 2024-01-13T12:24:04.759837+01:00 s2 postfix/postqueue[305135]: public/showq socket: wanted attribute: reason 2024-01-13T12:24:04.759863+01:00 s2 postfix/postqueue[305135]: input attribute name: reason 2024-01-13T12:24:04.759890+01:00 s2 postfix/postqueue[305135]: input attribute value: (end) 2024-01-13T12:24:04.759929+01:00 s2 postfix/postqueue[305135]: public/showq socket: terminator (consumed) 2024-01-13T12:24:04.759955+01:00 s2 postfix/postqueue[305135]: public/showq socket: non-terminator 'q' (lookahead) 2024-01-13T12:24:04.759982+01:00 s2 postfix/postqueue[305135]: public/showq socket: wanted attribute: queue_name 2024-01-13T12:24:04.760007+01:00 s2 postfix/postqueue[305135]: input attribute name: queue_name 2024-01-13T12:24:04.760037+01:00 s2 postfix/postqueue[305135]: input attribute value: maildrop 2024-01-13T12:24:04.760066+01:00 s2 postfix/postqueue[305135]: public/showq socket: wanted attribute: queue_id 2024-01-13T12:24:04.760121+01:00 s2 postfix/postqueue[305135]: input attribute name: queue_id 2024-01-13T12:24:04.760150+01:00 s2 postfix/postqueue[305135]: input attribute value: 080801361D98 2024-01-13T12:24:04.760177+01:00 s2 postfix/postqueue[305135]: public/showq socket: wanted attribute: time 2024-01-13T12:24:04.760205+01:00 s2 postfix/postqueue[305135]: input attribute name: time 2024-01-13T12:24:04.760231+01:00 s2 postfix/postqueue[305135]: input attribute value: 1699766704 2024-01-13T12:24:04.760259+01:00 s2 postfix/postqueue[305135]: public/showq socket: wanted attribute: size 2024-01-13T12:24:04.760288+01:00 s2 postfix/postqueue[305135]: input attribute name: size 2024-01-13T12:24:04.760314+01:00 s2 postfix/postqueue[305135]: input attribute value: 613 2024-01-13T12:24:04.760340+01:00 s2 postfix/postqueue[305135]: public/showq socket: wanted attribute: forced_expire 2024-01-13T12:24:04.760367+01:00 s2 postfix/postqueue[305135]: input attribute name: forced_expire 2024-01-13T12:24:04.760394+01:00 s2 postfix/postqueue[305135]: input attribute value: 0 2024-01-13T12:24:04.760421+01:00 s2 postfix/postqueue[305135]: public/showq socket: wanted attribute: sender 2024-01-13T12:24:04.760461+01:00 s2 postfix/postqueue[305135]: input attribute name: sender 2024-01-13T12:24:04.760503+01:00 s2 postfix/postqueue[305135]: input attribute value: root 2024-01-13T12:24:04.760532+01:00 s2 postfix/postqueue[305135]: public/showq socket: non-terminator 'r' (lookahead) 2024-01-13T12:24:04.760558+01:00 s2 postfix/postqueue[305135]: public/showq socket: wanted attribute: recipient 2024-01-13T12:24:04.760584+01:00 s2 postfix/postqueue[305135]: input attribute name: recipient 2024-01-13T12:24:04.760622+01:00 s2 postfix/postqueue[305135]: input attribute value: root 2024-01-13T12:24:04.760648+01:00 s2 postfix/postqueue[305135]: public/showq socket: wanted attribute: reason 2024-01-13T12:24:04.760675+01:00 s2 postfix/postqueue[305135]: input attribute name: reason 2024-01-13T12:24:04.760700+01:00 s2 postfix/postqueue[305135]: input attribute value: (end) 2024-01-13T12:24:04.760727+01:00 s2 postfix/postqueue[305135]: public/showq socket: terminator (consumed) 2024-01-13T12:24:04.760758+01:00 s2 postfix/postqueue[305135]: public/showq socket: non-terminator 'q' (lookahead) 2024-01-13T12:24:04.760784+01:00 s2 postfix/postqueue[305135]: public/showq socket: wanted attribute: queue_name 2024-01-13T12:24:04.760810+01:00 s2 postfix/postqueue[305135]: input attribute name: queue_name What's interesting is that 2DFCB1362756 is a file, not a directory. I tried stoping postfix an doing postsuper -s but that did not help. Code: -rwxr--r-- 1 vmail postdrop 675 Jan 12 23:13 /var/spool/postfix/maildrop/2DFCB1362756 i suspect that there is something obviously wrong with my configuraction that i cannot see.
None of the lines you show contain 2DFCB1362756. What shows command Code: grep 2DFCB1362756 /var/log/mail.log /var/log/mail.log.1 Also Code: systemctl status postfix.service systemctl status networking.service
yes, they do: Code: 2024-01-13T12:20:08.241998+01:00 s2 postfix/postsuper[304608]: Requeued: 0 messages 2024-01-13T12:20:14.398140+01:00 s2 postfix/postsuper[304615]: fatal: invalid directory name: 2DFCB1362756 [..] 2024-01-13T12:24:04.759180+01:00 s2 postfix/postqueue[305135]: input attribute value: 2DFCB1362756 [...] Code: /var/log/mail.log.1:2024-01-13T12:20:14.398140+01:00 s2 postfix/postsuper[304615]: fatal: invalid directory name: 2DFCB1362756 /var/log/mail.log.1:2024-01-13T12:24:04.759180+01:00 s2 postfix/postqueue[305135]: input attribute value: 2DFCB1362756 /var/log/mail.log.1:2024-01-13T12:24:23.471053+01:00 s2 postfix/postqueue[305162]: input attribute value: 2DFCB1362756 /var/log/mail.log.1:2024-01-13T12:25:44.207394+01:00 s2 postfix/postqueue[305417]: input attribute value: 2DFCB1362756 /var/log/mail.log.1:2024-01-13T12:26:05.838744+01:00 s2 postfix/postqueue[305492]: input attribute value: 2DFCB1362756 /var/log/mail.log.1:2024-01-13T12:26:57.447595+01:00 s2 postfix/postqueue[305606]: input attribute value: 2DFCB1362756 Code: systemctl status postfix.service ● postfix.service - Postfix Mail Transport Agent Loaded: loaded (/lib/systemd/system/postfix.service; enabled; preset: enabled) Active: active (exited) since Sun 2024-01-14 09:37:30 CET; 3h 30min ago Docs: man:postfix(1) Process: 237536 ExecStart=/bin/true (code=exited, status=0/SUCCESS) Process: 271246 ExecReload=/bin/true (code=exited, status=0/SUCCESS) Main PID: 237536 (code=exited, status=0/SUCCESS) CPU: 1ms Jan 14 09:37:30 s2 systemd[1]: Starting postfix.service - Postfix Mail Transport Agent... Jan 14 09:37:30 s2 systemd[1]: Finished postfix.service - Postfix Mail Transport Agent. i'm getting a little frustrated here. Maybe i'll share my main.cf and dovecot.conf? Also my postfix instance is running fine, the rest of email communication is working properly - no deliverability issues. I have a zabbix monitoring set up for main server services. The only problem there is is that mail sent by sieve filters are not sent.
Follow this https://www.howtoforge.com/perfect-...ot-ispconfig-3-2/#-install-pureftpd-and-quota and post the report. Have you configured maildrop in addition to a normal ISPConfig system? Why? The error message Code: fatal: invalid directory name is strange, since the entries should not be directories, they are ordinary text files. Try searching the Interwebs with that error message, something is mixed up.
the problem is i dont recall configuring maildrop. I just followed "debian12 perfect server guide" I'll try to reconfigure ftp and quota and report back if it helps.
thie files .ispconfig-before.sieve, .ispconfig.sieve in /var/vmail/[domain]/[user] are updating every time i change the autoresponder configuration. I'm assuming that the sieve filters do work beacuse i'm getting log lines o this type: Code: 024-01-12T23:13:30.201300+01:00 s2 dovecot: lmtp(***@***.pl)<52403><2HViCIq5oWWzzAAApXXQdg>: sieve: msgid=<[email protected]>: vacation action: sent vacation response to <***@gmail.com>
ok, i solved it myself. The problem was one of 2 things: 1. i uncomented "pickup" line in master.cf (god knows why it was commented out): Code: pickup unix n - y 60 1 pickup 2. i added "smtps" section (i had only the submissions section): Code: smtps inet n - y - - smtpd -o syslog_name=postfix/smtps -o smtpd_tls_wrappermode=yes -o smtpd_sasl_auth_enable=yes -o smtpd_client_restrictions=permit_sasl_authenticated,reject and now the vacation messages are coming through. Tkanks everyone for the time you spent trying to help me.
