Node.js 诊断指南 第一弹

程序员成长指北

共 7296字,需浏览 15分钟

 ·

2022-01-10 05:46


作者 | 冰森
来源 | Node地下铁
https://mp.weixin.qq.com/s/gxEGrU9wnORfCmINW29dzA

大厂技术  高级前端  Node进阶

点击上方 程序员成长指北,关注公众号

回复1,加入高级Node交流群

TL;DR

本文介绍的诊断小技巧有:调试环境变量、进程退出码、废弃 API 警告、识别同步 I/O 和处理 Unhandled Promise Rejection。


调试环境变量


以笔者最初使用的 Node.js 版本 v0.10.x 来说,当时内置的 util 还没有提供 util.debuglog 方法,只有一个的 util.debug 效果等同于 console.error,而在当时已经开始要流行的是使用 TJ 的 debug 包,例如:


var debug = require('debug')('http')
, http = require('http')
, name = 'My App';

// fake app

debug('booting %o', name);

http.createServer(function(req, res){
debug(req.method + ' ' + req.url);
res.end('hello\n');
}).listen(3000, function(){
debug('listening');
});


通过 DEBUG 环境变量指定了 http 这个 label,就可以把代码中 label 为 http 的调试日志输出:



在 TJ 的设想里,只要开发者所写的所有模块及其依赖的模块都使用这种方式,那么调试的时候大家都可以使用 DEBUG 这个 env 来过滤和开启所有人的 debug 日志。


由于 TJ 的这个模块出来实在是太早了(10年前),所以基本上已经成为了社区的一个默认约定,大部分流行的 npm 包内部都会使用这个模块来输出调试日志(而不是 util.debuglog)。


以国内的 eggjs 框架为例,我们直接启动的日志如下:



通过 DEBUG 环境变量可以开启指定模块的内部调试日志:


PS: 通过 DEBUG=* 就可以开启所有的日志。


实际上 Node.js 也内置了类似的机制,不过目前普遍认为 Node.js 内置的环境变量主要是用来排查 Node.js 内置的代码和模块用的。这里主要有两个环境变量分别对应了 Node.js 内置的 JavaScript 层以及 C++ 层的调试日志,分别是:


  • NODE_DEBUG 用于开启 JavaScript 调试日志开关(也包括用户使用 util.debuglog 的部分)

  • NODE_DEBUG_NATIVE 用户开启 C++ 调试日志开关


常见的 NODE_DEBUG 开关:

  • timer

  • http

  • net

  • fs

  • cluster

  • tls

  • stream

  • child_process

  • module


以上文中的 http 代码为例,同时开启 DEBUG 和 NODE_DEBUG 效果:


上图中,我们开启了 Node.js 内置的 net 模块的日志初始,之后每次 http 请求进来,net 模块的 debug 日志都会详细输出(由于日志内容比较多所以没有放出来请求的例子图,大家可以自行测试)。


进程退出码


有的时候我们的 Node.js 进程直接退出了,如果没有收集到足够错误日志,可以根据进程的退出码辅助判断错误情况。下面引用 Node.js 文档中的内容:


在没有更多异步操作的时候,Node.js 会直接 0 状态代码退出。在其他情况下使用以下状态代码:


  • 1 未捕获的致命异常:存在未捕获的异常,并且其没有被 domain 模块或 'uncaughtException' 事件处理器处理。

  • // 省略...

  • 6 空函数的内部异常处理器:存在未捕获的异常,然后内部致命异常处理器由于不明原因设置为了 nullptr(空函数),即没有办法执行默认的致命异常处理。

  • 7 内部异常处理器运行时失败:存在未捕获的异常,并且内部致命异常处理函数本身在尝试处理时抛出错误。例如,如果 'uncaughtException' 或 domain.on('error') 处理器抛出错误,就会发生这种情况。

  • // 省略..


  • >128 信号退出:如果 Node.js 收到致命的信号,例如 SIGKILL 或 SIGHUP,则其退出码将是 128 加上信号代码的值。这是标准的 POSIX 实践,因为退出码被定义为 7 位整数,并且信号退出设置高位,然后包含信号代码的值。例如,信号 SIGABRT 的值是 6,因此预期的退出码将是 128 + 6 或 134。


完整参见 https://nodejs.org/api/process.html#process_exit_codes。


我们来举个例子:


// arr-crash.jsprocess.on('uncaughtException', () => {  console.error('Ya!');});
const arr = [];while(true) arr.push(1);


然后测试:




在 Node.js 进程 crash 之后我们通过 echo $? 可以输出之前进程崩溃之后的退出码,上例中为 134。根据上文中的文档,我们可以知道 134 = 128 + 6 即导致进程退出的异常类型为 6,参考文档:


  • 6 空函数的内部异常处理器:存在未捕获的异常,然后内部致命异常处理器由于不明原因设置为了 nullptr(空函数),即没有办法执行默认的致命异常处理。


虽然无法根据错误码知道本例中的进程 crash 的异常是什么,但是可以知道 crash 的时候进程是被强行 SIGKILL 或 SIGHUP 退出的,并且此时  V8 连默认的异常处理器都用不了了(内存爆了啥都做不了了)。


再来一个测试列子:


// uncaught-exception.jsprocess.on('uncaughtException', () => {  throw new Error('there..')});
throw new Error('here..')


测试情况:



根据错误码,我们可以找到文档:


  • 7 内部异常处理器运行时失败:存在未捕获的异常,并且内部致命异常处理函数本身在尝试处理时抛出错误。例如,如果 'uncaughtException' 或 domain.on('error') 处理器抛出错误,就会发生这种情况。


可以发现跟我们测试的情况吻合 —— 在执行致命异常处理器(uncaughtException handler)的时候抛错导致异常无法处理然后进程退出。


废弃 API 警告


Node.js 提供了官方的废弃(deprecate)标记,开发者也可以通过 util.deprecate 方法,将某些接口标记为废弃状态,例如:


const util = require('util');
exports.mergeData = util.deprecate(() => { // 之前的代码}, 'mergeData() 已废弃. 请使用 merge() ');


我们也可以通过 --no-deprecations 来关闭平常使用过程中碰到的 deprecate 警告(不过不是很推荐)。


有时候我们想找到抛这个警告的代码位置在哪,那么可以使用 --trace-deprecation 来开启 trace 错误栈,这样在警告出来的时候就会附上具体的代码 stack。如果更严格一些不希望代码中出现使用废弃版本的情况,那么可以考虑使用 --throw-deprecations 标志,这样使用废弃 API 的地方会直接 throw error,例如:



识别同步 I/O 操作


由于 Node.js 是单线程的,在使用提供服务的时候,如果出现了耗时过长的同步 I/O 操作,那么期间就会 block 住整个线程。为了避免这种情况我们可以使用 --trace-sync-io 标志开启 Node.js 内置的同步 I/O 追踪检测功能。


const { readFileSync } = require('fs');
setImmediate(() => readFileSync(__filename));


测试效果:



Unhandled Promise Rejection


测试代码:


const p = new Promise((resolve, reject) => {  // throw new Error(111); // 与下一行等效  reject(new Error(111));});


测试效果


(node:10142) UnhandledPromiseRejectionWarning: Error: 111
at /Users/lellansinhuang/workspace/midwayjs-tutorial/tmp/reject.js:4:10
at new Promise (<anonymous>)
at Object.<anonymous> (/Users/lellansinhuang/workspace/midwayjs-tutorial/tmp/reject.js:2:11)
at Module._compile (internal/modules/cjs/loader.js:1147:30)
at Object.Module._extensions..js (internal/modules/cjs/loader.js:1167:10)
at Module.load (internal/modules/cjs/loader.js:996:32)
at Function.Module._load (internal/modules/cjs/loader.js:896:14)
at Function.executeUserEntryPoint [as runMain] (internal/modules/run_main.js:71:12)
at internal/main/run_main_module.js:17:47
(node:10142) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1)


JavaScript 的 Promise 有三种状态,分别是 pending、fulfilled 和 rejected。当一个 promise 的 rejected 状态没有后续处理的时候就会触发上述报错。也就是说如果上面的代码加上,如 p.catch(console.log) 这样的捕获异常逻辑就不会出现 UnhandledPromiseRejectionWarning 警告。


Unhandled Promise Rejection 是在 Node.js v12 时引入的新特性。不处理 Promies 的这种状态在浏览器中一种可以接受的行文,但是在服务端则不然,因为这种报错可能导致内存泄漏。为了避免这种问题,可以了解一下 --unhandled-rejections 的几种设置:


  • throw: 触发一个 unhandledRejection 事件,你可以通过 promise.on('unhandledRejection') 来监听,如果你没有监听,那么会当成一个未捕获的 error 抛出。(默认行为)

  • strict: 直接抛 error。

  • warn: 不论是否设置了监听行为,总是产生一个警告。

  • warn-with-error-code: 触发 unhandledRejection 事件,如果没有监听,触发一个警告,并把进程退出码设置为 1。

  • none: 静默所有警告。


我们将上文中的测试改为使用 strict 模式,则可以得到如下报错:


$ node --unhandled-rejections=strict reject.js
/Users/lellansinhuang/workspace/midwayjs-tutorial/tmp/reject.js:4
reject(new Error(111));
^

Error: 111
at /Users/lellansinhuang/workspace/midwayjs-tutorial/tmp/reject.js:4:10
at new Promise (<anonymous>)
at Object.<anonymous> (/Users/lellansinhuang/workspace/midwayjs-tutorial/tmp/reject.js:2:11)
at Module._compile (internal/modules/cjs/loader.js:1147:30)
at Object.Module._extensions..js (internal/modules/cjs/loader.js:1167:10)
at Module.load (internal/modules/cjs/loader.js:996:32)
at Function.Module._load (internal/modules/cjs/loader.js:896:14)
at Function.executeUserEntryPoint [as runMain] (internal/modules/run_main.js:71:12)
at internal/main/run_main_module.js:17:47


通过 strict 让进程直接 crash 或者流程报错 block 住,这是一种 let it crash 的思想。

Node 社群


我组建了一个氛围特别好的 Node.js 社群,里面有很多 Node.js小伙伴,如果你对Node.js学习感兴趣的话(后续有计划也可以),我们可以一起进行Node.js相关的交流、学习、共建。下方加 考拉 好友回复「Node」即可。


   “分享、点赞在看” 支持一波👍

浏览 57
点赞
评论
收藏
分享

手机扫一扫分享

分享
举报
评论
图片
表情
推荐
点赞
评论
收藏
分享

手机扫一扫分享

分享
举报