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

Eserv Forum / E4 / Mail / Ошибка PopFile

wikipost // (v3)
Продукты и услуги Скачать Документация Купить Поддержка Форумы Партнёрам Статьи О компании
В SPOOL зависло письмо, которое никому не дошло, хотя по журналу 2010-11-19-log.txt вроде бы обработалось нормально, за исключением ошибки PopFile.
201011delivery.txt:
2010-11-19 12:02:34;194.186.47.92;@;26057;4032;aori@co.ru;*@*.*.ru;ExDelivery:Local;47141;<redirect-364351714@backend1.cgp.gldn.net>;popfile_error
acFilter.log :
19.11.2010;12:00:14;1576;0;1;200;POST;302;IFORTH-SCRIPT;/RPC2;c:\Program Files\E4\CommonPlugins\acFilter\apps\index.e;text/xml c:\Program Files\E4\DATA\mail\spool\aori@co.ru!26057!3119616125!1.eml @D1=2 c:\Program Files\E4\DATA\mail\spool\aori@co.ru!26057!3119616125!1.eml drweb:PF:iup=10060 iup2=10060 <Error 10060> c:\Program Files\E4\DATA\mail\spool\aori@co.ru!26057!3119616125!1.eml 1 10000 10000 PF=popfile_error
acSMTP.log:
Signature domain don't match. Can't add message into index. ERR=2 ..\DATA\mail\arc4\201011\2010-11-19\popfile_error\co.ru\aori@co.ru!26057!3119616125!1.eml Can't add message into index. ERR=2 ..\DATA\mail\arc4\201011\2010-11-19\popfile_error\co.ru\aori@co.ru!26057!3119616125!1.eml Signature domain don't match.

Такой папки .\DATA\mail\arc4\201011\2010-11-19\popfile_error\ — не существует
2010-11-19 11:59:42;194.186.47.92;@;26057;4032;IN;MAIL FROM:<aori@co.ru> SIZE=48267 2010-11-19 11:59:43;194.186.47.92;@;26057;4032;OUT;250 aori@co.ru OK 2010-11-19 11:59:43;194.186.47.92;@;26057;4032;IN;RCPT TO:<*@*.*.ru> 2010-11-19 12:00:15;194.186.47.92;@;26057;4032;OUT;250 *@*.*.ru OK, ExDelivery:Local, a=L 2010-11-19 12:00:15;194.186.47.92;@;26057;4032;IN;DATA 2010-11-19 12:00:15;194.186.47.92;@;26057;4032;SPOOL;..\DATA\mail\spool\aori@co.ru!26057!3119616125!1.eml 2010-11-19 12:00:15;194.186.47.92;@;26057;4032;OUT;354 send the mail data, end with . 2010-11-19 12:02:34;194.186.47.92;@;26057;4032;OUT;250 OK message accepted for delivery (ExDelivery:Local) 2010-11-19 12:02:34;194.186.47.92;@;26057;4032;ARCHIVE;aori@co.ru;**@*.*.ru;ExDelivery:Archive 2010-11-19 12:02:34;194.186.47.92;@;26057;4032;DELIVERY;*@*.*.ru;ExDelivery:Local 2010-11-19 12:02:34;194.186.47.92;@;26057;4032;IN;QUIT 2010-11-19 12:02:35;194.186.47.92;@;26057;4032;OUT;221 Goodbye.

Мне не понравилось, что не было никаких извещений об ошибке. Письмо обнаружила только потому, что теперь регулярно просматриваю все эти папки (SPOOL, OUT, Retry...) на предмет проблем.
 
Комментарии к версии 1 (22.11.2010 11:08) [~matveeva] 6265e20c
Комментарии к версии 2 (22.11.2010 11:09) [~matveeva] 1ca05b5f
Комментарии к этой версии (22.11.2010 11:09) [~matveeva] 325d2375
АвторДатаТекстtags
matveeva22.11.2010 11:23
Вот сегодня еще одна ошибка popFile, но письмо доставилось нормально.
Появилась папка \DATA\mail\arc4\201011\2010-11-22\popfile_error\, а в журнале PopFile вот такие записи
2010/11/22 10:17:05 2592: Perl warning: binmode() on closed filehandle MSG at /Progra~1/POPFile/Classifier/MailParse.pm line 1700.[0a] 2010/11/22 10:17:05 2592: Perl warning: readline() on closed filehandle MSG at /Progra~1/POPFile/Classifier/MailParse.pm line 1705.[0a]

Вот 201011popfile_debug.txt
2010-11-22 10:14:05;**@*.ru;***@**.**.ru;0;;81.95.135.2;SMTP;81.95.135.2 2010-11-22 10:14:05;r.**@**.ru;***@**.**.ru;0;;81.95.135.2;SMTP;<?xml version='1.0' encoding='UTF-8'?><methodResponse><params><param><value><string>250</string></value></param></params></methodResponse> 2010-11-22 10:14:17;**@seve***.ru;**@**.co**ru;226845;<94d3819fc8d5844f9ff8e16538f79b50@mail.**.ru>;81.95.135.2;SMTP;c:\Program Files\E4\DATA\mail\spool\**@**.ru!30434!3372457484!1.eml 2010-11-22 10:14:18;;;0;;127.0.0.1;HTTP;c:\Program Files\E4\DATA\mail\spool\**@**.ru!30434!3372457484!1.eml 2010-11-22 10:16:38;;;0;;127.0.0.1;HTTP;<Error 10060> 2010-11-22 10:16:47;**@**.ru;***@**.*.ru;226845;<94d3819fc8d5844f9ff8e16538f79b50@mail.**.ru>;81.95.135.2;SMTP;<?xml version='1.0' encoding='UTF-8'?><methodResponse><params><param><value><string>popfile_error</string></value></param><param><value><string>PF=popfile_error, SPR=10000</string></value></param></params></methodResponse>
wikipost
ac22.11.2010 22:29
matveeva пишет: ..\DATA\mail\arc4\201011\2010-11-19\popfile_error\co.ru\aori@co.ru!26057!3119616125!1.eml

Этот файл на диске есть? Упомянутая "ошибка 2" — это "нет такого файла". Вероятно последовательность была такой: 1) Dr.WEB заблокировал файл (долго проверял — например, ожидал перезагрузки базы), 2) PopFile не смог обработать заблокированный файл, 3) проверка в acFilter завершилась таймаутом 10060, 4) acSMTP не смог скопировать файл в архив и, соответственно, добавить в индекс.

Поищу, где какой проверки не хватило, чтобы отбой случился раньше, с выдачей 4xx MTA отправителя.
wikipost
matveeva23.11.2010 10:13
ac пишет: ..\DATA\mail\arc4\201011\2010-11-19\popfile_error\co.ru\aori@co.ru!26057!3119616125!1.eml
Этот файл на диске есть?

Нет, такой папки .\DATA\mail\arc4\201011\2010-11-19\popfile_error\ — вообще не существует. А само письмо aori@co.ru!26057!3119616125!1.eml до сих пор лежит в SPOOL.
wikipost
ac27.11.2010 08:47
ред: 27.11.2010 08:50
Забыл отчитаться. Виноват в ситуации всё-таки popfile. Точнее, я виноват, что не учел всех вариантов последствий после popfile_error. Т.к. достоверно определить, отдаст ли popfile занятый файл в момент фактической доставки (она производится уже после второго ответа на DATA, т.е. шанса сказать отправляющему MTA о том, что у нас что-то не сработало, больше не будет), невозможно, то остается отвечать отправителю "4xx попробуйте позже" по любому popfile_error. Это и сделано в новой версии конфига (поедет в обновлении). Хотя, если ошибка вызвана не временной перегрузкой машины и прочими преходящими проблемами, а "несовместимостью" popfile с конкретным экземпляром письма (сбой в парсере и т.п.), то такой ответ только усугубит проблему — следующие посылки только еще раз напрягут popfile с тем же печальным результатом. Ну, по крайней мере письмо не будет валяться в спуле, а отправитель рано или поздно получит отлуп от своего MTA — "че-то мне никак не удается доставить письмо на этот хост"... Так что, возможно, стоит выловить то проблемное письмо из спула и погонять его через popfile для выяснения воспроизводимости проблемы "таймаут/popfile_error".

Новый конфиг пока тестируется на нашем сервере. В обновления поедет на той неделе.
wikipost
ac30.11.2010 10:58
Вчера обновления поехали.
wikipost
Работает на Eserv/5.05555 (05.06.2016)