Express记录该操作发生所有事情后的资源使用情况

Question

我使用Express4.17.1-和Winston3.3.3/Morgan1.10.0进行日志记录，我已经设法以这种方式配置:

import winston from "winston";
const options = {
console: {
colorize: true,
format: winston.format.combine(
winston.format.colorize(),
winston.format.timestamp(),
winston.format.printf((msg) => {
return `${msg.timestamp} [${msg.level}] - ${msg.message}`;
})
),
handleExceptions: true,
json: true,
level: "debug",
},
};
const logger = winston.createLogger({
exitOnError: false,
transports: [new winston.transports.Console(options.console)], // alert > error > warning > notice > info > debug
});
logger.stream = {
write: (message) => {
logger.info(message.slice(0, -1)); // ...use lowest log level so the output will be picked up by any transports
},
};
export { logger };

然后在application.js中导入Winston配置，其中所有Express设置都是这样完成的:

import express from "express";
import morgan from "morgan";
import { logger } from "./config/winston.js";
const app = express();
app.use(express.json());
// ...some more settings
app.use(morgan("dev", { stream: logger.stream })); // combined, dev
// ...route Definitions
export { app };

我遇到的问题是，一些日志语句看起来像是由Winston(我猜)根据所处理的Express路由自动处理的(我喜欢)，但它们在对应工作流的所有常规语句之后被记录。下面是一个示例:

2021-03-03T16:25:22.199Z [info] - Searching all customers...
{
method: 'select',
options: {},
timeout: false,
cancelOnTimeout: false,
bindings: [],
__knexQueryUid: 'dFKYVOlaQyJswbxoex7Y6',
sql: 'select `customers`.* from `customers`'
}
2021-03-03T16:25:22.203Z [info] - Done with all customers workflow...
2021-03-03T16:25:22.215Z [info] - GET /api/customers 200 11.727 ms - 395

我希望2021-03-03T16:25:22.215Z [info] - GET /api/customers 200 11.727 ms - 395是工作流程中的第一个部分，因为它是它的开始，但它在最后被记录-这有点令人困惑。

是否有办法解决这个问题，或者这是由设计的?如果它可以修复，我应该在Winston配置或其他任何地方添加/删除/调整什么。

Answer 1

根据morgan-documentation，有一个设置允许您在请求上写入访问日志，而不是在响应上写入(后者是默认值):

在请求而不是响应上写日志行。这意味着a请求将被记录，即使服务器崩溃，但是来自响应(如响应代码、内容长度等)不能是记录。

因此，将{ immediate: true }传递给morgan应该使日志出现在工作流的开始，但日志显然只包含与请求相关的数据。