[Kolab-devel] [issue4368] Mails get lost when kolabmailboxfilter fails

[Alexander Gran]

New submission from Alexander Gran <alexg at moduleworks.com>:

Hi,

when kolabmailboxfilter fails, e.g. because something goes wrong with my ldap
lookup (having two entries that return on a mailoruid lookup instead of jsut
one), kolabmailboxfilter logs an error message and then DROPs the email.
I don't think this is intended behaviour, right?
I think the mail should stay in the mailq or bounce back, but nothing like this
happens.
Here is an example of a log:
postfix/smtpd[5294]: 4393210A7C749: client=storage.moduleworks.com[212.66.137.233]
postfix/cleanup[5296]: 4393210A7C749:
message-id=<20100507041523.4393210A7C749 at webmail.moduleworks.com>
postfix/qmgr[4351]: 4393210A7C749: from=<alex at grans.eu>, size=394, nrcpt=1
(queue active)
Kolab Filter[5298]: [horde] Arguments: Horde_Argv_Values Object#012(#012   
[sender] => alex at grans.eu#012    [recipient] => Array#012      
  (#012            [0] => alexg at moduleworks.com#012        )#012#012    [host]
=> webmail.moduleworks.com#012    [client] => 212.66.137.233#012    [user] => #012  
  [config] => /etc/kolab/filter/kolabfilter.conf#012)#012 [pid 5298 on line 249
of "/usr/share/php/Horde/Kolab/Filter/Base.php"]
Kolab Filter[5298]: [horde] PHP Error: Assigning the return value of new by
reference is deprecated; Code: 0 [pid 5298 on line 75 of "/usr/
share/php/Horde/Kolab/Filter/Transport.php"]
postfix/smtpd[5300]: connect from localhost[127.0.0.1]
postfix/smtpd[5300]: 206AE113F694A: client=localhost[127.0.0.1]
postfix/cleanup[5301]: 206AE113F694A:
message-id=<20100507041523.4393210A7C749 at webmail.moduleworks.com>
postfix/qmgr[4351]: 206AE113F694A: from=<alex at grans.eu>, size=581, nrcpt=1
(queue active)
postfix/smtpd[5300]: disconnect from localhost[127.0.0.1]
Kolab Filter[5298]: [horde] Horde_Kolab_Filter_Content successfully completed
(sender=alex at grans.eu, recipients=alexg at moduleworks.com,
client_address=212.66.137.233,
id=<20100507041523.4393210A7C749 at webmail.moduleworks.com>) [pid 5298 on line 146
of "/usr/share/php/Horde/Kolab/Filter/Base.php"]
postfix/pipe[5297]: 4393210A7C749: to=<alexg at moduleworks.com>,
relay=kolabfilter, delay=7.5, delays=7.3/0/0/0.14, dsn=2.0.0, status=sent
(delivered via kolabfilter service)
postfix/qmgr[4351]: 4393210A7C749: removed
Kolab Filter[5304]: [horde] Arguments: Horde_Argv_Values Object#012(#012   
[sender] => alex at grans.eu#012    [recipient] => Array#012        (#012         
  [0] => alexg at moduleworks.com#012        )#012#012    [host] =>
webmail.moduleworks.com#012    [client] => 127.0.0.1#012    [user] => #012   
[config] => /etc/kolab/filter/kolabfilter.conf#012)#012 [pid 5304 on line 249 of
"/usr/share/php/Horde/Kolab/Filter/Base.php"]
Kolab Filter[5304]: [horde] PHP Error: Assigning the return value of new by
reference is deprecated; Code: 0 [pid 5304 on line 20 of
"/usr/share/horde3/lib/Horde/Kolab/Server.php"]



Kolab Filter[5304]: [horde] Found 3 results when expecting only one!; Code: 
[pid 5304 on line 456 of "/usr/share/horde3/lib/Horde/Kolab/Server/ldap.php"]

Any in any case, this should perhaps have some more exclamation marks.

postfix/pipe[5303]: 206AE113F694A: to=<alexg at moduleworks.com>,
relay=kolabmailboxfilter, delay=0.16, delays=0.05/0.01/0/0.1, dsn=2.0.0,
status=sent (delivered via kolabmailboxfilter service)
postfix/qmgr[4351]: 206AE113F694A: removed
postfix/smtpd[5294]: disconnect from storage.moduleworks.com[212.66.137.233]

The remote host (storage.moduleworks.com in this case), just gets a 
250 2.0.0 Ok: queued as 4393210A7C749
in this case.

If there is ever a case where that happens in a production system, my clients
will kill me ;) 

regards
Alex

----------
messages: 25020
nosy: alexg
priority: minor bug
status: unread
title: Mails get lost when kolabmailboxfilter fails

______________________________________
Kolab issue tracker <issues at kolab.org>
<https://issues.kolab.org/issue4368>
______________________________________