Postfixadmin + vacation, not working

[garson]

Hi.
I have Ubuntu 11.04, postfix, dovecot, postfixadmin, and 3 webmails, Horde, Squirrelmail and Roundcube.
Everything is working as expected, but few days ago I have added vacation to postfixadmin.
I was setting it up according to this How to.
http://postfixmail.com/blog/index.ph...-postfixadmin/

So, when I try to send email from user1@mydomain.com to user2@mydomain.com which has vacation enabled, user1 doesn't get any email. Nothing happens.

This is what I get in /var/log/mail.log

Dec 26 17:13:15 mail-server postfix/smtpd[2923]: connect from localhost[127.0.0.1]
Dec 26 17:13:15 mail-server postfix/smtpd[2923]: 494E746BEB: client=localhost[127.0.0.1], sasl_method=LOGIN, sasl_username=user1@mydomain.com
Dec 26 17:13:15 mail-server postfix/cleanup[2928]: 494E746BEB: message-id=<a2582d1884b6e3d4811bce510ab94c91.squirrel@192. 168.100.30>
Dec 26 17:13:15 mail-server postfix/qmgr[10573]: 494E746BEB: from=<user1@mydomain.com>, size=748, nrcpt=2 (queue active)
Dec 26 17:13:15 mail-server postfix/smtpd[2923]: disconnect from localhost[127.0.0.1]
Dec 26 17:13:18 mail-server postfix/smtpd[2944]: connect from localhost[127.0.0.1]
Dec 26 17:13:18 mail-server postfix/smtpd[2944]: 1BDE946B74: client=localhost[127.0.0.1]
Dec 26 17:13:18 mail-server postfix/cleanup[2928]: 1BDE946B74: message-id=<a2582d1884b6e3d4811bce510ab94c91.squirrel@192. 168.100.30>
Dec 26 17:13:18 mail-server postfix/qmgr[10573]: 1BDE946B74: from=<user1@mydomain.com>, size=1160, nrcpt=2 (queue active)
Dec 26 17:13:18 mail-server amavis[26797]: (26797-04) Passed CLEAN, LOCAL [127.0.0.1] [127.0.0.1] <user1@mydomain.com> -> <user2#mydomain.com@autoreply.mydomain.com>,<user2 @mydomain.com>, Message-ID: <a2582d1884b6e3d4811bce510ab94c91.squirrel@192.168 .100.30>, mail_id: IrgryC5kaQF1, Hits: -1, size: 748, queued_as: 1BDE946B74, 2766 ms
Dec 26 17:13:18 mail-server postfix/smtpd[2944]: disconnect from localhost[127.0.0.1]
Dec 26 17:13:18 mail-server postfix/smtp[2929]: 494E746BEB: to=<user2#mydomain.com@autoreply.mydomain.com>, orig_to=<user2@mydomain.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=2.8, delays=0.04/0.02/0.01/2.8, dsn=2.0.0, status=sent (250 2.0.0 from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 1BDE946B74)
Dec 26 17:13:18 mail-server postfix/smtp[2929]: 494E746BEB: to=<user2@mydomain.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=2.8, delays=0.04/0.02/0.01/2.8, dsn=2.0.0, status=sent (250 2.0.0 from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 1BDE946B74)
Dec 26 17:13:18 mail-server postfix/qmgr[10573]: 494E746BEB: removed
Dec 26 17:13:18 mail-server postfix/pipe[2946]: 1BDE946B74: to=<user2@mydomain.com>, relay=dovecot, delay=0.04, delays=0.01/0.01/0/0.02, dsn=2.0.0, status=sent (delivered via dovecot service)
Dec 26 17:13:18 mail-server postfix/pipe[2945]: 1BDE946B74: to=<user2#mydomain.com@autoreply.mydomain.com>, relay=vacation, delay=0.15, delays=0.01/0.01/0/0.13, dsn=2.0.0, status=sent (delivered via vacation service)
Dec 26 17:13:18 mail-server postfix/qmgr[10573]: 1BDE946B74: removed

And this is from /var/log/vacation.log

2012/12/26 17:13:18 DEBUG> /var/spool/vacation/vacation.pl:560 main:: - Script argument SMTP recipient is : 'user2#mydomain.com@autoreply.mydomain.com' and smtp_sender : 'user1@mydomain.com'
2012/12/26 17:13:18 DEBUG> /var/spool/vacation/vacation.pl:590 main:: - Converted autoreply mailbox back to normal style - from user2#mydomain.com@autoreply.mydomain.com to user2@mydomain.com
2012/12/26 17:13:18 DEBUG> /var/spool/vacation/vacation.pl:600 main:: - Email headers have to: 'user2@mydomain.com' and From: 'user1@mydomain.com'
2012/12/26 17:13:18 DEBUG> /var/spool/vacation/vacation.pl:337 main::find_real_address - Found user2@mydomain.com has vacation active
2012/12/26 17:13:18 DEBUG> /var/spool/vacation/vacation.pl:626 main:: - Attempting to send vacation response for: <a2582d1884b6e3d4811bce510ab94c91.squirrel@192.168 .100.30> to: user1@mydomain.com, user2@mydomain.com, user2@mydomain.com (test_mode = 0)
2012/12/26 17:13:18 DEBUG> /var/spool/vacation/vacation.pl:428 main::send_vacation_email - Asked to send vacation reply to user2@mydomain.com thanks to <a2582d1884b6e3d4811bce510ab94c91.squirrel@192.168 .100.30>
2012/12/26 17:13:18 DEBUG> /var/spool/vacation/vacation.pl:436 main::send_vacation_email - Already notified user1@mydomain.com, or some error prevented us from doing so

/var/log/vacation.debug is empty.

Does anybody knows what could be the problem?

Thanks.

[sremac]

Hi,

I am experiencing the same error, platform is Centos + Amavisd + Dovecot.

I see mails are properly arriving to postfix and redirected to vacation domain, but vacation mail is not sent:

...
to=<vacation@domain>, relay=dovecot, delay=0.16, delays=0.02/0.01/0/0.13, dsn=2.0.0, status=sent (delivered via dovecot service)
Jan 14 21:05:59 ip-10-226-109-205 postfix/pipe[10074]: 3FA9CC1F8: to=<vacation#domain@autoreply.domain2>, relay=vacation, delay=0.31, delays=0.02/0.02/0/0.26, dsn=2.0.0, status=sent (delivered via vacation service)
Jan 14 21:05:59 ip-10-226-109-205 postfix/qmgr[10056]: 3FA9CC1F8: removed
...

I have put into /etc/hosts entry for my public IP:

IP autoreply.domain2

but it is the same.

Any ideas, anyone?

Thanks

[shiwu]

I encountered the same problem when trying configure following configuration:

Distribitution: CentOS release 6.4 (Final) + updates
postfix: 2.2.el6_1
Roundcube: 0.8.6
postfixadmin: 2.3.6

Perl modules required for vacation.pl were installed from EPEL. To do create new yum repo config file /etc/yum.repos.d/epel.repo:

[epel]
name=Extra Packages for Enterprise Linux 6 - $basearch
#baseurl=http://download.fedoraproject.org/pub/epel/6/$basearch
mirrorlist=https://mirrors.fedoraproject.org/metalink?repo=epel-6&arch=$basearch
failovermethod=priority
enabled=1
gpgcheck=1
gpgkey=file:///etc/pki/rpm-gpg/RPM-GPG-KEY-EPEL-6

After every new mail was sent to email address, which had vacation active, in vacation log file the last entry was always:

2013/05/02 15:23:29 DEBUG> /var/spool/vacation/vacation.pl:426 main::send_vacation_email - Already notified user1@mydomain.com, or some error prevented us from doing so

I spent a lot of time tracking the source of the problem. Finally I downloaded the lates available vacation.pl (version 4.0r1) script from that URL:
http://svn.code.sf.net/p/postfixadmi...ON/vacation.pl

After some searching I found that when return in line 490 is commented out the vacation start working fine. It seems that So open vacation.pl and find part of code containing:

if ($rv == 1) {
my @row = $stm->fetchrow_array;
if (already_notified($email, $orig_from) == 1) {
$logger->debug("Already notified $email, or some error prevented us from doing so");
return;
}

When return is commented out, script do not ends in this if statement, and is continuing further till sending vacation message. after script modification I have now in vacation log file:

2013/05/02 17:44:09 DEBUG> /var/spool/vacation/vacation.pl:489 main::send_vacation_email - Already notified user1@mydomain.com, or some error prevented us from doing so
2013/05/02 17:44:09 DEBUG> /var/spool/vacation/vacation.pl:494 main::send_vacation_email - Will send vacation response for <9501a8e91d7c1f72a1de0afec12e0058@mydomain.com>: FROM: user1@mydomain.com (orig_to: user1@mydomain.com), TO: user2@mydomain.com; VACATION SUBJECT: I'm on vacation ; VACATION BODY: I will replay to all emails when I'm back.
2013/05/02 17:44:12 DEBUG> /var/spool/vacation/vacation.pl:536 main::send_vacation_email - Vacation response sent to user2@mydomain.com, from user1@mydomain.com

I have also implement some more changes in vacation.pl script, which maybe useful:

diff vacation.pl.oryg vacation.pl
335c335
< $interval = get_interval($to);
---
> #$interval = get_interval($to);
372c372
< my $query = qq{SELECT email FROM vacation WHERE email=? and active=$db_true and activefrom <= NOW() and activeuntil >= NOW()};
---
> my $query = qq{SELECT email FROM vacation WHERE email=? and active=$db_true and activefrom <= NOW() and activeto >= NOW()};
490c490
< return;
---
> #return;
499c499
< my $friendly_from = "Vacation Service";
---
> my $friendly_from = "$orig_to";