Re: Миллисекунды в error log

2019-02-06 Пенетрантность Maxim Dounin
Hello!

On Wed, Feb 06, 2019 at 01:09:57AM -0500, Ilya Evseev wrote:

> Вижу "timer delta: %M" в выводе "strings nginx-debug", но не вижу ни одной
> строки с ним в error_log.
> 
> Упоминания про таймер только такие:
> 
> 2019/02/05 09:38:23 [debug] 18108#18108: *5453 event timer add: 15:
> 75000:435707458
> 2019/02/05 09:38:23 [debug] 18108#18108: *5453 event timer del: 15:
> 435707458
> 2019/02/05 09:38:23 [debug] 18103#18103: *5454 event timer add: 27:
> 75000:435707463
> 2019/02/05 09:38:23 [debug] 18103#18103: *5454 event timer del: 27:
> 435707463
> 
> Как сделать так, чтобы сообщения timer delta тоже начали записываться в
> "error_log ... debug"?

Следует задать "error_log ... debug" на глобальном уровне.

-- 
Maxim Dounin
http://mdounin.ru/
___
nginx-ru mailing list
nginx-ru@nginx.org
http://mailman.nginx.org/mailman/listinfo/nginx-ru

Re: Миллисекунды в error log

2019-02-05 Пенетрантность Ilya Evseev
> Вытаскивать миллисекунды в error log - мы в своё время думали, но,
кажется, проблем от этого больше,
> чем пользы. Особенно с учётом того, что время nginx в норме обновляет один
раз за итерацию
> event loop'а, и все сообщения между уходами в ядро будут использовать одно
и то же время.

Если миллисекунды не годятся, то годится ли rdtsc вместо них?

Или это непортабельно\несекурно\ненаглядно\непроизводительно и т.д.?

Posted at Nginx Forum: 
https://forum.nginx.org/read.php?21,282919,282928#msg-282928

___
nginx-ru mailing list
nginx-ru@nginx.org
http://mailman.nginx.org/mailman/listinfo/nginx-ru

Re: Миллисекунды в error log

2019-02-05 Пенетрантность Ilya Evseev
Вижу "timer delta: %M" в выводе "strings nginx-debug", но не вижу ни одной
строки с ним в error_log.

Упоминания про таймер только такие:

2019/02/05 09:38:23 [debug] 18108#18108: *5453 event timer add: 15:
75000:435707458
2019/02/05 09:38:23 [debug] 18108#18108: *5453 event timer del: 15:
435707458
2019/02/05 09:38:23 [debug] 18103#18103: *5454 event timer add: 27:
75000:435707463
2019/02/05 09:38:23 [debug] 18103#18103: *5454 event timer del: 27:
435707463

Как сделать так, чтобы сообщения timer delta тоже начали записываться в
"error_log ... debug"?

Posted at Nginx Forum: 
https://forum.nginx.org/read.php?21,282919,282927#msg-282927

___
nginx-ru mailing list
nginx-ru@nginx.org
http://mailman.nginx.org/mailman/listinfo/nginx-ru

Re: Миллисекунды в error log

2019-02-05 Пенетрантность Konstantin Tokarev


05.02.2019, 18:03, "Maxim Dounin" :
> Hello!
>
> On Tue, Feb 05, 2019 at 02:15:38AM -0500, Ilya Evseev wrote:
>
>>  Пытаюсь отладить тормоза на одном из серверов Nginx.
>>  Включил "error_log ... debug"
>>  Проблема в том, что записи туда пишутся с секундной точностью.
>>  Есть ли возможность обеспечить миллисекундную?
>
> После каждого ухода в ядро (и соответственно обновления времени по
> выходе из него) - в debug-лог пишется строка "timer delta: ...", в
> которой указано количество прошедших миллисекунд.
>
> Вытаскивать миллисекунды в error log - мы в своё время думали, но,
> кажется, проблем от этого больше, чем пользы. Особенно с учётом
> того, что время nginx в норме обновляет один раз за итерацию event
> loop'а, и все сообщения между уходами в ядро будут использовать
> одно и то же время.

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

https://lttng.org/docs/v2.10/#doc-tracef

>
> --
> Maxim Dounin
> http://mdounin.ru/
> ___
> nginx-ru mailing list
> nginx-ru@nginx.org
> http://mailman.nginx.org/mailman/listinfo/nginx-ru

-- 
Regards,
Konstantin

___
nginx-ru mailing list
nginx-ru@nginx.org
http://mailman.nginx.org/mailman/listinfo/nginx-ru

Re: Миллисекунды в error log

2019-02-05 Пенетрантность Maxim Dounin
Hello!

On Tue, Feb 05, 2019 at 02:15:38AM -0500, Ilya Evseev wrote:

> Пытаюсь отладить тормоза на одном из серверов Nginx.
> Включил "error_log ... debug"
> Проблема в том, что записи туда пишутся с секундной точностью.
> Есть ли возможность обеспечить миллисекундную?

После каждого ухода в ядро (и соответственно обновления времени по 
выходе из него) - в debug-лог пишется строка "timer delta: ...", в 
которой указано количество прошедших миллисекунд.

Вытаскивать миллисекунды в error log - мы в своё время думали, но, 
кажется, проблем от этого больше, чем пользы.  Особенно с учётом 
того, что время nginx в норме обновляет один раз за итерацию event 
loop'а, и все сообщения между уходами в ядро будут использовать 
одно и то же время.

-- 
Maxim Dounin
http://mdounin.ru/
___
nginx-ru mailing list
nginx-ru@nginx.org
http://mailman.nginx.org/mailman/listinfo/nginx-ru

Миллисекунды в error log

2019-02-04 Пенетрантность Ilya Evseev
Пытаюсь отладить тормоза на одном из серверов Nginx.
Включил "error_log ... debug"
Проблема в том, что записи туда пишутся с секундной точностью.
Есть ли возможность обеспечить миллисекундную?

Написал патч, но ещё не проверил:
https://gist.github.com/ilyaevseev/ca636314e1ba2a7889c7efca5d85f594

Posted at Nginx Forum: 
https://forum.nginx.org/read.php?21,282912,282912#msg-282912

___
nginx-ru mailing list
nginx-ru@nginx.org
http://mailman.nginx.org/mailman/listinfo/nginx-ru