由于 OOM 导致不健壮的 Netty 一系列诡异的行为,这次的问题分析会比上次那个更有意思一点。(备注:本文 Netty 版本是上古时代的 3.7.0.Final)
现象描述
开发的同学反馈 dubbo 客户端无法调用远程的服务,抓包来看,客户端一直在建连,每次建连成功 3 秒以后就主动断开连接。
这个现象就很奇怪了,默认情况下 dubbo 消费端对属于同一个 provider 的不同 service 只会共享一条 tcp 连接进行通信,此处就是为了跟 provider 端建立这个连接。
为什么这里三次握手成功以后会断开连接呢?这个现象其实挺诡异的,于是想到用 strace 看一下背后到底发生了什么。
strace -f -T -p 238289 -o strace-new.238289.out
在 strace 中找 connect 相关的调用,根据线程号过滤对应的日志,可以看到发生了哪些系统调用:
一开始就创建一个 socket,将该套接字设置为非阻塞,随后调用 connect 发起建立,因为是非阻塞套接字,connect 这里不阻塞直接返回 -1,随后开始等待 3s,如果 3s 内没有能建立成功,futex 超时退出。
但是这个跟抓包的行为就不一致了,从包上看,duboo 服务端有回复 SYN+ACK,但是 java 应用认为我没有收到,3s 超时。
同时,这里整个 strace 日志中没有看到对应 fd 相关 epoll_ctl 调用,也就是没有人把这个 fd 加入到 epoll 的事件监听中。
正常来说,我们的一个非阻塞的 connect 编程是这样的。(以下代码来自 ChatGPT,错了别赖我)
// 设置 socket 为非阻塞模式
int set_nonblocking(int fd) {// 省略
}// 连接服务器
int connect_to_server(const char *hostname, int port) {int sockfd;struct sockaddr_in serv_addr;struct hostent *server;// 创建 socketsockfd = socket(AF_INET, SOCK_STREAM, 0);// 设置 socket 为非阻塞模式set_nonblocking(sockfd)// 尝试连接服务器if (connect(sockfd, (struct sockaddr *) &serv_addr, sizeof(serv_addr)) < 0) {if (errno != EINPROGRESS) { // 非阻塞下会返回这个错误码return -1;}}return sockfd;
}// 使用 epoll 监听 socket 连接的状态
int wait_for_connection(int sockfd) {int epfd, nfds;struct epoll_event ev, events[1];// 创建 epoll 实例epfd = epoll_create(1);// 将 socket 添加到 epoll 的事件监听集合中memset(&ev, 0, sizeof(ev));ev.events = EPOLLOUT | EPOLLET;ev.data.fd = sockfd;if (epoll_ctl(epfd, EPOLL_CTL_ADD, sockfd, &ev) < 0) {return -1;}// 监听事件for (;;) {nfds = epoll_wait(epfd, events, 1, -1);if (nfds == -1) {return -1;}// 检查连接是否成功if (events[0].events & EPOLLOUT) {return sockfd;}}return -1;
}int main(int argc, char *argv[]) {const char *hostname = "localhost";int port = 8080;// 创建并连接到服务器int sockfd = connect_to_server(hostname, port);if (sockfd < 0) {return 1;}// 使用 epoll 监听 socket 连接的状态sockfd = wait_for_connection(sockfd);if (sockfd < 0) {return 1;}// 连接成功,在这里执行你想要的操作
printf("Connection established!\n");// 关闭 socket
close(sockfd);return 0;
}
目前的思路大概就清楚了:没有人调用 epoll 相关的函数去注册事件,导致内核收到 SYN+ACK 包以后,没有程序感兴趣去处理。
为什么没有向 epoll 注册事件
上面是建连是 Dubbo 的重连线程来实现的,重连线程的主要作用是检测和管理网络连接的状态,如果发现连接断开或异常,就会尝试重新建立连接。先来看一下重连线程做了什么,重连线程的创建位于 com.alibaba.dubbo.remoting.transport.AbstractClient
类中。
Dubbo 内部用 ScheduledThreadPoolExecutor 线程池运行 reconnect 线程。 这个重连线程会调用 com.alibaba.dubbo.remoting.transport.netty.NettyClient.doConnect
发起建连。
ClientBootstrap.connect
不会直接为 channel 注册事件,而是生成了一个 RegisterTask
放入了 NioClientBoss
的 taskQueue
中,等待被处理。
通过注入 stack java.util.concurrent.ConcurrentLinkedQueue offer -n 1
就可以发现,确实如此。
如果 RegisterTask
的 run 方法被执行时,才是真正的注册事件。
现在可以推断出 RegisterTask
的 run 没有被调用。
继续看 taskqueue 是如何消费的,就知道 run 为什么没有被执行了。这个队列是在 org.jboss.netty.channel.socket.nio.AbstractNioSelector#processTaskQueue
中消费的
这个方法是被 org.jboss.netty.channel.socket.nio.AbstractNioSelector#run
调用的,实际是实现类 org.jboss.netty.channel.socket.nio.NioClientBoss
,这个类也是一个 runnable,启动后生成一个名为 New I/O boss #N
的线程,内部是一个无限循环消费 taskqueue 以及处理就绪事件。
下一步就是进一步确认 taskqueue 是不是确实没有消费,这个可以通过 dump 内存的方式来验证,看看 taskqueue 里面的数据有没有变化。
这下实锤了,接下来去 dump 线程堆栈,看看 New I/O boss
线程还在不在。
通过 jstack 对比确认,无限重连的服务确实没有 New I/O boss
线程。
结合服务在半夜定时任务时堆内存 OOM 的日志,可以合理怀疑因为 OOM 导致 New I/O boss
线程退出,没有能继续执行 run 方法消费队列,导致非阻塞建连 connect 以后没有用 epoll_ctl 注册感兴趣事件。
通过分析,run 方法是有捕获 Throwable 异常的,如果有 OutOfMemoryError 会进入 catch 中,理论上线程不会挂掉。但是好死不死 catch 块还有逻辑,有 logger 去打印 warn 日志,这里如果再次抛出 OutOfMemoryError,那就凉凉。
如何修改
- 优化代码,杜绝 OOM
- 完善 Netty 对 OOM 的处理逻辑,核心线程退出以后重建
- 升版本。。。
后记
只要能复现的基本上都可以被解决,稳定复现的那就更容易了。这个问题出现的概率比上次那个大量 CLOSE_WAIT 情况更低,但是好在开发的同学没改 bug,昨天又出现了。
Dubbo 版本真难升啊,不好用。。。