日志


日志对于 Web 开发的重要性毋庸置疑,它对于监控应用的运行状态、问题排查等都有非常重要的意义。

框架内置了强大的企业级日志支持,由 egg-logger 模块提供。

主要特性:

  • 日志分级
  • 统一错误日志,所有 logger 中使用 .error() 打印的 ERROR 级别日志都会打印到统一的错误日志文件中,便于追踪
  • 启动日志和运行日志分离
  • 自定义日志
  • 多进程日志
  • 自动切割日志
  • 高性能

日志路径

  • 所有日志文件默认都放在 ${appInfo.root}/logs/${appInfo.name} 路径下,例如 /home/admin/logs/example-app
  • 在本地开发环境 (env: local) 和单元测试环境 (env: unittest),为了避免冲突以及集中管理,日志会打印在项目目录下的 logs 目录,例如 /path/to/example-app/logs/example-app

如果想自定义日志路径:

  1. // config/config.${env}.js
  2. exports.logger = {
  3. dir: '/path/to/your/custom/log/dir',
  4. };

日志分类

框架内置了几种日志,分别在不同的场景下使用:

  • appLogger ${appInfo.name}-web.log,例如 example-app-web.log,应用相关日志,供应用开发者使用的日志。我们在绝大数情况下都在使用它。
  • coreLogger egg-web.log 框架内核、插件日志。
  • errorLogger common-error.log 实际一般不会直接使用它,任何 logger 的 .error() 调用输出的日志都会重定向到这里,重点通过查看此日志定位异常。
  • agentLogger egg-agent.log agent 进程日志,框架和使用到 agent 进程执行任务的插件会打印一些日志到这里。

如果想自定义以上日志文件名称,可以在 config 文件中覆盖默认值:

  1. // config/config.${env}.js
  2. module.exports = appInfo => {
  3. return {
  4. logger: {
  5. appLogName: `${appInfo.name}-web.log`,
  6. coreLogName: 'egg-web.log',
  7. agentLogName: 'egg-agent.log',
  8. errorLogName: 'common-error.log',
  9. },
  10. };
  11. };

如何打印日志

Context Logger

如果我们在处理请求时需要打印日志,这时候使用 Context Logger,用于记录 Web 行为相关的日志。

每行日志会自动记录上当前请求的一些基本信息, 如 [$userId/$ip/$traceId/${cost}ms $method $url]

  1. ctx.logger.debug('debug info');
  2. ctx.logger.info('some request data: %j', ctx.request.body);
  3. ctx.logger.warn('WARNNING!!!!');
  4. // 错误日志记录,直接会将错误日志完整堆栈信息记录下来,并且输出到 errorLog 中
  5. // 为了保证异常可追踪,必须保证所有抛出的异常都是 Error 类型,因为只有 Error 类型才会带上堆栈信息,定位到问题。
  6. ctx.logger.error(new Error('whoops'));

对于框架开发者和插件开发者会使用到的 Context Logger 还有 ctx.coreLogger

例如

  1. ctx.coreLogger.info('info');

App Logger

如果我们想做一些应用级别的日志记录,如记录启动阶段的一些数据信息,可以通过 App Logger 来完成。

  1. // app.js
  2. module.exports = app => {
  3. app.logger.debug('debug info');
  4. app.logger.info('启动耗时 %d ms', Date.now() - start);
  5. app.logger.warn('warning!');
  6. app.logger.error(someErrorObj);
  7. };

对于框架和插件开发者会使用到的 App Logger 还有 app.coreLogger

  1. // app.js
  2. module.exports = app => {
  3. app.coreLogger.info('启动耗时 %d ms', Date.now() - start);
  4. };

Agent Logger

在开发框架和插件时有时会需要在 Agent 进程运行代码,这时使用 agent.coreLogger

  1. // agent.js
  2. module.exports = agent => {
  3. agent.logger.debug('debug info');
  4. agent.logger.info('启动耗时 %d ms', Date.now() - start);
  5. agent.logger.warn('warning!');
  6. agent.logger.error(someErrorObj);
  7. };

如需详细了解 Agent 进程,请参考多进程模型

日志文件编码

默认编码为 utf-8,可通过如下方式覆盖:

  1. // config/config.${env}.js
  2. exports.logger = {
  3. encoding: 'gbk',
  4. };

日志文件格式

设置输出格式为JSON,方便日志监控系统分析

  1. // config/config.${env}.js
  2. exports.logger = {
  3. outputJSON: true,
  4. };

日志级别

日志分为 NONEDEBUGINFOWARNERROR 5 个级别。

日志打印到文件中的同时,为了方便开发,也会同时打印到终端中。

文件日志级别

默认只会输出 INFO 及以上(WARNERROR)的日志到文件中。

可通过如下方式配置输出到文件日志的级别:

打印所有级别日志到文件中:

  1. // config/config.${env}.js
  2. exports.logger = {
  3. level: 'DEBUG',
  4. };

关闭所有打印到文件的日志:

  1. // config/config.${env}.js
  2. exports.logger = {
  3. level: 'NONE',
  4. };

生产环境打印 debug 日志

为了避免一些插件的调试日志在生产环境打印导致性能问题,生产环境默认禁止打印 DEBUG 级别的日志,如果确实有需求在生产环境打印 DEBUG 日志进行调试,需要打开 allowDebugAtProd 配置项。

  1. // config/config.prod.js
  2. exports.logger = {
  3. level: 'DEBUG',
  4. allowDebugAtProd: true,
  5. };

终端日志级别

默认只会输出 INFO 及以上(WARNERROR)的日志到终端中。(注意:这些日志默认只在 local 和 unittest 环境下会打印到终端)

  • logger.consoleLevel: 输出到终端日志的级别,默认为 INFO

可通过如下方式配置输出到终端日志的级别:

打印所有级别日志到终端:

  1. // config/config.${env}.js
  2. exports.logger = {
  3. consoleLevel: 'DEBUG',
  4. };

关闭所有打印到终端的日志:

  1. // config/config.${env}.js
  2. exports.logger = {
  3. consoleLevel: 'NONE',
  4. };
  • 基于性能的考虑,在正式环境下,默认会关闭终端日志输出。如有需要,你可以通过下面的配置开启。(不推荐
  1. // config/config.${env}.js
  2. exports.logger = {
  3. disableConsoleAfterReady: false,
  4. };

自定义日志

增加自定义日志

一般应用无需配置自定义日志,因为日志打太多或太分散都会导致关注度分散,反而难以管理和难以排查发现问题。

如果实在有需求可以如下配置:

  1. // config/config.${env}.js
  2. const path = require('path');
  3. module.exports = appInfo => {
  4. return {
  5. customLogger: {
  6. xxLogger: {
  7. file: path.join(appInfo.root, 'logs/xx.log'),
  8. },
  9. },
  10. };
  11. };

可通过 app.getLogger('xxLogger') / ctx.getLogger('xxLogger') 获取,最终的打印结果和 coreLogger 类似。

自定义日志格式

  1. // config/config.${env}.js
  2. const path = require('path');
  3. module.exports = appInfo => {
  4. return {
  5. customLogger: {
  6. xxLogger: {
  7. file: path.join(appInfo.root, 'logs/xx.log'),
  8. formatter(meta) {
  9. return `[${meta.date}] ${meta.message}`;
  10. },
  11. // ctx logger
  12. contextFormatter(meta) {
  13. return `[${meta.date}] [${meta.ctx.method} ${meta.ctx.url}] ${meta.message}`;
  14. },
  15. },
  16. },
  17. };
  18. };

高级自定义日志

日志默认是打印到日志文件中,当本地开发时同时会打印到终端。 但是,有时候我们会有需求把日志打印到其他媒介上,这时候我们就需要自定义日志的 transport。

Transport 是一种传输通道,一个 logger 可包含多个传输通道。比如默认的 logger 就有 fileTransport 和 consoleTransport 两个通道, 分别负责打印到文件和终端。

举个例子,我们不仅需要把错误日志打印到 common-error.log,还需要上报给第三方服务。

首先我们定义一个日志的 transport,代表第三方日志服务。

  1. const util = require('util');
  2. const Transport = require('egg-logger').Transport;
  3. class RemoteErrorTransport extends Transport {
  4. // 定义 log 方法,在此方法中把日志上报给远端服务
  5. log(level, args) {
  6. let log;
  7. if (args[0] instanceof Error) {
  8. const err = args[0];
  9. log = util.format('%s: %s\n%s\npid: %s\n', err.name, err.message, err.stack, process.pid);
  10. } else {
  11. log = util.format(...args);
  12. }
  13. this.options.app.curl('http://url/to/remote/error/log/service/logs', {
  14. data: log,
  15. method: 'POST',
  16. }).catch(console.error);
  17. }
  18. }
  19. // app.js 中给 errorLogger 添加 transport,这样每条日志就会同时打印到这个 transport 了
  20. app.getLogger('errorLogger').set('remote', new RemoteErrorTransport({ level: 'ERROR', app }));

上面的例子比较简单,实际情况中我们需要考虑性能,很可能采取先打印到内存,再定时上传的策略,以提高性能。

日志切割

企业级日志一个最常见的需求之一是对日志进行自动切割,以方便管理。框架对日志切割的支持由 egg-logrotator 插件提供。

按天切割

这是框架的默认日志切割方式,在每日 00:00 按照 .log.YYYY-MM-DD 文件名进行切割。

以 appLog 为例,当前写入的日志为 example-app-web.log,当凌晨 00:00 时,会对日志进行切割,把过去一天的日志按 example-app-web.log.YYYY-MM-DD 的形式切割为单独的文件。

按照文件大小切割

我们也可以按照文件大小进行切割。例如,当文件超过 2G 时进行切割。

例如,我们需要把 egg-web.log 按照大小进行切割:

  1. // config/config.${env}.js
  2. const path = require('path');
  3. module.exports = appInfo => {
  4. return {
  5. logrotator: {
  6. filesRotateBySize: [
  7. path.join(appInfo.root, 'logs', appInfo.name, 'egg-web.log'),
  8. ],
  9. maxFileSize: 2 * 1024 * 1024 * 1024,
  10. },
  11. };
  12. };

添加到 filesRotateBySize 的日志文件不再按天进行切割。

按照小时切割

我们也可以选择按照小时进行切割,这和默认的按天切割非常类似,只是时间缩短到每小时。

例如,我们需要把 common-error.log 按照小时进行切割:

  1. // config/config.${env}.js
  2. const path = require('path');
  3. module.exports = appInfo => {
  4. return {
  5. logrotator: {
  6. filesRotateByHour: [
  7. path.join(appInfo.root, 'logs', appInfo.name, 'common-error.log'),
  8. ],
  9. },
  10. };
  11. };

添加到 filesRotateByHour 的日志文件不再被按天进行切割。

性能

通常 Web 访问是高频访问,每次打印日志都写磁盘会造成频繁磁盘 IO,为了提高性能,我们采用的文件日志写入策略是:

日志同步写入内存,异步每隔一段时间(默认 1 秒)刷盘

更多详细请参考 egg-loggeregg-logrotator