springboot2.5日志系统的设计缺陷

最近springboot2.5.0发布后,将logging.register-shutdown-hook默认值从false改成了true,暴露了问题,进程关闭时日志系统可能先于spring容器关闭,造成无法打印日志

先了解一下springboot中关闭spring容器的机制

  • spring.main.register-shutdown-hook参数默认是true,该参数会注册jvm hook在进程关闭时关闭spring容器



再来看下springboot中关闭日志系统的机制

  • logging.register-shutdown-hook参数默认是true,该参数会注册jvm hook在进程关闭时关闭日志系统


  • 以logback为例看shutdownHook就是直接调用的stop


问题分析

  • 所以可以看出springboot的容器关闭和日志系统关闭用伪代码表示就是
Runtime.getRuntime().addShutdownHook(new Thread(() -> spring.stop));
Runtime.getRuntime().addShutdownHook(new Thread(() -> logSystem.stop));

-jvm的不同shutdownHook执行是并行的也就造成了,spring容器的关闭和日志系统关闭时间先后的不确定

  • 在springboot2.5.0之前,logging.register-shutdown-hook默认false,不会注册日志系统的shutdownHook,所以该问题不明显,只有在使用异步appender的时候才有可能出问题

    jvm关闭时,异步append的打印队列中还有未打印的日志时,这些日志就丢了

  • 从工程依赖关系看:用户业务 -> spring -> 包括日志系统在内的基础组件。在这种单向依赖的设计下,spring除了要对上层的用户业务暴露出生命周期回调接口外,还应该对下层依赖的基础组件暴露出生命周期的回调接口才对,比如下图红圈里就应该提供一个回调


  • spring关闭后只是将active标示为了false,并没有回调
  • 该问题已经在社区被注意到了,但是修复起来好像比较麻烦,因为日志系统的关闭一开始从设计上的思路就不对,就不该依赖并行的jvm shutdownHook。现在springboot的修复方法很不优雅,通过弱引用+不断调用isActive来判断spring的关闭

https://github.com/spring-projects/spring-boot/issues/26660

3种解决办法

  • 等待springboot修复
  • 使用2.5.0之前的springboot,或将logging.register-shutdown-hook设为false,并且不要使用异步appender打印日志
  • 不使用springboot提供的关闭日志系统机制,自己实现。以logback为例,自己在代码中实现ch.qos.logback.core.hook.ShutdownHookBase并在logback-spring.xml中配置

注意:实现ShutdownHookBase的类自己添加到jvm hook中会失效,只能在配置文件中使用,logback解析配置文件时会把context传入到该类中



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

推荐阅读更多精彩内容