慢查询日志帮助开发和运维人员定位系统存在的慢操作。慢查询日志就是系统在命令执行前后计算每条命令的执行时间,当超过预设阀值,就将这条命令的相关信息(慢查询ID,发生时间戳,耗时,命令的详细信息)记录下来。Redis客户端一条指令分为如下四部分执行:
其中慢查询只统计第3个执行步骤的时间。
1、慢查询的两个配置参数
slowlog-log-slower-than:
预设阀值,它的单位是微秒(1秒=1000毫秒,1毫秒=1000微秒),默认值是10000,假如执行了一个“很慢”的命令,如 “keys * ”;如果它超过了10000微秒,那么它将会被记录在慢查询日志里。
预设阀值的两种方式:默认为10毫秒
a、动态设置
6379:> config set slowlog-log-slower-than 10000 //10毫秒10000微秒
使用config set完后,若想将配置持久化保存到redis.conf,要执行config rewrite
b、redis.conf修改:找到slowlog-log-slower-than 10000 ,修改保存即可
注意: 如果 slowlog-log-slower-than=0 会记录所有的命令;
如果 slowlog-log-slower-than<0对于任何命令都不会进行记录。
slowlog-max-len:
慢查询日志最多存储的条数。Redis使用一个列表来存储慢查询日志,slowlog-max-len就是列表的最大长度,当列表已经处于最大长度时,继续新增一个慢查询日志时,会将最早插入的一个命令从列表中移除。
设置方式和slowlog-log-slower-than一样,可以config set动态设置,也可以修改redis.conf完成配置。
2、相关命令
获取队列里慢查询的命令:slowlog get
查询返回的结构如下:
获取慢查询列表当前的长度:slowlog len //以上只有1条慢查询,返回1
对慢查询列表清理(重置):slowlog reset //再查slowlog len 此时返回0 清空
为了方便解说,我设置超时时间为0毫秒,日志记录为1条
那么记录的中的1)2)3)4)分别表示什么呢?
1)表示日志唯一标识符uid
2)命令执行时系统的时间戳
3)命令执行的时长,以微妙来计算
4)命令和命令的参数
做日志查询的时候,可以通过3)来查看是具体的命令运行时间(注意:再强调一次,时间的单位是微妙,但对于一个插入操作来说,10000微妙,也就是10毫秒即0.01秒已经可以算是慢操作了)哪些操作出了问题。当然这只限于测试使用,如果需要当业务出现redis插入查询缓慢的事件,需要去查看redis生成的持久型日志,这需要额外去配置一些内容,其中涉及到了集群和分布式,这里先点到为止。
3、配置建议
对于线上slow-max-len配置的建议:线上可加大slow-max-len的值,记录慢查询存长命令时redis会做截断,不会占用大量内存,线上可设置1000以上
对于线上slowlog-log-slower-than配置的建议:默认为10毫秒,根据redis并发量来调整,对于高并发比建议为1毫秒
注意:
1,慢查询只记录命令在redis的执行时间,不包括排队、网络传输时间
2,慢查询是先进先出的队列,访问日志记录出列丢失,需定期执行slow get,将结果存储到其它设备中(如mysql)