ngnix worker的高cpu 导致redis timeout ？

jianhuang0

有这样的一个问题

下面有个function 有大量的计算 top看worker跑到80% 多

function A()

结束之后有个redis的操作

lcoal start_time = ngx.time()

redis:cmd()

print(ngx.now() - start_time()) —2s多 timeout设置的是1s所以超时了

在 resty/redis.lua中加时间记录

local function _read_reply(self, sock)

lcoal start_time = ngx.time()

local line, err = sock:receive()

print(ngx.now() - start_time()) —2s多

redis的操作是一个非常非常简单的操作,reids的处理在ms级别，这里却消耗了2s多

目前有一个解决方法就是在 function A 结束之后ngx.sleep(0.1) 就一切正常。但是很奇怪不知道出现上面的原因，有没有什么方向可以提供做这个问题的定位排查

382482175

在 2017年4月17日星期一 UTC+8下午6:09:05，redcloser写道：

有这样的一个问题

下面有个function 有大量的计算 top看worker跑到80% 多

function A()

结束之后有个redis的操作
lcoal start_time = ngx.time()
redis:cmd()
print(ngx.now() - start_time()) —2s多 timeout设置的是1s所以超时了

在 resty/redis.lua中加时间记录

local function _read_reply(self, sock)
lcoal start_time = ngx.time()
local line, err = sock:receive()
print(ngx.now() - start_time()) —2s多

redis的操作是一个非常非常简单的操作,reids的处理在ms级别，这里却消耗了2s多

目前有一个解决方法就是在 function A 结束之后ngx.sleep(0.1) 就一切正常。但是很奇怪不知道出现上面的原因，有没有什么方向可以提供做这个问题的定位排查

照下火焰图，看下主要cpu耗在哪里？

另外ngx.sleep(0.1)后正常？你怎么想到这里的？

jianhuang0

cpu 就消耗在那个函数里的(假设不能优化了)，

之前的解决方法是redis:set_timeout()设置一个大的值

后来看了一下执行上面函数的时候cpu一下变成80%多，然后就试了一下ngx.sleep(0.1) 结果正常。。

在 2017年4月17日星期一 UTC+8下午6:26:59，keke fan写道：

在 2017年4月17日星期一 UTC+8下午6:09:05，redcloser写道：
有这样的一个问题

下面有个function 有大量的计算 top看worker跑到80% 多

function A()

结束之后有个redis的操作
lcoal start_time = ngx.time()
redis:cmd()
print(ngx.now() - start_time()) —2s多 timeout设置的是1s所以超时了

在 resty/redis.lua中加时间记录

local function _read_reply(self, sock)
lcoal start_time = ngx.time()
local line, err = sock:receive()
print(ngx.now() - start_time()) —2s多

redis的操作是一个非常非常简单的操作,reids的处理在ms级别，这里却消耗了2s多

目前有一个解决方法就是在 function A 结束之后ngx.sleep(0.1) 就一切正常。但是很奇怪不知道出现上面的原因，有没有什么方向可以提供做这个问题的定位排查

照下火焰图，看下主要cpu耗在哪里？

另外ngx.sleep(0.1)后正常？你怎么想到这里的？

qleex001

1. 单个请求还是多个请求并发处理呢？
2. function A如果执行的，有使用coroutine、ngx.thread或者ngx.timer的API吗

在 2017年4月17日星期一 UTC+8下午6:40:05，redcloser写道：

cpu 就消耗在那个函数里的(假设不能优化了)，

之前的解决方法是redis:set_timeout()设置一个大的值

后来看了一下执行上面函数的时候cpu一下变成80%多，然后就试了一下ngx.sleep(0.1) 结果正常。。

在 2017年4月17日星期一 UTC+8下午6:26:59，keke fan写道：

在 2017年4月17日星期一 UTC+8下午6:09:05，redcloser写道：
有这样的一个问题

下面有个function 有大量的计算 top看worker跑到80% 多

function A()

结束之后有个redis的操作
lcoal start_time = ngx.time()
redis:cmd()
print(ngx.now() - start_time()) —2s多 timeout设置的是1s所以超时了

在 resty/redis.lua中加时间记录

local function _read_reply(self, sock)
lcoal start_time = ngx.time()
local line, err = sock:receive()
print(ngx.now() - start_time()) —2s多

redis的操作是一个非常非常简单的操作,reids的处理在ms级别，这里却消耗了2s多

目前有一个解决方法就是在 function A 结束之后ngx.sleep(0.1) 就一切正常。但是很奇怪不知道出现上面的原因，有没有什么方向可以提供做这个问题的定位排查

照下火焰图，看下主要cpu耗在哪里？

另外ngx.sleep(0.1)后正常？你怎么想到这里的？

jianhuang0

1. 同时有可能有多个同样的请求，测试环境我就用了一个请求
2. function A没有使用coroutine、ngx.thread或者ngx.timer的API

在 2017年4月17日星期一 UTC+8下午6:46:47，FQ Liu写道：

1. 单个请求还是多个请求并发处理呢？
2. function A如果执行的，有使用coroutine、ngx.thread或者ngx.timer的API吗

在 2017年4月17日星期一 UTC+8下午6:40:05，redcloser写道：
cpu 就消耗在那个函数里的(假设不能优化了)，

之前的解决方法是redis:set_timeout()设置一个大的值

后来看了一下执行上面函数的时候cpu一下变成80%多，然后就试了一下ngx.sleep(0.1) 结果正常。。

在 2017年4月17日星期一 UTC+8下午6:26:59，keke fan写道：

在 2017年4月17日星期一 UTC+8下午6:09:05，redcloser写道：
有这样的一个问题

下面有个function 有大量的计算 top看worker跑到80% 多

function A()

结束之后有个redis的操作
lcoal start_time = ngx.time()
redis:cmd()
print(ngx.now() - start_time()) —2s多 timeout设置的是1s所以超时了

在 resty/redis.lua中加时间记录

local function _read_reply(self, sock)
lcoal start_time = ngx.time()
local line, err = sock:receive()
print(ngx.now() - start_time()) —2s多

redis的操作是一个非常非常简单的操作,reids的处理在ms级别，这里却消耗了2s多

目前有一个解决方法就是在 function A 结束之后ngx.sleep(0.1) 就一切正常。但是很奇怪不知道出现上面的原因，有没有什么方向可以提供做这个问题的定位排查

照下火焰图，看下主要cpu耗在哪里？

另外ngx.sleep(0.1)后正常？你怎么想到这里的？