Node.js 诊断指南 第一弹

7ac8fddef36855ac8845692b3f251ef9.png

作者 | 冰森

来源 | 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 的调试日志输出:

d20f168dc58fae5780abe5f261b71012.png

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

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

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

38a204dfc7bbcba086810cbb1a9f2aac.png

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

e45480b06e2d922f6347e0de71f0febd.png

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 效果:

c95e91594f25e86dae39e5ad345ef63a.png

上图中,我们开启了 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.js
process.on('uncaughtException', () => {
  console.error('Ya!');
});


const arr = [];
while(true) arr.push(1);

然后测试:

42688563678804d7e83b02522155704d.png

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

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

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

再来一个测试列子:

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


throw new Error('here..')

测试情况:

44935159a83f1dce812a201c9559a3bf.png

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

  • 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,例如:

e083e1897ef44247baeaeb0748dc338d.png

识别同步 I/O 操作

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

const { readFileSync } = require('fs');


setImmediate(() => readFileSync(__filename));

测试效果:

6e12ffc833a1a7be09702d81d57ea0d1.png

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」即可。

ebc3d1e71f68edc7f9c703fc8c624b82.png

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

  • 0
    点赞
  • 0
    收藏
    觉得还不错? 一键收藏
  • 0
    评论
评论
添加红包

请填写红包祝福语或标题

红包个数最小为10个

红包金额最低5元

当前余额3.43前往充值 >
需支付:10.00
成就一亿技术人!
领取后你会自动成为博主和红包主的粉丝 规则
hope_wisdom
发出的红包
实付
使用余额支付
点击重新获取
扫码支付
钱包余额 0

抵扣说明:

1.余额是钱包充值的虚拟货币,按照1:1的比例进行支付金额的抵扣。
2.余额无法直接购买下载,可以购买VIP、付费专栏及课程。

余额充值