Courier Mail Server Forum Index Courier Mail Server
www.courierms.ru
 
 FAQFAQ   SearchSearch   MemberlistMemberlist   UsergroupsUsergroups   RegisterRegister 
 ProfileProfile   Log in to check your private messagesLog in to check your private messages   Log inLog in 

Прекращение приема почты

 
Post new topic   Reply to topic    Courier Mail Server Forum Index -> Courier Mail Server 3.xx
View previous topic :: View next topic  
Author Message
Альберт
Newbie
Newbie


Joined: 26 Mar 2014
Posts: 4

PostPosted: 26 Mar 2014 16:40 (Wed)    Post subject: Прекращение приема почты Reply with quote

Здравствуйте.

Наблюдается следующая проблема: CMS 3.02 установлен на комп с Win7 и настроен на получение писем с внешнего ящика (задание выполняется каждые 5 мин.). Периодически при приеме возникает ситуация, когда прием писем "зависает" на каком-то письме. Перезапуск сервиса результатов не приносит, помогает только перезагрузка системы. Антивирус отключен.

Лог при "зависании":

===[CMSLog cut]===============8<----------------------------------------------
~26.03.2014 14:25:00 014S Thread started (TCsReceiveThread)
~26.03.2014 14:25:00 014S Начали приём почты из группы внешних п/я "admin"...
26.03.2014 14:25:00 014S Подключаемся к серверу pop3.ht-systems.ru:110...
26.03.2014 14:25:00 014S Начали приём почты из внешнего п/я <info@arina-tour.ru>...
~26.03.2014 14:25:00 014S iphlpapi.dll loaded
~26.03.2014 14:25:00 014S Windows DNS servers: 192.168.0.1;
~26.03.2014 14:25:00 014S iphlpapi.dll unloaded
26.03.2014 14:25:00 014S Получаем IP-адреса для pop3.ht-systems.ru...
~26.03.2014 14:25:00 014S DNS query [192.168.0.1]: pop3.ht-systems.ru 1
~26.03.2014 14:25:00 014S pop3.ht-systems.ru CNAME mpmx.ht-systems.ru
~26.03.2014 14:25:00 014S mpmx.ht-systems.ru A 78.110.50.185
~26.03.2014 14:25:00 014S mpmx.ht-systems.ru A 78.110.50.95
26.03.2014 14:25:00 014S IP-адреса: 78.110.50.185;78.110.50.95;
+26.03.2014 14:25:00 014S Подключились к серверу pop3.ht-systems.ru:110
>26.03.2014 14:25:00 014S +OK POP3 ready <1757708526.1395829503@mp0.ht-systems.ru>
<26.03.2014 14:25:00 014S USER info@arina-tour.ru
>26.03.2014 14:25:00 014S +OK
<26.03.2014 14:25:00 014S PASS ********
>26.03.2014 14:25:00 014S +OK Logged in.
<26.03.2014 14:25:00 014S UIDL
>26.03.2014 14:25:00 014S +OK
>26.03.2014 14:25:00 014S 1 0003512c4bdb1a3d
>26.03.2014 14:25:00 014S 2 0003512d4bdb1a3d
>26.03.2014 14:25:00 014S 3 0003512e4bdb1a3d
>26.03.2014 14:25:00 014S 4 0003512f4bdb1a3d
>26.03.2014 14:25:00 014S 5 000351304bdb1a3d
>26.03.2014 14:25:00 014S 6 000351314bdb1a3d
>26.03.2014 14:25:00 014S 7 000351324bdb1a3d
>26.03.2014 14:25:00 014S .
26.03.2014 14:25:00 014S Писем во внешнем п/я: 7 (новых 7)
<26.03.2014 14:25:00 014S RETR 1
>26.03.2014 14:25:00 014S +OK 529985 octets
!26.03.2014 14:53:11 014S Прерван приём письма N1 (4056 байт) от <> из п/я <info@arina-tour.ru>: Неверный сокет (10038) (Тема: ""; UID: "0003512c4bdb1a3d")
-26.03.2014 14:53:11 014S Отключились от сервера pop3.ht-systems.ru:110
26.03.2014 14:53:11 014S Закончили приём почты из внешнего п/я <info@arina-tour.ru>
~26.03.2014 14:53:11 014S Закончили приём почты из группы внешних п/я "admin"
~26.03.2014 14:53:11 014S Thread stopped (TCsReceiveThread)
------------------------------>8===============================[CMSLog cut]===



После 14:25:00 через каждые 5 мин. выводятся сообщения такого рода:

===[CMSLog cut]===============8<----------------------------------------------
~26.03.2014 14:30:00 015C Thread started (TCsTaskThread)
~26.03.2014 14:30:00 015C Task "Receive" already running
~26.03.2014 14:30:00 015C Thread stopped (TCsTaskThread)
------------------------------>8===============================[CMSLog cut]===


Т.е. понятно, что это задание не завершилось и оно "висит". В 14:53:11 служба была вручную остановлена и запущена заново, после чего получение опять застопорилось на этом же письме:

===[CMSLog cut]===============8<----------------------------------------------
~26.03.2014 14:55:00 000B Thread started (TCsReceiveThread)
~26.03.2014 14:55:00 000B Начали приём почты из группы внешних п/я "admin"...
26.03.2014 14:55:00 000B Подключаемся к серверу pop3.ht-systems.ru:110...
26.03.2014 14:55:00 000B Начали приём почты из внешнего п/я <info@arina-tour.ru>...
~26.03.2014 14:55:00 000B iphlpapi.dll loaded
~26.03.2014 14:55:00 000B Windows DNS servers: 192.168.0.1;
~26.03.2014 14:55:00 000B iphlpapi.dll unloaded
26.03.2014 14:55:00 000B Получаем IP-адреса для pop3.ht-systems.ru...
~26.03.2014 14:55:00 000B DNS query [192.168.0.1]: pop3.ht-systems.ru 1
~26.03.2014 14:55:00 000B pop3.ht-systems.ru CNAME mpmx.ht-systems.ru
~26.03.2014 14:55:00 000B mpmx.ht-systems.ru A 78.110.50.95
~26.03.2014 14:55:00 000B mpmx.ht-systems.ru A 78.110.50.185
26.03.2014 14:55:00 000B IP-адреса: 78.110.50.95;78.110.50.185;
+26.03.2014 14:55:00 000B Подключились к серверу pop3.ht-systems.ru:110
>26.03.2014 14:55:00 000B +OK POP3 ready <1592091756.1395831303@mp1.ht-systems.ru>
<26.03.2014 14:55:00 000B USER info@arina-tour.ru
>26.03.2014 14:55:00 000B +OK
<26.03.2014 14:55:00 000B PASS ********
>26.03.2014 14:55:00 000B +OK Logged in.
<26.03.2014 14:55:00 000B UIDL
>26.03.2014 14:55:00 000B +OK
>26.03.2014 14:55:00 000B 1 0003512c4bdb1a3d
>26.03.2014 14:55:00 000B 2 0003512d4bdb1a3d
>26.03.2014 14:55:00 000B 3 0003512e4bdb1a3d
>26.03.2014 14:55:00 000B 4 0003512f4bdb1a3d
>26.03.2014 14:55:00 000B 5 000351304bdb1a3d
>26.03.2014 14:55:00 000B 6 000351314bdb1a3d
>26.03.2014 14:55:00 000B 7 000351324bdb1a3d
>26.03.2014 14:55:00 000B 8 000351334bdb1a3d
>26.03.2014 14:55:00 000B 9 000351344bdb1a3d
>26.03.2014 14:55:00 000B 10 000351354bdb1a3d
>26.03.2014 14:55:00 000B 11 000351364bdb1a3d
>26.03.2014 14:55:00 000B 12 000351374bdb1a3d
>26.03.2014 14:55:00 000B 13 000351384bdb1a3d
>26.03.2014 14:55:00 000B 14 000351394bdb1a3d
>26.03.2014 14:55:00 000B 15 0003513a4bdb1a3d
>26.03.2014 14:55:00 000B 16 0003513b4bdb1a3d
>26.03.2014 14:55:00 000B 17 0003513c4bdb1a3d
>26.03.2014 14:55:00 000B 18 0003513d4bdb1a3d
>26.03.2014 14:55:00 000B 19 0003513e4bdb1a3d
>26.03.2014 14:55:00 000B 20 0003513f4bdb1a3d
>26.03.2014 14:55:00 000B 21 000351404bdb1a3d
>26.03.2014 14:55:00 000B 22 000351414bdb1a3d
>26.03.2014 14:55:00 000B 23 000351424bdb1a3d
>26.03.2014 14:55:00 000B .
26.03.2014 14:55:00 000B Писем во внешнем п/я: 23 (новых 23)
<26.03.2014 14:55:00 000B RETR 1
>26.03.2014 14:55:00 000B +OK 529985 octets
------------------------------>8===============================[CMSLog cut]===


Через 5 минут ожидания получили следующее:

===[CMSLog cut]===============8<----------------------------------------------
~26.03.2014 15:00:00 000Z Thread started (TCsTaskThread)
~26.03.2014 15:00:00 000Z Task "Receive" already running
~26.03.2014 15:00:00 000Z Thread stopped (TCsTaskThread)
------------------------------>8===============================[CMSLog cut]===



Для сравнения на другую машину с XP также установлен CMS 3.02 и забирает письма с того же ящика, но не удаляет их. Такой проблемы не наблюдается, все исправно работает. Антивирус включен!

Такая же ситуация наблюдалась и с CMS 1.58 под win7.

Предварительно почитал форум, похожие проблемы были описаны, но ответа не нашел
Back to top
View user's profile Send private message
NAMOR
CMS Developer


Joined: 15 Oct 2005
Posts: 1079

PostPosted: 26 Mar 2014 18:07 (Wed)    Post subject: Reply with quote

С вероятностью 99% виноват антивирус, антиспам, файрвол или аналогичное приложение, перехватывающее сетевой трафик.
У одного пользователя подобную проблему вызывала некорректная настройка сетевой карты.
Во всяком случае, из вашего сообщения ясно, что проблема не в CMS.
Поищите по форуму строку NOD32. В найденных темах можно получить много полезной информации по данной проблеме.
Back to top
View user's profile Send private message Send e-mail Visit poster's website
Альберт
Newbie
Newbie


Joined: 26 Mar 2014
Posts: 4

PostPosted: 27 Mar 2014 14:56 (Thu)    Post subject: Reply with quote

Может кому пригодится.

Для отлова подвисания решил сделать такой эксперимент. На Комп1 поставил CMS, который забирает почту с внешнго п/я и кладет в 3 п/я (2 пользователей, 1 вспомогательный). На Комп2 поставил CMS, который забирает почту из вспомогательного п/я Комп1 и кладет в 2 п/я пользователей.
На обоих компах win7, брандмауэры выключены, установлен антивирус Касперского, причем на Комп1 в исключения добавлены все папки и программы, связанные с CMS, а также сняты все галочки, связанные с портами 25, 110 и трафиком по POP3, SMTP.


Спустя час работы наблюдал следующее:

Логи с Комп1
Приняли почту на Комп1 с внешнего п/я:
===[CMSLog cut]===============8<----------------------------------------------
27.03.2014 10:50:00 002S Подключаемся к серверу pop3.ht-systems.ru:110...
27.03.2014 10:50:00 002S Начали приём почты из внешнего п/я <info@arina-tour.ru>...
27.03.2014 10:50:00 002S Получаем IP-адреса для pop3.ht-systems.ru...
27.03.2014 10:50:00 002S IP-адреса: 78.110.50.95;78.110.50.185;
+27.03.2014 10:50:00 002S Подключились к серверу pop3.ht-systems.ru:110
>27.03.2014 10:50:00 002S +OK POP3 ready <1439211943.1395903004@mp1.ht-systems.ru>
<27.03.2014 10:50:00 002S USER info@arina-tour.ru
>27.03.2014 10:50:00 002S +OK
<27.03.2014 10:50:00 002S PASS ********
>27.03.2014 10:50:00 002S +OK Logged in.
<27.03.2014 10:50:00 002S UIDL
>27.03.2014 10:50:00 002S +OK
>27.03.2014 10:50:00 002S 1 000352354bdb1a3d
>27.03.2014 10:50:00 002S .
27.03.2014 10:50:00 002S Писем во внешнем п/я: 1 (новых 1)
<27.03.2014 10:50:00 002S RETR 1
>27.03.2014 10:50:00 002S +OK 55055 octets
>27.03.2014 10:50:00 002S ... (55055 байт принято)
@27.03.2014 10:50:00 002S Принято письмо N1 00G0000B (55055 байт) от <itmgroup@itmgroup-adv.ru> из п/я <info@arina-tour.ru> (Тема: "ITMgroup предложения дня из СПб"; UID: "000352354bdb1a3d")
<27.03.2014 10:50:00 002S DELE 1
>27.03.2014 10:50:00 002S +OK Marked to be deleted.
<27.03.2014 10:50:00 002S QUIT
>27.03.2014 10:50:00 002S +OK Logging out, messages deleted.
-27.03.2014 10:50:00 002S Отключились от сервера pop3.ht-systems.ru:110
27.03.2014 10:50:00 002S Закончили приём почты из внешнего п/я <info@arina-tour.ru>
------------------------------>8===============================[CMSLog cut]===


Потом к Комп1 подключился Комп2, не смог получить сообщение и впал в ступор, стал слать NOOPы
===[CMSLog cut]===============8<----------------------------------------------
~27.03.2014 10:54:56 0032 Thread started (TCsPOP3Server)
<27.03.2014 10:54:56 0032 +OK [192.168.0.11] Courier Mail Server 3.02 POP3 service ready <7004.73531939296881@[192.168.0.11]>
>27.03.2014 10:54:56 0032 USER admin@arina.local2
<27.03.2014 10:54:56 0032 +OK Name accepted
>27.03.2014 10:54:56 0032 PASS ********
<27.03.2014 10:54:56 0032 +OK 1 messages (55096 bytes) in mailbox
>27.03.2014 10:54:56 0032 UIDL
<27.03.2014 10:54:56 0032 +OK 1 messages (55096 bytes)
<27.03.2014 10:54:56 0032 1 00G0000B
<27.03.2014 10:54:56 0032 .
>27.03.2014 10:54:56 0032 RETR 1
<27.03.2014 10:54:56 0032 +OK 55096 bytes
<27.03.2014 10:54:56 0032 ... (55096 байт отправлено)
<27.03.2014 10:54:56 0032 .
@27.03.2014 10:54:56 0032 Передано письмо 00G0000B (55096 байт) от <itmgroup@itmgroup-adv.ru> из п/я <admin@arina.local2> (Тема: "ITMgroup предложения дня из СПб")
>27.03.2014 10:55:06 0032 NOOP
<27.03.2014 10:55:06 0032 +OK
>27.03.2014 10:55:16 0032 NOOP
<27.03.2014 10:55:16 0032 +OK
>27.03.2014 10:55:26 0032 NOOP
<27.03.2014 10:55:26 0032 +OK
>27.03.2014 10:55:36 0032 NOOP
<27.03.2014 10:55:36 0032 +OK
>27.03.2014 10:55:46 0032 NOOP
<27.03.2014 10:55:46 0032 +OK
>27.03.2014 10:55:56 0032 NOOP
<27.03.2014 10:55:56 0032 +OK
>27.03.2014 10:56:06 0032 NOOP
<27.03.2014 10:56:06 0032 +OK
>27.03.2014 10:56:16 0032 NOOP
<27.03.2014 10:56:16 0032 +OK
>27.03.2014 10:56:26 0032 NOOP
<27.03.2014 10:56:26 0032 +OK
>27.03.2014 10:56:36 0032 NOOP
<27.03.2014 10:56:36 0032 +OK
------------------------------>8===============================[CMSLog cut]===


Лог с комп2
Комп2 пытается получить почту с Комп1...
===[CMSLog cut]===============8<----------------------------------------------
~27.03.2014 11:00:00 0080 Thread started (TCsReceiveThread)
~27.03.2014 11:00:00 0080 Начали приём почты из группы внешних п/я "admin"...
27.03.2014 11:00:00 0080 Подключаемся к серверу 192.168.0.11:110...
27.03.2014 11:00:00 0080 Начали приём почты из внешнего п/я <admin@arina.local2>...
+27.03.2014 11:00:00 0080 Подключились к серверу 192.168.0.11:110
>27.03.2014 11:00:00 0080 +OK [192.168.0.11] Courier Mail Server 3.02 POP3 service ready <7004.73531939296881@[192.168.0.11]>
<27.03.2014 11:00:00 0080 USER admin@arina.local2
>27.03.2014 11:00:00 0080 +OK Name accepted
<27.03.2014 11:00:00 0080 PASS ********
>27.03.2014 11:00:00 0080 +OK 1 messages (55096 bytes) in mailbox
<27.03.2014 11:00:00 0080 UIDL
>27.03.2014 11:00:00 0080 +OK 1 messages (55096 bytes)
>27.03.2014 11:00:00 0080 1 00G0000B
>27.03.2014 11:00:00 0080 .
27.03.2014 11:00:00 0080 Писем во внешнем п/я: 1 (новых 1)
<27.03.2014 11:00:00 0080 RETR 1
>27.03.2014 11:00:00 0080 +OK 55096 bytes
------------------------------>8===============================[CMSLog cut]===
... и подвисает


В предыдущем посте меня смутила такая ситуация из-за того, что нить, которая отвечает за прием почты на Комп2 молчит, а задание, отвечающее за прием почты запускаясь говорит, что процесс уже запущен. Здесь мы видим из логов Комп1, что CMS на Комп2 все-таки шлет команды NOOP и не висит. Может разработчикам в лог добавить эти команда при получении почты? Кстати такие же NOOPы можно видеть и при подключении клиентов типа Outlook, WinLive к CMS (где-то на форуме про это говорилось).

И напрашивается вопрос, а что же все-таки делать с трафиком по портам 25 и 110, не проверять его вобще анивирусом если он так подвешивает почту? Но для чего же тогда в антивирусах предусматривается такая возможность? И как в этом случае осуществлять проверку входящей/исходящей почты?


P.S. После возникновения этой ситуации на Комп2 были проделаны такие же настройки Касперского, как на Комп1, пока 4 часа работает без проблем...
Back to top
View user's profile Send private message
NAMOR
CMS Developer


Joined: 15 Oct 2005
Posts: 1079

PostPosted: 27 Mar 2014 17:45 (Thu)    Post subject: Reply with quote

Альберт wrote:
Здесь мы видим из логов Комп1, что CMS на Комп2 все-таки шлет команды NOOP и не висит. Может разработчикам в лог добавить эти команда при получении почты? Кстати такие же NOOPы можно видеть и при подключении клиентов типа Outlook, WinLive к CMS (где-то на форуме про это говорилось).

CMS никогда сам не посылает команду NOOP. Если в приведённом фрагменте журнала с NOOP'ами в качестве клиента выступал другой CMS, то вывод однозначный: команды NOOP посылало приложение, перехватывающее трафик между сервером (CMS) и клиентом (CMS).

Альберт wrote:
И напрашивается вопрос, а что же все-таки делать с трафиком по портам 25 и 110, не проверять его вобще анивирусом если он так подвешивает почту? Но для чего же тогда в антивирусах предусматривается такая возможность? И как в этом случае осуществлять проверку входящей/исходящей почты?

На мой субъективный взгляд, разработчики антивирусов уделяют недостаточно внимания модулям проверки почты, отсюда очень большое (опять же, на мой взгляд) количество сбоев в почте, вызванных некорректной работой антивирусов. Причём такая ситуация сохраняется годами.
Наверное, проверка почты — не основное направление у разработчиков антивирусов, поэтому на него выделяют мало ресурсов.
Вы можете найти несколько писем, на которых антивирус точно сбоит, и отправить их его разработчикам. Тем самым, сможете поспособствовать улучшению проверки почты в следующих версиях антивируса Smile
Вариант, альтернативный проверке почтового трафика: использовать консольный сканер, вызываемый CMS для проверки файлов писем.

Альберт wrote:
P.S. После возникновения этой ситуации на Комп2 были проделаны такие же настройки Касперского, как на Комп1, пока 4 часа работает без проблем...

В Касперском была проблема, в Касперском...
Back to top
View user's profile Send private message Send e-mail Visit poster's website
Альберт
Newbie
Newbie


Joined: 26 Mar 2014
Posts: 4

PostPosted: 27 Mar 2014 18:33 (Thu)    Post subject: Reply with quote

Спасибо.

По поводу NOOPа вот здесь http://www.courierms.ru/forum/viewtopic.php?t=986&highlight=noop вы писали
Quote:
NOOP означает "NO OPeration" ("нет операции"); отправка этой команды на сервер означает, что клиент всё ещё "жив", но пока занят. Сервер не выполняет никаких операций, просто отвечает "OK". Таким образом, с помощью NOOP клиент может предотвратить отключение по тайм-ауту во время длительных операций импорта принятых с сервера писем в свою почтовую базу.

А так как в нашем случае клиентом выступал CMS на Комп2, то я подумал что это он шлет NOOP.
Но теперь получается, что антивирус не просто сканирует трафик, а еще и может вклиниться между клиентом и сервером и слать NOOP?

Немного позднее наблюдал ситуацию, когда 2 пользователя получая почту "споткнулись" при получении одного и того же письма с Комп1. Антивирусы у них естественно были включены.
Back to top
View user's profile Send private message
NAMOR
CMS Developer


Joined: 15 Oct 2005
Posts: 1079

PostPosted: 27 Mar 2014 19:47 (Thu)    Post subject: Reply with quote

Альберт wrote:
Но теперь получается, что антивирус не просто сканирует трафик, а еще и может вклиниться между клиентом и сервером и слать NOOP?

Чтобы просканировать почтовый трафик, антивирусу как раз и нужно "вклиниться" между клиентом и сервером. Сначала антивирус в роли клиента принимает письмо с сервера "к себе", проверяет, потом, выступая уже в роли сервера, передаёт письмо клиенту. Своего рода почтовый прокси-сервер.
Команду NOOP, полагаю, антивирус шлёт, пока проверяет письмо, чтобы сервер не отключился по тайм-ауту. Когда антивирус "спотыкается" на каком-то письме и проверка затягивается (зависает), NOOP'ы идут сплошным потоком (каждые 10 сек. в вашем случае).

Альберт wrote:
Немного позднее наблюдал ситуацию, когда 2 пользователя получая почту "споткнулись" при получении одного и того же письма с Комп1. Антивирусы у них естественно были включены.

Да, дело в конкретных письмах, которые антивирус не может нормально обработать.
Back to top
View user's profile Send private message Send e-mail Visit poster's website
Альберт
Newbie
Newbie


Joined: 26 Mar 2014
Posts: 4

PostPosted: 04 Apr 2014 10:41 (Fri)    Post subject: Reply with quote

После отключения всех галок в Касперском почта неделю проработала без сбоев. На следующей неделе попробую постепенно возвращать настройки в Каспере, чтобы отловить какая-же из них приводила к блокировке писем.
Back to top
View user's profile Send private message
Display posts from previous:   
Post new topic   Reply to topic    Courier Mail Server Forum Index -> Courier Mail Server 3.xx All times are GMT + 4 Hours
Page 1 of 1

 
Jump to:  
You cannot post new topics in this forum
You cannot reply to topics in this forum
You cannot edit your posts in this forum
You cannot delete your posts in this forum
You cannot vote in polls in this forum
You cannot attach files in this forum
You can download files in this forum


Powered by phpBB © 2001, 2005 phpBB Group