Jenkins/Java 线程泄露排查(二)

angry-jenkins

📜 目录

  • 😮 一天上涨五十个线程?
  • 🔍 初步分析
  • 🚀 复现方式
  • 🐢 二分法确定问题
  • 🗒️ 总结

😮 一天上涨五十个线程?

上次提到,经过初步修复后,又继续观察了几天线程变化情况,发现每天仍然会有 50 个线程的泄露

虽然对比之前已经好了太多,但是现在的情况仍然难以接受,问题没有彻底解决,因此又开始了新一轮的排查与修复

🔍 初步分析

确认存在问题以后,首先要确认泄露的线程是哪些

每隔一段时间就搜集一下线程数据 ( 方式参考 一个 Jenkins 实例启动了两万多个线程? ),通过对比前后两次线程详情,确认了泄露的线程如下

leakage.png

这种线程是最让人反感的,没有任何调用方的提示,压根找不到在哪里发生了泄露,让人垂头丧气

但是看线程变化趋势,即使是在 Jenkins 静息状态(没有流水线执行)下,线程也会增长,看起来泄露和执行流水线关系不大

联系到在 上篇文章 中提到的 kubernetes-client/java 存在的线程泄露的问题,怀疑这次是否又是类似的问题?

浏览了 controller 相关的代码。发现在 controller 启动时,会启动一个 informer ,这个 informer 也会启动一个线程,public SharedInformerFactory() 在初始化 SharedInformerFactory 时会通过 Executors.newCachedThreadPool() 新建一个线程池

因为暂时没找到哪里会关闭这个 informer,所以怀疑是这里的问题,通过将 informer 代码的调用注释掉,发现线程仍然会继续增长,明显不是这里造成的问题,后续才发现 informer 的关闭是在 ControllerManager shutdown() 中完成的

线索断掉了,单纯的分析代码可能难有进展,那就分析下线程变化,看看能够有什么进展

🚀 复现方式

耐心的分析线程变化,发现线程泄露也是周期性的,每次会新增两个线程,那可能和上一个 TIMED_WAITTING 线程泄露的触发条件一样

因此使用一些手段重启了 controller manager ,发现线程的泄露数目会增加的很快,之前是周期性的增长,这次在重启 controller manager 之后,可以明显看到类似线程的泄露现象

这就算是找到了稳定的复现方式了,问题有解决的希望了

🐢 二分法确定问题

线程堆栈信息中没有提供指向性明确的信息,因为目前只是确认了问题代码范围,就只能用原始的二分法来确定问题代码了

首先将 controller manager 的调用代码注释掉,编译插件部署后,发现问题果然不在复现了,因此基本能够确认问题就在 controller manager 这里

后续将 controller 分为两组,一部分启动,一部分不启动,编译部署,通过复现方式确认线程变化后,可以找到在哪些 controller 中存在问题

找到对应的 controller 后,再将 controller 的代码分为上下两部分,注释一部分,编译复现确认,再注释一部分,编译复现确认,最终终于发现了问题代码

问题就出现在下面这段代码中,在某些 controller 启动时,会调用这里的 pollWithNoInitialDelay 函数,这个函数启动时就会初始化一个线程,用来完成任务,但是这里缺少关闭这个线程的逻辑

cause-code.png

目前已经知道,每小时 controller manger 会重启一次,也就是意味着这里每天会重启 24 次,看 usage 也能知道有两个地方在调用这个函数,所以次重启会泄露两个线程,计算下来正好是 48 个线程,正好与之前问题复现中提到的 50 个线程匹配

看起来或许这个就是最后一个窟窿了

问题足够简单,修复也很简单,只需要在 finally 处关闭这个线程即可,这样无论该函数调度多少次,都不会再发生线程泄露了

 try {
   while (System.currentTimeMillis() < dueDate) {
     if (result.get()) {
       future.cancel(true);
       return true;
     }
   }
 } catch (Exception e) {
   return result.get();
 } finally {
   # 将线程关闭
   executorService.shutdown();
 }

后续连续观察了三天线程变化,并且在这期间跑了几次集成测试,通过在 Jenkins 上调度了很多流水线模拟正常使用情况

发现在流水线执行过程中,线程会有变化起伏,但是都没有超过 250 个,在流水线执行结束后,最终都会稳定回归到 170 左右

stable.png

后续在 Jenkins 静息状态下观察了一周,发现线程一直在 170 上下,没有出现线程泄露的情况,至此确认问题解决,所有的窟窿都被补上了

🗒️ 总结

在 Java 中启动线程时,一定要考虑关闭线程,我们应该要考虑照顾到这样一个场景,也就是 这段代码如果被反复调用一万次,是否会出现线程泄露,不能单纯的认为,这个初始化的代码应该只会被调用一次,就不去关闭线程

另外在启动和关闭线程时,考虑 debug 打印一下这两个操作,不仅有助于自己判断对线程的处理是否生效,也对后来可能的排错场景大有裨益

最后对我来说,这次解决问题时最大的提升,就是在我走投无路时,耐心的查看线程变化信息,并且坚信它会提供给我解决问题的信息,最终结果也没让我失望,我觉得这是这次对我来说最重要的变化


希望通过这两篇线程泄露排查的博文,能为各位在解决类似问题时,提供一些解决思路,帮助各位解决问题

有任何问题欢迎评论交流!

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

推荐阅读更多精彩内容