Регистрация...

Eserv Forum / E3 / Eserv 3 Mail Server Support / Странная ошибка [popfile_error] было но не решено

recent wikipost // (v1)
Продукты и услуги Скачать Документация Купить Поддержка Форумы Партнёрам Статьи О компании
Новости
12.10.2009
Переезд завершен
День добрый, господа.
Была такая тема, но ответы меня, мягкого говоря не вдохновили.
В Eserve оринетируюсь с трудом ибо вижу первые два дня.
Как назло в первые дни появилась странная ошибка.
При получении письма в теме письма помимо самого названия имеется [popfile_error].
Перезапуск acSMTP помогает на период от часа до двух, а потом заново. Помимо странного префикса иногда валится спам.
Имею PigMail + PigProxy — версия 2.3 на базе Eserv / Eproxy v3.36beta из которого задействован только PigMail.
Еще раз повторюсь, в Eserve полный нуб.
 
Комментарии к этой версии (10.10.2012 17:12) [~redrepublics] f1bc4011
АвторДатаТекстtags
pig10.10.2012 17:30
Интересно глянуть в оперативный журнал SMTP-сервера за время, соответствующее последнему Received: в шапке письма.
Хотя нет, сначала наводящий вопрос — а вы сам POPfile устанавливали?

И рекомендация: обновитесь до версии 2.4, особенно если POPfile у вас в работе.
wikipost
redrepublics10.10.2012 17:35
pig пишет: ее последнему Received: в шапке письма.
Хотя нет, сначала наводящий вопрос — а вы сам POPfile устанавливали?

Файл бы установлен старым админом. Дел мне он толком не передал, да если бы и передал, то толку от этой инфы.
Вы бы мне последовательность действий по выявлению неисправностей написали, был бы весьма признателен.
wikipost
redrepublics10.10.2012 17:41
Вот кусок лога на тот момент

17:45:29 592 Reply: 221 Goodbye.
17:45:29 592 STAT: 381954:604:0:0:1594
17:45:43 594 Secure
Connection from: 192.168.1.165:3871 17:45:43 594 Local Network 17:45:43 594 Reply: 220 riapanda.spb.ru Eserv/3.4832 (PigMail/2.3) ESMTP. Welcome, Local user! Wed, 10 Oct 2012 17:45:43 +0400 17:45:43 594 EHLO [127.0.0.1] 17:45:43 594 BlackList (deferred) 17:45:43 594 Reply: 250-riapanda.spb.ru Hello [192.168.1.165], your messages will be rejected probably. You don't permitted to use IP in HELO command 17:45:44 594 AUTH PLAIN AEVzYW1zb25vdmEAMTU5MTU5 17:45:44 594 Authorization domain: riapanda.spb.ru, authorization source: Eserv3, authorization type: auth_md5 17:45:44 594 Authorized as: Esamsonova@riapanda.spb.ru 17:45:44 594 Authorization domain: riapanda.spb.ru, authorization source: Eserv3, authorization type: auth_md5 17:45:44 594 Reply: 235 Authentication successful (Esamsonova@riapanda.spb.ru) 17:45:44 594 MAIL FROM:<Esamsonova@riapanda.spb.ru> 17:45:44 594 Authorized sender, antispam check disabled 17:45:44 594 Local domain user , accepted 17:45:44 594 Reply: 250 Esamsonova@riapanda.spb.ru OK. Glad to meet you, 17:45:44 594 RCPT TO:<Alena.Samrinova@bangbonsomer.com> 17:45:44 594 Accepted 17:45:44 594 Reply: 250 Alena.Samrinova@bangbonsomer.com OK, auth: Esamsonova@riapanda.spb.ru 17:45:44 594 DATA 17:45:44 594 Reply: 354 send the mail data, end with . 17:45:47 594 Message has been received completely, 1671584 bytes size 17:45:47 594 Message size check passed OK 17:45:47 594 Loopback check passed OK ("Received:" count is 0) 17:45:47 594 Sender validation passed OK 17:45:47 594 MailRoll updated: from Esamsonova@riapanda.spb.ru to Alena.Samrinova@bangbonsomer.com 17:45:47 594 Start processing "magic" words 17:45:47 594 End processing "magic" words 17:45:47 594 Reply: 250 OK message accepted for delivery 17:45:48 594 Local sender: Esamsonova@riapanda.spb.ru, copied to archive folder: ..\DATA\mail\archive\local\ 17:45:48 594 Starting MContent for ..\DATA\mail\spool\Esamsonova@riapanda.spb.ru!594!381917938!1.eml 17:45:48 594 MContent: Temporary copy ..\DATA\temp\Esamsonova@riapanda.spb.ru!594!381921922!3.eml has been created 17:45:48 594 MContent: ..\DATA\temp\Esamsonova@riapanda.spb.ru!594!381921922!3.eml is successfully loaded 17:45:48 594 MContent successfully processed ..\DATA\mail\spool\Esamsonova@riapanda.spb.ru!594!381917938!1.eml 17:45:50 594 Remote recipient: Alena.Samrinova@bangbonsomer.com, passed to archive folder: ..\DATA\mail\archive20121010\out\Alena.Samrinova 17:45:50 594 Remote recipient: Alena.Samrinova@bangbonsomer.com, passed to folder: ..\DATA\mail\out 17:45:50 594 Copied to folder: ..\DATA\mail\out, recipients: Alena.Samrinova@bangbonsomer.com 17:45:50 594 Copied to folder: ..\DATA\mail\archive20121010\out\Alena.Samrinova, recipients: Alena.Samrinova@bangbonsomer.com 17:45:50 594 QUIT 17:45:50 594 Reply: 221 Goodbye. 17:45:50 594 STAT: 1671738:605:0:0:6938 17:47:00 597 Secure connection from: 192.168.1.148:2418 17:47:00 597 Local Network 17:47:00 597 Reply: 220 riapanda.spb.ru Eserv/3.4832 (PigMail/2.3) ESMTP. Welcome, Local user! Wed, 10 Oct 2012 17:47:00 +0400 17:47:00 597 EHLO [127.0.0.1] 17:47:00 597 BlackList (deferred) 17:47:00 597 Reply: 250-riapanda.spb.ru Hello [192.168.1.148], your messages will be rejected probably. You don't permitted to use IP in HELO command 17:47:00 597 AUTH PLAIN AGVkZW1lbnRvdnNrYXlhADJzaTE3Zw== 17:47:00 597 Authorization domain: riapanda.spb.ru, authorization source: Eserv3, authorization type: auth_md5 17:47:00 597 Authorized as: edementovskaya@riapanda.spb.ru 17:47:00 597 Authorization domain: riapanda.spb.ru, authorization source: Eserv3, authorization type: auth_md5 17:47:00 597 Reply: 235 Authentication successful (edementovskaya@riapanda.spb.ru) 17:47:00 597 MAIL FROM:<edementovskaya@riapanda.spb.ru> 17:47:00 597 Authorized sender, antispam check disabled 17:47:00 597 Local domain user Евгения Дементовская, accepted 17:47:00 597 Reply: 250 edementovskaya@riapanda.spb.ru OK. Glad to meet you, Евгения Дементовская 17:47:00 597 RCPT TO:<lscvorcova@riapanda.spb.ru> 17:47:01 597 Local domain user, accepted 17:47:01 597 Reply: 250 lscvorcova@riapanda.spb.ru OK, my domain user 17:47:01 597 DATA 17:47:01 597 Reply: 354 send the mail data, end with . 17:47:01 597 Message has been received completely, 172907 bytes size 17:47:01 597 Message size check passed OK 17:47:01 597 Loopback check passed OK ("Received:" count is 0) 17:47:01 597 Sender validation passed OK 17:47:01 597 MailRoll updated: from edementovskaya@riapanda.spb.ru to lscvorcova@riapanda.spb.ru 17:47:01 597 Start processing "magic" words 17:47:01 597 End processing "magic" words 17:47:01 597 Reply: 250 OK message accepted for delivery 17:47:01 597 Local sender: edementovskaya@riapanda.spb.ru, copied to archive folder: ..\DATA\mail\archive\local\ 17:47:01 597 Starting MContent for ..\DATA\mail\spool\edementovskaya@riapanda.spb.ru!597!381994407!1.eml 17:47:01 597 MContent: Temporary copy ..\DATA\temp\edementovskaya@riapanda.spb.ru!597!381994938!3.eml has been created 17:47:01 597 MContent: ..\DATA\temp\edementovskaya@riapanda.spb.ru!597!381994938!3.eml is successfully loaded 17:47:01 597 MContent successfully processed ..\DATA\mail\spool\edementovskaya@riapanda.spb.ru!597!381994407!1.eml 17:47:01 597 Local recipient: lscvorcova@riapanda.spb.ru, passed to archive folder: ..\DATA\mail\archive20121010\lscvorcova 17:47:01 597 Delivered to local recipient: lscvorcova@riapanda.spb.ru 17:47:02 597 Copied to folder: ..\DATA\mail\archive20121010\lscvorcova, recipients: lscvorcova@riapanda.spb.ru 17:47:02 597 QUIT
wikipost
pig10.10.2012 19:15
Эти письма антиспамом не проверялись.
В DATA\log\maillog\SMTP\ есть 201210popfile_debug.txt? Если есть — найдите пару последних строчек с <Error n> — вот это самое n интересно.
wikipost
redrepublics11.10.2012 07:55
pig пишет: Эти письма антиспамом не проверялись.
В DATA\

Странно, все пока решилось банальным рестартом сервака. Спрашивал таки у старого админа, он тоже сервак ребутил в таком случае. Рестар сервисов не помогал.

В искомом файле повторяющаяся ошибка POP3RECV;<Error 10061>
После ребута исчезла.

Присутствует 20121009SMTPSENDErr и иже с ними.
Инфа там скудная, буквально одна-две строчки.

09:03:01 2006 Error 183 while processing queue ..\DATA\mail\out
13:40:10 198 Error 2 while processing queue ..\DATA\mail\out
wikipost
ac11.10.2012 08:43
redrepublics пишет: Error 183

Это "Невозможно создать файл, так как он уже существует." (вероятно о файле протокола)

redrepublics пишет: Error 2

А это "Файл не найден". (вероятно рабочий поток пытался отправить письмо, которое параллельно уже перехватил и отправил другой поток)

redrepublics пишет: Спрашивал таки у старого админа, он тоже сервак ребутил в таком случае. Рестар сервисов не помогал.

10061 — это "на заданном порту сервер не запущен" — acSMTP не может соединиться с POPFile, т.к. тот либо слетел, либо отключился от порта (см. по наличию процесса *perl.exe* в списке процессов). Вместо перезапуска скорее всего поможет останов этого *perl*, если есть, и перезапуск службы acSMTP. Может имеет смысл обновить POPFile (getpopfile.org), только о резервной копии не забудьте.
wikipost
ac11.10.2012 08:48
redrepublics пишет: ошибка POP3RECV;<Error 10061>

Пропустил, что это про POP3RECV, а не POPFile. В случае POP3RECV ошибка 10061 относится очевидно к целевому серверу, с которого принимается почта по POP3 — т.е. там сервер не запущен. Как это может лечиться перезагрузкой... Разве что с той же машины по POP3 снимается (?) или через маппинг.
wikipost
redrepublics11.10.2012 09:06
То то и оно, perl крутился и происходили такие вещи, специально смотрел. =( Сейчас в процессах (смотрю с вебморды) его не обнаружено, и все бли нарботает. Чудеса чудесатые... =(
wikipost
redrepublics11.10.2012 10:35
Проблема повторилась. Запустил perl с pop, все снова закрутилось. Надолго ли? =(
wikipost
ac11.10.2012 10:59
redrepublics пишет: Сейчас в процессах (смотрю с вебморды) его не обнаружено, и все бли нарботает.

Без perl'а POPFile работать не может. Если не perl.exe, то может wperl.exe.

redrepublics пишет: Проблема повторилась. Запустил perl с pop, все снова закрутилось. Надолго ли?

ac пишет: Может имеет смысл обновить POPFile (getpopfile.org), только о резервной копии не забудьте.
wikipost
redrepublics11.10.2012 11:37
ac пишет: wperl.exe.
wperl.exe popfile.pl

Я правильно понимаю?
wikipost
ac11.10.2012 11:58
Да. В последние годы (в E4, E5) с POPFile (при запуске из acFilter) используется не wperl.exe, а perl.exe (чтобы снимать лог и с его консоли), но это не принципиально. Можете попробовать perl.exe, вдруг это стабильнее будет.
wikipost
redrepublics11.10.2012 12:03
Спасибо. Буду пробовать. Пока вроде все снова встало на круги своя.
wikipost
pig11.10.2012 14:39
ac пишет: redrepublics пишет: ошибка POP3RECV;<Error 10061>
Пропустил, что это про POP3RECV, а не POPFile.

Это дебаг-лог POPfile-коннектора, а POP3RECV — "протокол", по которому письмо принято. Так что таки да, POPfile отъезжает. Может, действительно его обновить надо.
wikipost
redrepublics15.10.2012 10:21
Трабл решился, как только я копнул суть проблемы.
Для меня новость, но корифеи посмеются, однако перл и попфайл запускались ручками и не как служба, а как приложение. Стоило выйти из сеанса, как сразу происходило завершение процесса, потому и сыпало ошибками.
Спасибо за помощь, поставлю себе галочку по этому вопросу. 4-й день полет нормальный.
wikipost
Работает на Eserv/5.05555 (05.06.2016)