email envoyé mais non reçu par le destinataire

BlueMind - FR Discussions
1

Bonjour,

Nous sommes en production depuis deux mois. Nous utilisons la version 1.0.7027. Nous constatons que pour certaines adresses les emails ne sont pas reçus par le destinataire quand ceux-ci sont envoyés par le serveur Blue Mind alors qu’ils le sont quand ceux-ci sont envoyés directement au serveur OVH par l’intermédiaire de Thunderbird au travers d’une adresse mail non répertoriée par Blue Mind. Nous avons installé Fetchmail pour récupérer les mails, des adresses répertoriées sur Blue Mind, sur notre serveur OVH.

Cela concerne 4 adresses sur environ 300

Cordialement

2

Que contient le log /var/log/mail.log pour un envoi de mail à une des adresses qui rencontrent ce problème ?

3

voici le résultat

Feb 14 13:11:02 mailserveur fetchmail[4151]: 1 message (1 déjà vu) pour jm.essai@tutu.fr dans ns0.ovh.net.
Feb 14 13:11:02 mailserveur fetchmail[4151]: 116 messages (116 déjà vus) pour bibi@tutu.fr dans ns0.ovh.net.
Feb 14 13:11:03 mailserveur fetchmail[4151]: 8 messages (8 déjà vus) pour commercial@tutu.fr dans ns0.ovh.net.
Feb 14 13:11:12 mailserveur postfix/smtpd[12397]: connect from localhost.localdomain[127.0.0.1]
Feb 14 13:11:12 mailserveur postfix/smtpd[12397]: B54FCF48001: client=localhost.localdomain[127.0.0.1]
Feb 14 13:11:12 mailserveur postfix/cleanup[12399]: B54FCF48001: message-id=8426bae9e495a0ac8404ab3851d2046d@tutu.fr
Feb 14 13:11:12 mailserveur postfix/smtpd[12397]: B54FCF48001: client=localhost.localdomain[127.0.0.1]
Feb 14 13:11:12 mailserveur postfix/cleanup[12399]: B54FCF48001: message-id=8426bae9e495a0ac8404ab3851d2046d@tutu.fr
Feb 14 13:11:12 mailserveur postfix/qmgr[10623]: B54FCF48001: from=bibi@tutu.fr, size=1382, nrcpt=1 (queue active)
Feb 14 13:11:13 mailserveur cyrus/imap[12161]: accepted connection
Feb 14 13:11:13 mailserveur cyrus/imap[12161]: imapd:Loading hard-coded DH parameters
Feb 14 13:11:13 mailserveur cyrus/imap[12161]: SSL_accept() incomplete → wait
Feb 14 13:11:13 mailserveur cyrus/imap[12161]: SSL_accept() succeeded → done
Feb 14 13:11:13 mailserveur cyrus/imap[12161]: starttls: TLSv1 with cipher DHE-RSA-AES256-SHA (256/256 bits new) no authentication
Feb 14 13:11:13 mailserveur cyrus/imap[12161]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:13 mailserveur cyrus/imap[12161]: login: mailserveur.tutu.lan [192.168.1.1] bibi@tutu.fr PLAIN+TLS User logged in

SESSIONID=
Feb 14 13:11:13 mailserveur cyrus/imap[12161]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:13 mailserveur cyrus/imap[12161]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:13 mailserveur cyrus/imap[12161]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:13 mailserveur cyrus/imap[12161]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:13 mailserveur cyrus/imaps[11918]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:13 mailserveur cyrus/imap[12080]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:13 mailserveur postfix/smtpd[12397]: disconnect from localhost.localdomain[127.0.0.1]
Feb 14 13:11:13 mailserveur cyrus/imap[12161]: USAGE bibi@tutu.fr user: 0.032002 sys: 0.008000
Feb 14 13:11:13 mailserveur cyrus/imaps[11614]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:13 mailserveur cyrus/imaps[11918]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:13 mailserveur cyrus/imap[12080]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:13 mailserveur cyrus/imaps[11918]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:13 mailserveur cyrus/imaps[11918]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:13 mailserveur cyrus/imaps[11918]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:13 mailserveur cyrus/imaps[11918]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:13 mailserveur cyrus/imap[12080]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:13 mailserveur cyrus/imap[12080]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:13 mailserveur cyrus/imaps[11614]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:13 mailserveur cyrus/imap[12080]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:13 mailserveur cyrus/imaps[11614]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:13 mailserveur cyrus/imap[12080]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:13 mailserveur cyrus/imaps[11614]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:14 mailserveur cyrus/imap[12123]: accepted connection
Feb 14 13:11:14 mailserveur cyrus/imap[12123]: imapd:Loading hard-coded DH parameters
Feb 14 13:11:14 mailserveur cyrus/imap[12123]: SSL_accept() incomplete → wait
Feb 14 13:11:14 mailserveur cyrus/imap[12123]: SSL_accept() succeeded → done
Feb 14 13:11:14 mailserveur cyrus/imap[12123]: starttls: TLSv1 with cipher DHE-RSA-AES256-SHA (256/256 bits new) no authentication
Feb 14 13:11:14 mailserveur cyrus/imap[12123]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:14 mailserveur cyrus/imap[12123]: login: mailserveur.tutu.lan [192.168.1.1] bibi@tutu.fr PLAIN+TLS User logged in

SESSIONID=
Feb 14 13:11:14 mailserveur cyrus/imap[12123]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:14 mailserveur cyrus/imap[12123]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:14 mailserveur cyrus/imap[12123]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:14 mailserveur cyrus/imap[12123]: USAGE bibi@tutu.fr user: 0.020001 sys: 0.016001
Feb 14 13:11:14 mailserveur cyrus/imap[12361]: accepted connection
Feb 14 13:11:14 mailserveur cyrus/imap[12361]: imapd:Loading hard-coded DH parameters
Feb 14 13:11:14 mailserveur cyrus/imap[12162]: accepted connection
Feb 14 13:11:14 mailserveur cyrus/imap[12361]: imapd:Loading hard-coded DH parameters
Feb 14 13:11:14 mailserveur cyrus/imap[12162]: accepted connection
Feb 14 13:11:14 mailserveur cyrus/master[12442]: about to exec /usr/lib/cyrus/bin/imapd
Feb 14 13:11:14 mailserveur cyrus/imap[12162]: SSL_accept() incomplete → wait
Feb 14 13:11:14 mailserveur cyrus/imap[12162]: SSL_accept() succeeded → done
Feb 14 13:11:14 mailserveur cyrus/imap[12162]: starttls: TLSv1 with cipher DHE-RSA-AES256-SHA (256/256 bits new) no authentication
Feb 14 13:11:14 mailserveur cyrus/imap[12442]: executed
Feb 14 13:11:14 mailserveur cyrus/imap[12162]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:14 mailserveur cyrus/imap[12162]: login: mailserveur.tutu.lan [192.168.1.1] bibi@tutu.fr PLAIN+TLS User logged in

SESSIONID=
Feb 14 13:11:14 mailserveur cyrus/imap[12162]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:14 mailserveur cyrus/imap[12162]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:14 mailserveur cyrus/imap[12361]: SSL_accept() incomplete → wait
Feb 14 13:11:14 mailserveur cyrus/imap[12162]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:14 mailserveur cyrus/imap[12162]: USAGE bibi@tutu.fr user: 0.016001 sys: 0.000000
Feb 14 13:11:14 mailserveur cyrus/imap[12361]: SSL_accept() succeeded → done
Feb 14 13:11:14 mailserveur cyrus/imap[12361]: starttls: TLSv1 with cipher DHE-RSA-AES256-SHA (256/256 bits new) no authentication
Feb 14 13:11:14 mailserveur cyrus/imap[12361]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:14 mailserveur cyrus/imap[12361]: login: mailserveur.tutu.lan [192.168.1.1] bibi@tutu.fr PLAIN+TLS User logged in

SESSIONID=
Feb 14 13:11:14 mailserveur cyrus/imap[12361]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:14 mailserveur cyrus/imap[12361]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:14 mailserveur cyrus/imap[12361]: open: user bibi@tutu.fr opened INBOX
Feb 14 13:11:14 mailserveur cyrus/imap[12361]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:14 mailserveur cyrus/imap[12361]: SQUAT failed to open index file
Feb 14 13:11:14 mailserveur cyrus/imap[12361]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:14 mailserveur cyrus/imap[12361]: SQUAT failed to open index file
Feb 14 13:11:14 mailserveur cyrus/imap[12361]: SQUAT failed
Feb 14 13:11:14 mailserveur cyrus/imap[12361]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:14 mailserveur cyrus/imap[12361]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:14 mailserveur cyrus/imap[12361]: USAGE bibi@tutu.fr user: 0.040002 sys: 0.000000
Feb 14 13:11:18 mailserveur postfix/smtp[12425]: B54FCF48001: to=alima@destinataire.fr, relay=mail.destinataire.fr[109.2.235.118]:25,

delay=5.7, delays=0.05/0.01/0.2/5.4, ds$
Feb 14 13:11:19 mailserveur cyrus/imap[12411]: accepted connection
Feb 14 13:11:19 mailserveur cyrus/imap[12411]: imapd:Loading hard-coded DH parameters
Feb 14 13:11:19 mailserveur cyrus/imap[12411]: SSL_accept() incomplete → wait
Feb 14 13:11:19 mailserveur cyrus/imap[12411]: SSL_accept() succeeded → done
Feb 14 13:11:19 mailserveur cyrus/imap[12411]: starttls: TLSv1 with cipher DHE-RSA-AES256-SHA (256/256 bits new) no authentication
Feb 14 13:11:19 mailserveur cyrus/imap[12411]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:19 mailserveur cyrus/imap[12411]: login: mailserveur.tutu.lan [192.168.1.1] bibi@tutu.fr PLAIN+TLS User logged in

SESSIONID=
Feb 14 13:11:19 mailserveur cyrus/imap[12411]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:19 mailserveur cyrus/imap[12411]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:19 mailserveur cyrus/imap[12411]: open: user bibi@tutu.fr opened Sent
Feb 14 13:11:19 mailserveur cyrus/imap[12411]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:19 mailserveur cyrus/imap[12411]: SQUAT failed to open index file
Feb 14 13:11:19 mailserveur cyrus/imap[12411]: SQUAT failed
Feb 14 13:11:19 mailserveur cyrus/imap[12411]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:19 mailserveur cyrus/imap[12411]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:20 mailserveur cyrus/imap[12411]: USAGE bibi@tutu.fr user: 0.028001 sys: 0.020001
Feb 14 13:11:19 mailserveur cyrus/imap[12411]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:20 mailserveur cyrus/imap[12411]: USAGE bibi@tutu.fr user: 0.028001 sys: 0.020001
Feb 14 13:11:23 mailserveur postfix/cleanup[12399]: 7E129F48002: message-id=20130214121123.7E129F48002@mailserveur.tutu.lan
Feb 14 13:11:23 mailserveur postfix/bounce[12444]: B54FCF48001: sender non-delivery notification: 7E129F48002
Feb 14 13:11:23 mailserveur postfix/qmgr[10623]: 7E129F48002: from=<>, size=3802, nrcpt=1 (queue active)
Feb 14 13:11:23 mailserveur postfix/qmgr[10623]: B54FCF48001: removed
Feb 14 13:11:23 mailserveur cyrus/lmtp[12408]: accepted connection
Feb 14 13:11:23 mailserveur cyrus/lmtp[12408]: connection from mailserveur.tutu.lan [192.168.1.1] preauth’d as postman
Feb 14 13:11:23 mailserveur cyrus/lmtp[12408]: Delivered: 20130214121123.7E129F48002@mailserveur.tutu.lan to mailbox: tutu.fr!user.bibi
Feb 14 13:11:23 mailserveur cyrus/lmtp[12408]: USAGE bibi user: 0.004001 sys: 0.000000
Feb 14 13:11:23 mailserveur cyrus/imap[5615]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:23 mailserveur cyrus/imaps[11616]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:23 mailserveur cyrus/imap[12096]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:23 mailserveur cyrus/imap[5615]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:23 mailserveur cyrus/imaps[11607]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:23 mailserveur cyrus/imap[12096]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:23 mailserveur cyrus/imaps[11616]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:23 mailserveur cyrus/imap[12096]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:23 mailserveur cyrus/imaps[11607]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:23 mailserveur cyrus/imap[5615]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:23 mailserveur cyrus/imaps[11616]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:23 mailserveur cyrus/imap[12096]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:23 mailserveur cyrus/imap[5615]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:23 mailserveur cyrus/imap[12096]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:23 mailserveur cyrus/imap[5615]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:23 mailserveur cyrus/imaps[11616]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:23 mailserveur postfix/lmtp[12400]: 7E129F48002: to=bibi@tutu.fr, relay=192.168.1.1[192.168.1.1]:2400, delay=0.24,

delays=0.01/0/0/0.23, dsn=2.1.5, status=sent (250 2.$
Feb 14 13:11:23 mailserveur cyrus/imap[12096]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:23 mailserveur postfix/qmgr[10623]: 7E129F48002: removed
Feb 14 13:11:23 mailserveur cyrus/imap[5615]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:23 mailserveur cyrus/imaps[11607]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:23 mailserveur cyrus/imaps[11616]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:23 mailserveur cyrus/imaps[11607]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:23 mailserveur cyrus/imaps[11607]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:23 mailserveur cyrus/imap[12096]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:23 mailserveur cyrus/imaps[11616]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:23 mailserveur cyrus/imap[5615]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:23 mailserveur cyrus/imaps[11607]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:23 mailserveur cyrus/imap[12096]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:23 mailserveur cyrus/imap[12096]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:24 mailserveur cyrus/imaps[11616]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:24 mailserveur cyrus/imaps[11607]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:24 mailserveur cyrus/imaps[11616]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:24 mailserveur cyrus/imap[5615]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:24 mailserveur cyrus/imap[5615]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:24 mailserveur cyrus/imap[5615]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:24 mailserveur cyrus/imap[5615]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:24 mailserveur cyrus/imap[5615]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:24 mailserveur cyrus/imap[5615]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:24 mailserveur cyrus/imaps[11607]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:30 mailserveur cyrus/imap[12393]: accepted connection
Feb 14 13:11:30 mailserveur cyrus/imap[12393]: imapd:Loading hard-coded DH parameters
Feb 14 13:11:30 mailserveur cyrus/imap[12393]: SSL_accept() incomplete → wait
Feb 14 13:11:30 mailserveur cyrus/imap[12393]: SSL_accept() succeeded → done
Feb 14 13:11:30 mailserveur cyrus/imap[12393]: starttls: TLSv1 with cipher DHE-RSA-AES256-SHA (256/256 bits new) no authentication
Feb 14 13:11:30 mailserveur cyrus/imap[12393]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:30 mailserveur cyrus/imap[12393]: login: mailserveur.tutu.lan [192.168.1.1] bibi@tutu.fr PLAIN+TLS User logged in

SESSIONID=
Feb 14 13:11:30 mailserveur cyrus/imap[12393]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:30 mailserveur cyrus/imap[12393]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:30 mailserveur cyrus/imap[12393]: open: user bibi@tutu.fr opened Sent
Feb 14 13:11:30 mailserveur cyrus/imap[12393]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:30 mailserveur cyrus/imap[12393]: SQUAT failed to open index file
Feb 14 13:11:30 mailserveur cyrus/imap[12393]: SQUAT failed
Feb 14 13:11:30 mailserveur cyrus/imap[12393]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:30 mailserveur cyrus/imap[12393]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:30 mailserveur cyrus/imap[12393]: USAGE bibi@tutu.fr user: 0.036002 sys: 0.012000
Feb 14 13:11:34 mailserveur fetchmail[4151]: 1 message (1 déjà vu) pour jm.essai@tutu.fr dans ns0.ovh.net.
Feb 14 13:11:34 mailserveur fetchmail[4151]: 116 messages (116 déjà vus) pour bibi@tutu.fr dans ns0.ovh.net.
Feb 14 13:11:35 mailserveur fetchmail[4151]: 8 messages (8 déjà vus) pour commercial@tutu.fr dans ns0.ovh.net.
Feb 14 13:11:34 mailserveur fetchmail[4151]: 116 messages (116 déjà vus) pour bibi@tutu.fr dans ns0.ovh.net.
Feb 14 13:11:35 mailserveur fetchmail[4151]: 8 messages (8 déjà vus) pour commercial@tutu.fr dans ns0.ovh.net.
Feb 14 13:11:48 mailserveur cyrus/imap[12419]: accepted connection
Feb 14 13:11:48 mailserveur cyrus/imap[12419]: imapd:Loading hard-coded DH parameters
Feb 14 13:11:48 mailserveur cyrus/imap[12419]: SSL_accept() incomplete → wait
Feb 14 13:11:48 mailserveur cyrus/imap[12419]: SSL_accept() succeeded → done
Feb 14 13:11:48 mailserveur cyrus/imap[12419]: starttls: TLSv1 with cipher DHE-RSA-AES256-SHA (256/256 bits new) no authentication
Feb 14 13:11:48 mailserveur cyrus/imap[12419]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:48 mailserveur cyrus/imap[12419]: login: mailserveur.tutu.lan [192.168.1.1] bibi@tutu.fr PLAIN+TLS User logged in

SESSIONID=
Feb 14 13:11:48 mailserveur cyrus/imap[12419]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:48 mailserveur cyrus/imap[12419]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:48 mailserveur cyrus/imap[12419]: open: user bibi@tutu.fr opened INBOX
Feb 14 13:11:48 mailserveur cyrus/imap[12419]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:48 mailserveur cyrus/imap[12419]: SQUAT failed to open index file
Feb 14 13:11:48 mailserveur cyrus/imap[12419]: SQUAT failed
Feb 14 13:11:48 mailserveur cyrus/imap[12419]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:48 mailserveur cyrus/imap[12419]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:48 mailserveur cyrus/imap[12419]: SQUAT failed to open index file
Feb 14 13:11:48 mailserveur cyrus/imap[12419]: SQUAT failed
Feb 14 13:11:48 mailserveur cyrus/imap[12419]: fetching user_deny.db entry for ‘bibi@tutu.fr’
Feb 14 13:11:48 mailserveur cyrus/imap[12419]: USAGE bibi@tutu.fr user: 0.024001 sys: 0.016001
Feb 14 13:11:50 mailserveur cyrus/master[10822]: process 12176 exited, status 0

Cordialement

4

Pouvez-vous m’envoyer ce fichier de log ainsi qu’une adresse mail qui a rencontré le problème ?

5

Juste pour information, j’ai eu le même problème, un utilisateur qui ne recevait pas ses mails, et des messages « SQUAT failed » dans le syslog.

J’ai résolu mon problème avec les commandes suivantes :

ctl_mboxlist -d > dump for x in `awk '{print $4}' dump | sort | uniq`;do squatter -r user/$x; done

Cordialement.

6

Ce n’est pas le même soucis. Vous êtes en version edge de Blue Mind, et effectivement SQUAT est nécessaire, ce qui n’est pas le cas en version 1, SQUAT n’est pas activé.

Il est étonnant que vous ayez ce problème en version edge de Blue Mind par contre.

7

Non là c’est sur un autre serveur qui tourne avec la version 1 que j’ai rencontré ce problème.

8

Pouvez-vous retrouver un des messages qui n’était pas délivré dans le log /var/log/mail.log (en cherchant pas expéditeur ou destinataire et l’heure) pour connaître le message d’erreur que rencontrait postfix ?

En version 1, SQUAT n’est pas activé, il y a bien ces messages d’erreurs SQUAT, mais ils ne gênent pas la réception des mails.

9

Voilà quelques lignes de mail qui posaient problèmes par exemple :

Apr  7 07:23:37 mx1 postfix/qmgr[1747]: 1A33B1C9C5D6: from=<noreply@adobe.com>, size=2086, nrcpt=1 (queue active)
Apr  7 07:23:37 mx1 postfix/qmgr[1747]: F21061C9C258: from=<to9873-ovh@nichandle.ovh.net>, size=2159, nrcpt=1 (queue active)
Apr  7 07:23:37 mx1 postfix/qmgr[1747]: 2B6621C9C257: from=<root@mx1.ltpsn.org>, size=16668, nrcpt=1 (queue active)
Apr  7 07:23:37 mx1 postfix/qmgr[1747]: 432B01C9C553: from=<root@ns3301101.ovh.net>, size=49149, nrcpt=1 (queue active)
Apr  7 07:23:37 mx1 postfix/qmgr[1747]: 4D0211C9C5C7: from=<root@mx1.ltpsn.org>, size=18942, nrcpt=1 (queue active)
Apr  7 07:23:37 mx1 postfix/qmgr[1747]: A5A121C9C259: from=<root@ns3301101.ovh.net>, size=48011, nrcpt=1 (queue active)
Apr  7 07:28:37 mx1 postfix/lmtp[1192]: F21061C9C258: to=<admin@ltpsn.org>, relay=46.105.47.172[46.105.47.172]:2400, delay=164767, delays=164467/0.01/300/0, dsn=4.4.2, status=deferred (conversation with 46.105.47.172[46.105.47.172] timed out while receiving the initial server greeting)
Apr  7 07:28:37 mx1 postfix/lmtp[1193]: 2B6621C9C257: to=<admin+mx1@ltpsn.org>, relay=46.105.47.172[46.105.47.172]:2400, delay=98914, delays=98614/0.01/300/0, dsn=4.4.2, status=deferred (conversation with 46.105.47.172[46.105.47.172] timed out while receiving the initial server greeting)
Apr  7 07:28:37 mx1 postfix/lmtp[1194]: 432B01C9C553: to=<admin+hv1@ltpsn.org>, relay=46.105.47.172[46.105.47.172]:2400, delay=185313, delays=185013/0.01/300/0, dsn=4.4.2, status=deferred (conversation with 46.105.47.172[46.105.47.172] timed out while receiving the initial server greeting)
Apr  7 07:28:37 mx1 postfix/lmtp[1196]: 4D0211C9C5C7: to=<admin+mx1@ltpsn.org>, relay=46.105.47.172[46.105.47.172]:2400, delay=185315, delays=185015/0.01/300/0, dsn=4.4.2, status=deferred (conversation with 46.105.47.172[46.105.47.172] timed out while receiving the initial server greeting)
Apr  7 07:28:37 mx1 postfix/lmtp[1191]: 1A33B1C9C5D6: to=<admin+nicolas@ltpsn.org>, relay=46.105.47.172[46.105.47.172]:2400, delay=169816, delays=169516/0.01/300/0, dsn=4.4.2, status=deferred (conversation with 46.105.47.172[46.105.47.172] timed out while receiving the initial server greeting)
Apr  7 07:28:37 mx1 postfix/error[1219]: A5A121C9C259: to=<admin+hv1@ltpsn.org>, relay=none, delay=98912, delays=98612/300/0/0, dsn=4.4.2, status=deferred (delivery temporarily suspended: conversation with 46.105.47.172[46.105.47.172] timed out while receiving the initial server greeting)

Bah bizarrement après avoir « reconstruit l’index » avec squatter, les deux mails de test que j’avais envoyé il y a quelques minutes sont arrivés immédiatement, et au fur et à mesure des mails plus anciens.

10

Pouvez-vous nous envoyer les logs de bm-lmtp /var/log/bm-lmtpd/lmtpd.log ?

11

Voilà le fichier complet : http://pastebin.com/7GJ4vnXz

12

En fait c’est le re-démarrage de Blue Mind ce midi du service LMTP qui a débloqué la situtation:

2013-04-09 10:01:06,530 n.b.l.LMTPDaemon INFO - Starting LMTP daemon...

Par contre vos logs de LMTP nous paraissent très bizarre, rien depuis vendredi, suite à votre mise à jour vue les heures… Serait-il possible de prendre la main sur votre machine ?

13

Ah c’est bien possible, il me semble avoir fait un « bmctl restart ».

Oui j’ai vu ça aussi…

Ok pas de soucis, je vous envoie les identifiants par mail.