2023-04-08 来研究下日志对 Nest.js 性能的影响

前言

最近在研究压力测试,发现是否打印日志对服务器的 QPS 有很大影响,下面来探讨一下这个问题。

基础准备

初始化项目

要测试性能,先得来个项目,本文中用到的被测项目是我自己写的 Nest.js 项目模板

git clone https://github.com/CaoMeiYouRen/nest-template.git
cd nest-template
npm i

由于只是做个研究,所以用什么框架来测都无所谓,找个自己喜欢的就行了。

为了简单起见,项目中都不带具体的业务逻辑。

去除 limit

由于默认的项目是配置了 limit 的,可能会限制 QPS,所以就先注释掉了

// main.ts 中    
// app.use(limiter)

然后再编译运行项目

npm run build
npm start

这样就完成了被测项目的准备。

安装 autocannon

本次用来压力测试的工具是 autocannon,是一个 Node.js 写的压测工具,可以直接使用 npm 安装。

npm i -g autocannon
autocannon -v

使用 autocannon

autocannon 使用起来还是非常简单的,主要的参数有以下几个:

-c/--connections NUM 并发连接的数量,默认10
-p/--pipelining NUM 每个连接的流水线请求请求数。默认1
-d/--duration SEC 执行的时间,单位秒
-m/--method METHOD 请求类型 默认GET
-b/--body BODY 请求报文体

使用例:

autocannon -c 10 -d 5 -p 1 http://127.0.0.1:3000

执行结果参考:

image-20230408172552592

深入研究

在完成基础准备后,我就想研究下日志对性能的影响。

默认情况(控制台和文件都输出日志)

作为基准,先看下默认配置了控制台和文件日志输出的 QPS:

// main.ts 中       
app.use(fileLogger) // 文件日志
app.use(consoleLogger) // 控制台日志

执行命令:

autocannon -c 10 -d 5 -p 1 http://127.0.0.1:3000

执行结果:

image-20230408172949826

可以看到,在开启控制台和文件日志输出的情况,平均的 QPS 在 1100 左右。

下面就来测下其他情况。

仅控制台输出日志

// main.ts 中       
// app.use(fileLogger) // 文件日志
app.use(consoleLogger) // 控制台日志

然后重新执行

npm run build && npm run start

这是为了采用生产环境代码进行压测,后续不再赘述

执行代码:

autocannon -c 10 -d 5 -p 1 http://127.0.0.1:3000

执行结果:

image-20230408173301759

可以看到平均 QPS 提高到了 1600 左右!这证明了输出日志确实会对服务器性能造成一定影响。

仅文件输出日志

作为控制变量,还得看下仅文件输出日志会是什么情况。

// main.ts 中       
app.use(fileLogger) // 文件日志
// app.use(consoleLogger) // 控制台日志

执行代码:

autocannon -c 10 -d 5 -p 1 http://127.0.0.1:3000

执行结果:

image-20230408173638633

此时的平均 QPS 达到了 1700 左右,比仅控制台输出日志还要略高一点,看来在生产环境下还是仅输出文件日志会好些。

无输出日志

既然去掉控制台日志和文件日志都能提高性能,那么把两个日志都去掉会怎么样呢?

// main.ts 中       
// app.use(fileLogger) // 文件日志
// app.use(consoleLogger) // 控制台日志

执行代码:

autocannon -c 10 -d 5 -p 1 http://127.0.0.1:3000

执行结果:

image-20230408173933686

此时平均 QPS 来到了惊人的 4500 左右!比默认情况翻了快 4 倍!看来不输出日志才是最快的!

总结

经过本次测试,得出的结论是:输出日志确实对 Nest.js 的性能有很大影响。

在实际情况中,打印日志的目的往往是为了记录错误,如果什么日志都没有的话,也就难以找到问题的所在了,所以该打印日志的地方还是得打印。

但这次实验也告诉我们,没必要打印的日志就别打印了,没什么大用还影响服务器性能,所以在输出日志的地方还是得稍微思考下,是否真的有必要打印日志,即便真的要打印日志,也最好处理成根据配置输出不同级别的日志的情况。

本文作者:草梅友仁
本文地址:https://blog.cmyr.ltd/archives/2b5bf0d8.html
版权声明:转载请注明出处!

©著作权归作者所有,转载或内容合作请联系作者
  • 序言:七十年代末,一起剥皮案震惊了整个滨河市,随后出现的几起案子,更是在滨河造成了极大的恐慌,老刑警刘岩,带你破解...
    沈念sama阅读 82,275评论 1 181
  • 序言:滨河连续发生了三起死亡事件,死亡现场离奇诡异,居然都是意外死亡,警方通过查阅死者的电脑和手机,发现死者居然都...
    沈念sama阅读 29,159评论 1 147
  • 文/潘晓璐 我一进店门,熙熙楼的掌柜王于贵愁眉苦脸地迎上来,“玉大人,你说我怎么就摊上这事。” “怎么了?”我有些...
    开封第一讲书人阅读 34,043评论 0 105
  • 文/不坏的土叔 我叫张陵,是天一观的道长。 经常有香客问我,道长,这世上最难降的妖魔是什么? 我笑而不...
    开封第一讲书人阅读 18,654评论 0 90
  • 正文 为了忘掉前任,我火速办了婚礼,结果婚礼上,老公的妹妹穿的比我还像新娘。我一直安慰自己,他们只是感情好,可当我...
    茶点故事阅读 23,957评论 0 150
  • 文/花漫 我一把揭开白布。 她就那样静静地躺着,像睡着了一般。 火红的嫁衣衬着肌肤如雪。 梳的纹丝不乱的头发上,一...
    开封第一讲书人阅读 19,828评论 1 91
  • 那天,我揣着相机与录音,去河边找鬼。 笑死,一个胖子当着我的面吹牛,可吹牛的内容都是我干的。 我是一名探鬼主播,决...
    沈念sama阅读 12,480评论 2 167
  • 文/苍兰香墨 我猛地睁开眼,长吁一口气:“原来是场噩梦啊……” “哼!你这毒妇竟也来了?” 一声冷哼从身侧响起,我...
    开封第一讲书人阅读 11,867评论 0 83
  • 想象着我的养父在大火中拼命挣扎,窒息,最后皮肤化为焦炭。我心中就已经是抑制不住地欢快,这就叫做以其人之道,还治其人...
    爱写小说的胖达阅读 10,404评论 5 115
  • 序言:老挝万荣一对情侣失踪,失踪者是张志新(化名)和其女友刘颖,没想到半个月后,有当地人在树林里发现了一具尸体,经...
    沈念sama阅读 13,650评论 0 130
  • 正文 独居荒郊野岭守林人离奇死亡,尸身上长有42处带血的脓包…… 初始之章·张勋 以下内容为张勋视角 年9月15日...
    茶点故事阅读 12,364评论 1 129
  • 正文 我和宋清朗相恋三年,在试婚纱的时候发现自己被绿了。 大学时的朋友给我发了我未婚夫和他白月光在一起吃饭的照片。...
    茶点故事阅读 13,230评论 0 135
  • 白月光回国,霸总把我这个替身辞退。还一脸阴沉的警告我。[不要出现在思思面前, 不然我有一百种方法让你生不如死。]我...
    爱写小说的胖达阅读 7,997评论 0 18
  • 序言:一个原本活蹦乱跳的男人离奇死亡,死状恐怖,灵堂内的尸体忽然破棺而出,到底是诈尸还是另有隐情,我是刑警宁泽,带...
    沈念sama阅读 10,813评论 2 121
  • 正文 年R本政府宣布,位于F岛的核电站,受9级特大地震影响,放射性物质发生泄漏。R本人自食恶果不足惜,却给世界环境...
    茶点故事阅读 13,990评论 3 129
  • 文/蒙蒙 一、第九天 我趴在偏房一处隐蔽的房顶上张望。 院中可真热闹,春花似锦、人声如沸。这庄子的主人今日做“春日...
    开封第一讲书人阅读 9,593评论 0 3
  • 文/苍兰香墨 我抬头看了看天上的太阳。三九已至,却和暖如春,着一层夹袄步出监牢的瞬间,已是汗流浃背。 一阵脚步声响...
    开封第一讲书人阅读 9,922评论 0 81
  • 我被黑心中介骗来泰国打工, 没想到刚下飞机就差点儿被人妖公主榨干…… 1. 我叫王不留,地道东北人。 一个月前我还...
    沈念sama阅读 14,566评论 2 139
  • 正文 我出身青楼,却偏偏与公主长得像,于是被迫代替她去往敌国和亲。 传闻我的和亲对象是个残疾皇子,可洞房花烛夜当晚...
    茶点故事阅读 15,030评论 2 136

推荐阅读更多精彩内容