Lettuce RedisCommandTimeoutException

  • 偶然看到的问题技术分享:Spring Boot默认Redis Client Lettuce报出RedisCommandTimeoutException,多台客户端集群只有一台出现报错,无法立即重连,持续15min后自动恢复报错消失,再次发现又是15min,过程中Redis没有宕机。

一、排查过程:

  1. 从报错来看,猜测是单纯网络问题,但都是15分钟后自动恢复,所以猜测并不只是网络抖动,而且网络15分钟以内应该已经恢复,但是客户端没有及时重连,所以代码逻辑上也有问题
  2. 报错前客户端访问Redis正常,排除长时间闲置导致客户端断开,应该并没有达到本机Keepalive时间(猜测),如果Keepalive超时断连,客户端应该也会触发重连
  3. 分析Lettuce后,发现Lettuce内部Netty触发ChannelInactived后就会重连,ChannelInactived是客户端断开后的回调,所以猜测客户端15分钟后才断连,触发了重连
  4. 然后分析服务端,Redis并没有重启,就算进程退出,正常在结束时TCP也会触发close(),客户端收到后也会及时退出
  5. 猜测网络抖动服务端因为Keepalive关闭连接,FIN并没有到达客户端,导致了TCP半打开,这也能解释客户端并没有及时触发ChannelInactived
  6. 剩下2个问题,为什么是15分钟,还有重试时为什么客户会端超时,而不是服务端返回RST(我印象里半打开服务端恢复后客户端再发消息会返回RST,进而客户端收到后会断连!)
  7. 原文分享了一个issue:https://github.com/lettuce-io/lettuce-core/issues/1428

二、相关概念

  1. TCP半打开、半连接、半关闭:
  • 半打开:TCP正常连接下,程序退出会主动断连,但遇上断电、断网等场景,无法发出断连消息,对方不知道连接已失效的状态,不发消息的情况下,只能通过KeepAlive等机制探活超时后关闭
  • 半连接:三次握手发起方一直不发起最后一次握手,连接会堆在syns queue时的状态
  • 半关闭:四次挥手时,发起方发送FIN,对方回复ACK后不发出FIN时的状态
  1. KeepAlive常用的探活机制:启用SO_KEEPALIVE进行探活,如果无法返回则认为对方宕机,后续可以采取重连或其他措施
  • SO_KEEPALIVE:是否开启KeepAlive
  • TCP_KEEPIDLE:N秒内连接没有任何网络传输,就启动KeepAlive机制
  • TCP_KEEPINTVL:启动KeepAlive时,每隔N秒发一次探活包
  • TCP_KEEPCNT:N次探活包没有响应,认为对方宕机
  1. 这里有个问题,KeepAlive启动的场景是在一个正常响应后,就是说如果上一个数据包发送没有响应,这时是超时重试阶段,系统并不会启动KeepAlive进行探活,只是简单的认为卡了
  2. tcp_retries2:Linux配置,默认15次代表tcp请求重试次数,重试的时间从200ms指数增长,最大120s,总时长15.4min,如果15.4min还是超时则断开连接,重试次数和时间分布如下图,这个问题之前竟然没注意过!


    tcp_retries2重试次数和时间
  3. 关于服务端闪断后对客户端连接的影响(半打开):
  • 如果客户端一直没有数据传输:这时客户端无法知道服务端状态,没有其他机制影响会一直保持正常连接
  • 如果客户端发送了数据,服务端一直没有恢复:这时因为对端没有返回ACK所以默认连接会重试15次共15.4min后触发超时断开连接,即tcp_retries2
  • 如果客户端发送了数据,服务端已恢复,或重试过程中恢复:需要确认服务端这个连接还有没有保持,如果服务端重启,或服务端KeepAlive等机制导致连接已经丢弃,客户端重试tcp_retries2后会超时断开连接!如果服务端还保持连接则恢复正常通信
  • 这里有个误区!一直以为半打开服务端恢复服务后如果连接已经被清理,发送消息会回复RST!但其实会一直搁置导致超时。这个可能存在争议,看过一些文章有写会返回RST,但是问题排查和本地自测结果确实会tcp_retries2超时后断连,可能问题就在这!
  1. TCP_USER_TIMEOUT:代表数据包N秒没收到响应,触发超时,配置后tcp_retries2会失效!

三、代码调整:

  • 按Git issue建议,减小Keepalive的检测时间,同时增加TCP_USER_TIMEOUT机制
  • TCP_USER_TIMEOUT负责请求超时处理,Keepalive负责空闲时心跳检测

四、总结:

  • 虽然配置能对应报错,修改后应该也没有复现Bug,但没有保留现场无法抓包确认,最直接能确认问题是当时的服务端、客户端的连接状态,和服务端是否发出FIN包未到达客户端,大概率是因为节点网络问题导致整个节点全部半打开,其他未受影响。

五、后续:

  • 正好同事遇到了这个问题,客户端Jedis,使用Redis PubSub,并没有出现超时,因为只是单纯收消息并没有发,发生问题时网络原因断网一段时间,因服务端Keepalive时间较短,客户端Keepalive较长,网络在服务端Keepalive内没有恢复,服务端连接断开,产生半打开,此后客户端一直收不到消息,直到客户端Keepalive生效,连接重连,符合上述推测
  • 这个问题也可以发现,半打开导致的bug和Redis客户端没有太大关系,是TCP层面的逻辑,完全取决于Keepalive相关的配置
最后编辑于
©著作权归作者所有,转载或内容合作请联系作者
  • 序言:七十年代末,一起剥皮案震惊了整个滨河市,随后出现的几起案子,更是在滨河造成了极大的恐慌,老刑警刘岩,带你破解...
    沈念sama阅读 216,402评论 6 499
  • 序言:滨河连续发生了三起死亡事件,死亡现场离奇诡异,居然都是意外死亡,警方通过查阅死者的电脑和手机,发现死者居然都...
    沈念sama阅读 92,377评论 3 392
  • 文/潘晓璐 我一进店门,熙熙楼的掌柜王于贵愁眉苦脸地迎上来,“玉大人,你说我怎么就摊上这事。” “怎么了?”我有些...
    开封第一讲书人阅读 162,483评论 0 353
  • 文/不坏的土叔 我叫张陵,是天一观的道长。 经常有香客问我,道长,这世上最难降的妖魔是什么? 我笑而不...
    开封第一讲书人阅读 58,165评论 1 292
  • 正文 为了忘掉前任,我火速办了婚礼,结果婚礼上,老公的妹妹穿的比我还像新娘。我一直安慰自己,他们只是感情好,可当我...
    茶点故事阅读 67,176评论 6 388
  • 文/花漫 我一把揭开白布。 她就那样静静地躺着,像睡着了一般。 火红的嫁衣衬着肌肤如雪。 梳的纹丝不乱的头发上,一...
    开封第一讲书人阅读 51,146评论 1 297
  • 那天,我揣着相机与录音,去河边找鬼。 笑死,一个胖子当着我的面吹牛,可吹牛的内容都是我干的。 我是一名探鬼主播,决...
    沈念sama阅读 40,032评论 3 417
  • 文/苍兰香墨 我猛地睁开眼,长吁一口气:“原来是场噩梦啊……” “哼!你这毒妇竟也来了?” 一声冷哼从身侧响起,我...
    开封第一讲书人阅读 38,896评论 0 274
  • 序言:老挝万荣一对情侣失踪,失踪者是张志新(化名)和其女友刘颖,没想到半个月后,有当地人在树林里发现了一具尸体,经...
    沈念sama阅读 45,311评论 1 310
  • 正文 独居荒郊野岭守林人离奇死亡,尸身上长有42处带血的脓包…… 初始之章·张勋 以下内容为张勋视角 年9月15日...
    茶点故事阅读 37,536评论 2 332
  • 正文 我和宋清朗相恋三年,在试婚纱的时候发现自己被绿了。 大学时的朋友给我发了我未婚夫和他白月光在一起吃饭的照片。...
    茶点故事阅读 39,696评论 1 348
  • 序言:一个原本活蹦乱跳的男人离奇死亡,死状恐怖,灵堂内的尸体忽然破棺而出,到底是诈尸还是另有隐情,我是刑警宁泽,带...
    沈念sama阅读 35,413评论 5 343
  • 正文 年R本政府宣布,位于F岛的核电站,受9级特大地震影响,放射性物质发生泄漏。R本人自食恶果不足惜,却给世界环境...
    茶点故事阅读 41,008评论 3 325
  • 文/蒙蒙 一、第九天 我趴在偏房一处隐蔽的房顶上张望。 院中可真热闹,春花似锦、人声如沸。这庄子的主人今日做“春日...
    开封第一讲书人阅读 31,659评论 0 22
  • 文/苍兰香墨 我抬头看了看天上的太阳。三九已至,却和暖如春,着一层夹袄步出监牢的瞬间,已是汗流浃背。 一阵脚步声响...
    开封第一讲书人阅读 32,815评论 1 269
  • 我被黑心中介骗来泰国打工, 没想到刚下飞机就差点儿被人妖公主榨干…… 1. 我叫王不留,地道东北人。 一个月前我还...
    沈念sama阅读 47,698评论 2 368
  • 正文 我出身青楼,却偏偏与公主长得像,于是被迫代替她去往敌国和亲。 传闻我的和亲对象是个残疾皇子,可洞房花烛夜当晚...
    茶点故事阅读 44,592评论 2 353

推荐阅读更多精彩内容