nodejs日志

开发一个项目时，可以通过控制台输出或者debug来获取到项目的运行信息。当项目上线时，我们就需要通过日志来分析。如同Java的log4j，nodejs中也有相关的log4js。使用过log4j的同学应该对此不会陌生。

1、日志级别

log4js共有6种日志级别，分别为：trace、debug、info、warn、error、fatal。权值从小到大，其初始化代码为：

TRACE: new Level(5000, "TRACE"), DEBUG: new Level(10000, "DEBUG"), INFO: new Level(20000, "INFO"), WARN: new Level(30000, "WARN"), ERROR: new Level(40000, "ERROR"), FATAL: new Level(50000, "FATAL"),

假如设置默认的日志级别为info，那么权值小于info的日志不会被记录下来，也就是说只有调用log.info(), log.warn(), log.error()或者log.fatal()才会触发记录日志。该部分代码在lib/logger.js中。

Logger.prototype.log = function() {  var args = Array.prototype.slice.call(arguments)  , logLevel = levels.toLevel(args.shift())  , loggingEvent;  if (this.isLevelEnabled(logLevel)) {    loggingEvent = new LoggingEvent(this.category, logLevel, args, this);    this.emit("log", loggingEvent);  }};

2、集成express

log4js可以作为express的一个中间件来使用。首先需要引入log4js

var express = require("express");var log4js = require("log4js");var app = express();

接着配置log4js

log4js.configure({ appenders: [   { type: 'console' },   { type: 'file', filename: 'cheese.log', category: 'cheese' }  ]});

该配置的意思是console是默认的appender，使用cheese这个appender时会将日志记录文件中，日志文件名为cheese.log。

然后用use连接到中间件，我们默认使用的是cheese这个appender，级别为info。

app.use(log4js.connectLogger(log4js.getLogger("cheese"), {level: log4js.levels.INFO}));

其输出与此类似：

[2014-07-04 20:27:21.205] [INFO] cheese - 127.0.0.1 - - "GET / HTTP/1.1" 200 22896 "" "Mozilla/5.0 (Windows NT 6.1) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/31.0.1650.63 Safari/537.36"

......

3、再修改一下中间件

上面的做法是可以的，只是当中间件太多的时候，都写在同一个文件中也许感觉会有些丑陋。所以我倾向于将其分离出来作为单独的一个模块，也就是一个单独的文件。然后对外暴露接口。

var path = require("path");var log4js = require("log4js");/** * 日志配置 */exports.configure = function() {    log4js.configure(path.join(__dirname, "log4js.json"));    }/** * 暴露到应用的日志接口，调用该方法前必须确保已经configure过 * @param name 指定log4js配置文件中的category。依此找到对应的appender。 *              如果appender没有写上category，则为默认的category。可以有多个 * @returns {Logger} */exports.logger = function(name) {    var dateFileLog = log4js.getLogger(name);    dateFileLog.setLevel(log4js.levels.INFO);    return dateFileLog;}/** * 用于express中间件，调用该方法前必须确保已经configure过 * @returns {Function|*} */exports.useLog = function() {    return log4js.connectLogger(log4js.getLogger("app"), {level: log4js.levels.INFO});} 

log4js.json文件内容如下

{  "appenders": [    {      "type": "console"    },    {      "type": "dateFile",      "filename": "logs/booklist.log",      "pattern": "-yyyy-MM-dd",      "alwaysIncludePattern": true    }  ]}

配置很简单，配置了两个appender，一个是控制台的，一个是dateFile，意思是每天都产生一个日志文件。 注意到我这里并没有配置category，这样的话当没有找到对应的appender时，这两个appender就是默认的appender。有些时候明明感觉配置没有错，但是日志文件并没有产生日志，往往问题就出在这里。

然后在app.js中我们修改为如下

var express = require("express");// 这个是我们上面自定义的模块var log4js = require("./log");var app = express();app.configure();app.use(log4js.useLog());...

4、单进程与多进程

好了，上面对于单进程是适用的，但是如果你的nodejs应用是多进程的，使用上面的配置你会看到日志的输出有点奇怪，比如：

感觉有点像是资源抢占了。

log4js的wiki中有给出multiprocess的配置。但是当时使用的时候也会有问题，当时没有细究。不过社区中有人建立了另外一种方式，我采用了这种。可以参看一下这个issue 。下面我们来配置一下。修改我们在上面修改的log模块文件，变为：

var path = require("path");var log4js = require("log4js");/** * 多进程的日志配置 */exports.configure = function(mode) {  if (mode === "master") {    log4js.configure(path.join(__dirname, "./log4js-master.json"));  } else {    // 多进程的配置项    log4js.configure(path.join(__dirname, "./log4js-worker.json"));    // 单进程的配置项//log4js.configure(path.join(__dirname, "../config/log4js.json"));  }}/** * 暴露到应用的日志接口，调用该方法前必须确保已经configure过 * @param name 指定log4js配置文件中的category。依此找到对应的appender。 *  如果appender没有写上category，则为默认的category。可以有多个 * @returns {Logger} */exports.logger = function(name) {  var dateFileLog = log4js.getLogger(name);  dateFileLog.setLevel(log4js.levels.INFO);  return dateFileLog;}/** * 用于express中间件，调用该方法前必须确保已经configure过 * @returns {Function|*} */exports.useLog = function() {  return log4js.connectLogger(log4js.getLogger("app"), {level: log4js.levels.INFO});}

主要是修改了configure方法。

log4js-master.json的内容为：

{  "appenders": [{    "type": "clustered",    "appenders": [      {        "type": "console"      },      {        "type": "dateFile",        "filename": "logs/booklist.log",        "pattern": "-yyyy-MM-dd",        "alwaysIncludePattern": true,        "pollInterval": 1,        "category": "dateFileLog"      }    ]  }]}

log4js-worker.js的内容为：

{    "appenders": [{        "type": "clustered"    }]}

假设主进程的内容在文件master.js，工作进程在worker.js。master.js中的配置内容为：

var log4js = require("./lib/log");log4js.configure("master");

worker.js的配置内容为：

var express = require("express");// 这个是我们上面自定义的模块var log4js = require("./log");var app = express();app.configure("worker");app.use(log4js.useLog());...

如此就完成了。

需要在某个地方记录日志的时候，我们可以如此

var log = require("./log").logger("index"); // logger中的参数随便起...log.info("...");log.error("...")

5、写在后面

log4js还有挺多好玩的内容，比如smtp。这是个很有用的功能，比如当项目发生某个错误时，你希望程序能发邮件通知你，该功能就能派出用场了。

另外，我开源了一个项目 booklist 。该项目主要在于探讨nodejs建立应用所需要或者注意的地方，非常期待各位指导与探讨，谢谢！

参考

https://github.com/nomiddlename/log4js-node    

https://github.com/gcfeng/booklist