日志打印规范

日志打印规范

本文不讨论使用什么日志框架(通常是slf4j+lo4j/log4j2/logback)或者如何使用,重点讨论在什么情况下需要打印日志,如何打印日志,用什么样的日志级别,打印哪些关键信息,用什么格式打印等。

为什么要规范日志打印

为什么需要日志?因为我们需要知道代码内部的运行状态,快速定位运行情况。为什么需要规范日志?希望在规范化后,提高日志的可读性,方便终端查询,及后续用日志分析工具也更方便。

如何做能打出规范的日志

日志打印(java)分为两个部分, 一部分是日志库打印的元信息(时间、方法、进程、行号等),另一部分是业务中重要的流程和变量值,其中元信息可以通过规范日志框架的conversion pattern来规范,业务中的重要的流程和变量需要一些约定,通过代码评审和peer追bug来持续改进。

日志打印建议

1.RD需要注意(以下英文摘自Google blog,Optimal Logging)
Good things to log:

  • Important startup configuration 重要的启动配置
  • Changes to persistent data 对持久化数据的更改
  • Requests and responses between major system components 主要系统组件间的请求和响应
  • Significant state changes 非常重要的状态转换(微餐厅重要状态变换都打印了日志)
  • User interaction 用户的交互
  • Calls with a known risk of failure 调用一个已知可能会很大概率失败的方法
  • Waits on conditions that could take measurable time to satisfy 在等待很久才会满足的条件时
  • Periodic progress during long-running tasks 运行很久的周期性的作业
  • Significant branch points of logic and conditions that led to the branch 重要的分支逻辑判断
  • Summaries of processing steps or events from high level functions - Avoid logging every step of a complex process in low-level functions. ::>_<::,翻译不了了,大概意思是:多步操作要从循环外总结后输出精简的日志,不要打印每一步操作日志

Bad things to log:

  • Function entry - Don’t log a function entry unless it is significant or logged at the debug level. 方法的入口-不要在方法的入口打印,除非非常重要,或者是dubug的级别(微餐厅借鉴一下)
  • Data within a loop - Avoid logging from many iterations of a loop. It is OK to log from iterations of small loops or to log periodically from large loops. 循环中的数据-避免在很大的迭代中打印log
  • Content of large messages or files - Truncate or summarize the data in some way that will be useful to debugging. 内容非常大的消息和文件-精简或者总结的有用的数据可以dubug打出
  • Benign errors - Errors that are not really errors can confuse the log reader. This sometimes happens when exception handling is part of successful execution flow. 又不会翻译了,良性的错误-不是真正错误的错误会混淆日志信息,当异常处理是成功执行流程的一部分时容易发生这种情况。总结的说就是:有些error不是真正的error,在打印error log的时候要考虑一下,不要看到异常就是error级别。
  • Repetitive errors - Do not repetitively log the same or similar error. This can quickly fill a log and hide the actual cause. Frequency of error types is best handled by monitoring. Logs only need to capture detail for some of those errors. 高频错误-不要重复的打印同样或者相似的错误。这样会马上添满文件,淹没真正错误的原因。错误的频率最好被监控。对于这样的一些错误只需要捕获打印detail信息。

附:谷歌文章:Optimal Logging ,翻不了墙,用360doc图书馆的 http://www.360doc.com/content/17/0502/14/42565517_650321240.shtml

一些日志打印问题:

  • 重要的信息用key=value格式输出,前后空格分离,是很多log处理工具(splunk, logentris)的推荐,netflix的zuul KV用:分割,我们也有很多用:分割KV的例子,这里统一使用k=v这种格式。
  • 用{}的方式构造日志字符串信息,不要用+拼接,这样会提升一些性能,但在netfilx的zuul里也有很多是用+拼接的,但是在netflix eureka中全部使用{}。
  • 能合并的日志合并的一行打印,不要分开的多次打印。
logger.debug("att:" + matcher.group());
logger.debug(", att key:" + key);
logger.debug(", att value:" + value);
//建议
logger.debug("att={},att key={},att value={}",matcher.group(),key,value)
  • 同一个包或者类中,如果有大量重复格式的日志, 用统一的方法打印,如:
logger.error(“Gearman timeout exception for request ” + getRequestID() + ” value: ” + value, e);
logger.error(“RequestID: ” + getRequestID() + “, Error Message: Gearman timeout exception: ” + e);
//建议
logger.error(getErrorMessage(getRequestID(), getErrorMessage(), e));

2.项目owner需要注意

  • 外部访问的请求(web,app),要有一个唯一标识(标识最好有语义),串起所有系统的日志信息,可以放在MDC/NDC/ThreadContext中,或者rpc框架中,便于日志追踪(现在已经有大型分布式系统的追踪系统这种概念了)
  • 日志中除非出必要的中文参数,否则日志内容全用英文打印,装逼一下。
  • 代码评审,让不好的日志打印习惯,从源头改正。
  • 系统可以做到日志级别的动态调整(co-market-api已经做到)
  • 日志库的conversion pattern用于规范业务无关的元信息打印,可根据具体情况设定,这里给几个知名库的使用作为参考:
    netflix zuul:%5p %d{HH:mm:ss,SSS} %m%n
    netflix eureka:%d %-5p %C:%L [%t] [%M] %m%n
    根据经验日志打印行号时,服务性能会有所下降很多,如果出现日志导致的性能问题时,可以先去掉打印行号试试。

参考:LOGENTRIES日志工具日志打印实践
https://logentries.com/doc/best-practices-user-tracking/
https://logentries.com/doc/best-practices-logs/

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

推荐阅读更多精彩内容

  • 在应用程序中添加日志记录总的来说基于三个目的:监视代码中变量的变化情况,周期性的记录到文件中供其他应用进行统计分析...
    时待吾阅读 5,048评论 1 13
  • 在应用程序中添加日志记录总的来说基于三个目的:监视代码中变量的变化情况,周期性的记录到文件中供其他应用进行统计分析...
    时待吾阅读 4,985评论 0 6
  • Spring Cloud为开发人员提供了快速构建分布式系统中一些常见模式的工具(例如配置管理,服务发现,断路器,智...
    卡卡罗2017阅读 134,656评论 18 139
  • 这是一个破旧等待拆迁的老式居民小区,小区里的年轻人几乎都已搬离了小区,因为它的居住面积只有四五十平,对现在人来讲真...
    马樱佳阅读 416评论 0 1
  • 有一个流浪汉,走进寺庙,看到菩萨坐在莲花台上众人膜拜,非常羡慕。 流浪汉:我可以和你换一下吗? 菩萨:只要你不开口...
    乐享荟阅读 279评论 0 0