看日志痛苦——可能是你方法不对

看日志,是研发的日常工作,相信你已经从各种报错日志中查出许多BUG,但也被冗长、复杂的日志困扰过无数次。

在日常工作中,我也经常痛苦于看日志,一般是这些日志长得面目狰狞:

  • 日志文件太大,打开都很困难
  • 日志文件里的信息错综复杂,各个模块的时序纠缠不清
  • 日志搜集引擎导出的文件被切割成多个文件,很难联系上下文来看

不仅日志看的不顺眼,看日志的工具也不蹭手:

  • 搜索完关键词,上下跳几次,就忘记要干什么了(我是谁,我在哪,我在干嘛?)
  • 想按某个关键字过滤日志,有的工具还不支持
  • 刚刚自己分析得头头是道,一叫人过来就忘记从哪看起了

其实,这些问题,我们可以分为两类来看待。一类是工具问题,也就是工具的功能还不够完善;一类是方法问题,也就是缺少系统的方法来从日志中排查问题。

对于前者,我们需要的是专业的日志查看工具,而不是使用编辑器;对于后者,我有总结了一些方法论,倒是可以分享分享。

思路可视化

回顾看,排查日志一般步骤是:了解问题 => 定位出错位置 => 查看上下文 => 推测出错原因

而在这个过程中,如果我们把日志看做一维的线条,那么看日志就是在线条里,前前后后的跳转,搜集信息,然后总结得出结论。

而上面提到的一些问题,比如看日志忘记看到哪里了,讲解不知从而谈起等等,其实是没有把日志浏览的足迹、搜集的信息、总结的疑点、结论记录下来。

要把这些信息记录并可视化,你认为最合适的方式是什么呢?

我的方式是“时间线”。

20200902210300253_1087589769.png

像整理历史事件,分析历史事件一样,我们看到日志中有价值的地方,就做一个标记,把它放入时间线里。

起初,时间线只是一个书签栏,帮我们记录位置信息。比如出错位置在哪里,xx时间xxx在干嘛,xx在xx之前/之后等等

接着,我们开始结合日志+时间线,找到一些疑点。这个地方可疑,加个备注;这个地方的时序貌似不对,标个黄色;这个事件似乎没啥意义,先删了

上面的可疑点的梳理,基本围绕着“时间线条”这个工具在走,但,其实本质上是对你自己思路的检视,也就是,这个时候的“时间线”已经在扮演思路可视化的过程了。

最后,我们对可疑点进一步升华,发现了这份日志对应问题的根本原因。

这时候,时间线(思路的可视化),还能给你带来另一个价值——我们可以把这一份整理后的时间线发给相关人员,陈述你对日志的分析过程,是不是比直接文字表述更直观了?

主体+辅助

上面提到的“时间线”,其核心是保持思路一直延续,不被工具操作,或是其他信息干扰、打断。

思路在线,是排查日志最关键的一点。时间线是从时间维度来帮助我们做到思路在线,而“过滤窗”则可以帮助我们在“信息维度”保持思路在线。

日志排查的过程中,经常要用到的功能是:过滤含某个关键字的日志。但是仅仅过滤就行了吗?

如果只是过滤的话,会导致信息丢失。我们其实需要了解的是在整个日志范畴,这个过滤规则下的行为发生了什么,分布在哪些位置,上下文(其他模块)在干嘛,与我刚刚记录到时间线里的信息前后关系是怎样的……

也就是说,按关键字过滤出的信息除本身的信息价值外,还需要对照主体日志来提取更多信息。换言之,既是平行的,又是交错的。

那么,怎样才能合理的解决这个矛盾呢?

我把关键字过滤出的信息定义为辅助信息,放在过滤小窗,主体窗口显示完整日志,实现平行查看;同时,以“时间”为“纽带”连接“主体窗口”、“过滤小窗”、“时间线”,无论双击哪一个窗口中的事件,其他两个窗口都会立即定位到对应位置,这就实现了信息交织。

且看下图:

all

图中3大区域,左上是主体窗口,左下是过滤小窗,右边是时间线。

TAG

仍然围绕“思路在线”,时间(思路)和信息维度,我们都有工具辅助了,还有什么困扰呢?

工具!

看日志可不是一两个关键词搜索就能搞定的(能搜索一两个关键词搞定的,那只能叫做瞄一眼),而是很多。那么,最好能把搜索过的关键词记录下来,最好还能用不同的颜色标记出来。

所以,我还设计了一个TAG栏,记录当前高亮的关键词;然后在搜索栏里,加入了历史功能,可以自动补全之前输入过的单词。

其他

为了让天下没有难看的日志,定位到xx行、前进/后退、反向搜索、字体设置……等等,这些功能也不能少

既然把日志分析这件事已经摸索出了一定的经验和方法,而现有工具又没有特别蹭手的,当然是要自己写一个了!

来看看我写的loginsight吧:

loginsight

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