Дубляж писем (dovecot + postfix)

EXIM, sendmail, postfix, Dovecot и прочие. Решение проблем связанных с работой электронной почты

Модератор: xM

Правила форума
Убедительная просьба юзать теги [code] при оформлении листингов.
Сообщения не оформленные должным образом имеют все шансы быть незамеченными.
Аватара пользователя
gumeniuc
ст. сержант
Сообщения: 343
Зарегистрирован: 2009-11-08 15:46:05
Откуда: md
Контактная информация:

Дубляж писем (dovecot + postfix)

Непрочитанное сообщение gumeniuc » 2013-06-28 22:14:50

Доброго времени суток,

Решил развернуть тестовую платформу со связкой dovecot + postfix. Создал домен mail.athome и добавил 2 ящика user@mail.athome и user1@mail.athome.

Отправляю тестовое письмо от user1@mail.athome user@mail.athome. В итоге, письмо для user@mail.athome оказывается у user1@mail.athome в отправленных и входящих ! При этом, в свойствах письма явно видно, что получатель user@mail.athome.

Логи момента отправки

Postfix:

Код: Выделить всё

Jun 16 08:22:08 centos postfix/pickup[4434]: 36D9D9981: uid=48 from=<user1@mail.athome>
Jun 16 08:22:08 centos postfix/cleanup[4917]: 36D9D9981: message-id=<37ec66085bfbdc8549066b0c194f5d8e@mail.athome>
Jun 16 08:22:08 centos postfix/qmgr[4435]: 36D9D9981: from=<user1@mail.athome>, size=502, nrcpt=1 (queue active)
Jun 16 08:22:08 centos postfix/pipe[4922]: 36D9D9981: to=<user@mail.athome>, relay=dovecot, delay=0.33, delays=0.12/0.05/0/0.16, dsn=2.0.0, status=sent (delivered via dovecot service)
Jun 16 08:22:08 centos postfix/qmgr[4435]: 36D9D9981: removed
Dovecot:

Код: Выделить всё

Jun 16 08:22:08 auth: Debug: auth client connected (pid=4913)
Jun 16 08:22:08 auth: Debug: client in: AUTH    1       PLAIN   service=imap    secured lip=127.0.0.1   rip=127.0.0.1   lport=143       rport=34426     resp=
dXNlcjFAbWFpbC5hdGhvbWUAdXNlcjFAbWFpbC5hdGhvbWUAdXNlcjE=
Jun 16 08:22:08 auth: Debug: sql(user1@mail.athome,127.0.0.1): query: SELECT username AS user,domain,password FROM mailbox WHERE username = 'user1@mail.athom
e' AND active='1'
Jun 16 08:22:08 auth: Debug: client out: OK     1       user=user1@mail.athome
Jun 16 08:22:08 auth: Debug: master in: REQUEST 4250533889      4913    1       7b8ff947309f60a5430266aaafcaeb3f
Jun 16 08:22:08 auth: Debug: sql(user1@mail.athome,127.0.0.1): SELECT username AS user, '5000' AS uid, '5000' AS gid FROM mailbox WHERE username = 'user1'+'@
'+'mail.athome' AND domain = 'mail.athome' AND active='1'
Jun 16 08:22:08 auth: Debug: auth(user1@mail.athome,127.0.0.1): username changed user1@mail.athome -> user@mail.athome
Jun 16 08:22:08 auth: Debug: master out: USER   4250533889      user@mail.athome        uid=5000        gid=5000
Jun 16 08:22:08 imap-login: Info: Login: user=<user1@mail.athome>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=4914, secured
Jun 16 08:22:08 imap: Debug: Loading modules from directory: /usr/lib/dovecot
Jun 16 08:22:08 imap: Debug: Module loaded: /usr/lib/dovecot/lib20_autocreate_plugin.so
Jun 16 08:22:08 imap(user@mail.athome): Debug: Effective uid=5000, gid=5000, home=
Jun 16 08:22:08 imap(user@mail.athome): Debug: maildir++: root=/var/vmail/mail.athome/user, index=, control=, inbox=/var/vmail/mail.athome/user
Jun 16 08:22:08 imap(user@mail.athome): Debug: Namespace : Using permissions from /var/vmail/mail.athome/user: mode=0777 gid=-1
Jun 16 08:22:08 imap(user@mail.athome): Info: Disconnected: Logged out bytes=439/499
Jun 16 08:22:08 auth: Debug: master in: USER    1       user@mail.athome        service=lda
Jun 16 08:22:08 auth: Debug: sql(user@mail.athome): SELECT username AS user, '5000' AS uid, '5000' AS gid FROM mailbox WHERE username = 'user'+'@'+'mail.atho
me' AND domain = 'mail.athome' AND active='1'
Jun 16 08:22:08 auth: Debug: master out: USER   1       user@mail.athome        uid=5000        gid=5000
Jun 16 08:22:08 lda: Debug: auth input: user@mail.athome uid=5000 gid=5000
Jun 16 08:22:08 lda(user@mail.athome): Debug: Effective uid=5000, gid=5000, home=
Jun 16 08:22:08 lda(user@mail.athome): Debug: maildir++: root=/var/vmail/mail.athome/user, index=, control=, inbox=/var/vmail/mail.athome/user
Jun 16 08:22:08 lda(user@mail.athome): Debug: none: root=, index=, control=, inbox=
Jun 16 08:22:08 lda(user@mail.athome): Info: msgid=<37ec66085bfbdc8549066b0c194f5d8e@mail.athome>: saved mail to INBOX
Jun 16 08:22:08 auth: Debug: auth client connected (pid=4925)
Jun 16 08:22:08 auth: Debug: client in: AUTH    1       PLAIN   service=imap    secured lip=127.0.0.1   rip=127.0.0.1   lport=143       rport=34427     resp=dXNlcjFAbWFpbC5hdGhvbWUAdXNlcjFAbWFpbC5hdGhvbWUAdXNlcjE=
Jun 16 08:22:08 auth: Debug: sql(user1@mail.athome,127.0.0.1): query: SELECT username AS user,domain,password FROM mailbox WHERE username = 'user1@mail.athome' AND active='1'
Jun 16 08:22:08 auth: Debug: client out: OK     1       user=user1@mail.athome
Jun 16 08:22:08 auth: Debug: master in: REQUEST 3397124097      4925    1       d915243938e3a8c9c5c0d6f9f5733c35
Jun 16 08:22:08 auth: Debug: sql(user1@mail.athome,127.0.0.1): SELECT username AS user, '5000' AS uid, '5000' AS gid FROM mailbox WHERE username = 'user1'+'@'+'mail.athome' AND domain = 'mail.athome' AND active='1'
Jun 16 08:22:08 auth: Debug: auth(user1@mail.athome,127.0.0.1): username changed user1@mail.athome -> user@mail.athome
Jun 16 08:22:08 auth: Debug: master out: USER   3397124097      user@mail.athome        uid=5000        gid=5000
Jun 16 08:22:08 imap-login: Info: Login: user=<user1@mail.athome>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=4926, secured
Jun 16 08:22:08 imap: Debug: Loading modules from directory: /usr/lib/dovecot
Jun 16 08:22:08 imap: Debug: Module loaded: /usr/lib/dovecot/lib20_autocreate_plugin.so
Jun 16 08:22:08 imap(user@mail.athome): Debug: Effective uid=5000, gid=5000, home=
Jun 16 08:22:08 imap(user@mail.athome): Debug: maildir++: root=/var/vmail/mail.athome/user, index=, control=, inbox=/var/vmail/mail.athome/user
Jun 16 08:22:08 imap(user@mail.athome): Debug: Namespace : Using permissions from /var/vmail/mail.athome/user: mode=0777 gid=-1
Jun 16 08:22:09 imap(user@mail.athome): Info: Disconnected: Logged out bytes=44/531
Jun 16 08:22:09 auth: Debug: auth client connected (pid=4929)
Jun 16 08:22:09 auth: Debug: client in: AUTH    1       PLAIN   service=imap    secured lip=127.0.0.1   rip=127.0.0.1   lport=143       rport=34428     resp=dXNlcjFAbWFpbC5hdGhvbWUAdXNlcjFAbWFpbC5hdGhvbWUAdXNlcjE=
Jun 16 08:22:09 auth: Debug: sql(user1@mail.athome,127.0.0.1): query: SELECT username AS user,domain,password FROM mailbox WHERE username = 'user1@mail.athome' AND active='1'
Jun 16 08:22:09 auth: Debug: client out: OK     1       user=user1@mail.athome
Jun 16 08:22:09 auth: Debug: auth client connected (pid=4931)
Jun 16 08:22:09 auth: Debug: client in: AUTH    1       PLAIN   service=imap    secured lip=127.0.0.1   rip=127.0.0.1   lport=143       rport=34429     resp=dXNlcjFAbWFpbC5hdGhvbWUAdXNlcjFAbWFpbC5hdGhvbWUAdXNlcjE=
Jun 16 08:22:09 auth: Debug: sql(user1@mail.athome,127.0.0.1): query: SELECT username AS user,domain,password FROM mailbox WHERE username = 'user1@mail.athome' AND active='1'
Jun 16 08:22:09 auth: Debug: client out: OK     1       user=user1@mail.athome
Jun 16 08:22:09 auth: Debug: auth client connected (pid=4931)
Jun 16 08:22:09 auth: Debug: client in: AUTH    1       PLAIN   service=imap    secured lip=127.0.0.1   rip=127.0.0.1   lport=143       rport=34429     resp=dXNlcjFAbWFpbC5hdGhvbWUAdXNlcjFAbWFpbC5hdGhvbWUAdXNlcjE=
Jun 16 08:22:09 auth: Debug: sql(user1@mail.athome,127.0.0.1): query: SELECT username AS user,domain,password FROM mailbox WHERE username = 'user1@mail.athome' AND active='1'
Jun 16 08:22:09 auth: Debug: client out: OK     1       user=user1@mail.athome
Jun 16 08:22:09 auth: Debug: master in: REQUEST 3042050049      4929    1       78b7de1198b4652c90f614d7180a1fb6
Jun 16 08:22:09 auth: Debug: sql(user1@mail.athome,127.0.0.1): SELECT username AS user, '5000' AS uid, '5000' AS gid FROM mailbox WHERE username = 'user1'+'@'+'mail.athome' AND domain = 'mail.athome' AND active='1'
Jun 16 08:22:09 auth: Debug: auth(user1@mail.athome,127.0.0.1): username changed user1@mail.athome -> user@mail.athome
Jun 16 08:22:09 auth: Debug: master out: USER   3042050049      user@mail.athome        uid=5000        gid=5000
Jun 16 08:22:09 imap-login: Info: Login: user=<user1@mail.athome>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=4930, secured
Jun 16 08:22:09 imap: Debug: Loading modules from directory: /usr/lib/dovecot
Jun 16 08:22:09 imap: Debug: Module loaded: /usr/lib/dovecot/lib20_autocreate_plugin.so
Jun 16 08:22:09 imap(user@mail.athome): Debug: Effective uid=5000, gid=5000, home=
Jun 16 08:22:09 imap(user@mail.athome): Debug: maildir++: root=/var/vmail/mail.athome/user, index=, control=, inbox=/var/vmail/mail.athome/user
Jun 16 08:22:09 imap(user@mail.athome): Debug: Namespace : Using permissions from /var/vmail/mail.athome/user: mode=0777 gid=-1
Jun 16 08:22:09 auth: Debug: master in: REQUEST 2212626433      4931    1       eeea67376badf5cb28929a2c3e504c29
Jun 16 08:22:09 auth: Debug: sql(user1@mail.athome,127.0.0.1): SELECT username AS user, '5000' AS uid, '5000' AS gid FROM mailbox WHERE username = 'user1'+'@'+'mail.athome' AND domain = 'mail.athome' AND active='1'
Jun 16 08:22:09 auth: Debug: auth(user1@mail.athome,127.0.0.1): username changed user1@mail.athome -> user@mail.athome
Jun 16 08:22:09 auth: Debug: master out: USER   2212626433      user@mail.athome        uid=5000        gid=5000
Jun 16 08:22:09 imap-login: Info: Login: user=<user1@mail.athome>, method=PLAIN, rip=127.0.0.1, lip=127.0.0.1, mpid=4932, secured
Jun 16 08:22:09 imap: Debug: Loading modules from directory: /usr/lib/dovecot
Jun 16 08:22:09 imap: Debug: Module loaded: /usr/lib/dovecot/lib20_autocreate_plugin.so
Jun 16 08:22:09 imap(user@mail.athome): Debug: Effective uid=5000, gid=5000, home=
Jun 16 08:22:09 imap(user@mail.athome): Debug: maildir++: root=/var/vmail/mail.athome/user, index=, control=, inbox=/var/vmail/mail.athome/user
Jun 16 08:22:09 imap(user@mail.athome): Debug: Namespace : Using permissions from /var/vmail/mail.athome/user: mode=0777 gid=-1
Jun 16 08:22:09 imap(user@mail.athome): Info: Disconnected: Logged out bytes=82/599
Jun 16 08:22:09 imap(user@mail.athome): Info: Disconnected: Logged out bytes=267/1969
Подскажите, пожалуйста, в какой стороне искать правду.
Да шо ему сделается...

Хостинговая компания Host-Food.ru
Хостинг HostFood.ru
 

Услуги хостинговой компании Host-Food.ru

Хостинг HostFood.ru

Тарифы на хостинг в России, от 12 рублей: https://www.host-food.ru/tariffs/hosting/
Тарифы на виртуальные сервера (VPS/VDS/KVM) в РФ, от 189 руб.: https://www.host-food.ru/tariffs/virtualny-server-vps/
Выделенные сервера, Россия, Москва, от 2000 рублей (HP Proliant G5, Intel Xeon E5430 (2.66GHz, Quad-Core, 12Mb), 8Gb RAM, 2x300Gb SAS HDD, P400i, 512Mb, BBU):
https://www.host-food.ru/tariffs/vydelennyi-server-ds/
Недорогие домены в популярных зонах: https://www.host-food.ru/domains/

moury
сержант
Сообщения: 249
Зарегистрирован: 2009-02-06 23:02:40
Откуда: Москва
Контактная информация:

Re: Дубляж писем (dovecot + postfix)

Непрочитанное сообщение moury » 2013-07-01 14:58:24

Jun 16 08:22:08 auth: Debug: auth(user1@mail.athome,127.0.0.1): username changed user1@mail.athome -> user@mail.athome
У Вас что-то не то с привязкой dovecot'а к БД. Это - основной источник проблем.

Мне в Вашем логе много не нравится, например: у почтовых юзеров нет домашней директори; права 777 на ящики, и т. п.
Есть смутное подозрение, что не перечислены реалмы (могу ошибаться).

Еще есть подозрение, что в настройках транспорта "dovecot " в MTA параметр "-d" не соответствует действующей конфигурации dovecot'а. Возможно, после исправления в dovecot все заработает как надо.
Сисадмин - вождь апачей