sieve vacation filter not working for one user

Thu Mar 18 20:10:19 EDT 2010

Simon Matter wrote:
>> Simon Matter wrote:
>>>> Can anyone tell me where the file(s) with list of email addresses that
>>>> have been sent a vacation reply lives in a debian installation? I can't
>>>> seem to find it.
>>> Hi,
>>>
>>> I think what you are looking for is deliver.db in $configdir. Don't know
>>> on Debian but on my systems it's /var/lib/imap/deliver.db.
>>>
>>> Regards,
>>> Simon
>>>
>> Thanks, I found it. Didn't answer my question though, since I can't read
>> it. Wanted to see if my test email sent to a user on vacation was read,
>> and entered into the database, but for some reason the vacation response
>> didn't work, or if it didn't even get that far. Is it possible/likely
>> that this could have been corrupted, and that is why I have a user whose
>> vacation sieve doesn't work? It seems like this file must be used by all
>> users. Is it used for anything besides vacations? What would happen if I
>> deleted it?
> 
> I'm not exactly an expert here... but IIRC deliver.db is also used to
> detect duplicate deliveries and things like single instance storage may
> therefore also use it.
> But, removing it should not hurt much. It will get created again and maybe
> some users will get vacation responses again because of it but nothing
> serious should happen. Maybe you just rename it and check how it goes so
> you can go back if there is need to.
> 
> Regards,
> Simon
> 

I tried changing the name. Cyrus remade the file, and it didn't seem to 
cause any problems, but unfortunately, it didn't help either. Here is 
the output of the log file for a mail sent to mike, which should have 
been sent a vacation notice.:

Mar 18 16:44:52 ella cyrus/lmtpunix[6709]: duplicate_check: 
#010<C8>S<9A><E4><AD>xT<EF>y<B8><ED>7<B4><DC>U 
mike                 1269560373
Mar 18 16:44:52 ella cyrus/lmtpunix[6709]: duplicate_check: 
<fddba4731003181644m14809f49y7356fcb965ad92f4 at mail.gmail.com> user.mike 
            0
Mar 18 16:44:52 ella cyrus/lmtpunix[6709]: duplicate_check: 
<fddba4731003181644m14809f49y7356fcb965ad92f4 at mail.gmail.com> user.mike 
            0
Mar 18 16:44:52 ella cyrus/lmtpunix[6709]: mystore: starting txn 2147486822
Mar 18 16:44:52 ella cyrus/lmtpunix[6709]: mystore: committing txn 
2147486822
Mar 18 16:44:52 ella cyrus/lmtpunix[6709]: duplicate_mark: 
<fddba4731003181644m14809f49y7356fcb965ad92f4 at mail.gmail.com> user.mike 
            1268955892 4483413
Mar 18 16:44:52 ella cyrus/lmtpunix[6709]: Delivered: 
<fddba4731003181644m14809f49y7356fcb965ad92f4 at mail.gmail.com> to 
mailbox: user.mike
Mar 18 16:44:52 ella cyrus/lmtpunix[6709]: mystore: starting txn 2147486823
Mar 18 16:44:52 ella cyrus/lmtpunix[6709]: mystore: committing txn 
2147486823
Mar 18 16:44:52 ella cyrus/lmtpunix[6709]: duplicate_mark: 
<fddba4731003181644m14809f49y7356fcb965ad92f4 at mail.gmail.com> 
.mike+ at .sieve.       1268955892 0
Mar 18 16:44:52 ella cyrus/lmtpunix[6709]: telling master 1
Mar 18 16:44:52 ella cyrus/master[27902]: service lmtpunix pid 6709 in 
BUSY state: now available and in READY state
Mar 18 16:44:52 ella cyrus/master[27902]: service lmtpunix now has 1 
ready workers
Mar 18 16:44:52 ella postfix/lmtp[6708]: 1EA71D1A5E: 
to=<mike at mycompany.org>, 
relay=smtp.mycompany.org[/var/run/cyrus/socket/lmtp], delay=0.06, 
delays=0.01/0.02/0.02/0.01, dsn=2.1.5, status=sent (250 2.1.5 Ok)
Mar 18 16:44:52 ella postfix/qmgr[2779]: 1EA71D1A5E: removed

Here is an email sent to another user, maria, in which the vacation 
notice worked successfully. Clearly there is much more going on here. 
Interesting that actual email addresses (which I have changed) show up 
in the logs here, but didn't for mike.

Mar 18 16:52:06 ella postfix/smtpd[7189]: connect from localhost[127.0.0.1]
Mar 18 16:52:06 ella postfix/smtpd[7189]: E7B64D1A5E: 
client=localhost[127.0.0.1]
Mar 18 16:52:06 ella postfix/cleanup[7178]: E7B64D1A5E: 
message-id=<fddba4731003181652s517a450bpf7b36be9c8db9601 at mail.gm
ail.com>
Mar 18 16:52:06 ella postfix/smtpd[7189]: disconnect from 
localhost[127.0.0.1]
Mar 18 16:52:06 ella postfix/qmgr[2779]: E7B64D1A5E: 
from=<someone at gmail.com>, size=2438, nrcpt=1 (queue active)
Mar 18 16:52:06 ella amavis[5974]: (05974-05) FWD via SMTP: 
<someone at gmail.com> -> <maria at ourcompany.org>,BODY=7BIT 250
  2.0.0 Ok, id=05974-05, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: 
queued as E7B64D1A5E
Mar 18 16:52:06 ella cyrus/lmtpunix[7192]: telling master 2
Mar 18 16:52:06 ella cyrus/lmtpunix[7192]: accepted connection
Mar 18 16:52:06 ella cyrus/lmtpunix[7192]: telling master 3
Mar 18 16:52:06 ella cyrus/lmtpunix[7192]: lmtp connection preauth'd as 
postman
Mar 18 16:52:06 ella cyrus/master[27902]: service lmtpunix pid 7192 in 
READY state: now unavailable and in BUSY state
Mar 18 16:52:06 ella cyrus/master[27902]: service lmtpunix now has 0 
ready workers
Mar 18 16:52:06 ella cyrus/master[27902]: service lmtpunix pid 7192 in 
BUSY state: now serving connection
Mar 18 16:52:06 ella cyrus/master[27902]: service lmtpunix now has 0 
ready workers
Mar 18 16:52:06 ella cyrus/lmtpunix[7192]: duplicate_check: 
R<9B>#034#036t<E9><9B><<E0>#023<D5>l1<F7>#005&
            maria                0
Mar 18 16:52:06 ella cyrus/lmtpunix[7192]: mystore: starting txn 2147486834
Mar 18 16:52:06 ella cyrus/lmtpunix[7192]: mystore: committing txn 
2147486834
Mar 18 16:52:06 ella cyrus/lmtpunix[7192]: duplicate_mark: 
R<9B>#034#036t<E9><9B><<E0>#023<D5>l1<F7>#005& 
maria                1269561126 0
Mar 18 16:52:06 ella cyrus/lmtpunix[7192]: mystore: starting txn 2147486835
Mar 18 16:52:06 ella cyrus/lmtpunix[7192]: mystore: committing txn 
2147486835
Mar 18 16:52:06 ella cyrus/lmtpunix[7192]: duplicate_mark: 
<cmu-sieve-7192-1268956326-0 at ella>       .maria.sieve.        1268956326 0
Mar 18 16:52:06 ella postfix/pickup[3743]: EDB92D1A72: uid=108 from=<>
Mar 18 16:52:06 ella postfix/cleanup[7178]: EDB92D1A72: 
message-id=<cmu-sieve-7192-1268956326-0 at ella>
Mar 18 16:52:06 ella cyrus/lmtpunix[7192]: duplicate_check: 
<fddba4731003181652s517a450bpf7b36be9c8db9601 at mail.gmail.com> user.maria 
           0
Mar 18 16:52:06 ella cyrus/lmtpunix[7192]: duplicate_check: 
<fddba4731003181652s517a450bpf7b36be9c8db9601 at mail.gmail.com> user.maria 
           0
Mar 18 16:52:06 ella cyrus/lmtpunix[7192]: mystore: starting txn 2147486836
Mar 18 16:52:06 ella cyrus/lmtpunix[7192]: mystore: committing txn 
2147486836
Mar 18 16:52:06 ella cyrus/lmtpunix[7192]: duplicate_mark: 
<fddba4731003181652s517a450bpf7b36be9c8db9601 at mail.gmail.com> user.maria 
           1268956326 4483413
Mar 18 16:52:06 ella postfix/qmgr[2779]: EDB92D1A72: from=<>, size=691, 
nrcpt=1 (queue active)
Mar 18 16:52:06 ella cyrus/lmtpunix[7192]: Delivered: 
<fddba4731003181652s517a450bpf7b36be9c8db9601 at mail.gmail.com> to 
mailbox: user.maria
Mar 18 16:52:06 ella cyrus/lmtpunix[7192]: mystore: starting txn 2147486837
Mar 18 16:52:06 ella cyrus/lmtpunix[7192]: mystore: committing txn 
2147486837
Mar 18 16:52:06 ella cyrus/lmtpunix[7192]: duplicate_mark: 
<fddba4731003181652s517a450bpf7b36be9c8db9601 at mail.gmail.com> 
.maria+ at .sieve.      1268956326 0
Mar 18 16:52:06 ella cyrus/lmtpunix[7192]: telling master 1
Mar 18 16:52:06 ella cyrus/master[27902]: service lmtpunix pid 7192 in 
BUSY state: now available and in READY state
Mar 18 16:52:06 ella cyrus/master[27902]: service lmtpunix now has 1 
ready workers
Mar 18 16:52:06 ella postfix/lmtp[7191]: E7B64D1A5E: 
to=<maria at ourcompany.org>, 
relay=smtp.mycompany.org[/var/run/cyrus/socket/lmtp], delay=0.03, 
delays=0.01/0/0/0.02, dsn=2.1.5, status=sent (250 2.1.5 Ok)
Mar 18 16:52:06 ella postfix/qmgr[2779]: E7B64D1A5E: removed
Mar 18 16:52:06 ella amavis[5974]: (05974-05) Passed CLEAN, 
[209.85.160.47] [209.85.160.47] <someone at gmail.com> -> 
<maria at ourcompany.org>, Message-ID: 
<fddba4731003181652s517a450bpf7b36be9c8db9601 at mail.gmail.com>, mail_id: 
PvIzh3LfhHuP, Hits: -4.155, size: 1818, queued_as: E7B64D1A5E, 1072 ms
Mar 18 16:52:06 ella postfix/lmtp[7183]: D8D7FD1A69: 
to=<maria at ourcompany.org>, relay=127.0.0.1[127.0.0.1]:10024, delay=1.1, 
delays=0.06/0.01/0/1.1, dsn=2.0.0, status=sent (250 2.0.0 Ok, 
id=05974-05, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 
E7B64D1A5E)
Mar 18 16:52:06 ella postfix/qmgr[2779]: D8D7FD1A69: removed
Mar 18 16:52:07 ella postfix/smtp[7195]: EDB92D1A72: 
to=<someone at gmail.com>, 
relay=gmail-smtp-in.l.google.com[209.85.222.34]:25, delay=0.2, 
delays=0.01/0.01/0.06/0.12, dsn=2.0.0, status=sent (250 2.0.0 OK 
1268956327 42si1092432pzk.134)
Mar 18 16:52:07 ella postfix/qmgr[2779]: EDB92D1A72: removed

Maybe someone else will see something that I don't, but I couldn't make 
anything out from this.

thanks,
maria