42 ответов в этой теме

[zirro.s]

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

Столкнулся со следующими "непонятками" при экслуатации связки Exim 4.72 и drweb для почтовых серверов Unix (версия 6.0.1). ОС FreeBSD 8.1 x32.

При старте монитора в журнале появляются следующие записи.

monitor ERROR in parse client response: ConfigClient::invalid response type ->842149920
monitor ERROR error for address(es) [local:/var/drweb/ipc/.agent]: ConfigClient::invalid response type ->842149920
monitor ERROR cannot read maild data from drweb-agent: ConfigClient::invalid response type ->842149920

при этом вроде как все компонеты drweb'а стартуют

ps ax | grep drw
15163 ?? Ss 0:17,70 /usr/local/drweb/drwebd
16167 ?? I 0:00,07 /usr/local/drweb/drwebd
16270 ?? I 0:00,09 /usr/local/drweb/drwebd
16293 ?? Ss 38:38,52 /usr/local/drweb/drweb-monitor -c /usr/local/etc/drweb/monitor.conf
16294 ?? Is 0:00,17 /usr/local/drweb/drweb-agent -c /usr/local/etc/drweb/agent.conf
16296 ?? Is 0:01,31 /usr/local/drweb/drweb-notifier local:/var/drweb/ipc/.agent
16297 ?? Is 0:00,07 /usr/local/drweb/drweb-sender local:/var/drweb/ipc/.agent
16298 ?? Is 0:00,23 /usr/local/drweb/drweb-maild local:/var/drweb/ipc/.agent
16299 ?? Is 0:00,06 /usr/local/drweb/drweb-receiver local:/var/drweb/ipc/.agent
16727 ?? I 0:00,00 /usr/local/drweb/drwebd

и почта между пользователями ходит (и судя по логам проверяется)

Второй вопрос. Для того чтобы мне на почту приходили уведомления об обновлении в файле drweb32.ini прописал параметр

MailCommand = "/usr/local/sbin/exim -i -bm -f drweb-daemon@kio.ru -- root@kio.ru"

Однако письма на адрес root@kio.ru не приходят, а в логах Exim'а следующие сообщения:

<= drweb@kio.ru U=root P=drweb-scanned S=1369 id=E1PQETS-0004Bn-Hg@mail.kio.ru T="Cron <drweb@mail> /usr/local/drweb/update.pl --ini=/usr/local/etc/drweb/drweb32.ini" from <drweb@kio.ru> for drweb@kio.ru

то есть, несмотря на то что указано отправлять сообщения от отправителя drweb-daemon@kio.ru на адрес root@kio.ru, сообщения посылаются от drweb@kio.ru на адрес drweb@kio.ru.

И следующий вопрос: как уже писал выше, почта между внутренними пользователями ходит нормально. Хочу чтобы maild посылал мне ежедневные (еженедельные, ежемесячные) отчёты. Для этого в файле maild-exim.conf в секции [Reports] указал следующие параметры:

Send = yes
SendTimes = 01:03:39-24h, 1w/01:07:21-7d, 2M/21:23:32-31d
Mail = root@kio.ru
Names = drweb,vaderetro

в секции [Sender]

MailerName = Exim
Address = /usr/local/sbin/exim
Options =


В файле monitor.conf установлено:


InjectCmd = "/usr/local/sbin/exim -t"

В итоге логи exim'а вообще пусто (то есть до exim'а) такие сообщения вообще не доходят, а в логе maild следующее:

Wed Dec 8 10:36:00 2010 [0x8227d80] notifier DEBUG SendReport: id= start_time=1291707360 required_period=86399
Wed Dec 8 10:36:00 2010 [0x8227d80] notifier INFO Send 'report' from 2010-Dec-07 10:36:00 to 2010-Dec-08 10:35:59; rcpts: root@kio.ru...
Wed Dec 8 10:36:00 2010 [0x8227d80] notifier.parser INFO select template report:report for instant
Wed Dec 8 10:36:00 2010 [0x8227d80] notifier DEBUG new mail msg:
Wed Dec 8 10:36:00 2010 [0x8227d80] notifier DEBUG ******* ENVELOPE *********
Wed Dec 8 10:36:00 2010 [0x8227d80] notifier DEBUG FROM: <{root@kio.ru}>
Wed Dec 8 10:36:00 2010 [0x8227d80] notifier DEBUG TO: <root@kio.ru>
Wed Dec 8 10:36:00 2010 [0x8227d80] notifier DEBUG ****** END ENVELOPE ******
Wed Dec 8 10:35:18 2010 [0x815e000] sender INFO add 11 number of mails for sending
Wed Dec 8 10:36:00 2010 [0x815e480] sender DEBUG notifier.y5uYkP Sender::processor: read from socket mail dir: /var/drweb/msgs/tmp/notifier.y5uYkP/ opt=1 timeout=120
Wed Dec 8 10:36:00 2010 [0x815e480] sender INFO notifier.y5uYkP start processing msg...
Wed Dec 8 10:36:00 2010 [0x815e480] sender.thr1 DEBUG notifier.y5uYkP SetMeBusy: set what i busy (1)
Wed Dec 8 10:36:00 2010 [0x815e480] sender DEBUG notifier.y5uYkP ******* ENVELOPE *********
Wed Dec 8 10:36:00 2010 [0x815e480] sender DEBUG notifier.y5uYkP FROM: <{root@kio.ru}>
Wed Dec 8 10:36:00 2010 [0x815e480] sender DEBUG notifier.y5uYkP TO: <root@kio.ru>
Wed Dec 8 10:36:00 2010 [0x815e480] sender DEBUG notifier.y5uYkP ****** END ENVELOPE ******
Wed Dec 8 10:36:00 2010 [0x815e480] sender DEBUG notifier.y5uYkP read mail msg...... ok
Wed Dec 8 10:36:00 2010 [0x815e480] sender.queue INFO notifier.y5uYkP put msg [from: <{root@kio.ru}>; to:<root@kio.ru>] to queue for processing after 0 sec
Wed Dec 8 10:36:00 2010 [0x815e480] sender.queue DEBUG notifier.y5uYkP AddNewRequest: add new request = /var/drweb/msgs/out/x/absLVr0Wx/ (sending_number=0)
Wed Dec 8 10:36:00 2010 [0x815e480] sender.thr1 DEBUG SetMeFree: set what i free (0) time=-1
Wed Dec 8 10:36:00 2010 [0x815e480] sender.thr1 DEBUG thread 1 in lower part (2): infinite timeout
Wed Dec 8 10:36:00 2010 [0xae876c0] maild INFO success send report request to drweb-notifier component; report size=9759; Client=
Wed Dec 8 10:36:00 2010 [0xae876c0] maild DEBUG timeout_thread: success send report about '' Client
Wed Dec 8 10:36:00 2010 [0xae876c0] maild DEBUG DwTimesSet: update new date=2010-Dec-09 10:36:00 lo=2010-Dec-08 10:36:00 with type=3/0
Wed Dec 8 10:36:00 2010 [0xae876c0] maild DEBUG timeout_thread: next timeout after 258 sec by task dump_cache_stat
Wed Dec 8 10:36:00 2010 [0x8227d80] notifier INFO success save new msg [from: <{root@kio.ru}>; to:<root@kio.ru>; size=9759] to disk. Send it...
Wed Dec 8 10:36:00 2010 [0x8227d80] notifier INFO success send request for send msg [from: <{root@kio.ru}>; to:<root@kio.ru>; size=9759]
Wed Dec 8 10:36:00 2010 [0x8227d80] notifier INFO Success send report
Wed Dec 8 10:36:00 2010 [0x8227d80] notifier.thr1 DEBUG thread 2 in lower part (2): infinite timeout
Wed Dec 8 10:36:00 2010 [0x8227d80] notifier.thr1 DEBUG SetMeFree: set what i free (0) time=-1
Wed Dec 8 10:36:00 2010 [0x815ea20] sender.thr2 DEBUG SetMeBusy: set what i busy (1)
Wed Dec 8 10:36:00 2010 [0x815ea20] sender INFO absLVr0Wx get msg [from: <{root@kio.ru}>; to:<root@kio.ru>] from queue for processing...
Wed Dec 8 10:36:00 2010 [0x815ea20] sender DEBUG absLVr0Wx success load file /var/drweb/msgs/out/x/absLVr0Wx/.msg with size 9759
Wed Dec 8 10:36:00 2010 [0x815ea20] sender.pipe DEBUG absLVr0Wx SendMailByDeliver: object = /usr/local/sbin/exim -i -bm -oMr drweb-scanned -f <{root@kio.ru}> -- <root@kio.ru>
Wed Dec 8 10:36:00 2010 [0x815ea20] sender.pipe DEBUG absLVr0Wx pid of process = 16301
Wed Dec 8 10:36:00 2010 [0x815ea20] sender.pipe DEBUG absLVr0Wx skip convert of eol
Wed Dec 8 10:36:00 2010 [0x815ea20] sender.pipe DEBUG absLVr0Wx SendMailByDeliver:success send 9759 bytes of message by pipe
Wed Dec 8 10:36:00 2010 [0x815ea20] sender.pipe DEBUG absLVr0Wx SendMailByDeliver: read 0 from stdout sock
Wed Dec 8 10:36:00 2010 [0x815ea20] sender.pipe WARN absLVr0Wx normal termination, exit status = 1; was error (pid=16301)
Wed Dec 8 10:36:00 2010 [0x815ea20] sender.MailDeliverSystem WARN absLVr0Wx fail to send message at 1 time (number of rcpts to send=1)



То есть, как я понимаю, не удалось отправить сообщение. Однако причин этого из лога не могу понять.

Сообщение было изменено zirro.s: 08 Декабрь 2010 - 11:46

[Anton Ivanov]

ответ на третий вопрос:

у Вас неверное задан адрес отправителя:
"{root@kio.ru}"


в результате exim не принимает такие письма (естественно).

проверьте свои настройки - прежде всего FilterMail - что в ней?

[Anton Ivanov]

по второму вопросу:
данные письма высылают не продукты Dr.Web, а cron - соответственно в нем и надо пробовать настроит - например установиви переменную MAILTO (подробнее в man cron).

Сообщение было изменено Anton Ivanov: 08 Декабрь 2010 - 12:40

[Gennady Proskurin]

по первому вопросу - хотелось бы увидеть дебаг лог агента, с параметрами:
Level = debug
IPCLevel = debug

[zirro.s]

у Вас неверное задан адрес отправителя:
"{root@kio.ru}"

Извиняюсь, мой косяк. Скопировал параметр и не убрал скобки  . Сейчас всё заработало.

А что по поводу остальных вопросов? И ещё родился по работе со статистикой. Для начала хотел бы использовать сервер статистики stat.drweb.com. Сгенерировал md5 сумму для файла drweb32.key. Настроил в файле maild-exim.conf секцию [Stat]

Detail = low
Send = yes
SendPeriod = 10m

В файле agent.conf

[StandaloneMode]
StatisticsServer = stat.drweb.com:80/update
StatisticsUpdatePeriod = 10
UUID = 635751191f2f03c3fee6e20e46b2e42c

Как проверить отправляется ли статистика? Как посмотреть эту статистику? Набираю в браузере адрес http://stat.drweb.com/view/635751191f2f03c3fee6e20e46b2e42c/

И получаю:

Ошибка обработки запроса
Неверный идентификатор пользователя.  

Если Вам не удаётся решить возникшую проблему самостоятельно, обратитесь в службу технической поддержки.

Если можно опишите вкратце логику работы модуля статистики. Мой сервер каждые StatisticsUpdatePeriod минут посылает что-то (что? письмо, пакет ещё что-то, как посмотреть что послалось и куда) на адрес StatisticsServer. Как на StatisticsServer появляется страничка с моим UUID? 

Сообщение было изменено zirro.s: 08 Декабрь 2010 - 12:57

[zirro.s]

по второму вопросу:
данные письма высылают не продукты Dr.Web, а cron - соответственно в нем и надо пробовать настроит - например установиви переменную MAILTO (подробнее в man cron).

Хотел сразу настроить cron, но смутил параметр MailCommand. Не совсем понятно для чего от тогда нужен.

P.S. cron поправлю

Сообщение было изменено zirro.s: 08 Декабрь 2010 - 13:01

[Anton Ivanov]

"смутил параметр MailCommand. Не совсем понятно для чего от тогда нужен."

он например используется для уведомления о приближении срока истечения времени действия ключа

[Paul Graphov]

В файле agent.conf

[StandaloneMode]
StatisticsServer = stat.drweb.com:80/update
StatisticsUpdatePeriod = 10
UUID = 635751191f2f03c3fee6e20e46b2e42c

Как проверить отправляется ли статистика? Как посмотреть эту статистику? Набираю в браузере адрес http://stat.drweb.com/view/635751191f2f03c3fee6e20e46b2e42c/

И получаю:

Ошибка обработки запроса
Неверный идентификатор пользователя.  

Если Вам не удаётся решить возникшую проблему самостоятельно, обратитесь в службу технической поддержки.

Если можно опишите вкратце логику работы модуля статистики. Мой сервер каждые StatisticsUpdatePeriod минут посылает что-то (что? письмо, пакет ещё что-то, как посмотреть что послалось и куда) на адрес StatisticsServer. Как на StatisticsServer появляется страничка с моим UUID? 

Почему stat.drweb.com ругается на md5sum Вашего ключа - обратитесь в тех. поддержку.

UUID можно явно не указывать в конфиге - агент посчитает его из ключа сам. Если ключей несколько, или они меняются, то следует указать явно.

У агента в логе (с Level=debug) должно быть нечто подобное:

20101109.162628.11 db2 [31386 31391] noname [HTTP] Requesting #1/5 http://stat.drweb.com:80/update (via stat.drweb.com:80 as HTTP/1.1)
20101109.162628.14 db3 [31386 31391] noname [HTTP] About to connect() to stat.drweb.com port 80 (#0)
20101109.162628.14 db3 [31386 31391] noname [HTTP] Trying 83.222.3.90...
20101109.162628.15 db3 [31386 31391] noname [HTTP] connected
20101109.162628.15 db3 [31386 31391] noname [HTTP] Connected to stat.drweb.com (83.222.3.90) port 80 (#0)
20101109.162628.15 db3 [31386 31391] noname [HTTP] 21 bytes out 'POST /update HTTP/1.1'
20101109.162628.15 db3 [31386 31391] noname [HTTP] 20 bytes out 'Host: stat.drweb.com'
20101109.162628.15 db3 [31386 31391] noname [HTTP] 11 bytes out 'Accept: */*'
20101109.162628.15 db3 [31386 31391] noname [HTTP] 28 bytes out 'User-Agent: drweb-agent/unix'
20101109.162628.15 db3 [31386 31391] noname [HTTP] 16 bytes out 'Pragma: no-cache'
20101109.162628.15 db3 [31386 31391] noname [HTTP] 23 bytes out 'Cache-Control: no-cache'
20101109.162628.15 db3 [31386 31391] noname [HTTP] 47 bytes out 'Content-Type: application/x-www-form-urlencoded'
20101109.162628.15 db3 [31386 31391] noname [HTTP] 19 bytes out 'Content-Length: 153'
20101109.162628.15 db3 [31386 31391] noname [HTTP] 153 bytes of posting document written

Если оно есть, то было бы интересно увидеть, что следует дальше.

Если нет, то покажите строки лога, содержащие "standalone_agent_t::send_statistic"

Лучше весь лог, конечно.

[zirro.s]

он например используется для уведомления о приближении срока истечения времени действия ключа

Понял. Cron поправил. И, кстати, вот логи агента с включенным дебагом 

Прикрепленные файлы:

•  agent.log   78,28К   41 Скачано раз

[zirro.s]

Почему stat.drweb.com ругается на md5sum Вашего ключа.

Мне самому это интересно  .

UUID можно явно не указывать в конфиге - агент посчитает его из ключа сам. Если ключей несколько, или они меняются, то следует указать явно.

Тогда не понятно по какому адресу просматривать статистику

У агента в логе (с Level=debug) должно быть нечто подобное:...

ничего подобного там нет

Если нет, то покажите строки лога, содержащие "standalone_agent_t::send_statistic"

Единственная строка содержащая standalone_agent_t::send_statistic

20101208.134003.76 db0 [18312] noname standalone_agent_t::send_statistic(635751191f2f03c3fee6e20e46b2e42c 0 0)

Сообщение было изменено zirro.s: 08 Декабрь 2010 - 13:56

[zirro.s]

 По поводу статистики появилась новая информация. С момента моего последнего сообщения прошёл примерно час, интервал отправки статистики стоит 10 минут. Значит за это время как минимум 4 сообщения должно было в журнале отразится. На самом же деле ситуация такая:  

20101208.142003.81 db0 [18312] noname standalone_agent_t::send_statistic(635751191f2f03c3fee6e20e46b2e42c 0 1)
20101208.142003.81 db0 [18312] noname http_stat_t::send_stat()
20101208.142003.81 db2 [18312] noname [HTTP] Requesting #1/5 http://stat.drweb.com:80/update (via stat.drweb.com:80 as HTTP/1.1)
20101208.142003.81 db3 [18312] noname [HTTP] About to connect() to stat.drweb.com port 80 (#0)
20101208.142003.81 db3 [18312] noname [HTTP] Trying 83.222.3.90...
20101208.142003.87 db3 [18312] noname [HTTP] connected
20101208.142003.87 db3 [18312] noname [HTTP] Connected to stat.drweb.com (83.222.3.90) port 80 (#0)
20101208.142003.87 db3 [18312] noname [HTTP] 21 bytes out 'POST /update HTTP/1.1'
20101208.142003.87 db3 [18312] noname [HTTP] 20 bytes out 'Host: stat.drweb.com'
20101208.142003.87 db3 [18312] noname [HTTP] 11 bytes out 'Accept: */*'
20101208.142003.87 db3 [18312] noname [HTTP] 28 bytes out 'User-Agent: drweb-agent/unix'
20101208.142003.87 db3 [18312] noname [HTTP] 16 bytes out 'Pragma: no-cache'
20101208.142003.87 db3 [18312] noname [HTTP] 23 bytes out 'Cache-Control: no-cache'
20101208.142003.87 db3 [18312] noname [HTTP] 47 bytes out 'Content-Type: application/x-www-form-urlencoded'
20101208.142003.87 db3 [18312] noname [HTTP] 18 bytes out 'Content-Length: 49'
20101208.142003.87 db3 [18312] noname [HTTP] 49 bytes of posting document written
20101208.142004.19 db3 [18312] noname [HTTP] 15 bytes in 'HTTP/1.1 200 OK'
20101208.142004.19 db3 [18312] noname [HTTP] HTTP response is OK ('200' -> 'OK')
20101208.142004.19 db3 [18312] noname [HTTP] 20 bytes in 'Server: nginx/0.4.13'
20101208.142004.19 db3 [18312] noname [HTTP] 35 bytes in 'Date: Wed, 08 Dec 2010 11:05:20 GMT'
20101208.142004.19 db3 [18312] noname [HTTP] 39 bytes in 'Content-Type: text/plain; charset=UTF-8'
20101208.142004.19 db3 [18312] noname [HTTP] 26 bytes in 'Transfer-Encoding: chunked'
20101208.142004.19 db3 [18312] noname [HTTP] 22 bytes in 'Connection: keep-alive'
20101208.142004.19 db3 [18312] noname [HTTP] 0 bytes in ''
20101208.142004.19 db3 [18312] noname [HTTP] 12 bytes of document body read
20101208.142004.19 db3 [18312] noname [HTTP] Connection #0 to host stat.drweb.com left intact
20101208.142004.19 tr3 [18312] noname [HTTP] 2 CPS for "http://stat.drweb.com:80/update"
20101208.142004.19 db3 [18312] noname [HTTP] Closing connection #0
20101208.142004.19 inf [18312] noname http response is OK
20101208.143004.20 db0 [18312] noname standalone_agent_t::send_statistic(635751191f2f03c3fee6e20e46b2e42c 0 0)



как я понимаю в 14:20 произошёл какой то обмен с сервером. В 14:30 только одинокая строка 

20101208.143004.20 db0 [18312] noname standalone_agent_t::send_statistic(635751191f2f03c3fee6e20e46b2e42c 0 0)

ни в 14:40 ни в 14:50 вообще ни одного сообщения. 

На основании данных об обмене сообщений с сервером подумал, что может сейчас будет доступна страничка статистики. Однако и там без изменений:


Ошибка обработки запроса
Неверный идентификатор пользователя.

[Gennady Proskurin]

по поводу первого вопроса (ошибок в логе монитора) нужен еще конфиг монитора и его дебаг лог
из лога агента непонятно что происходит

[zirro.s]

по поводу первого вопроса (ошибок в логе монитора) нужен еще конфиг монитора и его дебаг лог
из лога агента непонятно что происходит

Если включить в мониторе дебаг IPC, то лог растёт с ужасающей скоростью и за несколько минут заполняется весь раздел /var (я это выяснил когда пытался самостоятельно разобраться с вопросом). Достаточно ли будет перезапустить монитор с параметром Level = debag?

Сообщение было изменено zirro.s: 09 Декабрь 2010 - 13:36

[Gennady Proskurin]

по поводу первого вопроса (ошибок в логе монитора) нужен еще конфиг монитора и его дебаг лог
из лога агента непонятно что происходит

Если включить в мониторе дебаг IPC, то лог растёт с ужасающей скоростью и за несколько минут заполняется весь раздел /var (я это выяснил когда пытался самостоятельно разобраться с вопросом). Достаточно ли будет перезапустить монитор с параметром Level = debag?

нет, ipc дебаг монитора тоже нужен
То что лог монитора так быстро растет, это очень странно, такого быть не должно.

т.е. жду от вас конфиг монитора и дебаг лог монитора (с параметрами Level = debug, IPCLevel = debug)
лог лучше в архиве на почту: g.proskurin@drweb.com

Сообщение было изменено Gennady Proskurin: 09 Декабрь 2010 - 13:55

[Paul Graphov]

Касательно ссылки на сервер статистики - нужно убрать слеш в конце. Правда, данных там все равно не видно, хотя должны быть хотябы какие-то. Выясняем.

[zirro.s]

Касательно ссылки на сервер статистики - нужно убрать слеш в конце. Правда, данных там все равно не видно, хотя должны быть хотябы какие-то. Выясняем.

Изначально пробовал без слэша. Там другая ошибка выдаётся





Ошибка выполнения запроса
В ответ на Ваш запрос сервер не вернул данных. Это может быть по одной из следующих причин:

• неверный временной интервал;
• ошибка сервера

Если проблема не будет устранена, пожалуйста, напишите нам по адресу webmaster@drweb.com.

Поэтому и стал пробовать со слэшем.

[zirro.s]

Если включить в мониторе дебаг IPC, то лог растёт с ужасающей скоростью и за несколько минут заполняется весь раздел /var (я это выяснил когда пытался самостоятельно разобраться с вопросом). Достаточно ли будет перезапустить монитор с параметром Level = debag?

То что лог монитора так быстро растет, это очень странно, такого быть не должно.

Специально сейчас перепроверил выставил  в мониторе IPCdebug = debug. В результате за 4 минуты файл monitor.log вырос с нулевого размера до полутора ГИГАбайт. Естественно я не могу такой лог выслать по почте.

[Gennady Proskurin]

Если включить в мониторе дебаг IPC, то лог растёт с ужасающей скоростью и за несколько минут заполняется весь раздел /var (я это выяснил когда пытался самостоятельно разобраться с вопросом). Достаточно ли будет перезапустить монитор с параметром Level = debag?

То что лог монитора так быстро растет, это очень странно, такого быть не должно.

Специально сейчас перепроверил выставил в мониторе IPCdebug = debug. В результате за 4 минуты файл monitor.log вырос с нулевого размера до полутора ГИГАбайт. Естественно я не могу такой лог выслать по почте.

самое лучшее - выложить его куда-нибудь в архиве в инете, я скачаю

либо прислать начальную часть лога приемлемого размера + небольшой кусок из середины (скорее всего лог забит однотипными записями)
Есть шанс что туда не попадет нужная информация, но скорее всего этого будет достаточно.

[Paul Graphov]

Статистика неверно считается по причине ошибки в агенте. Будет исправлено в следующем баг фиксе.

[userr]

самое лучшее - выложить его куда-нибудь в архиве в инете, я скачаю

ftp://people.drweb.com/people/betatester/incoming/