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

Eserv Forum / E4 / Mail / Ошибка при приеме письма (POP3)

wikipost // (v1)
Продукты и услуги Скачать Документация Купить Поддержка Форумы Партнёрам Статьи О компании
При приеме письма, для которого срабатывает фильтр, происходит ошибка:
Из журнала DATA\log\pop2smtp\0531debug.txt
15:45:52 SMTP (3476): 450 error: 3221225477

И в конце сеанса:
15:45:53 ERROR (3476): 10054

Из журнала DATA\log\smtp\2010-05-31-log.txt
2010-05-31 15:45:47;127.0.0.1;@;52;2024;IN;MAIL FROM:<***@gmail.com> 2010-05-31 15:45:52;127.0.0.1;@;52;2024;OUT;250 ***@gmail.com OK 2010-05-31 15:45:52;127.0.0.1;@;52;2024;IN;RCPT TO:<***@aori.co.ru> 2010-05-31 15:45:52;127.0.0.1;@;52;2024;OUT;250 ***@aori.co.ru OK, ExDelivery:LocalHA, a=... 2010-05-31 15:45:52;127.0.0.1;@;52;2024;IN;DATA 2010-05-31 15:45:52;127.0.0.1;@;52;2024;SPOOL;..\DATA\mail\spool\***@gmail.com!52!4230347781!4.eml 2010-05-31 15:45:52;127.0.0.1;@;52;2024;OUT;354 send the mail data, end with . 2010-05-31 15:45:52;127.0.0.1;@;52;2024;FILTER;***@gmail.com;***@aori.co.ru;;SubjBlackList
и все, конец журнала.
Фильтр такой:
Стадия протокола header
Заголовок Subject something
Выполнить setrcpt с параметрами spamloop@aori.co.ru

Тема письма из одного слова "something". И оно не ложится ни в какой ящик.
 
Комментарии к этой версии (31.05.2010 15:07) [~matveeva] 1a4d870d
Комментарии к версии 2 (31.05.2010 15:14) [~matveeva] cb2ec98b
АвторДатаТекстtags
pig31.05.2010 16:26
3221225477 == C0000005 == ошибка обращения к памяти

В acSMTP4.log должно быть подробно написано про исключение.
wikipost
matveeva31.05.2010 17:00
pig пишет: В acSMTP4.log должно быть подробно написано про исключение.
Точно! Есть такое:
EXCEPTION! CODE:C0000005 ADDRESS:00556C13 WORD:GetChar USER DATA: 01C00654 THREAD ID: 000007E8 HANDLER: 01BFEEFC ** Exception time: Mon, 31 May 2010 15:45:52 +0400 ** Thread number/reuse/id:52 0 2024 ** API Calls: GetTickCount sqlite3_finalize STACK: (17) 01CF1488 0000003E 00BEBE50 0000003E 00000000 0000003A [0000003E] RETURN STACK: 01BFEEEC : 00556D17 SkipUpTo 01BFEEF0 : 00556E28 PARSE 01BFEEF4 : 005CE0C7 GetHdrX 01BFEEF8 : 0055516C CATCH 01BFEEFC : 01BFEF4C 01BFEF00 : 01BFFF88 01BFEF04 : 005680D5 EVALUATE-WITH 01BFEF08 : 00000006 01BFEF0C : 00000000 01BFEF10 : 00000004 01BFEF14 : 01CEFA00 01BFEF18 : 00000004 01BFEF1C : FFFFFFFF 01BFEF20 : 00000000 01BFEF24 : 005CE11B GetHdr 01BFEF28 : 005CE7E0 ReadData1 01BFEF2C : 0055326C (LocalsExit) 01BFEF30 : 00000014 [...] 01BFEF98 : 005CEED2 SetPreferredDns: 01BFEF9C : 005BAD09 DoCommand 01BFEFA0 : 0055326C (LocalsExit) 01BFEFA4 : 00000004 01BFEFA8 : 005CEED2 SetPreferredDns: 01BFEFAC : 0055516C CATCH 01BFEFB0 : 01BFEFF0 01BFEFB4 : 01BFFF98 01BFEFB8 : 005680D5 EVALUATE-WITH 01BFEFBC : 00000006 01BFEFC0 : 00000000 01BFEFC4 : 00000000 01BFEFC8 : 01C01AA0 01BFEFCC : 00000000 01BFEFD0 : 00000000 01BFEFD4 : 00000000 END OF EXCEPTION REPORT
wikipost
matveeva01.06.2010 13:49
ред: 01.06.2010 14:26
Так и не получается у меня перевести все на Е4
При приеме письма ошибка (DATA\log\pop2smtp\0601debug.txt )
12:01:47 pop3 (1180): . 12:01:47 SMTP (1180): MAIL FROM:<fabo.swynford@bqpm.com> 12:11:26 pop3 (1180): QUIT 12:11:26 ERROR (1180): 4294966294 12:15:26 pop3 (292): Connecting mail.co.ru:110...
на этом прием заканчивается, вся почта остается в ящике. В след. заход. ошибка повторяется на другом письме (принимаются от последнего к первому, а писем каждый раз становится все больше). Планировщик был настроен на 5мин интервал. При этом не отправляется почта наружу. У клиента на этапе отключения возникает таймаут. Отправляемое письмо попадает в SPOOL. Кстати, в идеале, эта папка должна быть пустой? Перезапуск acSmtp, acWeb ничего не дал. Вернулась на Е2. Там все ОК.
Еще забыла: в acSMTP\acSMTP.log много записей
AntiVirus support is not loaded.
Хотя включен drWeb, обновляется нормально.
wikipost
ac01.06.2010 14:31
Если этот фильтр на Subject отключить, то ошибка 4294966294 уходит?

matveeva пишет: AntiVirus support is not loaded.

При импорте из E2 перенесся параметр "UseAntivirus=1", он для E4 не актуален, т.к. настройки антивирусов теперь в разделе acFilter. В общем, можно эту запись игнорировать, или отключить указанную опцию.
wikipost
matveeva01.06.2010 16:39
ред: 01.06.2010 16:41
ac пишет: Если этот фильтр на Subject отключить, то ошибка 4294966294 уходит?
Да, я тоже про него подумала, но я его вчера еще отключила.
wikipost
ac01.06.2010 17:05
ред: 01.06.2010 17:06
А, да, я не обратил внимания, что код ошибки сменился в сравнении с тем, что вчера Игорь расшифровал. Да, 4294966294 = -1002, т.е. "соединение закрыто" (вместо ожидаемого здесь ответа от POP3-сервера на команду QUIT) — здесь это не представляет проблемы (POP3-сервер ушел, не попрощавшись), т.к. это уже завершение сессии, когда все дела уже сделаны, т.е. эта ошибка не должна вас смущать. А между этими строками
12:01:47 SMTP (1180): MAIL FROM:<fabo.swynford@bqpm.com> [вот здесь] 12:11:26 pop3 (1180): QUIT
что происходило? Письмо было принято?
wikipost
ac02.06.2010 07:04
matveeva пишет: EXCEPTION! CODE:C0000005 ADDRESS:00556C13 WORD:GetChar

В сегодняшнем обновлении исправлено, спасибо.
wikipost
matveeva02.06.2010 09:14
ac пишет: что происходило? Письмо было принято?
А там ничего не происходило. Это кусок журнала без вырезок. Просто висело все. Там так: в 12:00 запустился сеанс
12:00:26 pop3 (1180): Connecting mail.co.ru:110... 12:00:26 pop3 (1180): +OK CommuniGate Pro POP3 Server 5.2.18 ready <34594234.1275378961@frontend4.cgp.gldn.net>
, потом логин, пароль, потом
12:00:26 pop3 (1180): +OK 29 messages (2733500 bytes) (proxing) 12:00:26 pop3 (1180): STAT 12:00:26 pop3 (1180): +OK 29 2733500 12:00:26 pop3 (1180): LIST
... Потом
12:00:26 SMTP (1180): Connecting 127.0.0.1:25... 12:00:26 SMTP (1180): 220 Eserv v4.23.4741 ESMTP. Tue, 01 Jun 2010 12:00:26 +0400 () 12:00:26 SMTP (1180): HELO pop2smtp 12:00:26 SMTP (1180): 250 adsl.TREVIS.local Hello [127.0.0.1] 12:00:26 pop3 (1180): TOP 29 0
, потом пошел заголовок письма, потом
12:00:28 pop3 (1180): RETR 29
и принимается все письмо. И в конце
12:00:30 SMTP (1180): 250 OK message accepted for delivery (alias or copy) 12:00:30 pop3 (1180): DELE 29 12:00:30 pop3 (1180): +OK marked deleted 12:00:30 pop3 (1180): TOP 28 0
И пошло след. письмо. И так до 25-го. а на нем вот:
12:01:47 pop3 (1180): TOP 25 0 12:01:47 pop3 (1180): +OK message follows 12:01:47 pop3 (1180): X-Real-To: <mloz@aori.co.ru> 12:01:47 pop3 (1180): Return-Path: <fabo.swynford@bqpm.com> 12:01:47 pop3 (1180): X-DCC-deis-Metrics: frontend3 2004; bulk Body=1 Fuz1=3917 12:01:47 pop3 (1180): X-Spam-Status: Yes 12:01:47 pop3 (1180): Received: from [83.14.16.178] (HELO hucm.com) 12:01:47 pop3 (1180): by frontend3.cgp.gldn.net (CommuniGate Pro SMTP 5.2.19) 12:01:47 pop3 (1180): with ESMTP id 276239566 for mloz@aori.co.ru; Tue, 01 Jun 2010 11:53:17 +0400 12:01:47 pop3 (1180): Received-SPF: none 12:01:47 pop3 (1180): receiver=frontend3.cgp.gldn.net; client-ip=83.14.16.178; envelope-from=fabo.swynford@bqpm.com 12:01:47 pop3 (1180): Received: from bqpm.com ([208.80.85.37]) by 83.14.16.178; Tue, 01 Jun 2010 09:53:20 +0200 12:01:47 pop3 (1180): Message-ID: <000e01cb015f$80865800$b2100e53@bqpm.com> 12:01:47 pop3 (1180): From: "Fabo Swynford" <fabo.swynford@bqpm.com> 12:01:47 pop3 (1180): To: <mloz@aori.co.ru> 12:01:47 pop3 (1180): Subject: =?windows-1251?B?z+7k4e7wIO/l8PHu7eDr4A==?= 12:01:47 pop3 (1180): Date: Tue, 01 Jun 2010 09:53:20 +0200 12:01:47 pop3 (1180): MIME-Version: 1.0 12:01:47 pop3 (1180): Content-Type: multipart/alternative; 12:01:47 pop3 (1180): boundary="----=_NextPart_000_0007_01CB015F.80865800" 12:01:47 pop3 (1180): X-Priority: 3 12:01:47 pop3 (1180): X-MSMail-Priority: Normal 12:01:47 pop3 (1180): X-Mailer: Microsoft Outlook Express 6.00.2900.3028 12:01:47 pop3 (1180): X-MimeOLE: Produced By Microsoft MimeOLE V6.00.2900.3028 12:01:47 pop3 (1180): 12:01:47 pop3 (1180): . 12:01:47 SMTP (1180): MAIL FROM:<fabo.swynford@bqpm.com> 12:11:26 pop3 (1180): QUIT 12:11:26 ERROR (1180): 4294966294 12:15:26 pop3 (292): Connecting mail.co.ru:110...
Это уже след. сеанс.
Следов письма нигде не нашла. Ни в одном журнале в [smtp] о нем упоминания нет. На самом деле все еще раньше началось, я потом уже заметила. Первый раз было только одно письмо в ящике, и оно как бы принялось, но сеанс не закрылся нормально.
10:00:30 SMTP (3364): 250 OK message accepted for delivery (alias or copy) 10:00:30 pop3 (3364): DELE 1 10:00:30 pop3 (3364): +OK marked deleted 10:00:30 SMTP (3364): QUIT 10:10:26 pop3 (3364): QUIT 10:10:26 ERROR (3364): 4294966294 10:15:26 pop3 (264): Connecting mail.co.ru:110...
А потом все остальные сеансы уже были с такой же ошибкой на разных письмах. Может, весь лог выслать?
wikipost
matveeva02.06.2010 09:16
ac пишет: В сегодняшнем обновлении исправлено, спасибо.
Вам спасибо, буду пробовать, когда с другой ошибкой разберемся. Пока на Е2 сидим.
wikipost
ac03.06.2010 02:51
Эта команда
MAIL FROM:<fabo.swynford@bqpm.com>
в логе SMTP в районе 12:01:47 есть? (тот SMTP-лог, что в начале темы приведён — про другую ошибку, исправленную вчера).
wikipost
matveeva03.06.2010 13:33
Нет, есть про предыд. письмо. (\DATA\log\smtp\2010-06-01-log.txt )
2010-06-01 12:01:11;127.0.0.1;@;334;3028;INDEX;1047 2010-06-01 12:01:11;127.0.0.1;@;334;3028;IN;MAIL FROM:<telma.purkis@njpaktech.com> 2010-06-01 12:01:45;127.0.0.1;@;334;3028;OUT;250 telma.purkis@njpaktech.com OK 2010-06-01 12:01:45;127.0.0.1;@;334;3028;IN;RCPT TO:<director@aori.co.ru> 2010-06-01 12:01:45;127.0.0.1;@;334;3028;OUT;550 director@aori.co.ru no such email here, local domain 2 2010-06-01 12:01:45;127.0.0.1;@;334;3028;IN;RCPT TO:<sekr@aori.co.ru> 2010-06-01 12:01:45;127.0.0.1;@;334;3028;OUT;250 sekr@aori.co.ru OK, ExDelivery:Local, a=... 2010-06-01 12:01:45;127.0.0.1;@;334;3028;IN;DATA 2010-06-01 12:01:45;127.0.0.1;@;334;3028;SPOOL;..\DATA\mail\spool\telma.purkis@njpaktech.com!334!8333235!4.eml 2010-06-01 12:01:45;127.0.0.1;@;334;3028;OUT;354 send the mail data, end with . 2010-06-01 12:01:45;127.0.0.1;@;334;3028;FILTER;telma.purkis@njpaktech.com;sekr@aori.co.ru;;abs_spam 2010-06-01 12:01:47;127.0.0.1;@;334;3028;OUT;250 OK message accepted for delivery (alias or copy) 2010-06-01 12:01:47;127.0.0.1;@;334;3028;ARCHIVE;telma.purkis@njpaktech.com;safemail@aori.co.ru;ExDelivery:Archive 2010-06-01 12:03:28;192.168.16.1;@;335;1008;OUT;220 Eserv v4.23.4741 ESMTP. Tue, 01 Jun 2010 12:03:28 +0400 () 2010-06-01 12:03:28;192.168.16.1;@;335;1008;IN;EHLO SERVER2003 2010-06-01 12:03:28;192.168.16.1;@;335;1008;OUT;250-adsl.TREVIS.local Hello [192.168.16.1]
wikipost
ac03.06.2010 18:24
Выходит, что эту команду "MAIL FROM" SMTP-сервер от pop2smtp уже не прочитал, и pop2smtp на этом сессию и завершил. В предыдущем письме, на котором в той сессии всё закончилось, опять фигурирует "FILTER", может быть влияла та же ошибка, что была исправлена на день позже в обновлении от 2 июня...

В следующей сессии pop2smtp письмо от этого fabo.swynford@bqpm.com было принято, или этот адрес в логах SMTP-сервера так никогда и не появился?
wikipost
matveeva04.06.2010 09:22
ac пишет: или этот адрес в логах SMTP-сервера так никогда и не появился?
Не появился. Ни в одном из логов в [SMTP] за этот день такого нет.
wikipost
ac04.06.2010 09:43
Значит на POP-сервере письмо осталось?
wikipost
matveeva04.06.2010 10:11
matveeva пишет: Значит на POP-сервере письмо осталось?
Да, они все там остались, т.к. сессия не закрылась нормально. Т.е. они там копились и копились, пока не вернулась на Е2. Е2 их все забрал и удалил.
wikipost
matveeva10.06.2010 12:40
ред: 10.06.2010 12:41
Опять та же ошибка:
13:20:23 pop3 (1496): +OK marked deleted 13:20:23 pop3 (1496): TOP 3 0 13:20:23 pop3 (1496): +OK message follows 13:20:23 pop3 (1496): X-Real-To: <glavbuh@aori.co.ru> 13:20:23 pop3 (1496): Return-Path: <novosti1@novosti1.ru> 13:20:23 pop3 (1496): X-DCC-deis-Metrics: frontend4 2004; Body=1 Fuz1=1 13:20:23 pop3 (1496): Received: from [87.99.11.85] (HELO lanet.net.pl) 13:20:23 pop3 (1496): by frontend4.cgp.gldn.net (CommuniGate Pro SMTP 5.2.18) 13:20:23 pop3 (1496): with ESMTP id 257534084 for glavbuh@aori.co.ru; Thu, 10 Jun 2010 13:12:24 +0400 13:20:23 pop3 (1496): Received-SPF: pass 13:20:23 pop3 (1496): receiver=frontend4.cgp.gldn.net; client-ip=87.99.11.85; envelope-from=novosti1@novosti1.ru 13:20:23 pop3 (1496): Message-ID: <50CA901164B2A25CF9D459FA4D3E6E0A@host-87-99-11-85.lanet.net.pl> 13:20:23 pop3 (1496): From: =?koi8-r?Q?C=CF=C2=D3=D4=D7=C5=CE=CE=C9=CB=D5_P=D5=CB=CF=D7=CF=C4=C9=D4=C5=CC=C0?= <novosti1@novosti1.ru> 13:20:23 pop3 (1496): To: "glavbuh" <glavbuh@aori.co.ru> 13:20:23 pop3 (1496): Subject: =?koi8-r?Q?C=CF=C2=D3=D4=D7=C5=CE=CE=C9=CB=D5_=C9_p=D5=CB=CF?= 13:20:23 pop3 (1496): =?koi8-r?Q?=D7=CF=C4=C9=D4=C5=CC=C0=3A_kak_=D5=CA=D4=C9_=CF=D4_=CF?= 13:20:23 pop3 (1496): =?koi8-r?Q?=D4=D7=C5=D4=D3=D4=D7=C5=CE=CE=CF=D3=D4=C9_=D0=CF_=C4=CF?= 13:20:23 pop3 (1496): =?koi8-r?Q?=CC=C7=C1=CD_=CB=CF=CD=D0=C1=CE=C9=C9=2C_=DC=C6=C6=C5=CB?= 13:20:23 pop3 (1496): =?koi8-r?Q?=D4=C9=D7=CE=CF_=D7=DA=D9=D3=CB=C1=D4=D8_=DA=C1=C4=CF=CC?= 13:20:23 pop3 (1496): =?koi8-r?Q?=D6=C5=CE=CE=CF=D3=D4=D8?= 13:20:23 pop3 (1496): Date: Thu, 10 Jun 2010 13:12:23 +0400 13:20:23 pop3 (1496): MIME-Version: 1.0 13:20:23 pop3 (1496): Content-Type: multipart/alternative; 13:20:23 pop3 (1496): boundary="----=_NextPart_000_00E7_01CB089E.8E4CE490" 13:20:23 pop3 (1496): List-Unsubscribe: <mailto:out@novosti1.ru?subject=glavbuh@aori.co.ru> 13:20:23 pop3 (1496): X-Priority: 3 13:20:23 pop3 (1496): X-MSMail-Priority: Normal 13:20:23 pop3 (1496): X-Mailer: Microsoft Windows Mail 6.0.6001.18000 13:20:23 pop3 (1496): X-MimeOLE: Produced By Microsoft MimeOLE V6.0.6001.18000 13:20:23 pop3 (1496): 13:20:23 pop3 (1496): . 13:20:23 SMTP (1496): MAIL FROM:<novosti1@novosti1.ru> 13:30:23 pop3 (1496): QUIT 13:30:23 ERROR (1496): 4294966294

Вчера целый день нормально работало все и сегодня полдня. Примерно в этот же момент работала в ThunderBird и в нем наблюдалось зависание при IMAP доступе к некот. папкам. Т.е. какие-то папки отображал быстро, а на нескольких ничего не отображалась, а внизу, в статус-строке бесконечно бегал индикатор загрузки. Несколько раз закрыла-открыла его, вроде, стал показывать содержимое. А вот почта на приеме застряла.
В acsmtp.log есть странные записи, но по времени не совпадают с ошибкой.
40 Ok SNMP:starting... acSMTP/4.23 SNMP server: 25 : ================== spam bug ==============Content-Type: text/html; charset="windows-1251" ================== spam bug ==============MIME-Version: 1.0 ================== spam bug ==============Content-Type: text/plain; charset=koi8-r ================== spam bug ==============Content-Type: image/gif; name="djcyrjath.gif" ================== spam bug ==============Content-Type: text/plain; charset=koi8-r ================== spam bug ==============Content-Type: text/html; charset=koi8-r ================== spam bug ==============To: "***" <***@aori.co.ru> ================== spam bug ==============MIME-Version: 1.0 ================== spam bug ==============Content-Type: multipart/report; report-type=disposition-notification; ================== spam bug ==============Content-Type: text/plain; charset=UTF-8

Планировщик забирает почту каждые 10 мин.
wikipost
ac10.06.2010 13:52
matveeva пишет: 13:20:23 SMTP (1496): MAIL FROM:<novosti1@novosti1.ru>

И из протокольного лога SMTP несколько строк до и после этой команды процитируйте, пожалуйста.
wikipost
matveeva10.06.2010 14:13
автор пишет: из протокольного лога SMTP
— Это из какого? Вот из \DATA\log\smtp\2010-06-10-log.txt
2010-06-10 13:10:19;127.0.0.1;@;216;1400;OUT;221 Goodbye. 2010-06-10 13:20:16;127.0.0.1;@;217;568;OUT;220 Eserv v4.23.4752 ESMTP. Thu, 10 Jun 2010 13:20:16 +0400 () 2010-06-10 13:20:16;127.0.0.1;@;217;568;IN;HELO pop2smtp 2010-06-10 13:20:16;127.0.0.1;@;217;568;OUT;250 adsl.TREVIS.local Hello [127.0.0.1] 2010-06-10 13:20:16;127.0.0.1;@;217;568;IN;MAIL FROM:<novosti1@novosti1.ru> 2010-06-10 13:20:17;127.0.0.1;@;217;568;OUT;250 novosti1@novosti1.ru OK 2010-06-10 13:20:17;127.0.0.1;@;217;568;IN;RCPT TO:<***@aori.co.ru> 2010-06-10 13:20:17;127.0.0.1;@;217;568;OUT;250 ***@aori.co.ru OK, ExDelivery:Local, a=... 2010-06-10 13:20:17;127.0.0.1;@;217;568;IN;DATA 2010-06-10 13:20:17;127.0.0.1;@;217;568;SPOOL;..\DATA\mail\spool\novosti1@novosti1.ru!217!790643422!1.eml 2010-06-10 13:20:17;127.0.0.1;@;217;568;OUT;354 send the mail data, end with . 2010-06-10 13:20:17;127.0.0.1;@;217;568;FILTER;novosti1@novosti1.ru;***@aori.co.ru;;spam 2010-06-10 13:20:17;127.0.0.1;@;217;568;SPAM;<B02CB5A577BB93F6314EAC2669039679@host-87-99-11-85.lanet.net.pl>;PF=spam, SPR=0 2010-06-10 13:20:20;127.0.0.1;@;217;568;OUT;550-Vashe pis'mo NE DOSTAVLENO, tak kak pohozhe na SPAM. Nazhmite na ssylku nizhe dlya dostavki. 550 Your message is classified as SPAM! Please click here: http://adsl.TREVIS.local:3130/MailClassify/2010-06-10/novosti1@novosti1.ru!217!790646047!2.eml 2010-06-10 13:20:20;127.0.0.1;@;217;568;ARCHIVE;novosti1@novosti1.ru;spam_admin@aori.co.ru;ExDelivery:Archive 2010-06-10 13:20:20;127.0.0.1;@;217;568;INDEX;172 2010-06-10 13:20:20;127.0.0.1;@;217;568;DELIVERY;spam_mail@aori.co.ru; 2010-06-10 13:20:20;127.0.0.1;@;217;568;INDEX;44 2010-06-10 13:20:20;127.0.0.1;@;217;568;IN;MAIL FROM:<novosti1@novosti1.ru> 2010-06-10 13:20:21;127.0.0.1;@;217;568;OUT;250 novosti1@novosti1.ru OK 2010-06-10 13:20:21;127.0.0.1;@;217;568;IN;RCPT TO:<glavbux@aori.co.ru> 2010-06-10 13:20:21;127.0.0.1;@;217;568;OUT;550 glavbux@aori.co.ru no such email here, local domain 2 2010-06-10 13:20:21;127.0.0.1;@;217;568;IN;RCPT TO:<***@aori.co.ru> 2010-06-10 13:20:21;127.0.0.1;@;217;568;OUT;250 ***@aori.co.ru OK, ExDelivery:Local, a=... 2010-06-10 13:20:21;127.0.0.1;@;217;568;IN;DATA 2010-06-10 13:20:21;127.0.0.1;@;217;568;SPOOL;..\DATA\mail\spool\novosti1@novosti1.ru!217!790647079!3.eml 2010-06-10 13:20:21;127.0.0.1;@;217;568;OUT;354 send the mail data, end with . 2010-06-10 13:20:21;127.0.0.1;@;217;568;FILTER;novosti1@novosti1.ru;***@aori.co.ru;;abs_spam 2010-06-10 13:20:23;127.0.0.1;@;217;568;OUT;250 OK message accepted for delivery (alias or copy) 2010-06-10 13:20:23;127.0.0.1;@;217;568;ARCHIVE;novosti1@novosti1.ru;safemail@aori.co.ru;ExDelivery:Archive 2010-06-10 13:38:17;192.168.16.44;@;218;1380;OUT;220 Eserv v4.23.4752 ESMTP. Thu, 10 Jun 2010 13:38:17 +0400 ()

Там несколько спамных писем было от novosti1@novosti1.ru
wikipost
ac10.06.2010 14:37
Получается, ситуация точно такая же, опять до MAIL FROM на сервере дело не дошло. ОК, спасибо, иду копать.

P.S. автор пишет — это я сегодня ночью поломал цитирование на форуме, верну обратно.
wikipost
matveeva10.06.2010 14:44
ред: 10.06.2010 14:52
автор пишет
))
Может, журналы прислать какие целиком? Может, чего там заметите незаметное?
А почта так и не принимается. Уже все сервисы поперезапускала... Первое письмо проходит нормально, а на след. виснет. А какие-то сеансы проходят нормально. По 4 письма принимается. Непонятно.. А еще сеанс не заканчивается правильно.
Вот правильное окончание
00:20:18 pop3 (2016): +OK marked deleted 00:20:18 SMTP (2016): QUIT 00:20:19 SMTP (2016): 221 Goodbye. 00:20:19 pop3 (2016): QUIT
А вот неправильное
15:00:55 SMTP (3312): 250 OK message accepted for delivery (alias or copy) 15:00:55 pop3 (3312): DELE 1 15:00:55 pop3 (3312): +OK marked deleted 15:00:55 SMTP (3312): QUIT 15:10:48 pop3 (3312): QUIT 15:10:48 ERROR (3312): 4294966294 15:20:48 pop3 (3348): Connecting mail.co.ru:110...
И письма из ящика не удаляются.
wikipost
pig10.06.2010 15:43
В обоих процитированых фрагментах сессия затыкается на попытке доставки письма в ящик safemail@. Я бы проверил остальные затыки и посмотрел в сам ящик. Похоже на зависание при добавлении письма в базу.
wikipost
matveeva10.06.2010 16:01
Этот ящик, в который сохраняется на всяк. случай вся приходящая/исходящая почта (параметр "копировать всю почту на емэйл"). Там сейчас порядка 1800 сообщений показывается. На ней IMAP действительно зависал неск. раз и в ОЕ, и в ТВ. 1800 — это много? Переименовала эту папку в почте, чтобы создалась новая. Послежу. Спасибо.
wikipost
pig10.06.2010 18:40
Проблемы не от количества писем, IMHO.
wikipost
ac10.06.2010 22:14
Да, проблема точно не в количестве. У нас есть ящики и по 20 000 и по 30 000 сообщений в INBOX'е (например, support@eserv.ru , и ящики размером в несколько гигабайт, причем все активно используются.

pig пишет: В обоих процитированых фрагментах сессия затыкается на попытке доставки письма в ящик safemail@.

А ведь точно, записей INDEX/DELIVERY, которые в норме должны следовать за ARCHIVE, для этих сессий не видно. В логе acSMTP.log ничего подозрительного (кроме тех "spam bug", которые просто отчет детектора неправильного формата писем)?
wikipost
matveeva11.06.2010 09:24
ред: 11.06.2010 09:31
ac пишет: В логе acSMTP.log ничего подозрительного
Нет, там все чисто. Вот такой, например, кусок перед очередным перезапуском:
[Temp] : Temp[noname2] S" *@mynewdomain.tld" S@ ; 40 Ok SNMP:starting... acSMTP/4.23 SNMP server: 25 : Thu, 10 Jun 2010 15:43:49 +0400- shutdown...
Ну и дальше параметры запуска. Без ошибок.
Но с этим ящиком действительно все странно. Вот сейчас утром запустила ОЕ и IMAPом зашла в этот ящик. показал новых 158 писем из 192 (вчера обнулила его вечером). И даже мне отобразил заголовки. но дальше все попытки загрузить какое-либо сообщение или синхронизировать папки вызывает таймаут.
Остальные IMAPы работают нормально. В журнале IMAPа попытка загрузить сообщение выглядит так:
1 — раскрываем папку "Входящие"
2010-06-11 10:22:49;192.168.16.14;matveeva@aori.co.ru;IN;1028;2544;;yfnm CAPABILITY 2010-06-11 10:22:49;192.168.16.14;matveeva@aori.co.ru;OUT;1028;2544;;* CAPABILITY IMAP4rev1 AUTH=LOGIN IDLE STARTTLS 2010-06-11 10:22:49;192.168.16.14;matveeva@aori.co.ru;OUT;1028;2544;;yfnm OK CAPABILITY completed 2010-06-11 10:22:49;192.168.16.14;matveeva@aori.co.ru;IN;1028;2544;;8fbh LOGIN "safemail" "***" 2010-06-11 10:22:49;192.168.16.14;safemail@aori.co.ru;OUT;1028;2544;;* CAPABILITY IMAP4rev1 LITERAL+ IDLE 2010-06-11 10:22:49;192.168.16.14;safemail@aori.co.ru;OUT;1028;2544;;8fbh OK LOGIN completed (safemail@aori.co.ru) 2010-06-11 10:22:49;192.168.16.14;safemail@aori.co.ru;IN;1028;2544;;ggif IDLE 2010-06-11 10:22:49;192.168.16.14;safemail@aori.co.ru;OUT;1028;2544;;+ idling (safemail@aori.co.ru/) 2010-06-11 10:22:49;192.168.16.14;safemail@aori.co.ru;OUT;1028;2544;;ggif OK IDLE completed 2010-06-11 10:22:49;192.168.16.14;safemail@aori.co.ru;IN;1028;2544;;c2vc SELECT "INBOX"

При этом ОЕ кричит о тайм ауте:
Не были получены сведения о числе непрочитанных сообщений в папке 'Входящие'. Не удается получить непрочитанные пункты для 'Входящие' на 'IMAP_safemail' Учетная запись: 'IMAP_safemail', Сервер: 'adsl', Протокол: IMAP, Ответ сервера: 'Сервер не ответил на данную команду IMAP до завершения соединения.', Порт: 143, Защита (SSL): Нет, Код ошибки: 0x800C006F Загрузка заголовков из папки 'Входящие' не была завершена. Не удается выбрать 'Входящие' на сервере IMAP. Попробуйте обновить список папок для синхронизации с сервером IMAP. Учетная запись: 'IMAP_safemail', Сервер: 'adsl', Протокол: IMAP, Ответ сервера: 'Сервер не ответил на данную команду IMAP до завершения соединения.', Порт: 143, Защита (SSL): Нет, Код ошибки: 0x800C006F


  1. а это из журнала после нажатия на ссылку "Загрузить" для одного сообщения в ОЕ
    2. 2010-06-11 10:26:14;192.168.16.14;matveeva@aori.co.ru;IN;1029;1200;;nbo7 CAPABILITY 2010-06-11 10:26:14;192.168.16.14;matveeva@aori.co.ru;OUT;1029;1200;;* CAPABILITY IMAP4rev1 AUTH=LOGIN IDLE STARTTLS 2010-06-11 10:26:14;192.168.16.14;matveeva@aori.co.ru;OUT;1029;1200;;nbo7 OK CAPABILITY completed 2010-06-11 10:26:14;192.168.16.14;matveeva@aori.co.ru;IN;1029;1200;;437a LOGIN "safemail" "***" 2010-06-11 10:26:14;192.168.16.14;safemail@aori.co.ru;OUT;1029;1200;;* CAPABILITY IMAP4rev1 LITERAL+ IDLE 2010-06-11 10:26:14;192.168.16.14;safemail@aori.co.ru;OUT;1029;1200;;437a OK LOGIN completed (safemail@aori.co.ru) 2010-06-11 10:26:14;192.168.16.14;safemail@aori.co.ru;IN;1029;1200;;xlip IDLE 2010-06-11 10:26:14;192.168.16.14;safemail@aori.co.ru;OUT;1029;1200;;+ idling (safemail@aori.co.ru/) 2010-06-11 10:26:14;192.168.16.14;safemail@aori.co.ru;OUT;1029;1200;;xlip OK IDLE completed 2010-06-11 10:26:14;192.168.16.14;safemail@aori.co.ru;IN;1029;1200;;4gah SELECT "INBOX"
    Ну и опять "ваш сервер не ответил в течение 60 сек...."Уфф. Что-то много кода получилосьP.S. Пока писала, опять все сломалось на приеме. Попробую опять переименовать папку \DATA\domains\aori.co.ru\safemail во что-нибудь другое, чтобы она создалась новая.PP.S. Кстати переименовать смогла только после остановки службы acSmtp. Что-то он там с ней пытается сделать. )
wikipost
i.schelokov11.06.2010 10:31
Можно попробовать в ОЕ увеличить параметр "Длительность ожидания сервера".
wikipost
pig11.06.2010 11:40
Надо проводником заглянуть в папку ящика и посмотреть состояние баз писем.
wikipost
ac11.06.2010 12:21
Да, надо посмотреть, как выглядит каталог ящика — есть ли на момент блокировки файл .messages.db3-journal или .headers.db3-journal, и есть ли в спуле ожидающие доставки туда письма.
wikipost
ac11.06.2010 12:27
i.schelokov пишет: Можно попробовать в ОЕ увеличить параметр "Длительность ожидания сервера".

В данной ситуации врядли поможет, т.к. по ситуации с pop2smtp выходит, что здесь не временное торможение обработки, а какой-то постоянный стопор.

Кстати, на момент возникновения этого сбоя с pop2smtp почтовый клиент сидит в этой папке, или затор возникает независимо от активности IMAP?
wikipost
matveeva11.06.2010 13:02
i.schelokov пишет: Можно попробовать в ОЕ увеличить параметр "Длительность ожидания сервера".
Нет, это не поможет, правильно ac говорит. "Подождать еще" не помогает. Дело не в этом.
ac пишет: есть ли на момент блокировки файл .messages.db3-journal или .headers.db3-journal
Что-то таких не помню файлов. Сейчас нет ни в активном, ни в переименованных копиях, но сейчас все и работает.
ac пишет: Кстати, на момент возникновения этого сбоя с pop2smtp почтовый клиент сидит в этой папке, или затор возникает независимо от активности IMAP
Не могу сказать точно. но вполне возможно, т.к.,кажется, проблемы с доставкой начинались после зависания IMAP. Похоже, что IMAP захватывал ящик (какой-то файл), и все остальное уже не могла получить к нему доступ. Если еще такое произойдет, попробую сначала перезагрузить IMAP сервис и посмотреть, идет ли загрузка.
wikipost
ac11.06.2010 13:21
matveeva пишет: Если еще такое произойдет

Раз уже неоднократно у вас было, значит еще раз будет. Надо будет сделать так:
  • заглянуть в папку INBOX на предмет файла *-journal;
  • если он есть, то записать имя файла и время модификации;
  • по этому времени взять вырезки из логов imap и smtp (если *-journal нет, то на момент последней нормальной записи pop2smtp);
  • потом остановить acimap, acsmtp, acweb и посмотреть, после останова кого из них исчез journal (если был).
А я пока пытаюсь теоретически выяснить, как такая ситуация может возникнуть. Что-то похожее случалось и ранее — у Dandy — блокировка, именно с файлом -journal, правда без связи с pop2smtp. Ему я выслал тестовый acIMAP для проверки одного предположения, но у него такой сбой случается достаточно редко, а у вас практически каждый день, поэтому есть шанс быстро отловить. Тут правда acSMTP под бОльшим пока подозрением, чем acIMAP.
wikipost
matveeva11.06.2010 13:26
ac пишет: Надо будет сделать так:
Ждем. Пока все работает, папка откликается.
wikipost
matveeva11.06.2010 15:14
Все пока работает! Оставляем до вторника. Всех с праздником!
wikipost
matveeva15.06.2010 10:03
Все работает!
wikipost
matveeva16.06.2010 10:27
ред: 16.06.2010 10:33
Ага! Сломалось! Ломается в момент, когда в клиенте (сейчас ОЕ) выделяю группу записей (сейчас 225 штук) и пытаюсь пометить их как прочитанные. Этот процесс пересекся с сеансом доставки почты. В итоге сеанс завис на обычном месте (MAIL FROM, в клиенте — таймаут.
ac пишет: заглянуть в папку INBOX на предмет файла *-journal;
Есть:
Содержимое папки \DATA\domains\aori.co.ru\safemail\INBOX 16.06.2010 11:25 <DIR> . 16.06.2010 11:25 <DIR> .. 11.06.2010 10:40 10 .created 16.06.2010 11:19 151 552 .events.db3 16.06.2010 11:10 806 912 .headers.db3 16.06.2010 11:19 421 888 .messages.db3 16.06.2010 11:19 12 824 .messages.db3-journal

ac пишет: (если *-journal нет, то на момент последней нормальной записи pop2smtp

11:20:35 pop3 (2920): +OK marked deleted 11:20:35 pop3 (2920): TOP 7 0 11:20:35 pop3 (2920): +OK message follows 11:20:35 pop3 (2920): X-Real-To: aori@co.ru 11:20:35 pop3 (2920): Return-Path: <info@blackwoods.net> 11:20:35 pop3 (2920): X-DCC-deis-Metrics: frontend4 2004; bulk Body=714 Fuz1=714 11:20:35 pop3 (2920): X-Spam-Status: Yes 11:20:35 pop3 (2920): Received: from [83.242.144.199] (HELO GrandPolis.Moscow.comstar.ru) 11:20:35 pop3 (2920): by frontend4.cgp.gldn.net (CommuniGate Pro SMTP 5.2.18) 11:20:35 pop3 (2920): with ESMTP id 257988936 for aori@co.ru; Wed, 16 Jun 2010 11:14:21 +0400 11:20:35 pop3 (2920): Received-SPF: none 11:20:35 pop3 (2920): receiver=frontend4.cgp.gldn.net; client-ip=83.242.144.199; envelope-from=info@blackwoods.net 11:20:35 pop3 (2920): Message-ID: <F020468B34B34F5ABB2B8009D9B49E72@cold> 11:20:35 pop3 (2920): From: "Print" <info@blackwoods.net> 11:20:35 pop3 (2920): To: <aori@co.ru> 11:20:35 pop3 (2920): Subject: =?koi8-r?B?5dfSz8zJ09TP18vJ?= 11:20:35 pop3 (2920): Date: Wed, 16 Jun 2010 09:36:01 +0400 11:20:35 pop3 (2920): MIME-Version: 1.0 11:20:35 pop3 (2920): Content-Type: multipart/alternative; 11:20:35 pop3 (2920): boundary="----=_NextPart_000_005C_01CB0D37.55831F10" 11:20:35 pop3 (2920): X-Priority: 3 11:20:35 pop3 (2920): X-MSMail-Priority: Normal 11:20:35 pop3 (2920): X-Mailer: Microsoft Outlook Express 6.00.2900.5843 11:20:35 pop3 (2920): X-MimeOLE: Produced By Microsoft MimeOLE V6.00.2900.5579 11:20:35 pop3 (2920): 11:20:35 pop3 (2920): . 11:20:35 SMTP (2920): MAIL FROM:<info@blackwoods.net>

ac пишет: вырезки из логов imap

.... * 962 FETCH (UID 962 FLAGS (abs_spam)) * 963 FETCH (UID 963 FLAGS (clear)) 2010-06-16 11:18:25;192.168.16.14;safemail@aori.co.ru;OUT;6356;1860;INBOX;hmb9 OK UID FETCH completed 2010-06-16 11:18:25;192.168.16.14;safemail@aori.co.ru;IN;6356;1860;INBOX;7zur IDLE 2010-06-16 11:18:25;192.168.16.14;safemail@aori.co.ru;OUT;6356;1860;INBOX;+ idling (safemail@aori.co.ru/INBOX) 2010-06-16 11:18:39;192.168.16.14;safemail@aori.co.ru;OUT;6356;1860;INBOX;7zur OK IDLE completed 2010-06-16 11:18:39;192.168.16.14;safemail@aori.co.ru;IN;6356;1860;INBOX;iy2o UID STORE 620:963 +FLAGS.SILENT (\Seen) 2010-06-16 11:19:02;192.168.16.14;matveeva@aori.co.ru;IN;6358;3916;;iddx CAPABILITY 2010-06-16 11:19:02;192.168.16.14;matveeva@aori.co.ru;OUT;6358;3916;;* CAPABILITY IMAP4rev1 AUTH=LOGIN IDLE STARTTLS 2010-06-16 11:19:02;192.168.16.14;matveeva@aori.co.ru;OUT;6358;3916;;iddx OK CAPABILITY completed 2010-06-16 11:19:02;192.168.16.14;matveeva@aori.co.ru;IN;6358;3916;;v9fx LOGIN "safemail" "123" 2010-06-16 11:19:02;192.168.16.14;safemail@aori.co.ru;OUT;6358;3916;;* CAPABILITY IMAP4rev1 LITERAL+ IDLE 2010-06-16 11:19:02;192.168.16.14;safemail@aori.co.ru;OUT;6358;3916;;v9fx OK LOGIN completed (safemail@aori.co.ru) 2010-06-16 11:19:02;192.168.16.14;safemail@aori.co.ru;IN;6358;3916;;6qe5 IDLE 2010-06-16 11:19:02;192.168.16.14;safemail@aori.co.ru;OUT;6358;3916;;+ idling (safemail@aori.co.ru/) 2010-06-16 11:19:02;192.168.16.14;safemail@aori.co.ru;OUT;6358;3916;;6qe5 OK IDLE completed 2010-06-16 11:19:02;192.168.16.14;safemail@aori.co.ru;IN;6358;3916;;x639 SELECT "&BB4EQgQ,BEAEMAQyBDsENQQ9BD0ESwQ1-" 2010-06-16 11:19:02;192.168.16.14;safemail@aori.co.ru;OUT;6358;3916;&BB4EQgQ,BEAEMAQyBDsENQQ9BD0ESwQ1-;* FLAGS ($Forwarded $MDNSent \Answered \Flagged \Deleted \Draft \Seen $label1 $label2 $label3 $label4 $label5 Junk NonJunk Hidden ambiguous) 2010-06-16 11:19:02;192.168.16.14;safemail@aori.co.ru;OUT;6358;3916;&BB4EQgQ,BEAEMAQyBDsENQQ9BD0ESwQ1-;* 0 EXISTS 2010-06-16 11:19:02;192.168.16.14;safemail@aori.co.ru;OUT;6358;3916;&BB4EQgQ,BEAEMAQyBDsENQQ9BD0ESwQ1-;* 0 RECENT 2010-06-16 11:19:02;192.168.16.14;safemail@aori.co.ru;OUT;6358;3916;&BB4EQgQ,BEAEMAQyBDsENQQ9BD0ESwQ1-;* OK [UIDVALIDITY 590] UID validity status 2010-06-16 11:19:02;192.168.16.14;safemail@aori.co.ru;OUT;6358;3916;&BB4EQgQ,BEAEMAQyBDsENQQ9BD0ESwQ1-;* OK [PERMANENTFLAGS ($Forwarded $MDNSent \* \Answered \Flagged \Deleted \Draft \Seen)] Permanent flags 2010-06-16 11:19:02;192.168.16.14;safemail@aori.co.ru;OUT;6358;3916;&BB4EQgQ,BEAEMAQyBDsENQQ9BD0ESwQ1-;* OK [UIDNEXT 1] Predicted next UID 2010-06-16 11:19:02;192.168.16.14;safemail@aori.co.ru;OUT;6358;3916;&BB4EQgQ,BEAEMAQyBDsENQQ9BD0ESwQ1-;x639 OK [READ-WRITE] SELECT completed (&BB4EQgQ,BEAEMAQyBDsENQQ9BD0ESwQ1-) 2010-06-16 11:19:02;192.168.16.14;safemail@aori.co.ru;IN;6358;3916;&BB4EQgQ,BEAEMAQyBDsENQQ9BD0ESwQ1-;ZZZZ LOGOUT 2010-06-16 11:19:02;192.168.16.14;safemail@aori.co.ru;OUT;6358;3916;;* BYE Eserv IMAP4rev1 server terminating connection 2010-06-16 11:19:03;192.168.16.14;matveeva@aori.co.ru;IN;6359;2504;;fze3 CAPABILITY 2010-06-16 11:19:03;192.168.16.14;matveeva@aori.co.ru;OUT;6359;2504;;* CAPABILITY IMAP4rev1 AUTH=LOGIN IDLE STARTTLS 2010-06-16 11:19:03;192.168.16.14;matveeva@aori.co.ru;OUT;6359;2504;;fze3 OK CAPABILITY completed 2010-06-16 11:19:03;192.168.16.14;matveeva@aori.co.ru;IN;6359;2504;;2qiy LOGIN "safemail" "123" 2010-06-16 11:19:03;192.168.16.14;safemail@aori.co.ru;OUT;6359;2504;;* CAPABILITY IMAP4rev1 LITERAL+ IDLE 2010-06-16 11:19:03;192.168.16.14;safemail@aori.co.ru;OUT;6359;2504;;2qiy OK LOGIN completed (safemail@aori.co.ru) 2010-06-16 11:19:03;192.168.16.14;safemail@aori.co.ru;IN;6359;2504;;8d34 IDLE 2010-06-16 11:19:03;192.168.16.14;safemail@aori.co.ru;OUT;6359;2504;;+ idling (safemail@aori.co.ru/) 2010-06-16 11:19:03;192.168.16.14;safemail@aori.co.ru;OUT;6359;2504;;8d34 OK IDLE completed 2010-06-16 11:19:03;192.168.16.14;safemail@aori.co.ru;IN;6359;2504;;tl4d SELECT "INBOX" 2010-06-16 11:19:03;192.168.16.14;safemail@aori.co.ru;OUT;6359;2504;INBOX;* FLAGS ($Forwarded $MDNSent \Answered \Flagged \Deleted \Draft \Seen $label1 $label2 $label3 $label4 $label5 Junk NonJunk Hidden ambiguous) 2010-06-16 11:19:03;192.168.16.14;safemail@aori.co.ru;OUT;6359;2504;INBOX;* 963 EXISTS 2010-06-16 11:19:03;192.168.16.14;safemail@aori.co.ru;OUT;6359;2504;INBOX;* 0 RECENT 2010-06-16 11:19:03;192.168.16.14;safemail@aori.co.ru;OUT;6359;2504;INBOX;* OK [UIDVALIDITY 590] UID validity status 2010-06-16 11:19:03;192.168.16.14;safemail@aori.co.ru;OUT;6359;2504;INBOX;* OK [UNSEEN 739] first unseen message in this box 2010-06-16 11:19:03;192.168.16.14;safemail@aori.co.ru;OUT;6359;2504;INBOX;* OK [PERMANENTFLAGS ($Forwarded $MDNSent \* \Answered \Flagged \Deleted \Draft \Seen)] Permanent flags 2010-06-16 11:19:03;192.168.16.14;safemail@aori.co.ru;OUT;6359;2504;INBOX;* OK [UIDNEXT 964] Predicted next UID 2010-06-16 11:19:03;192.168.16.14;safemail@aori.co.ru;OUT;6359;2504;INBOX;tl4d OK [READ-WRITE] SELECT completed (INBOX) 2010-06-16 11:19:03;192.168.16.14;safemail@aori.co.ru;IN;6359;2504;INBOX;nhtf IDLE 2010-06-16 11:19:03;192.168.16.14;safemail@aori.co.ru;OUT;6359;2504;INBOX;+ idling (safemail@aori.co.ru/INBOX) 2010-06-16 11:19:03;192.168.16.14;safemail@aori.co.ru;OUT;6359;2504;INBOX;nhtf OK IDLE completed 2010-06-16 11:19:03;192.168.16.14;safemail@aori.co.ru;IN;6359;2504;INBOX;1js2 UID FETCH 964:* (BODY.PEEK[HEADER.FIELDS (References X-Ref X-Priority X-MSMail-Priority X-MSOESRec Newsgroups)] ENVELOPE RFC822.SIZE UID FLAGS INTERNALDATE) 2010-06-16 11:19:03;192.168.16.14;safemail@aori.co.ru;OUT;6359;2504;INBOX;1js2 OK UID FETCH completed 2010-06-16 11:19:03;192.168.16.14;safemail@aori.co.ru;IN;6359;2504;INBOX;yb8e UID FETCH 1:963 (UID FLAGS) 2010-06-16 11:19:03;192.168.16.14;safemail@aori.co.ru;OUT;6359;2504;INBOX;* 1 FETCH (UID 1 FLAGS (\Seen abs_spam)) * 2 FETCH (UID 2 FLAGS (\Seen abs_spam)) ....... * 945 FETCH (UID 945 FLAGS (abs_spam)) * 946 FETCH (UID 946 FLAGS (abs_spam)) * 947 FETCH (UID 947 FLAGS ()) * 948 FETCH (UID 948 FLAGS ()) * 949 FETCH (UID 949 FLAGS ($label1)) * 950 FETCH (UID 950 FLAGS ()) * 951 FETCH (UID 951 FLAGS (clear)) * 952 FETCH (UID 952 FLAGS (abs_spam)) * 953 FETCH (UID 953 FLAGS ()) * 954 FETCH (UID 954 FLAGS (clear)) * 955 FETCH (UID 955 FLAGS (clear)) * 956 FETCH (UID 956 FLAGS (abs_spam)) * 957 FETCH (UID 957 FLAGS (abs_spam)) * 958 FETCH (UID 958 FLAGS (abs_spam)) * 959 FETCH (UID 959 FLAGS (abs_spam)) * 960 FETCH (UID 960 FLAGS (abs_spam)) * 961 FETCH (UID 961 FLAGS (abs_spam)) * 962 FETCH (UID 962 FLAGS (abs_spam)) * 963 FETCH (UID 963 FLAGS (clear)) 2010-06-16 11:19:03;192.168.16.14;safemail@aori.co.ru;OUT;6359;2504;INBOX;yb8e OK UID FETCH completed 2010-06-16 11:19:03;192.168.16.14;safemail@aori.co.ru;IN;6359;2504;INBOX;u648 IDLE 2010-06-16 11:19:03;192.168.16.14;safemail@aori.co.ru;OUT;6359;2504;INBOX;+ idling (safemail@aori.co.ru/INBOX) 2010-06-16 11:19:03;192.168.16.14;safemail@aori.co.ru;OUT;6359;2504;INBOX;u648 OK IDLE completed 2010-06-16 11:19:03;192.168.16.14;safemail@aori.co.ru;IN;6359;2504;INBOX;vou5 UID STORE 739:963 +FLAGS.SILENT (\Seen) 2010-06-16 11:27:17;192.168.16.14;matveeva@aori.co.ru;IN;6367;824;;ogim CAPABILITY
wikipost
matveeva16.06.2010 10:35
ред: 16.06.2010 10:47
ac пишет: потом остановить acimap, acsmtp, acweb и посмотреть, после останова кого из них исчез journal (если был).
Остановила acimap, создался еще один journal (не успела посмотреть какой), потом оба исчезли.
Но затык при приеме не проходит. Попробую перезапустить acsmtp.
После перезапуска acsmtp "отвис" сеанс приема почты и продолжился с зависшего места. Закончился нормально.
wikipost
matveeva16.06.2010 13:57
Похоже, что при работе IMAPа одновременно с приемом почты от провайдера все и затыкается. Сейчас пыталась удалить письма из ящика и совпало с приемом почты. Все и встало. Помог перезапуск IMAPа. Все процессы завершились нормально. Вообще IMAP довольно медленно работает. Может, так и должно быть? Я раньше не работала с ним.
wikipost
ac17.06.2010 06:37
Давайте тогда ловить конкретный запрос, на котором стопорится. Обновление exe:
http://www.eserv.ru/download/E4_exe_upd.rar
(будет дополнительная информация в acSMTP.log или acIMAP.log)
matveeva пишет: Вообще IMAP довольно медленно работает. Может, так и должно быть? Я раньше не работала с ним.

В сравнении с POP3 (локальная работа с почтой) медленнее, но "некомфортных" задержек быть не должно. Какая именно операция "довольно медленна" — подключение к ящику, получение списка папок, получение списка сообщений, получение конкретного сообщения, перенос сообщений между папками, пометки флагами?
wikipost
matveeva17.06.2010 09:50
ac пишет: Какая именно операция "довольно медленна"

Вот выделение 295 писем и "помечание" их как прочтенные шло более минуты (таймаут стоит на 60 сек). А сейчас вот 18 писем из этого несчастного ящика safemail метились почти 2 минуты, а потом больше двух минут еще одно письмо оттуда же. POP3 в это время не работал. Комп не вис, окна откликались...
wikipost
matveeva17.06.2010 10:01
А сейчас при уходе из папки "спам" все того же ящика safemail вдруг повисло окно "Удаление писем", потом таймаут, сказала "подождать", потом ошибка: "Не удается выбрать "spam"на сервере IMAP. Попробуйте обновить список папок для синхронизации"
SELECT failed: (30005) unable to close due to unfinalised statements Настройка: Учетная запись: IMAP_safemail Сервер: adsl Имя пользователя: safemail Протокол: IMAP Порт: 143 Защита (SSL): 0 Код: 800cccd2
А в журнале IMAP
2010-06-17 10:55:31;192.168.16.14;safemail@aori.co.ru;OUT;272;4064;INBOX;sfks NO SELECT failed: (30114) 30114 2010-06-17 10:55:31;192.168.16.14;safemail@aori.co.ru;IN;272;4064;INBOX;ZZZZ LOGOUT 2010-06-17 10:55:31;192.168.16.14;safemail@aori.co.ru;OUT;272;4064;;* BYE Eserv IMAP4rev1 server terminating connection 2010-06-17 10:55:31;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;INBOX;xhaj OK IDLE completed 2010-06-17 10:55:31;192.168.16.14;safemail@aori.co.ru;IN;212;1276;INBOX;l74z CLOSE
Это по времени — самое начало операции. Потом ожидание. Потом
2010-06-17 10:59:18;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;INBOX;l74z NO CLOSE: error: unable to close due to unfinalised statements 2010-06-17 10:59:18;192.168.16.14;safemail@aori.co.ru;IN;212;1276;INBOX;kxqk IDLE 2010-06-17 10:59:18;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;INBOX;+ idling (safemail@aori.co.ru/INBOX) 2010-06-17 10:59:18;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;INBOX;kxqk OK IDLE completed 2010-06-17 10:59:18;192.168.16.14;safemail@aori.co.ru;IN;212;1276;INBOX;t4d3 SELECT "spam" 2010-06-17 10:59:18;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;INBOX;t4d3 NO SELECT failed: (30005) unable to close due to unfinalised statements 2010-06-17 10:59:18;192.168.16.14;safemail@aori.co.ru;IN;212;1276;INBOX;eum3 IDLE 2010-06-17 10:59:18;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;INBOX;+ idling (safemail@aori.co.ru/INBOX)
wikipost
matveeva17.06.2010 10:12
Ну вот похоже опять сломалось. В Inbox висит
17.06.2010 10:50 62 072 .messages.db3-journal
Вот журнал IMAP
2010-06-17 10:49:40;192.168.16.14;spamloop@aori.co.ru;OUT;210;1044;;oeji OK CLOSE completed 2010-06-17 10:49:40;192.168.16.14;spamloop@aori.co.ru;IN;210;1044;;zic3 IDLE 2010-06-17 10:49:40;192.168.16.14;spamloop@aori.co.ru;OUT;210;1044;;+ idling (spamloop@aori.co.ru/) 2010-06-17 10:50:16;192.168.16.14;matveeva@aori.co.ru;IN;212;1276;;n1em CAPABILITY 2010-06-17 10:50:16;192.168.16.14;matveeva@aori.co.ru;OUT;212;1276;;* CAPABILITY IMAP4rev1 AUTH=LOGIN IDLE STARTTLS 2010-06-17 10:50:16;192.168.16.14;matveeva@aori.co.ru;OUT;212;1276;;n1em OK CAPABILITY completed 2010-06-17 10:50:16;192.168.16.14;matveeva@aori.co.ru;IN;212;1276;;j5fd LOGIN "safemail" "***" 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;;* CAPABILITY IMAP4rev1 LITERAL+ IDLE 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;;j5fd OK LOGIN completed (safemail@aori.co.ru) 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;IN;212;1276;;qsue IDLE 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;;+ idling (safemail@aori.co.ru/) 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;;qsue OK IDLE completed 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;IN;212;1276;;du6a SELECT "INBOX" 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;INBOX;* FLAGS ($Forwarded $MDNSent \Answered \Flagged \Deleted \Draft \Seen $label1 $label2 $label3 $label4 $label5 Junk NonJunk Hidden ambiguous) 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;INBOX;* 1398 EXISTS 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;INBOX;* 11 RECENT 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;INBOX;* OK [UIDVALIDITY 590] UID validity status 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;INBOX;* OK [UNSEEN 1382] first unseen message in this box 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;INBOX;* OK [PERMANENTFLAGS ($Forwarded $MDNSent \* \Answered \Flagged \Deleted \Draft \Seen)] Permanent flags 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;INBOX;* OK [UIDNEXT 1399] Predicted next UID 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;INBOX;du6a OK [READ-WRITE] SELECT completed (INBOX) 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;IN;212;1276;INBOX;f1ck IDLE 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;INBOX;+ idling (safemail@aori.co.ru/INBOX) 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;INBOX;f1ck OK IDLE completed 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;IN;212;1276;INBOX;kj08 UID FETCH 1388:* (BODY.PEEK[HEADER.FIELDS (References X-Ref X-Priority X-MSMail-Priority X-MSOESRec Newsgroups)] ENVELOPE RFC822.SIZE UID FLAGS INTERNALDATE) 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;INBOX;* 1388 FETCH (UID 1388 BODY[HEADER.FIELDS ("References" "X-Ref" "X-Priority" "X-MSMail-Priority" "X-MSOESRec" "Newsgroups")] {2} ENVELOPE ("Thu, 17 Jun 2010 10:46:41 +0400" {21} Event from 09-Danilov () () NIL ((NIL NIL "3com" "aori.co.ru")) NIL NIL NIL "<d41d8cd98f00b204e9800998ecf8427e$am@adsl.TREVIS.local>") RFC822.SIZE 502 FLAGS (\Recent) INTERNALDATE "17-Jun-2010 10:46:41 +0400") * 1389 FETCH (UID 1389 BODY[HEADER.FIELDS ("References" "X-Ref" "X-Priority" "X-MSMail-Priority" "X-MSOESRec" "Newsgroups")] {2} ENVELOPE ("Thu, 17 Jun 2010 10:46:43 +0400" {21} Event from 09-Danilov () () NIL ((NIL NIL "3com" "aori.co.ru")) NIL NIL NIL "<d41d8cd98f00b204e9800998ecf8427e$am@adsl.TREVIS.local>") RFC822.SIZE 500 FLAGS (\Recent) INTERNALDATE "17-Jun-2010 10:46:43 +0400") * 1390 FETCH (UID 1390 BODY[HEADER.FIELDS ("References" "X-Ref" "X-Priority" "X-MSMail-Priority" "X-MSOESRec" "Newsgroups")] {2} ENVELOPE ("Thu, 17 Jun 2010 10:45:13 +0400" {396} =?utf-8?Q?=D0=98=D0=BD=D1=84=D0=BE=D1=80=D0=BC=D0=B0=D1=86=D0=B8=D0=BE=D0=BD=D0=BD=D0=BE=D0=B5=20=D1=81=D0=BE=D0=BE=D0=B1=D1=89=D0=B5=D0=BD=D0=B8=D0=B5=3A=20=D0=92=D0=BA=D0=BB=D1=8E=D1=87=D0=B5=D0=BD=D0=B8=D0=B5=20=D0=B8=20=D0=B2=D1=8B=D0=BA=D0=BB=D1=8E=D1=87=D0=B5=D0=BD=D0=B8=D0=B5=20=D0=BA=D0=BE=D0=BC=D0=BF=D0=BE=D0=BD=D0=B5=D0=BD=D1=82=D0=BE=D0=B2=20=D0=B7=D0=B0=D1=89=D0=B8=D1=82=D1=8B=00?= () () NIL ((NIL NIL "AVP" "aori.co.ru")) NIL NIL NIL "<d41d8cd98f00b204e9800998ecf8427e$am@adsl.TREVIS.local>") RFC822.SIZE 1250 FLAGS (\Recent) INTERNALDATE "17-Jun-2010 10:47:32 +0400") * 1391 FETCH (UID 1391 BODY[HEADER.FIELDS ("References" "X-Ref" "X-Priority" "X-MSMail-Priority" "X-MSOESRec" "Newsgroups")] {2} ENVELOPE ("Thu, 17 Jun 2010 10:45:16 +0400" {396} =?utf-8?Q?=D0=98=D0=BD=D1=84=D0=BE=D1=80=D0=BC=D0=B0=D1=86=D0=B8=D0=BE=D0=BD=D0=BD=D0=BE=D0=B5=20=D1=81=D0=BE=D0=BE=D0=B1=D1=89=D0=B5=D0=BD=D0=B8=D0=B5=3A=20=D0=92=D0=BA=D0=BB=D1=8E=D1=87=D0=B5=D0=BD=D0=B8=D0=B5=20=D0=B8=20=D0=B2=D1=8B=D0=BA=D0=BB=D1=8E=D1=87=D0=B5=D0=BD=D0=B8=D0=B5=20=D0=BA=D0=BE=D0=BC=D0=BF=D0=BE=D0=BD=D0=B5=D0=BD=D1=82=D0=BE=D0=B2=20=D0=B7=D0=B0=D1=89=D0=B8=D1=82=D1=8B=00?= () () NIL ((NIL NIL "AVP" "aori.co.ru")) NIL NIL NIL "<d41d8cd98f00b204e9800998ecf8427e$am@adsl.TREVIS.local>") RFC822.SIZE 1181 FLAGS (\Recent) INTERNALDATE "17-Jun-2010 10:47:34 +0400") * 1392 FETCH (UID 1392 BODY[HEADER.FIELDS ("References" "X-Ref" "X-Priority" "X-MSMail-Priority" "X-MSOESRec" "Newsgroups")] {2} ENVELOPE ("Thu, 17 Jun 2010 10:45:47 +0400" {222} =?utf-8?Q?=D0=9F=D1=80=D0=B5=D0=B4=D1=83=D0=BF=D1=80=D0=B5=D0=B6=D0=B4=D0=B5=D0=BD=D0=B8=D0=B5=3A=20=D0=A1=D0=BE=D0=BE=D0=B1=D1=89=D0=B5=D0=BD=D0=B8=D1=8F=20=D1=81=D0=B0=D0=BC=D0=BE=D0=B7=D0=B0=D1=89=D0=B8=D1=82=D1=8B=00?= () () NIL ((NIL NIL "AVP" "aori.co.ru")) NIL NIL NIL "<d41d8cd98f00b204e9800998ecf8427e$am@adsl.TREVIS.local>") RFC822.SIZE 1184 FLAGS (\Recent) INTERNALDATE "17-Jun-2010 10:48:06 +0400") * 1393 FETCH (UID 1393 BODY[HEADER.FIELDS ("References" "X-Ref" "X-Priority" "X-MSMail-Priority" "X-MSOESRec" "Newsgroups")] {26} X-Priority: 3 (Normal) ENVELOPE ("Thu, 17 Jun 2010 10:46:15 +0400" {25} =?koi8-r?B?z9PUwdTLyQ==?= (("=?koi8-r?B?7cXNzM/Oz9fBIPTB1NjRzsE=?=" NIL "tmm" "aori.co.ru")) (("=?koi8-r?B?7cXNzM/Oz9fBIPTB1NjRzsE=?=" NIL "tmm" "aori.co.ru")) NIL (("=?koi8-r?B?J/TB1NjRzsEg69LBxdfBJw==?=" NIL "tkraeva" "stdv.net")) NIL NIL {48} <F620DDB5017943EDA0829F3890D01AB9@labelle.local> "<000001cb0de8$ca576510$2c10a8c0@TREVIS.local>") RFC822.SIZE 10192 FLAGS (\Recent) INTERNALDATE "17-Jun-2010 10:48:34 +0400") * 1394 FETCH (UID 1394 BODY[HEADER.FIELDS ("References" "X-Ref" "X-Priority" "X-MSMail-Priority" "X-MSOESRec" "Newsgroups")] {2} ENVELOPE ("Thu, 17 Jun 2010 10:49:08 +0400" {33} Event from RMON Port 20 on unit 1 () () NIL ((NIL NIL "3com" "aori.co.ru")) NIL NIL NIL "<d41d8cd98f00b204e9800998ecf8427e$am@adsl.TREVIS.local>") RFC822.SIZE 525 FLAGS (\Recent) INTERNALDATE "17-Jun-2010 10:49:08 +0400") * 1395 FETCH (UID 1395 BODY[HEADER.FIELDS ("References" "X-Ref" "X-Priority" "X-MSMail-Priority" "X-MSOESRec" "Newsgroups")] {2} ENVELOPE ("Thu, 17 Jun 2010 10:48:29 +0400" {396} =?utf-8?Q?=D0=98=D0=BD=D1=84=D0=BE=D1=80=D0=BC=D0=B0=D1=86=D0=B8=D0=BE=D0=BD=D0=BD=D0=BE=D0=B5=20=D1=81=D0=BE=D0=BE=D0=B1=D1=89=D0=B5=D0=BD=D0=B8=D0=B5=3A=20=D0=92=D0=BA=D0=BB=D1=8E=D1=87=D0=B5=D0=BD=D0=B8=D0=B5=20=D0=B8=20=D0=B2=D1=8B=D0=BA=D0=BB=D1=8E=D1=87=D0=B5=D0=BD=D0=B8=D0=B5=20=D0=BA=D0=BE=D0=BC=D0=BF=D0=BE=D0=BD=D0=B5=D0=BD=D1=82=D0=BE=D0=B2=20=D0=B7=D0=B0=D1=89=D0=B8=D1=82=D1=8B=00?= () () NIL ((NIL NIL "AVP" "aori.co.ru")) NIL NIL NIL "<d41d8cd98f00b204e9800998ecf8427e$am@adsl.TREVIS.local>") RFC822.SIZE 1250 FLAGS (\Recent) INTERNALDATE "17-Jun-2010 10:50:02 +0400") * 1396 FETCH (UID 1396 BODY[HEADER.FIELDS ("References" "X-Ref" "X-Priority" "X-MSMail-Priority" "X-MSOESRec" "Newsgroups")] {2} ENVELOPE ("Thu, 17 Jun 2010 10:48:32 +0400" {396} =?utf-8?Q?=D0=98=D0=BD=D1=84=D0=BE=D1=80=D0=BC=D0=B0=D1=86=D0=B8=D0=BE=D0=BD=D0=BD=D0=BE=D0=B5=20=D1=81=D0=BE=D0=BE=D0=B1=D1=89=D0=B5=D0=BD=D0=B8=D0=B5=3A=20=D0=92=D0=BA=D0=BB=D1=8E=D1=87=D0=B5=D0=BD=D0=B8=D0=B5=20=D0=B8=20=D0=B2=D1=8B=D0=BA=D0=BB=D1=8E=D1=87=D0=B5=D0=BD=D0=B8=D0=B5=20=D0=BA=D0=BE=D0=BC=D0=BF=D0=BE=D0=BD=D0=B5=D0=BD=D1=82=D0=BE=D0=B2=20=D0=B7=D0=B0=D1=89=D0=B8=D1=82=D1=8B=00?= () () NIL ((NIL NIL "AVP" "aori.co.ru")) NIL NIL NIL "<d41d8cd98f00b204e9800998ecf8427e$am@adsl.TREVIS.local>") RFC822.SIZE 1181 FLAGS (\Recent) INTERNALDATE "17-Jun-2010 10:50:04 +0400") * 1397 FETCH (UID 1397 BODY[HEADER.FIELDS ("References" "X-Ref" "X-Priority" "X-MSMail-Priority" "X-MSOESRec" "Newsgroups")] {2} ENVELOPE ("Thu, 17 Jun 2010 10:39:41 +0400" {45} =?koi8-r?B?8sXF09TS2SDEzNEg58zF2sXSwSDsLvcu?= (("=?koi8-r?B?68HMydTB?=" NIL "kalita" "aha.ru")) (("=?koi8-r?B?68HMydTB?=" NIL "kalita" "aha.ru")) NIL ((NIL NIL "aori" "co.ru")) NIL NIL NIL "<000901cb0de7$de5c0490$57debdd5@scrvol>") RFC822.SIZE 474992 FLAGS (\Recent) INTERNALDATE "17-Jun-2010 10:50:11 +0400") * 1398 FETCH (UID 1398 BODY[HEADER.FIELDS ("References" "X-Ref" "X-Priority" "X-MSMail-Priority" "X-MSOESRec" "Newsgroups")] {2} ENVELOPE ("Thu, 17 Jun 2010 14:37:36 +0800" {49} =?koi8-r?B?7sHbySDV08zVx8kg3MvPzs/N0dQgzsHMz8fJ?= (("=?koi8-r?B?88HGz87P18Eg5M/NzsE=?=" NIL "angelica" "pop3.ru")) (("=?koi8-r?B?88HGz87P18Eg5M/NzsE=?=" NIL "angelica" "pop3.ru")) ((NIL NIL "stabilizerxq6" "pochta.ru")) (("3dloz" NIL "3dloz" "aori.co.ru")) NIL NIL NIL "<gxu74nipv52147pgrw@localhost.localdomain>") RFC822.SIZE 9689 FLAGS (\Recent abs_spam) INTERNALDATE "17-Jun-2010 10:50:13 +0400") 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;INBOX;kj08 OK UID FETCH completed 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;IN;212;1276;INBOX;lo2u UID FETCH 1:1387 (UID FLAGS) 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;INBOX;* 1 FETCH (UID 1 FLAGS (\Seen abs_spam)) * 2 FETCH (UID 2 FLAGS (\Seen abs_spam)) * 3 FETCH (UID 3 FLAGS (\Seen abs_spam)) ... * 1373 FETCH (UID 1373 FLAGS (\Seen)) * 1374 FETCH (UID 1374 FLAGS (\Seen)) * 1375 FETCH (UID 1375 FLAGS (\Seen abs_spam)) * 1376 FETCH (UID 1376 FLAGS (\Seen abs_spam)) * 1377 FETCH (UID 1377 FLAGS (\Seen abs_spam)) * 1378 FETCH (UID 1378 FLAGS (\Seen abs_spam)) * 1379 FETCH (UID 1379 FLAGS (\Seen)) * 1380 FETCH (UID 1380 FLAGS (\Seen clear)) * 1381 FETCH (UID 1381 FLAGS (\Seen)) * 1382 FETCH (UID 1382 FLAGS ()) * 1383 FETCH (UID 1383 FLAGS ()) * 1384 FETCH (UID 1384 FLAGS ()) * 1385 FETCH (UID 1385 FLAGS ()) * 1386 FETCH (UID 1386 FLAGS ()) * 1387 FETCH (UID 1387 FLAGS (abs_spam)) 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;INBOX;lo2u OK UID FETCH completed 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;IN;212;1276;INBOX;acxh IDLE 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;INBOX;+ idling (safemail@aori.co.ru/INBOX) 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;INBOX;* 1399 EXISTS * 1 RECENT 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;INBOX;acxh OK IDLE completed 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;IN;212;1276;INBOX;laac UID FETCH 1399:* (BODY.PEEK[HEADER.FIELDS (References X-Ref X-Priority X-MSMail-Priority X-MSOESRec Newsgroups)] ENVELOPE RFC822.SIZE UID FLAGS INTERNALDATE) 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;INBOX;* 1399 FETCH (UID 1399 BODY[HEADER.FIELDS ("References" "X-Ref" "X-Priority" "X-MSMail-Priority" "X-MSOESRec" "Newsgroups")] {2} ENVELOPE ("Thu, 17 Jun 2010 10:50:19 +0400" {22} Event from 18-Mixeenko () () NIL ((NIL NIL "3com" "aori.co.ru")) NIL NIL NIL "<d41d8cd98f00b204e9800998ecf8427e$am@adsl.TREVIS.local>") RFC822.SIZE 505 FLAGS (\Recent) INTERNALDATE "17-Jun-2010 10:50:19 +0400") 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;INBOX;laac OK UID FETCH completed 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;IN;212;1276;INBOX;od7b IDLE 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;INBOX;+ idling (safemail@aori.co.ru/INBOX) 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;OUT;212;1276;INBOX;od7b OK IDLE completed 2010-06-17 10:50:16;192.168.16.14;safemail@aori.co.ru;IN;212;1276;INBOX;oeve UID STORE 1382:1398 +FLAGS.SILENT (\Seen)
wikipost
matveeva17.06.2010 10:14
И при приеме почты задержки двухминутные:
11:12:26 SMTP (3444): MAIL FROM:<adolf@mpsystems.ru> 11:14:14 SMTP (3444): 250 adolf@mpsystems.ru OK ... 11:16:07 SMTP (3444): MAIL FROM:<xidefesixyfif@school-sale.ru> 11:17:53 SMTP (3444): 250 xidefesixyfif@school-sale.ru OK
wikipost
matveeva17.06.2010 10:19
ред: 17.06.2010 10:26
В acSmtp вот что:
SNMP:starting... acSMTP/4.23 SNMP server: 25 : db3_exec:maxwait:insert into sp_messages(clsid,uid,flags,created_t,modified_t, lastused_t,size,parid,ip,uri, subject,tags,refs,hits,votes, body,file,author,hash,ctype,due_t) values(1,:uid,32, 1276757427,1276757427,1276757427, 1184,:parid,:ip,:uri, :subject,'',0,0,0, :body,'..\DATA\mail\arc4\201006\2010-06-17\\\AVP Server!23!584777984!1.eml',:author,:hash,:ctype,:due) INJ ERR:30114 db3_prepare:maxwait:SELECT name FROM sqlite_master WHERE name like 'sp_meta%' ORDER BY name Can't add message into index. ERR=30114 db3_prepare:maxwait:SELECT name FROM sqlite_master WHERE name like 'sp_meta%' ORDER BY name Can't add message into index. ERR=30114 db3_prepare:maxwait:SELECT name FROM sqlite_master WHERE name like 'sp_meta%' ORDER BY name Can't add message into index. ERR=30114 db3_prepare:maxwait:SELECT name FROM sqlite_master WHERE name like 'sp_meta%' ORDER BY name Can't add message into index. ERR=30114 db3_prepare:maxwait:SELECT name FROM sqlite_master WHERE name like 'sp_meta%' ORDER BY name Can't add message into index. ERR=30114 db3_prepare:maxwait:SELECT name FROM sqlite_master WHERE name like 'sp_meta%' ORDER BY name Can't add message into index. ERR=30114 db3_prepare:maxwait:SELECT name FROM sqlite_master WHERE name like 'sp_meta%' ORDER BY name Can't add message into index. ERR=30114 db3_prepare:maxwait:SELECT name FROM sqlite_master WHERE name like 'sp_meta%' ORDER BY name Can't add message into index. ERR=30114 db3_prepare:maxwait:SELECT name FROM sqlite_master WHERE name like 'sp_meta%' ORDER BY name Can't add message into index. ERR=30114 db3_prepare:maxwait:SELECT name FROM sqlite_master WHERE name like 'sp_meta%' ORDER BY name Can't add message into index. ERR=30114 db3_prepare:maxwait:SELECT name FROM sqlite_master WHERE name like 'sp_meta%' ORDER BY name Can't add message into index. ERR=30114 db3_prepare:maxwait:SELECT name FROM sqlite_master WHERE name like 'sp_meta%' ORDER BY name Can't add message into index. ERR=30114 db3_prepare:maxwait:SELECT name FROM sqlite_master WHERE name like 'sp_meta%' ORDER BY name Can't add message into index. ERR=30114

Перезапуск acImap messages.db3-journal не удалил. После перезапуска acSmtp messages.db3-journal пропал
wikipost
ac17.06.2010 10:59
Это уже с новыми exe?!

matveeva пишет: \AVP Server!23!584777984!1.eml

А у вас случайно AVP этот не на машине ли с Eserv'ом стоит? Не задавал раньше этот стандартный вопрос про вредителей-резидентов, т.к. знаю, что вы брали спец.антивирус для Eserv.

matveeva пишет: Вот выделение 295 писем и "помечание" их как прочтенные шло более минуты (таймаут стоит на 60 сек). А сейчас вот 18 писем из этого несчастного ящика safemail метились почти 2 минуты, а потом больше двух минут еще одно письмо оттуда же. POP3 в это время не работал. Комп не вис, окна откликались...

Да, это совершенно ненормально гигантские задержки. Собственно и все эти ошибки 30114 и пр. говорят о том же. Надо искать, кто мешает файловой работе.

P.S. Еще одно обновление — http://www.eserv.ru/download/E4_exe_upd2.rar — исправление небольшой свежевнесенной ошибки (при переносе писем между папками может возникать ошибка 30007, она не мешает переносу, но все равно неправильная .
wikipost
matveeva17.06.2010 11:19
ред: 17.06.2010 11:26
ac пишет: Это уже с новыми exe?!
Да, с новыми. Переписала, перезапустила службы.
ac пишет: А у вас случайно AVP этот не на машине ли с Eserv'ом стоит?
Нет, там только штатный Eserv-ный. Другого антивируса нет.
ac пишет: Еще одно обновление
Закачала, сейчас поставлю.
wikipost
matveeva17.06.2010 12:57
Ну вот еще раз подвисла на операциях с письмами в папке, но потом выкарабкалась. В acSmtp (не знаю, правда, это ли время) вот что:
SNMP server: 25 : db3_exec:maxwait:commit; INJ ERR:30114 FERE err:30005

И почему-то на 2 мин подвисает acSmtp при приеме почты (писала выше)
13:50:09 SMTP (3848): 250 OK message accepted for delivery (alias or copy) 13:50:09 pop3 (3848): DELE 1 13:50:09 pop3 (3848): +OK marked deleted 13:50:09 SMTP (3848): QUIT 13:52:05 SMTP (3848): 221 Goodbye. 13:52:05 pop3 (3848): QUIT 13:52:05 pop3 (3848): +OK CommuniGate Pro POP3 Server connection closed

И еще сейчас интересно: удалила из папки "Входящие" штук 15 писем (все, какие были на тот момент). Перехожу в др. папку. Пишет "удаление сообщений..", потом таймаут, проблемы с IMAP сервером и т.д. А в папке INBOX Этого ящика сейчас находится 1007 файлов вида .messages.db3-mj00B28371. Ну и ящик не откликается уже. Остальные работают..
wikipost
ac18.06.2010 08:53
У вас удаление настроено в клиенте как "пометка к удалению" или "перенос в папку Удаленные"? Во втором случае (обычно в Outlook так по умолчанию) там в итоге на сервере запускается копирование файлов и изучение их acFilter'ом, т.е. ненужная работа ради перестраховки от случайного удаления, это на порядок дольше, чем просто пометки (хоть acFilter работает асинхронно, не внося своих задержек в обработку команды непосредственно, но все равно раз параллельно дёргает процессор и диск, то весь процесс замедляет). Впрочем при 15 письмах это не проблема...
По поводу пометок как (не)прочитано, удалено и прочих флагов — в этой версии оптимизировал немного работу sqlite с индексом events. У меня пометки ставятся со скоростью 2000 писем в секунду (проверял на папке с 20 000 писем — 10 секунд, как для пометки "прочитано", так и для удаления (без копирования в Удаленные)).

http://www.eserv.ru/download/E4_exe_upd3.rar

":maxwait:" включал для проверки задержек и автоматической отмены операции при задержках — поэтому SMTP у вас "зависает на 2 минуты", а не навечно как в начале темы. Но это конечно не выход, надо всё-таки не выход, т.к. :maxwait: может привести к недоставке почты вообще (принятое письмо не добавится в индекс). Поэтому в этой версии снова без :maxwait:. Посмотрим, поможет ли оптимизация.
Обратите внимание, в это тестовое обновление вошел и acFilter — добавлена еще пара общепринятых названий папок удаленных и нежелательных писем, чтобы acFilter их игнорировал.
wikipost
matveeva18.06.2010 09:27
ac пишет: У вас удаление настроено в клиенте как "пометка к удалению" или "перенос в папку Удаленные"?
Я в ОЕ не нашла такой настройки. Стоит галка на "Уничтожить удаленные сообщения при выходе из папок IMAP". В клиенте ТВ действительно есть такая отдельная настройка, что делать с удаленными. Я там сразу на всех ставила "сразу удалять", но на ТВ тоже все висло.
А что делать с 1000 образовавшихся сообщений "\DATA\domains\aori.co.ru\spamloop\INBOX\.messages.db3-mj00B28371 " ? Они сами не уничтожились. Ручками? Они же не нужны?
Обновление поставлю, буду смотреть.
wikipost
matveeva18.06.2010 10:32
ac пишет: оптимизировал немного работу sqlite с индексом events
Да, заметно быстрее все стало метиться и удаляться при переходе из папки
wikipost
ac18.06.2010 11:52
matveeva пишет: Я в ОЕ не нашла такой настройки.

Должно быть что-то похожее на это: windowsmail imap settings или этого http://www.eserv.ru/WindowsMail (OutlookExpress переименовали в WindowsMail).
matveeva пишет: А что делать с 1000 образовавшихся сообщений "\DATA\domains\aori.co.ru\spamloop\INBOX\.messages.db3-mj00B28371

Это не сообщения, это рабочие файлы sqlite'а (типа тех rollback-журналов *.db3-journal). Да, если он сам их не прибил, значит придётся за ним почистить.
wikipost
matveeva18.06.2010 12:29
ac пишет: Должно быть что-то похожее на это:
Это , видимо, новая версия. У меня ОЕ6, там эта закладка называется "Обслуживание" и не имеет такого развернутого окна с настройками.
ac пишет: придётся за ним почистить.
не проблема.
wikipost
matveeva01.07.2010 11:10
Опять сейчас был завис в ОЕ на моем "любимом ящике" safemail. В итоге во входящих оказалось более 16000 писем вида db3-mj... После всех манипуляций с удалением файлов-остановом служб-запуском, в папке spool остались письма порядка 10 шт. для отправки наружу. Можно ли их отправить, не переделывая письмо? Пробовала положить их в папку OUT, но они с ошибкой 11001 не уходят. Что-то в них добавить надо?
wikipost
pig01.07.2010 11:40
В самое начало — заголовок For: с указанием получателя.
wikipost
matveeva01.07.2010 11:40
Получилось вроде. В OUT положила письмо не из SPOOL, а из \DATA\mail\arc4\201007\2010-07-01\aori.co.ru\ Там впереди стоит For: адрес. Я так пыталась первый раз отправить из SPOOL, проставив там руками, но в ответ получила 11001.
wikipost
matveeva01.07.2010 11:41
pig пишет: В самое начало — заголовок For: с указанием получателя.
Не успела немножко.. Делала так, не получилось...
wikipost
ac01.07.2010 13:50
Наверное была какая-нибудь опечатка в "For:" (если есть лог с той ошибкой 11001, то можно проверить, в чем дело было).

matveeva пишет: более 16000 писем вида db3-mj

Значит обновление от 26 июня проблему db3-mj не решило? Тогда остается уповать на новую версию sqlite, запущенную на днях в бете 4.24. У нас на сайте бета работает без нареканий, но у нас и предыдущая вела себя примерно...
wikipost
ac01.07.2010 14:02
Эти db3-mj — это не письма, а sqlite'овские "главные журналы" — используются, когда в транзакции участвуют более одного файла БД (у нас так и есть — .messages.db3 и .headers.db3 обновляются совместно). Правда этот mj в норме всего один. Размножение их — sqlite'ный баг, который в версии 3.7.0 должен быть исправлен. А я в последних обновлениях Eserv/4.23 пытался выкрутиться — оставить группировку insert'ов в транзакции (которые у вас в частности дают ускорение IMAP), но не попадаться на этот баг. Значит не выходит, надо теперь ставить 3.7.0 и изучать этот момент с пристрастием.

Вы в этой беде не одинока — вчера был репорт про 300 000 (!) db3-mj
wikipost
matveeva01.07.2010 14:20
ред: 01.07.2010 14:21
ac пишет: Значит обновление от 26 июня проблему db3-mj не решило?
Да вроде все работало... Сегодня что-то споткнулось... Непонятки...
ac пишет: Эти db3-mj — это не письма
Оговорилась, хотела сказать файлов. Я помню, Вы уже объясняли про эти файлики.
wikipost
matveeva02.07.2010 13:59
ac пишет: Наверное была какая-нибудь опечатка в "For:" (если есть лог с той ошибкой 11001, то можно проверить, в чем дело было).
Письма для лок. получателей, положенные в OUT не доставляются.
Вот начало письма из \DATA\mail\arc4\...
For: avp@aori.co.ru Message-ID: <d41d8cd98f00b204e9800998ecf8427e$am@aori.co.ru> Received: from [192.168.16.1] (port=3142 helo=SERVER2003) by aori.co.ru (acSMTP/4.23.4769) with ESMTP id 4489.0.466912 (envelope-from <AVP Server>) for <avp@aori.co.ru>; Fri, 02 Jul 2010 12:27:23 +0400 To: <AVP@aori.co.ru>

Вот так оно пыталось отправиться
<?xml version='1.0' encoding='windows-1251'?> <?xml-stylesheet href='atom.xsl' type='text/xsl'?> <feed version='0.3' xmlns='http://purl.org/atom/ns#' xmlns:s='http://www.eserv.ru/smtpsend/log'> <title>SMTPSEND v4.63 log</title> <modified>2010-07-02T14:48:56Z</modified> <author><name>smtpsend4 at aori.co.ru</name></author> <!-- -dw -ln 200 -r 2 -helo aori.co.ru -rd ..\DATA\mail\out\127.0.0.1\25 -ra postmaster@aori.co.ru -rf ..\DATA\mail\retry_outbound\ -o ..\DATA\temp\2010-07-02\outbound-0!6712703!1_log.xml -f ..\DATA\mail\out\ --> <s:dns>Checking DNS settings. Server: 194.85.128.10 DNS settings seem OK</s:dns> <entry> <link rel='alternate' type='message/rfc822' href='AVP Server!4489!867719000!1.eml' /> <id><![CDATA[d41d8cd98f00b204e9800998ecf8427e$am@aori.co.ru]]></id> <author><name></name><email>AVP Server</email></author> <summary>To: AVP@aori.co.ru </summary> <title>Предупреждение: Сообщения самозащиты</title> <s:to>avp@aori.co.ru</s:to> <s:mx>MX: aori.co.ru</s:mx> <content type='text/plain'><![CDATA[ ]]></content> <s:ior>Error: 10060 </s:ior> <s:err>ERR:10060 </s:err> <s:result>FAILED. Error code: 10060 </s:result> </entry> <s:exit>Exit error cnt:1 </s:exit></feed>

Какой-то затык с почтой поутру был, перестала отправляться от нас и в ящики к лок. юзерам не ложилась, все письма накопились в SPOOL. Что было- не знаю, в журналах ошибок нет, вроде... Попыталась их еще раз отправить через OUT- наружу ушли, а по лок. пользователям не разошлись.
wikipost
ac02.07.2010 14:54
matveeva пишет: MX: aori.co.ru
Error: 10060

Таймаут при связи с aori.co.ru! Он локально в правильный IP резолвится? "telnet aori.co.ru 25" на машине с Eserv соединяется? Или Eserv в тот момент совсем висел...

matveeva пишет: Какой-то затык с почтой поутру был, перестала отправляться от нас и в ящики к лок. юзерам не ложилась

Это на бете 4.24, или вы не ставили её?
wikipost
matveeva02.07.2010 15:10
ac пишет: "telnet aori.co.ru 25" на машине с Eserv соединяется?
Неа... ПО локальному имени,по лок. IP и по доменному имени в лок.сети норм. соединяется. А aori.co.ru не откликается.... Что надо сделать? А как же он уверенно шлет внутреннюю почту между юзерами?
ac пишет: Или Eserv в тот момент совсем висел..
Нет, не висел, работал.
ac пишет: Это на бете 4.24, или вы не ставили её?
Нет, бету не ставила, на страом с о всеми обновлениями.
wikipost
ac02.07.2010 15:30
matveeva пишет: А aori.co.ru не откликается...

Любопытно. Отсюда соединяется. А "ping aori.co.ru" на машине с Eserv работает? У вас вообще этот IP назначен машине с Eserv или роутеру?

matveeva пишет: А как же он уверенно шлет внутреннюю почту между юзерами?

Юзеры-то соединяются с ним по локальному имени и IP. Aori.co.ru из ЛС и не обязан быть доступным.
wikipost
pig02.07.2010 15:38
matveeva пишет: Письма для лок. получателей, положенные в OUT не доставляются

Вообще-то письмам, отправленным на локальные адреса, в OUT делать нечего, они должны напрямую по ящикам раскладываться.
wikipost
matveeva02.07.2010 16:02
pig пишет: Вообще-то письмам, отправленным на локальные адреса, в OUT делать нечего, они должны напрямую по ящикам раскладываться.
Должны, но вот затык случился, и они попали в SPOOL и там и остались. Это от внешних отправителей для нас почта была.
ac пишет: А "ping aori.co.ru" на машине с Eserv работает? У вас вообще этот IP назначен машине с Eserv или роутеру?
ping попробую только в понед. уже. А IP назначен ADSL-модему, который входит в машину с Eserv. И провайдер на нем пробрасывает запрошенные порты внутрь, на Eserv.
wikipost
pig02.07.2010 17:34
С большой вероятностью модем не умеет заворачивать исходящий трафик на себя и далее через проброс. Или просто отказывается маршрутизировать пакеты (получаем 10060), или принимает на себя (10061, но может быть и 10060).
wikipost
ac02.07.2010 19:28
Да. Тогда до решения этой задачи (доступности с Eserv'а его внешнего IP) эту почту из spool можно складывать не в mail\out, а в mail\out\127.0.0.1\25\, чтобы Eserv через localhost её отправил.
wikipost
pig02.07.2010 19:50
Можно обмануть непосредственно машину с Eserv (не трогая DNS), прописав в system32\drivers\etc\hosts ассоциацию имени с подходящим сетевым интерфейсом, хоть с тем же 127.0.0.1.
wikipost
matveeva05.07.2010 08:53
pig пишет: С большой вероятностью модем не умеет заворачивать исходящий трафик на себя и далее через проброс.
Согласна.
ac пишет: эту почту из spool можно складывать не в mail\out, а в mail\out\127.0.0.1\25\
Вполне подходящее решение, я просто не знала.
pig пишет: Можно обмануть непосредственно машину с Eserv (не трогая DNS), прописав в system32\drivers\etc\hosts ассоциацию имени с подходящим сетевым интерфейсом, хоть с тем же 127.0.0.1.
Да, тоже вариант. Хотя, если письма заберутся из mail\out\127.0.0.1\25\, то этого вполне достаточно.
wikipost
Работает на Eserv/5.05567 (10.02.2020)