Java应用上云后被kill问题分析与解决

朴灿烈づ我的快乐病毒、 2023-09-25 19:53 6阅读 0赞

## 前言 ##

自从公司 2021 年 11 月份开始全面容器化后，酒店报价中心团队快速响应，迁移了 98% 的应用，由原来的 kvm 或实体机器到容器上，我们的多个应用出现了频繁被 kill 的情况，主要包括两大类：

*  因为 GC 时间过长导致 k8s 检活失败，被 kill 掉。
 *  因为内存碎片的问题，导致 OOM 被 kill 掉。

**本文主要介绍发现问题以及解决问题的过程。**

## 问题一：应用长时间 GC ##

应用 GC 时间长导致 k8s 检活失败，k8s 会 kill 掉业务应用。

### **·** 具体现象和分析 ###

![format_png][]

当时我们团队的两个应用在发布到 docker上以后，出现了频繁重启的现象，发布后一天内重启次数高达 29 次左右，提示也仅仅是 “时间：2021-11-25T21:34:58+08:00 原因：Error kill” 看不到具体的问题，只能到对应的容器内部去寻找线索了。具体排查过程如下：

#### 1、确认为啥会被 kill ？ ####

![format_png 1][]

![format_png 2][]

首先通过 dmesg 命令，查看容器所在主机上的日志。发现是存在 OOM kill 掉的 Java 应用，但是对比了下这个 total-vm 和我们自己的应用配置发现差别很大，不是我们的应用进程，我们的配置是：

![format_png 3][]

舍弃了这个方向后转向了 k8s 的日志（ps：为啥第一次不看这个，是因为这个日志的查看时需要权限的，前期没有权限，只能自己动手先看方便的），发现了在被 kill 之前出现了3次 unhealthy 的 k8s 日志，且返回请求状态不是200。

![format_png 4][]

看下为什么会有检活请求异常，检查业务访问日志，发现这个时间点是有接收到请求，手动访问也是成功的。但是当时为啥会有访问状态不是 200 的问题呢？怀疑当时业务进程是有大量任务在跑，响应超时问题导致， 所以开始排查业务的具体日志。

#### 2、发现问题 ####

在排查日志的时候发现了导致该问题的根本原因，本质是 GC 时间过长导致。查看重启前的容器 GC 日志：

![format_png 5][]

容器被重启是在 2021-11-25T21:34:58+08:00 重启的。在这个时间点前，也就是被 kill 之前的一次 GC 时间高达 18s + 7s 。至此，原因就很清晰了：因为应用进程的 GC 导致服务不能正常响应 k8s 的检活请求，k8s 认为应用“死”了，触发了 kill 和重启操作！

### **·** 解决 ###

*  通过 GC 日志，分析主要耗时点。推荐 GC 分析工具：[gceasy.io/][gceasy.io] ，调整 JVM参数。
 *  k8s 调整了检活机制，由原来超时 10s、20s，最后调整为 2min。
 *  通过分析日志发现主要的长时 GC 是因为新生代晋升失败，扩大 young 区和堆大小优化 JVM 参数。

## 问题二：内存碎片 ##

### **·** 现象 ###

问题现象是应用管理平台上出现了容器 “OOM kill” 的提示。

![format_png 6][]

### **·** JVM堆内存分析 ###

基于 OOM kill 的提示，开始分析应用日志，发现堆区、栈区并没有出现 OOM 的问题，怀疑是堆外内存内存溢出导致，因此，尝试添加相应的JVM参数以观察堆外内存的使用情况。

![format_png 7][]

观察是否是有堆外内存没有释放，再加上 OOM 没有明显征兆，写了脚本定时 30s 看下使用情况。

![format_png 8][]

下图是在容器启动后的 1 分钟 到 容器即将被 kill 时的 JVM 内存分配对比图：

![format_png 9][]

![format_png 10][]

发现 JVM 的使用内存并没有明显变化（12491M→ 12705M），且整体没有超过 docker 分配的内存限制（docker limit Memory:12G），但是为什么会有 OOM 呢？哪块的内存使用升高导致了 OOM 呢？

查询了大量的资料，排查方向转向内存这块。

### **·** pmap 内存映射分析 ###

使用 pmap 分析 Java 进程的内存映射关系：

![format_png 11][]

![format_png 12][]

***pmap说明***

> Address: 内存开始地址
> 
> Kbytes: 占用内存的字节数（KB）
> 
> RSS: 保留内存的字节数（KB）
> 
> Dirty: 脏页的字节数（包括共享和私有的）（KB）
> 
> Mode: 内存的权限：read、write、execute、shared、private (写时复制)
> 
> Mapping: 占用内存的文件、或\[anon\]（分配的内存）、或\[stack\]（堆栈）
> 
> Offset: 文件偏移
> 
> Device: 设备名 (major:minor)

发现可疑的地方有两个 1029712KB（1005M）的内存块和较多64M内存块，linux 默认使用的 glibc 的 ptmalloc 内存分配器，有这个问题。

#### Glibc为什么会有64M的内存块的问题？ ####

***引入内存分配器***

在进程申请内存时，根据需要分配的内存大小由内存分配器来想内核申请具体的内存区域，那么为什么会有内存分配器来申请内存，而不是进程直接向系统申请呢？因为系统调用的开销比较大，这样做是非常不值的。同时，在 linux下分配堆内存需要使用 brk系统调用，而这个系统调用只是简单地改变堆顶指针而已，也就是将堆扩大或者缩小。举个例子，进程分别申请了 M2 和 M1 两块内存，运行了一段时间后，M2 内存不需要了，需要回收了。

![format_png 13][]

使用系统处理的话，只能使用 brk 移动指针，那么 M1 也会被回收掉，这样显然是不行的。所以引入内存分配器，把 M2 的内存缓存下来，等到进程需要再次申请内存空间时不需要使用系统调用，而是直接从缓存中分配，这个动作就是由内存分配器完成的。内存分配器不仅提升了运行效率，还提高了内存的使用率。

![format_png 14][]

***ptmalloc 解读***

glibc 的内存分配器（ptmalloc）的结构如图所示，一个进程就有一个主分配区和若干个从分配区。所有的线程申请内存时，都要经过主分配区申请，多线程时就需要通过锁机制来保证分配的正确性，从分区就应运而生了，ptmalloc 根据系统对分配区的争用情况动态增加分配区的数量。

![format_png 15][]

在申请内存时，glibc 每次申请新的内存时，主分配区是可以通过 brk 或者 mmap 来向系统申请的，但是非主分配的内存只能通过 mmap 申请了，在64位机器上每次申请的虚拟内存区块大小是 64MB ，最大为8倍的 CPU 数量。且从分配区一旦创建，就不会被回收了。这个就是该问题中发现的 64MB 内存块产生的原因。进程申请内存的简单步骤如下：

1.  通过 fastbins 查找合适内存块，
2.  1没有，从 small bin 中获取，
3.  2没有，从 unsorted bin 中获取，
4.  3没有，从 large bin 中获取，
5.  4没有，从 top chunk 中，
6.  5不够，向系统申请 brk/mmap。

内存回收的简单步骤如下：

1.  判断是否是 mmap 映射，是直接回收
2.  判断是否邻近 top chunk
3.  不是2，根据 size 放到不同的 bins 中
4.  是2，判断 top chunk 中邻近内存是否在使用 是 合并 top chunk
5.  合并后判断 top chunk 大小，超过阈值（默认128k），但是开始分配128k不会回收。通过翻阅资料发现，现在市面上有不少内存分配器的实现，如 tcmalloc ，jemalloc 等，在这里我们选择了 jemalloc。

### **·** 替换内存分配器解决内存碎片问题 ###

jemalloc

jemalloc 是一个通用的 malloc(3) 实现，强调碎片避免和可扩展的并发支持。

*避免内存碎片和性能点提升*

*  线程内存池：在一个进程中每个线程会有自己的内存池，用来管理自己的内存使用，会大幅度减少并发时锁的性能损失。
 *  锁粒度：使用非公平锁，替换自旋锁，减少 CPU 空转。

*性能对比*

![format_png 16][]

在灰度环境尝试替换为 jemalloc 的内存管理器：

安装 jemalloc

![format_png 17][]

也可尝试安装 tcmalloc

![format_png 18][]

验证下是否使用成功了

![format_png 19][]

发现 '64MB' 的空间映射已经不存在了，且在观察时间范围内还有一次内存的下降， 观察一周一直平稳运行，没有出现 OOM kill 的问题。

![format_png 20][]

![format_png 21][]

## 总结 ##

### 问题处理流程 ###

![format_png 22][]

发现问题，就是个人前进的一大步。要发现问题，就要抓细节，不放弃再加上有头脑的处理问题！

### 工具优化 ###

*  优化 dump 体验。原来容器 dump 时会存在 dump 到一半机器就重启的问题，跟基础架构 和技术运营的同学沟通后，对该部分做了优化，让业务分析 GC 时间过长有了实质性帮助。
 *  确认监控问题。之前大家看到容器使用监控上应用重启都是因为内存翻倍使用，但实际情况是容器在重启后，监控平台把两个容器使用的内存求和了，没有单独分开处理。
 *  支持可选择分配器。基础架构部门对 jemalloc 和 tcmalloc 的内存分配器进行支持。

![format_png 23][]

作者：去哪儿技术沙龙  
链接：https://juejin.cn/post/7211395676801089591

[format_png]: https://img-blog.csdnimg.cn/img_convert/e5e2aaa70e4fa9e486442df149288ab8.webp?x-oss-process=image/format,png
[format_png 1]: https://img-blog.csdnimg.cn/img_convert/4b90ad4b30835e4f270282b313e1b69d.webp?x-oss-process=image/format,png
[format_png 2]: https://img-blog.csdnimg.cn/img_convert/1efcaa76b5bafb3c548223c3378482b3.webp?x-oss-process=image/format,png
[format_png 3]: https://img-blog.csdnimg.cn/img_convert/086d77662f38dd8676261ce6d3c40a0b.webp?x-oss-process=image/format,png
[format_png 4]: https://img-blog.csdnimg.cn/img_convert/ea74c01cf4c36810aad0b98aebb8288d.webp?x-oss-process=image/format,png
[format_png 5]: https://img-blog.csdnimg.cn/img_convert/4124ed360a840c016de83cfb9e7c537b.webp?x-oss-process=image/format,png
[gceasy.io]: https://link.juejin.cn?target=https%3A%2F%2Fgceasy.io%2F
[format_png 6]: https://img-blog.csdnimg.cn/img_convert/a2c5935376e0e7331b44703c5f6d6698.webp?x-oss-process=image/format,png
[format_png 7]: https://img-blog.csdnimg.cn/img_convert/615499520565902764f46f68aed12408.webp?x-oss-process=image/format,png
[format_png 8]: https://img-blog.csdnimg.cn/img_convert/3b603af6a2f9a91a7390656441e5c69b.webp?x-oss-process=image/format,png
[format_png 9]: https://img-blog.csdnimg.cn/img_convert/c630e6f8f4a46f37debd84391cc17ecc.webp?x-oss-process=image/format,png
[format_png 10]: https://img-blog.csdnimg.cn/img_convert/030760e61ec7f97507fed709be247c28.webp?x-oss-process=image/format,png
[format_png 11]: https://img-blog.csdnimg.cn/img_convert/1bf6f8adeaab31f364b3df820fc077bb.webp?x-oss-process=image/format,png
[format_png 12]: https://img-blog.csdnimg.cn/img_convert/c8dc7962ca58280e7c6178c3ca7a6282.webp?x-oss-process=image/format,png
[format_png 13]: https://img-blog.csdnimg.cn/img_convert/2c788900f88b4059bf0b1b51948a04cd.webp?x-oss-process=image/format,png
[format_png 14]: https://img-blog.csdnimg.cn/img_convert/6576f8593c297019f808218acf69284c.webp?x-oss-process=image/format,png
[format_png 15]: https://img-blog.csdnimg.cn/img_convert/77a6578f4714dbe560b7d453353283e6.webp?x-oss-process=image/format,png
[format_png 16]: https://img-blog.csdnimg.cn/img_convert/8db58d8157ecba231944ddb8457c831e.webp?x-oss-process=image/format,png
[format_png 17]: https://img-blog.csdnimg.cn/img_convert/c3c8a457d1d5abccbbceba50f42b35c1.webp?x-oss-process=image/format,png
[format_png 18]: https://img-blog.csdnimg.cn/img_convert/697272e90b6e793de41627b43496abfe.webp?x-oss-process=image/format,png
[format_png 19]: https://img-blog.csdnimg.cn/img_convert/8201cddbdb2f8e2769a1ec0c955232d8.webp?x-oss-process=image/format,png
[format_png 20]: https://img-blog.csdnimg.cn/img_convert/73eaa513b27507daeb0334f315d2a404.webp?x-oss-process=image/format,png
[format_png 21]: https://img-blog.csdnimg.cn/img_convert/596c15bbfd8b3f8a8c501bc9014ede14.webp?x-oss-process=image/format,png
[format_png 22]: https://img-blog.csdnimg.cn/img_convert/40f47b1d38244c6372a22a29b7bfce72.webp?x-oss-process=image/format,png
[format_png 23]: https://img-blog.csdnimg.cn/img_convert/bccbe2cc043977ec9bbad5273a8b5376.webp?x-oss-process=image/format,png