lua执行时间记录到nginx的access.log里面

yjfhfei · 2013-09-29T11:57:26+00:00

Hello! 你目前可以使用 ngx.location.capture + ngx_proxy 模块这种组合，&#x...

lua执行时间记录到nginx的access.log里面

yjfhfei

大家好：

lua执行时间记录到nginx的access.log里面，大家有这样的需求嘛？？？

agentzh

Hello!

On Sat, Sep 28, 2013 at 8:57 PM, Wang Jingfei wrote:
>       lua执行时间记录到nginx的access.log里面，大家有这样的需求嘛？？？
>

1. 你可以在你自己的 Lua 代码里面计时，并保存在某个 nginx 变量中，然后输出到 access.log. 你可以直接通过
LuaJIT 的 FFI 接口直接访问 gettimeofday() 这个 libc 函数，获得高精度的时间。

2. 你可以使用基于 systemtap 的外部工具来进行实时统计分布规律之类的东西，更加灵活。可以参考我的 Nginx Systemtap
Toolkit: https://github.com/agentzh/nginx-systemtap-toolkit

Regards,
-agentzh

yjfhfei

谢谢春哥，我使用第一个方法
但是我自定义了一个执行变量$exec_time，查看日志发现$request_time和$exec_time大部分一样，少部分$exec_time大于$request_time

$request_time不是包含客户端网络IO，理论上应该小于$exec_time

在 2013年9月29日星期日UTC+8下午12时50分50秒，agentzh写道：

Hello!

On Sat, Sep 28, 2013 at 8:57 PM, Wang Jingfei wrote:
> lua执行时间记录到nginx的access.log里面，大家有这样的需求嘛？？？
>

1. 你可以在你自己的 Lua 代码里面计时，并保存在某个 nginx 变量中，然后输出到 access.log. 你可以直接通过
LuaJIT 的 FFI 接口直接访问 gettimeofday() 这个 libc 函数，获得高精度的时间。

2. 你可以使用基于 systemtap 的外部工具来进行实时统计分布规律之类的东西，更加灵活。可以参考我的 Nginx Systemtap
Toolkit: https://github.com/agentzh/nginx-systemtap-toolkit

Regards,
-agentzh

agentzh

Hello!

2013/9/29 Wang Jingfei:
> 谢谢春哥，我使用第一个方法
> 但是我自定义了一个执行变量$exec_time，查看日志发现$request_time和$exec_time大部分一样，少部分$exec_time大于$request_time
> $request_time不是包含客户端网络IO，理论上应该小于$exec_time
>

$request_time 总是会有误差的，因为它使用的是 nginx 自己缓存的时间。这个时间缓存一般只有在 IO
事件处理周期的开始位置进行更新（比如在 epoll_wait 系统调用之后）。阻塞操作（比如普通的文件 IO 或者 CPU
计算）是不会自动更新 nginx 的时间缓存的。因为你这里得到的 $request_time 小于 Lua
代码中统计的精度更高的时间符合预期。

值得一提的是，你总是可以在 Lua 中自己来强制更新 nginx 时间缓存，即通过调用 ngx_lua 的 ngx.update_time() 接口来实现：

    http://wiki.nginx.org/HttpLuaModule#ngx.update_time

Regards,
-agentzh