0
  • 聊天消息
  • 系统消息
  • 评论与回复
登录后你可以
  • 下载海量资料
  • 学习在线课程
  • 观看技术视频
  • 写文章/发帖/加入社区
会员中心
创作中心

完善资料让更多小伙伴认识你,还能领取20积分哦,立即完善>

3天内不再提示

一行log日志,引发了P1的线上事故

jf_ro2CN3Fa 来源:芋道源码 作者:芋道源码 2022-11-07 15:48 次阅读


线上事故回顾

前段时间同事新增了一个特别简单的功能,晚上上线前review代码时想到公司拼搏进取的价值观临时他加一行 log 日志,觉得就一行简单的日志基本上没啥问题,结果刚上完线后一堆报警,赶紧回滚了代码,找到问题删除了添加日志的代码,重新上线完毕。

基于 Spring Boot + MyBatis Plus + Vue & Element 实现的后台管理系统 + 用户小程序,支持 RBAC 动态权限、多租户、数据权限、工作流、三方登录、支付、短信、商城等功能

  • 项目地址:https://gitee.com/zhijiantianya/ruoyi-vue-pro
  • 视频教程:https://doc.iocoder.cn/video/

情景还原

定义了一个 CountryDTO

publicclassCountryDTO{
privateStringcountry;

publicvoidsetCountry(Stringcountry){
this.country=country;
}

publicStringgetCountry(){
returnthis.country;
}

publicBooleanisChinaName(){
returnthis.country.equals("中国");
}
}

定义测试类 FastJonTest

publicclassFastJonTest{
@Test
publicvoidtestSerialize(){
CountryDTOcountryDTO=newCountryDTO();
Stringstr=JSON.toJSONString(countryDTO);
System.out.println(str);
}
}

运行时报空指针错误:

64775822-5d7b-11ed-a3b6-dac502259ad0.png空指针

通过报错信息可以看出来是序列化的过程中执行了isChinaName()方法,这时候this.country变量为空,那么问题来了:

  • 序列化为什么会执行isChinaName()呢?
  • 引申一下,序列化过程中会执行那些方法呢?

基于 Spring Cloud Alibaba + Gateway + Nacos + RocketMQ + Vue & Element 实现的后台管理系统 + 用户小程序,支持 RBAC 动态权限、多租户、数据权限、工作流、三方登录、支付、短信、商城等功能

  • 项目地址:https://gitee.com/zhijiantianya/yudao-cloud
  • 视频教程:https://doc.iocoder.cn/video/

源码分析

通过 debug 观察调用链路的堆栈信息

652d8e80-5d7b-11ed-a3b6-dac502259ad0.png65920e46-5d7b-11ed-a3b6-dac502259ad0.jpg

调用链中的ASMSerializer_1_CountryDTO.writeFastJson使用asm技术动态生成了一个类ASMSerializer_1_CountryDTO

asm技术其中一项使用场景就是通过到动态生成类用来代替java反射,从而避免重复执行时的反射开销

JavaBeanSerizlier序列化原理

通过下图看出序列化的过程中,主要是调用JavaBeanSerializer类的write()方法。

65b3b3de-5d7b-11ed-a3b6-dac502259ad0.pngObjectSerializer实现类JavaBeanSerializer

JavaBeanSerializer主要是通过getObjectWriter()方法获取,通过对getObjectWriter()执行过程的调试,找到比较关键的com.alibaba.fastjson.serializer.SerializeConfig#createJavaBeanSerializer方法,进而找到 com.alibaba.fastjson.util.TypeUtils#computeGetters

publicstaticListcomputeGetters(Classclazz,//
JSONTypejsonType,//
MapaliasMap,//
MapfieldCacheMap,//
booleansorted,//
PropertyNamingStrategypropertyNamingStrategy//
){
//省略部分代码....
Method[]methods=clazz.getMethods();
for(Methodmethod:methods){
//省略部分代码...
if(method.getReturnType().equals(Void.TYPE)){
continue;
}
if(method.getParameterTypes().length!=0){
continue;
}
//省略部分代码...
JSONFieldannotation=TypeUtils.getAnnotation(method,JSONField.class);
//省略部分代码...
if(annotation!=null){
if(!annotation.serialize()){
continue;
}
if(annotation.name().length()!=0){
//省略部分代码...
}
}
if(methodName.startsWith("get")){
//省略部分代码...
}
if(methodName.startsWith("is")){
//省略部分代码...
}
}
}

从代码中大致分为三种情况:

  • @JSONField(.serialize = false, name = "xxx")注解
  • getXxx() : get开头的方法
  • isXxx():is开头的方法

序列化流程图

65d61064-5d7b-11ed-a3b6-dac502259ad0.png序列化流程图

示例代码

/**
*case1:@JSONField(serialize=false)
*case2:getXxx()返回值为void
*case3:isXxx()返回值不等于布尔类型
*case4:@JSONType(ignores="xxx")
*/
@JSONType(ignores="otherName")
publicclassCountryDTO{
privateStringcountry;

publicvoidsetCountry(Stringcountry){
this.country=country;
}

publicStringgetCountry(){
returnthis.country;
}

publicstaticvoidqueryCountryList(){
System.out.println("queryCountryList()执行!!");
}

publicBooleanisChinaName(){
System.out.println("isChinaName()执行!!");
returntrue;
}

publicStringgetEnglishName(){
System.out.println("getEnglishName()执行!!");
return"lucy";
}

publicStringgetOtherName(){
System.out.println("getOtherName()执行!!");
return"lucy";
}

/**
*case1:@JSONField(serialize=false)
*/
@JSONField(serialize=false)
publicStringgetEnglishName2(){
System.out.println("getEnglishName2()执行!!");
return"lucy";
}

/**
*case2:getXxx()返回值为void
*/
publicvoidgetEnglishName3(){
System.out.println("getEnglishName3()执行!!");
}

/**
*case3:isXxx()返回值不等于布尔类型
*/
publicStringisChinaName2(){
System.out.println("isChinaName2()执行!!");
return"isChinaName2";
}
}

运行结果为:

isChinaName()执行!!
getEnglishName()执行!!
{"chinaName":true,"englishName":"lucy"}

代码规范

可以看出来序列化的规则还是很多的,比如有时需要关注返回值,有时需要关注参数个数,有时需要关注@JSONType注解,有时需要关注@JSONField注解;当一个事物的判别方式有多种的时候,由于团队人员掌握知识点的程度不一样,这个方差很容易导致代码问题,所以尽量有一种推荐方案。

这里推荐使用@JSONField(serialize = false)来显式的标注方法不参与序列化,下面是使用@JSONField注解后的代码,是不是一眼就能看出来哪些方法不需要参与序列化了。

publicclassCountryDTO{
privateStringcountry;

publicvoidsetCountry(Stringcountry){
this.country=country;
}

publicStringgetCountry(){
returnthis.country;
}

@JSONField(serialize=false)
publicstaticvoidqueryCountryList(){
System.out.println("queryCountryList()执行!!");
}

publicBooleanisChinaName(){
System.out.println("isChinaName()执行!!");
returntrue;
}

publicStringgetEnglishName(){
System.out.println("getEnglishName()执行!!");
return"lucy";
}

@JSONField(serialize=false)
publicStringgetOtherName(){
System.out.println("getOtherName()执行!!");
return"lucy";
}

@JSONField(serialize=false)
publicStringgetEnglishName2(){
System.out.println("getEnglishName2()执行!!");
return"lucy";
}

@JSONField(serialize=false)
publicvoidgetEnglishName3(){
System.out.println("getEnglishName3()执行!!");
}

@JSONField(serialize=false)
publicStringisChinaName2(){
System.out.println("isChinaName2()执行!!");
return"isChinaName2";
}
}

三个频率高的序列化的情况

65fbca66-5d7b-11ed-a3b6-dac502259ad0.png三个频率高的序列化的情况

以上流程基本遵循,发现问题 --> 原理分析 --> 解决问题 --> 升华(编程规范)。

  • 围绕业务上:解决问题 -> 如何选择一种好的额解决方案 -> 好的解决方式如何扩展 n 个系统应用;
  • 围绕技术上:解决单个问题,顺着单个问题掌握这条线上的原理。

但其实这段代码我并不满意,原因是和 FastJson 依赖太高了。我想要的效果是,不依赖任何特定的 JSON 序列化框架。当我需要替换掉它的时候,随时可以替换掉。

并且在写代码时,不要过于依赖日志。打日志只需要打紧要且关键的信息即可,不要什么日志都打,我曾见过一个系统,一个小时,把 128G 磁盘跑满的管理系统。几乎没啥并发,但几乎每个请求都输出几 M 的日志,这件事我后面会单独拿出来讲讲。

关于@JSONField@JSONType等特性注解,后面我会在团队内规范并给出新的解耦方案,把它们移除掉。



审核编辑 :李倩


声明:本文内容及配图由入驻作者撰写或者入驻合作网站授权转载。文章观点仅代表作者本人,不代表电子发烧友网立场。文章及其配图仅供工程师学习之用,如有内容侵权或者其他违规问题,请联系本站处理。 举报投诉
  • 代码
    +关注

    关注

    30

    文章

    4841

    浏览量

    69196
  • 日志
    +关注

    关注

    0

    文章

    139

    浏览量

    10690

原文标题:一行log日志,引发了P1的线上事故

文章出处:【微信号:芋道源码,微信公众号:芋道源码】欢迎添加关注!文章转载请注明出处。

收藏 人收藏

    评论

    相关推荐

    Linux实时查看日志的四种命令详解

    如何在Linux中实时查看日志文件的内容?那么有很多实用程序可以帮助用户在文件更改或不断更新时输出文件的内容。在Linux中实时显示文件内容的常用命令是tail命令(有效地管理文件)。 1
    的头像 发表于 01-13 10:45 538次阅读
    Linux实时查看<b class='flag-5'>日志</b>的四种命令详解

    丰田汽车一行到访中汽中心

    近日,丰田汽车株式会社(以下简称“丰田”)车辆开发中心中心长御沓悟司一行到访中汽中心,与中汽中心党委委员、副总经理李洧进行了座谈交流。
    的头像 发表于 01-09 10:56 173次阅读

    玩转Nginx日志管理:高效排查问题的终极指南

    Nginx日志对于统计、系统服务排错很有用。Nginx日志主要分为两种:access_log(访问日志)和error_log(错误
    的头像 发表于 12-30 13:50 225次阅读

    TFP401AMP第一行丢失4个像素是什么原因导致的?

    PC送出来的HDMI信号通过TFP401AMP转RGB后送到LCD,发现图片第一行会有4个像素丢失,经过逻辑分析仪抓取HS信号发现:HS在DE信号low的时间段里周期为31.774
    发表于 12-30 08:39

    泰国国家石油一行到访商汤科技

    近日,泰国国家石油有限公司董事长Chatchai Phromlert先生,首席执行官兼总裁Kongkrapan Intarajang先生率董事会到访商汤科技,商汤科技副总裁、亚太业务事业群总裁史军、亚太业务事业群副总裁邹春慧等热情接待了董事会一行
    的头像 发表于 12-19 09:15 353次阅读

    政府关怀 | 省、市、区领导干部一行莅临鑫金晖进行参观调研

    11月14日,省、市、区领导干部一行莅临我司进行调研参观,钟瑞明董事长全程陪同参观,深入沟通交流。领导一行深入生产车间,实地察看了生产现场情况,现场了解了鑫金晖的整体布局、运行情况以及发展理念。钟瑞
    的头像 发表于 11-23 01:07 419次阅读
    政府关怀 | 省、市、区领导干部<b class='flag-5'>一行</b>莅临鑫金晖进行参观调研

    nginx日志配置方法

    access_log用来定义日志级别,日志位置。
    的头像 发表于 10-24 17:43 282次阅读

    软通动力领导一行访问福州大学

    软通动力高级副总裁王晓良、杨念农,软通教育总裁林涛及福建新东湖集团有限公司董事长陈国平一行来访福州大学。校党委陈国龙书记在行政南楼会见了客人并座谈交流,郭文忠副校长陪同会见。
    的头像 发表于 10-10 10:43 570次阅读

    RIMAC与IMD一行来访声扬科技,共话AI语音赋能产业升级

    科技联合创始人、CTO陈东鹏博士等展开深入交流,共同探讨AI语音技术在保险行业的创新应用。RIMAC与IMD一行来访声扬科技来访中,RIMAC与IMD一行贵宾深入
    的头像 发表于 09-30 10:56 765次阅读
    RIMAC与IMD<b class='flag-5'>一行</b>来访声扬科技,共话AI语音赋能产业升级

    光伏互感器p1p2方向的区别

    光伏互感器是种用于测量光伏系统中电流和电压的设备,它在光伏发电系统中起着至关重要的作用。光伏互感器的P1P2方向是指互感器的输入端和输出端的方向,它们对于互感器的正常工作和测量精度具有重要
    的头像 发表于 08-21 18:21 2776次阅读

    清华PE产投汇一行赴谷东科技考察与交流

    日前,PE产投汇一行赴国家级专精特新小巨人校友企业——谷东科技北京公司进行考察与交流。
    的头像 发表于 08-13 09:45 668次阅读

    在VSCODE终端make时遇到错误要一行一行看然后定位,可以直接跳转点击或者VSCODE定位错误吗?

    每次在VSCODE终端make时遇到错误要一行一行看然后定位,可以直接跳转点击或者VSCODE直接定位错误吗?能给个指引链接吗?谢谢!
    发表于 06-25 07:37

    如何检查日志中是否有类似cm_cy_log_msg( CYLF_MIDDLEWARE, CY_LOG_ERR) 的内容?

    运行,有什么方法可以检查 cm_cy_log_msg 生成的日志? 像这样: cm_cy_log_msg( CYLF_MIDDLEWARE, CY_LOG_ERR,\"Invalid
    发表于 05-31 12:45

    甘肃考察团一行莅临拓维信息参观调研

    4月12至13日,甘肃考察团一行莅临拓维信息调研,深入
    的头像 发表于 04-18 08:14 950次阅读
    甘肃考察团<b class='flag-5'>一行</b>莅临拓维信息参观调研

    STM32CUBEMX4.22.1在main函数里面添加一行语句就死机的原因?

    用STM32CUBEMX4.22.1版本,内嵌FREERTOS,想把4路ADC通过DMA方式采集,但是在main函数里面添加一行语句:HAL_ADC_Start_DMA( hadc1, ADC_DMA_Buf[0],4);后就死机了
    发表于 04-11 07:57