Response time tracking middleware in Express - Performance & Optimization

Performance: Response time tracking middleware

MEDIUM IMPACT

This affects the server response time measurement, which indirectly impacts perceived page speed and user experience.

Measuring server response time for each HTTP request

Express

import { performance } from 'perf_hooks';
app.use((req, res, next) => {
  const start = performance.now();
  res.once('finish', () => {
    const duration = performance.now() - start;
    process.nextTick(() => console.log(`Response time: ${duration.toFixed(2)}ms`));
  });
  next();
});

Uses high-resolution timer for accuracy and defers logging asynchronously to avoid blocking the event loop.

📈 Performance GainReduces blocking time on event loop; more accurate timing; minimal CPU overhead

Measuring server response time for each HTTP request

Express

app.use((req, res, next) => {
  const start = Date.now();
  res.on('finish', () => {
    const duration = Date.now() - start;
    console.log(`Response time: ${duration}ms`);
  });
  next();
});

Using Date.now() can be less precise and may add slight overhead; logging on every request can slow down response handling under high load.

📉 Performance CostAdds small CPU overhead per request; logging can block event loop if synchronous

Performance Comparison

Pattern	CPU Overhead	Event Loop Blocking	Logging Impact	Verdict
Date.now() with synchronous logging	Low	Yes, blocks event loop	High under load	[X] Bad
perf_hooks.performance.now() with async logging	Very low	No, non-blocking	Minimal	[OK] Good

Rendering Pipeline

Response time tracking middleware runs during the server request lifecycle before sending the response. It measures time between request start and response finish, then logs or records it without blocking the event loop.

→Request Handling

→Event Loop

→Logging

⚠️ BottleneckSynchronous logging or heavy computation in middleware can block the event loop, delaying response sending.

Core Web Vital Affected

INP

This affects the server response time measurement, which indirectly impacts perceived page speed and user experience.

Optimization Tips

1Use high-resolution timers like performance.now() for accurate timing.

2Avoid synchronous logging inside middleware to prevent event loop blocking.

3Defer logging asynchronously to keep server response fast and responsive.

Performance Quiz - 3 Questions

What is the main performance risk of synchronous logging in response time middleware?

A It blocks the event loop, delaying responses

B It increases memory usage significantly

C It causes layout shifts in the browser

D It reduces network bandwidth

DevTools: Network and Performance panels

How to check: Use the Network panel to measure server response times and the Performance panel to check if server-side logging blocks event loop by analyzing flame charts.

What to look for: Look for long server response times and blocking tasks in the flame chart that correspond to middleware execution.

Practice

1. What is the main purpose of response time tracking middleware in Express?

easy

A. To measure how long each HTTP request takes to complete

B. To modify the request body before processing

C. To handle errors and send error responses

D. To serve static files like images and CSS

Solution

1. Step 1: Understand middleware role

   Response time tracking middleware measures the duration of requests.

2. Step 2: Identify main purpose

   It tracks how long the server takes to respond to each request.

3. Final Answer:

   To measure how long each HTTP request takes to complete -> Option A

4. Quick Check:

   Response time tracking = measuring request duration [OK]

Hint: Middleware timing tracks request duration, not request content [OK]

Common Mistakes:

• Confusing response time tracking with error handling
• Thinking it modifies request data
• Assuming it serves static files

2. Which of the following is the correct way to start measuring time in an Express middleware?

easy

A. const start = setTimeout();

B. const start = Date.now();

C. const start = process.hrtime();

D. const start = new Date().toString();

Solution

1. Step 1: Identify time measurement method

   Date.now() returns the current timestamp in milliseconds, suitable for timing.

2. Step 2: Check other options

   new Date().toString() returns a string, not a number; process.hrtime() returns a tuple, more complex; setTimeout() is for delays, not time capture.

3. Final Answer:

   const start = Date.now(); -> Option B

4. Quick Check:

   Use Date.now() for simple timestamps [OK]

Hint: Use Date.now() for simple time stamps in middleware [OK]

Common Mistakes:

• Using new Date().toString() which returns a string
• Confusing process.hrtime() with Date.now()
• Using setTimeout() incorrectly for timing

3. Given this middleware snippet, what will be logged?

app.use((req, res, next) => {
  const start = Date.now();
  res.on('finish', () => {
    const duration = Date.now() - start;
    console.log(`Request took ${duration}ms`);
  });
  next();
});

medium

A. Logs the time before the request starts processing

B. Logs the time repeatedly during the request

C. Logs the time taken for each request after response finishes

D. Does not log anything because of missing next()

Solution

1. Step 1: Understand event listener on 'finish'

   The 'finish' event triggers after the response is sent, so timing is complete.

2. Step 2: Calculate duration and log

   Duration is current time minus start time, logged once per request.

3. Final Answer:

   Logs the time taken for each request after response finishes -> Option C

4. Quick Check:

   res.on('finish') logs after response ends [OK]

Hint: res.on('finish') runs after response ends for timing [OK]

Common Mistakes:

• Thinking it logs before request processing
• Assuming multiple logs during request
• Forgetting to call next() to continue middleware chain

4. Identify the error in this response time middleware:

app.use((req, res, next) => {
  const start = Date.now();
  res.on('finish', () => {
    const duration = Date.now() - start;
    console.log(`Request took ${duration}ms`);
  });
});

medium

A. Logging inside middleware is not allowed

B. Using Date.now() instead of process.hrtime() causes error

C. res.on('finish') should be res.on('end')

D. Missing call to next(), so request hangs

Solution

1. Step 1: Check middleware flow

   Middleware must call next() to pass control; missing here causes request to hang.

2. Step 2: Validate event and logging

   res.on('finish') is correct event; Date.now() is valid; logging is allowed.

3. Final Answer:

   Missing call to next(), so request hangs -> Option D

4. Quick Check:

   Always call next() in middleware [OK]

Hint: Always call next() to continue middleware chain [OK]

Common Mistakes:

• Forgetting next() call
• Confusing 'finish' with 'end' event
• Thinking Date.now() causes errors

5. You want to log the response time in milliseconds to the console using the format Request took ${duration}ms. Which middleware code correctly does this?

hard

A. app.use((req, res, next) => {
const start = Date.now();
res.on('finish', () => {
const duration = Date.now() - start;
console.log(`Request took ${duration}ms`);
});
next();
});

B. app.use((req, res, next) => {
const start = Date.now();
const duration = Date.now() - start;
console.log(`Request took ${duration}ms`);
next();
});

C. app.use((req, res, next) => {
const start = Date.now();
console.log(`Request took ${Date.now() - start}ms`);
next();
});

D. app.use((req, res, next) => {
const start = Date.now();
res.on('close', () => {
const duration = Date.now() - start;
console.log(`Request took ${duration}ms`);
});
next();
});

Solution

1. Step 1: Understand logging timing

   To capture the full request processing time, record the start time, call next(), and log the duration in the 'finish' event handler which fires after the response is sent.

2. Step 2: Analyze incorrect approaches

   Logging right after starting the timer yields nearly 0ms because handlers haven't executed yet. The 'close' event relates to connection closure, not response completion, and may not fire reliably for timing.

3. Final Answer:

   Uses res.on('finish') to log accurate request duration -> Option A

4. Quick Check:

   res.on('finish') ensures complete request timing [OK]

Hint: Log duration in 'finish' event after response completes [OK]

Common Mistakes:

• Logging before calling next() (duration ~0ms)
• Using 'close' event instead of 'finish'
• Not waiting for response to finish