一次线上内存泄漏的解决过程

内存泄漏这种问题是可遇不可求的经历,终于有机会抓住了它,要好好的记录下来。出现问题的是打成jar包的一个引擎程序

引擎逻辑

大致是生产者消费者模式的一个数据处理引擎

public class MainClass {
    public static void main(String[] args) {
        try {
            //定义 线程池、队列、门闩
            ExecutorService service = Executors.newCachedThreadPool();
            BlockingQueue<JSONObject> queue = new LinkedBlockingQueue<JSONObject>(100);
            CountDownLatch latch = new CountDownLatch(10);
            //1个生产者
            Producer producer = new Producer(queue);
            service.execute(producer);
            //10个消费者,每个消费者加门闩,消费完成减一
            for (int i = 0; i < 10; i++) {
                service.submit(new Consumer(queue,latch));
            }

            service.shutdown();
            //主线程等待门闩,都完成后开始第二次循环
            try {
                latch.await();
            } catch (InterruptedException e) {
                e.printStackTrace();
            }
            
        }catch (Exception e){
        }
        try {
            TimeUnit.SECONDS.sleep(10);
        } catch (InterruptedException e) {
            e.printStackTrace();
        }
        System.out.println("循环一次结束,第二次开始调用");
        main(new String[]{});
    }
}

业务逻辑为生产者消费者启动,用CountDownLatch来阻塞住主线程,等所有消费者生产者线程完成并结束后,main方法开始调用自己,开始第二次启动,循环调用

这种情况下运行一段时间后会出现异常:

Caused by: java.lang.OutOfMemoryError: unable to create new native thread
    at java.lang.Thread.start0(Native Method)
    at java.lang.Thread.start(Thread.java:717)
    at java.util.concurrent.ThreadPoolExecutor.addWorker(ThreadPoolExecutor.java:957)
    at java.util.concurrent.ThreadPoolExecutor.execute(ThreadPoolExecutor.java:1367)
    at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:112)

针对OutOfMemoryError异常我们使用jdk自带的工具jvisualvm来查看

jvisualvm使用

jvisualvm自从 JDK 6 Update 7 以后已经作为JDK 的一部分,位于 JDK 根目录的 bin 文件夹下,无需安装,直接运行即可

image.png

打开后左侧是所有的进程,可以打开任意一个进行详细信息查看
image.png

右侧对应显示详细信息
image.png

分析程序崩溃时堆文件

程序运行时,设置参数

-Xms200m
-Xmx200m
-XX:+HeapDumpOnOutOfMemoryError
-XX:HeapDumpPath=d:/dump/

设置最大内存和指定OutOfMemoryError时存储堆文件的位置

我们使用jvisualvm打开堆文件java_pid42132.hprof


image.png

占用内存最大的是Obeject[]和byte[],并没有显示具体是哪个类导致的内存问题,暂时无从下手。

猜想1:线程池的线程数过多导致

我们只能从程序逻辑来猜想这个问题了,由于程序多次回调,很有可能是线程池里的线程未及时关闭导致的,我们修改代码来验证

public class MainClass {
    //全局线程池
    static ExecutorService service = Executors.newCachedThreadPool();
    
    public static void main(String[] args) {
        try {
            //定义 线程池、队列、门闩
            
            BlockingQueue<JSONObject> queue = new LinkedBlockingQueue<JSONObject>(100);
            CountDownLatch latch = new CountDownLatch(10);
            //1个生产者
            Producer producer = new Producer(queue);
            service.execute(producer);
            //10个消费者,每个消费者加门闩,消费完成减一
            for (int i = 0; i < 10; i++) {
                service.submit(new Consumer(queue,latch));
            }

            service.shutdown();
            //主线程等待门闩,都完成后开始第二次循环
            try {
                latch.await();
            } catch (InterruptedException e) {
                e.printStackTrace();
            }
            //输出线程池状态
            System.out.println(service.toString());
            
        }catch (Exception e){
        }
        try {
            TimeUnit.SECONDS.sleep(10);
        } catch (InterruptedException e) {
            e.printStackTrace();
        }
        System.out.println("循环一次结束,第二次开始调用");
        main(new String[]{});
    }
}

定义全局的线程池变量,每次输出线程池状态【长度,活动线程数,完成线程数】

java.util.concurrent.ThreadPoolExecutor@720653c2[Running, pool size = 11, active threads = 0, queued tasks = 0, completed tasks = 11]
循环一次结束,第二次开始调用
java.util.concurrent.ThreadPoolExecutor@720653c2[Running, pool size = 11, active threads = 0, queued tasks = 0, completed tasks = 22]
循环一次结束,第二次开始调用
java.util.concurrent.ThreadPoolExecutor@720653c2[Running, pool size = 11, active threads = 0, queued tasks = 0, completed tasks = 33]
循环一次结束,第二次开始调用
java.util.concurrent.ThreadPoolExecutor@720653c2[Running, pool size = 11, active threads = 0, queued tasks = 0, completed tasks = 44]
循环一次结束,第二次开始调用
java.util.concurrent.ThreadPoolExecutor@720653c2[Running, pool size = 11, active threads = 0, queued tasks = 0, completed tasks = 55]
循环一次结束,第二次开始调用
java.util.concurrent.ThreadPoolExecutor@720653c2[Running, pool size = 11, active threads = 0, queued tasks = 0, completed tasks = 66]
循环一次结束,第二次开始调用

通过输出可以看到:

存活线程数一直是0,当前线程池长度为pool size=11,也就是刚执行完的来不及释放的1个生产者10个消费者线程,已完成线程数completed tasks=11,22,33,44,55,66... 依次增长。

排除了线程池带来的内存溢出。

main方法无限回调导致的内存问题

为了验证这个猜想,设计代码如下

public class MainClass {
    public static void main(String[] args) {
        try {
            //定义 线程池、队列、门闩
            ExecutorService service = Executors.newCachedThreadPool();
            BlockingQueue<JSONObject> queue = new LinkedBlockingQueue<JSONObject>(100);
            CountDownLatch latch = new CountDownLatch(10);
            //new 10个生产者
            for(int i=0;i<10;i++){
                Producer producer = new Producer(queue);
            }
        }catch (Exception e){
        }
        try {
            TimeUnit.SECONDS.sleep(2);
        } catch (InterruptedException e) {
            e.printStackTrace();
        }
        System.out.println("循环一次结束,第二次开始调用");
        main(new String[]{});
    }
}

无限的new对象,无限的递归

通过jvisualvm进行监控,如下图


QQ图片20180918152927.png

可以看到内存会周期性的进行回收并保持良好状态,这个猜想也不正确。

client没close()导致

最终通过代码一块块的逻辑排除法得出结论:

是生产者和消费者中的连接Elasticsearch的Client使用完毕后,虽然线程关闭了,但是client没有关闭导致的

通过jvisualvm也可以发现一些线索,我们使用jvisualvm打开堆文件java_pid42132.hprof

image.png

双击打开java.lang.Object[]可以查看它的组成
image.png

一级一级的跟下去会发现有elasticsearch——client的影子

最后

解决方法很简单:线程结束时,关闭该线程使用的client客户端

elasticServer.client.close();
System.out.println("consumer end!");
latch.countDown();

我们要注意的就是在数据库连接的处理上要额外注意,一般情况下不会出问题,在频繁的连接释放和递归时,很有可能引起内存泄漏。

©著作权归作者所有,转载或内容合作请联系作者
  • 序言:七十年代末,一起剥皮案震惊了整个滨河市,随后出现的几起案子,更是在滨河造成了极大的恐慌,老刑警刘岩,带你破解...
    沈念sama阅读 204,732评论 6 478
  • 序言:滨河连续发生了三起死亡事件,死亡现场离奇诡异,居然都是意外死亡,警方通过查阅死者的电脑和手机,发现死者居然都...
    沈念sama阅读 87,496评论 2 381
  • 文/潘晓璐 我一进店门,熙熙楼的掌柜王于贵愁眉苦脸地迎上来,“玉大人,你说我怎么就摊上这事。” “怎么了?”我有些...
    开封第一讲书人阅读 151,264评论 0 338
  • 文/不坏的土叔 我叫张陵,是天一观的道长。 经常有香客问我,道长,这世上最难降的妖魔是什么? 我笑而不...
    开封第一讲书人阅读 54,807评论 1 277
  • 正文 为了忘掉前任,我火速办了婚礼,结果婚礼上,老公的妹妹穿的比我还像新娘。我一直安慰自己,他们只是感情好,可当我...
    茶点故事阅读 63,806评论 5 368
  • 文/花漫 我一把揭开白布。 她就那样静静地躺着,像睡着了一般。 火红的嫁衣衬着肌肤如雪。 梳的纹丝不乱的头发上,一...
    开封第一讲书人阅读 48,675评论 1 281
  • 那天,我揣着相机与录音,去河边找鬼。 笑死,一个胖子当着我的面吹牛,可吹牛的内容都是我干的。 我是一名探鬼主播,决...
    沈念sama阅读 38,029评论 3 399
  • 文/苍兰香墨 我猛地睁开眼,长吁一口气:“原来是场噩梦啊……” “哼!你这毒妇竟也来了?” 一声冷哼从身侧响起,我...
    开封第一讲书人阅读 36,683评论 0 258
  • 序言:老挝万荣一对情侣失踪,失踪者是张志新(化名)和其女友刘颖,没想到半个月后,有当地人在树林里发现了一具尸体,经...
    沈念sama阅读 41,704评论 1 299
  • 正文 独居荒郊野岭守林人离奇死亡,尸身上长有42处带血的脓包…… 初始之章·张勋 以下内容为张勋视角 年9月15日...
    茶点故事阅读 35,666评论 2 321
  • 正文 我和宋清朗相恋三年,在试婚纱的时候发现自己被绿了。 大学时的朋友给我发了我未婚夫和他白月光在一起吃饭的照片。...
    茶点故事阅读 37,773评论 1 332
  • 序言:一个原本活蹦乱跳的男人离奇死亡,死状恐怖,灵堂内的尸体忽然破棺而出,到底是诈尸还是另有隐情,我是刑警宁泽,带...
    沈念sama阅读 33,413评论 4 321
  • 正文 年R本政府宣布,位于F岛的核电站,受9级特大地震影响,放射性物质发生泄漏。R本人自食恶果不足惜,却给世界环境...
    茶点故事阅读 39,016评论 3 307
  • 文/蒙蒙 一、第九天 我趴在偏房一处隐蔽的房顶上张望。 院中可真热闹,春花似锦、人声如沸。这庄子的主人今日做“春日...
    开封第一讲书人阅读 29,978评论 0 19
  • 文/苍兰香墨 我抬头看了看天上的太阳。三九已至,却和暖如春,着一层夹袄步出监牢的瞬间,已是汗流浃背。 一阵脚步声响...
    开封第一讲书人阅读 31,204评论 1 260
  • 我被黑心中介骗来泰国打工, 没想到刚下飞机就差点儿被人妖公主榨干…… 1. 我叫王不留,地道东北人。 一个月前我还...
    沈念sama阅读 45,083评论 2 350
  • 正文 我出身青楼,却偏偏与公主长得像,于是被迫代替她去往敌国和亲。 传闻我的和亲对象是个残疾皇子,可洞房花烛夜当晚...
    茶点故事阅读 42,503评论 2 343

推荐阅读更多精彩内容

  • 本文是我自己在秋招复习时的读书笔记,整理的知识点,也是为了防止忘记,尊重劳动成果,转载注明出处哦!如果你也喜欢,那...
    波波波先森阅读 11,235评论 4 56
  • Spring Cloud为开发人员提供了快速构建分布式系统中一些常见模式的工具(例如配置管理,服务发现,断路器,智...
    卡卡罗2017阅读 134,596评论 18 139
  • 广袤无垠的撒哈拉有多少粒沙在风中挣扎翻滚,平凡的世界里就有多少只灵魂在尘世间爱恨情仇。佛说:阿弥佗佛,一切皆是因缘~
    清辉V阅读 306评论 2 1
  • 天亮了,太阳升起,门外传来远远的犬吠声。 睁开眼,伸个懒腰,披上外套,饮下一杯冽的山泉水,和着一块小饼。晨...
    听歌的古董阅读 242评论 0 0
  • 0918(day2) 书名:《赞赏的5种语言》 正文字数:720字 1.《学会倾听,让沟通更顺畅》 里克.里德是一...
    苹果Apple来了阅读 266评论 0 0