fix(driver-express): requestLogger does not compute the accurate request duration as it doesn't run the logger within the finish event listener.

Author: 3m1n3nc3

packages/driver-express/src/middlewares/request-logger.ts

@@ -22,26 +22,29 @@ export const requestLogger = ({
     allowInProduction?: boolean
 } = {}) => async (req: Request, res: Response, next: NextFunction) => {
     const VERBOSE = process.env.VERBOSITY != '0'
-    if ((nodeEnv() === 'prod' && !allowInProduction) || !VERBOSE) return next()
-
-    const start = Date.now()
-
-    const status = res.statusCode || 200
-    const duration = Date.now() - start
-    Logger.log([
-        [`[${req.method}]`, colors[req.method] || 'white'],
-        [req.url, 'cyan'],
-        [status.toString(), status >= 500 ? 'red' : status >= 400 ? 'yellow' : 'green'],
-        [`- ${duration}ms`, 'dim']
-    ], ' ')
+    const startedAt = performance.now()
+
+    res.once('finish', () => {
+        if ((nodeEnv() === 'prod' && !allowInProduction) || !VERBOSE) return
+        if (process.env.NODE_ENV === 'test' || process.env.VITEST) return
+
+        const status = res.statusCode || 200
+        const duration = Math.round((performance.now() - startedAt) * 100) / 100
+        Logger.log([
+            [`[${req.method}]`, colors[req.method] || 'white'],
+            [req.url, 'cyan'],
+            [status.toString(), status >= 500 ? 'red' : status >= 400 ? 'yellow' : 'green'],
+            [`- ${duration}ms`, 'dim']
+        ], ' ')
+    })
 
     next()
 }
 
 export class RequestLoggerMiddleware {
     constructor(private options: { allowInProduction?: boolean } = {}) { }
 
-    handler (req: Request, res: Response, next: NextFunction) {
+    handler(req: Request, res: Response, next: NextFunction) {
         const inst = requestLogger(this.options)
 
         return inst.call(inst, req, res, next)