【发布时间】:2017-11-10 11:33:15
【问题描述】:
我在我的 NodeJS 应用程序中使用 winston,以获取结构化日志记录格式 (JSON),稍后我将通过 Filebeat 处理并发送到 Logstash。
根据 PM2 和 12factor.net 的建议,我正在使用 winston.transports.Console 传输记录并让 PM2 处理我的 ecosystem.json 中的 stdout 和 stderr。
在我的logger.js 模块中,我有以下内容:
"use strict";
const winston = require("winston");
// Remove logging on console
winston.remove(winston.transports.Console);
// env
const env = process.env.NODE_ENV || 'development';
const isDev = env === "development";
// The default Console transport
const defaultLogLevel = isDev ? "debug" : "info";
const consoleTransport = new winston.transports.Console({
level: defaultLogLevel,
colorize: true,
json: true,
timestamp: true,
exceptionsLevel: "error",
prettyPrint: false,
handleExceptions: true,
humanReadableUnhandledException: false,
exitOnError: true
});
const defaultOptions = {
transports: [
consoleTransport
]
};
module.exports = function(options)
{
let initOpts = defaultOptions;
if(options)
{
Object.assign(initOpts, options);
}
const logger = new winston.Logger(initOpts);
// suppress any logger errors
logger.emitErrs = false;
winston.handleExceptions(consoleTransport);
return logger;
};
在我的主应用程序文件server.js 中,我运行此代码来模拟抛出未处理的异常,需要但未找到虚假依赖项:
"use strict";
var log = require('./logger')({name: "api-messages"});
log.info("some info message");
log.error("some error message");
var notFound = require("someInexistentPackage");
最后,在我的 PM2 ecosystem.json 我有以下内容:
{
"apps":
[
{
"name" : "my-app",
"script" : "server.js",
"max_restarts" : 1,
"error_file" : "~/.pm2/logs/my-app.log",
"out_file" : "~/.pm2/logs/my-app.log",
"merge_logs" : true
}
]
}
当我运行pm2 start ecosystem.json 时,日志文件的内容如下:
{
"level": "info",
"message": "some info message",
"timestamp": "2017-06-08T16:10:05.967Z"
}
{
"level": "error",
"message": "some error message",
"timestamp": "2017-06-08T16:10:05.968Z"
}
Error: Cannot find module 'someInexistentPackage'
at Function.Module._resolveFilename (module.js:470:15)
at Function.Module._load (module.js:418:25)
at Module.require (module.js:498:17)
at require (internal/module.js:20:19)
at Object.<anonymous> (/Users/mps/Sites/my-app/server.js:10:20)
at Module._compile (module.js:571:32)
at Object.Module._extensions..js (module.js:580:10)
at Module.load (module.js:488:32)
at tryModuleLoad (module.js:447:12)
at Function.Module._load (module.js:439:3)
{
"date": "Thu Jun 08 2017 13:10:05 GMT-0300 (-03)",
"process": {
"pid": 50862,
"uid": 501,
"gid": 20,
"cwd": "/Users/mps/Sites/my-app",
"execPath": "/Users/mps/.nvm/versions/node/v7.10.0/bin/node",
"version": "v7.10.0",
"argv": [
"/Users/mps/.nvm/versions/node/v7.10.0/bin/node",
"/Users/mps/.nvm/versions/node/v7.10.0/lib/node_modules/pm2/lib/ProcessContainerFork.js"
],
"memoryUsage": {
"rss": 36724736,
"heapTotal": 9879552,
"heapUsed": 6309680,
"external": 63983
}
},
"os": {
"loadavg": [
2.49560546875,
2.228515625,
2.0205078125
],
"uptime": 329559
},
"trace": [
{
"column": 15,
"file": "module.js",
"function": "Module._resolveFilename",
"line": 470,
"method": "_resolveFilename",
"native": false
},
{
"column": 25,
"file": "module.js",
"function": "Module._load",
"line": 418,
"method": "_load",
"native": false
},
{
"column": 17,
"file": "module.js",
"function": "Module.require",
"line": 498,
"method": "require",
"native": false
},
{
"column": 19,
"file": "internal/module.js",
"function": "require",
"line": 20,
"method": null,
"native": false
},
{
"column": 20,
"file": "/Users/mps/Sites/my-app/server.js",
"function": null,
"line": 10,
"method": null,
"native": false
},
{
"column": 32,
"file": "module.js",
"function": "Module._compile",
"line": 571,
"method": "_compile",
"native": false
},
{
"column": 10,
"file": "module.js",
"function": "Module._extensions..js",
"line": 580,
"method": ".js",
"native": false
},
{
"column": 32,
"file": "module.js",
"function": "Module.load",
"line": 488,
"method": "load",
"native": false
},
{
"column": 12,
"file": "module.js",
"function": "tryModuleLoad",
"line": 447,
"method": null,
"native": false
},
{
"column": 3,
"file": "module.js",
"function": "Module._load",
"line": 439,
"method": "_load",
"native": false
}
],
"stack": [
"Error: Cannot find module 'someInexistentPackage'",
" at Function.Module._resolveFilename (module.js:470:15)",
" at Function.Module._load (module.js:418:25)",
" at Module.require (module.js:498:17)",
" at require (internal/module.js:20:19)",
" at Object.<anonymous> (/Users/mps/Sites/my-app/server.js:10:20)",
" at Module._compile (module.js:571:32)",
" at Object.Module._extensions..js (module.js:580:10)",
" at Module.load (module.js:488:32)",
" at tryModuleLoad (module.js:447:12)",
" at Function.Module._load (module.js:439:3)"
],
"level": "error",
"message": "uncaughtException: Cannot find module 'someInexistentPackage'",
"timestamp": "2017-06-08T16:10:05.972Z"
}
如您所见,PM2 正在将未捕获的异常添加到日志文件中,即此代码:
Error: Cannot find module 'someInexistentPackage'
at Function.Module._resolveFilename (module.js:470:15)
at Function.Module._load (module.js:418:25)
at Module.require (module.js:498:17)
at require (internal/module.js:20:19)
at Object.<anonymous> (/Users/mps/Sites/my-app/server.js:10:20)
at Module._compile (module.js:571:32)
at Object.Module._extensions..js (module.js:580:10)
at Module.load (module.js:488:32)
at tryModuleLoad (module.js:447:12)
at Function.Module._load (module.js:439:3)
这破坏了我的日志的格式/结构,因为它是纯文本,而不是我从 winston 收到的格式化 JSON。
我确信这是 PM2 问题/错误配置,因为如果我运行 node server.js,控制台输出只是 JSON 对象。
我必须在 PM2 ecosystem.json 文件中设置特定属性吗?
如何让 PM2 忽略 Node 的 uncaughtException?
我想出的唯一选择(我还没有尝试过)是将我的 winston 传输更改为 winston.transports.File,将 ALL 日志重定向到 stdout 并告诉 PM2将stderr 保存在不同的文件中,但我宁愿坚持使用控制台方法。
【问题讨论】:
-
不确定是否可以让 PM2 忽略这些异常,但它确实可以选择输出 JSON 本身:
--log-type json -
@robertklep 确实会将“纯文本”异常打印为 JSON,但我仍然缺少所有我的 winston 特定格式(属性),而且我还会记录重复的异常。跨度>
-
您还可以在代码中添加自定义
uncaughtException处理程序,以防止将堆栈跟踪写入stderr,并发出日志消息。 -
@robertklep 我已经尝试过使用
uncaughtException事件处理程序,PM2 仍然将异常添加到日志文件中。
标签: node.js logging pm2 winston