problemas con el BCC en postfix

[Satoru Lucas Shindoi]

Gente:

Hasta hace unas semanas, estaba usando Postfix 2.1.5-9, junto con Amavis-new 20030616p10-5, SpamAssassin 3.0.3-2sarge1, Clamav 0.84-2.sarge y Cyrus 2.1.18-1 (todo sobre Debian Sarge).
Esta configuración estaba funcionando sin problemas.

Luego de leer las notas de actualización desde Sarge a Etch, me decido a dar el upgrade.

Así paso a tener Postfix 2.3.8-2+b1, Amavis-new 1:2.4.2-6.1, SpamAssassin 3.1.7-2, Clamav 0.90.2 y Cyrus 2.1.18-5.1 (no actualice a 2.2 aún)
Casi todo quedo funcionando como antes de la actualización, salvo por el hecho (crítico) de que a la cuenta a la cual llegan los BCC llegan dos copias de los emails enviados! Lo mas llamativo es que la duplicidad solo ocurre con los emails que "pasan" sin problemas por el SA y el Clamav.

Alguien tiene una idea de por donde pueden venir los tiros?

Estoy mirando logs y doc como loco, pero no encuentro nada raro salvo por lo siguiente:

###/ Amavis dice que si...
Apr 27 13:57:34 proxy-i amavis[7697]: (07697-06) Passed CLEAN, [192.168.1.30] [192.168.1.30] <usuario en example.com> -> <backupcorreo en example.com>,<servtecnico en example.com>, Message-ID: <20070427135539.5d7cead9 en develop.example.com>, mail_id: xG0F+47CfBC8, Hits: -1.19, queued_as: 5F5863DE141, 2773 ms
/###

Apr 27 13:57:34 proxy-i cyrus/lmtpd[8415]: duplicate_check: <20070427135539.5d7cead9 en develop.example.com> user.backupcorreo    0

###/ Postfix recibe el correo "reinyectado" 
Apr 27 13:57:34 proxy-i postfix/lmtp[8377]: 9AA9C3DE12E: to=<backupcorreo en example.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=2.9, delays=0.11/0/0.01/2.8, dsn=2.6.0, status=sent (250 2.6.0 Ok, id=07697-06, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 5F5863DE141)
Apr 27 13:57:34 proxy-i postfix/lmtp[8377]: 9AA9C3DE12E: to=<servtecnico en example.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=2.9, delays=0.11/0/0.01/2.8, dsn=2.6.0, status=sent (250 2.6.0 Ok, id=07697-06, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 5F5863DE141)
/###

Apr 27 13:57:34 proxy-i postfix/qmgr[8371]: 9AA9C3DE12E: removed

Apr 27 13:57:34 proxy-i cyrus/lmtpd[8416]: duplicate_check: <20070427135539.5d7cead9 en develop.example.com> user.servtecnico     0

Apr 27 13:57:34 proxy-i cyrus/lmtpd[8417]: mystore: starting txn 2147493813

Apr 27 13:57:34 proxy-i cyrus/lmtpd[8417]: mystore: committing txn 2147493813

Apr 27 13:57:34 proxy-i cyrus/lmtpd[8417]: duplicate_mark: <20070427135539.5d7cead9 en develop.example.com> user.backupcorreo    1177693054

###/ A traves de un socket lmtp Postfix le pasa al deliver Cyrus la BCC (la copia oculta)
Apr 27 13:57:34 proxy-i postfix/lmtp[8391]: 5F5863DE141: to=<backupcorreo en example.com>, relay=proxy-i.example.com[/var/run/cyrus/socket/lmtp], delay=0.24, delays=0.06/0/0/0.18, dsn=2.1.5, status=sent (250 2.1.5 Ok)
/###

Apr 27 13:57:34 proxy-i cyrus/lmtpd[8416]: mystore: starting txn 2147493814

Apr 27 13:57:34 proxy-i cyrus/lmtpd[8416]: mystore: committing txn 2147493814

Apr 27 13:57:34 proxy-i cyrus/lmtpd[8416]: duplicate_mark: <20070427135539.5d7cead9 en develop.example.com> user.servtecnico     1177693054

###/ A traves de un socket lmtp Postfix le pasa al deliver Cyrus el original
Apr 27 13:57:34 proxy-i postfix/lmtp[8395]: 5F5863DE141: to=<servtecnico en example.com>, relay=proxy-i.example.com[/var/run/cyrus/socket/lmtp], delay=0.26, delays=0.06/0/0.03/0.16, dsn=2.1.5, status=sent (250 2.1.5 Ok)
/###

Apr 27 13:57:34 proxy-i cyrus/lmtpd[8415]: mystore: starting txn 2147493815

Apr 27 13:57:34 proxy-i cyrus/lmtpd[8415]: mystore: committing txn 2147493815

Apr 27 13:57:34 proxy-i cyrus/lmtpd[8415]: duplicate_mark: <20070427135539.5d7cead9 en develop.example.com> 
user.backupcorreo    1177693054

###/ A traves de un socket lmtp Postfix le pasa al deliver Cyrus OTRA VEZ EL BCC!!
Apr 27 13:57:34 proxy-i postfix/lmtp[8390]: 5F5863DE141: to=<backupcorreo en example.com>, relay=proxy-i.example.com[/var/run/cyrus/socket/lmtp], delay=0.26, delays=0.06/0/0/0.2, dsn=2.1.5, status=sent (250 2.1.5 Ok)
/###

Apr 27 13:57:34 proxy-i postfix/qmgr[8371]: 5F5863DE141: removed

La verdad que no se que es. Revise la conf de cyrus, de amavis/SA. A mi parecer el problema no viene por ahi.

Si alguien tiene alguna idea de que pudo haber cambiado en Postfix o halla sufrido el mismo inconveniente al pasar de Debian Sarge a Etch, espero ansioso su comentario (mientras sigo investigando)

De ser necesario, puedo enviar los archivos de conf necesarios.

Un abrazo

-- 
Satoru Lucas Shindoi - lucas en shindoi.com.ar
SysAdmin GNU/Linux, *NIX
Oficina (06 a 15 hs) 03783 463449 / 425612
Particular (16 hs en adelante) 03783 459196
ICQ: 95357247 - Gmail: shindoi en gmail.com - lucas en dpec.com.ar
Messenger: slshindoi en hotmail.com - Yahoo: slshindoi en yahoo.com.ar
--------------------------------------------------------------------------
Sistemas de Informacion - DPEC - https://www.dpec.com.ar/sistemas
Tixpert - www.tixpert.com.ar - Tecnología, Investigación, Experiencia.
Proyectos NEA - www.nea.org.ar