fix request logging middleware status code reporting (medplum#3316)

Co-authored-by: dillon streator <[email protected]>

packages/server/src/app.ts

@@ -214,22 +214,26 @@ export async function shutdownApp(): Promise<void> {
 const loggingMiddleware = (req: Request, res: Response, next: NextFunction): void => {
  const ctx = getRequestContext();
  const start = new Date();
- next();
- const afterNext = new Date().getTime(); // Record the time after next() completes
- const totalTime = afterNext - start.getTime(); // Calculate the time taken including the time spent in next()
- let userProfile: string | undefined;
- if (ctx instanceof AuthenticatedRequestContext) {
- userProfile = ctx.profile.reference;
- }
 
- ctx.logger.info('Request served', {
- receivedAt: start,
- requestMethod: req.method,
- path: req.path,
- duration: `${totalTime} ms`,
- ip: req.ip,
- status: res.statusCode,
- ua: req.get('User-Agent'),
- profile: userProfile,
+ res.on('finish', () => {
+ const duration = new Date().getTime() - start.getTime();
+
+ let userProfile: string | undefined;
+ if (ctx instanceof AuthenticatedRequestContext) {
+ userProfile = ctx.profile.reference;
+ }
+
+ ctx.logger.info('Request served', {
+ duration: `${duration} ms`,
+ ip: req.ip,
+ method: req.method,
+ path: req.path,
+ profile: userProfile,
+ receivedAt: start,
+ status: res.statusCode,
+ ua: req.get('User-Agent'),
+ });
  });
+
+ next();
 };