That's it! Your logs will automatically include the default context of correlationId and duration.

Why nestjs-context-logger?

• 🎯 Developer experience: Easy to use, zero code changes required, keep using the familiar @nestjs/common logger interface.
• ⚡ High Performance: Built on Pino, one of the fastest loggers in the Node.js ecosystem
• 📊 Default Context: Automatically enriches request with correlationId and duration
• 🚀 Platform agnostic: works with Express and Fastify
• ✅ Automatic context cleanup: Memory is cleaned up and garbage collected when request cycle ends

Advanced Configuration

Automatic context enrichment via configuration

You can enrich your logs with custom context at the application level:

@Module({
  imports: [
    ContextLoggerModule.forRootAsync({
        imports: [ConfigModule],
        inject: [ConfigService],
        useFactory: (configService: ConfigService) => ({
          pinoHttp: {
            level: configService.get('LOG_LEVEL'),
          },
            // enrichContext intercepts requests and allows you to enrich the context
            enrichContext: async (context: ExecutionContext) => ({
                userId: context.switchToHttp().getRequest().user?.id,
                tenantId: context.switchToHttp().getRequest().headers['x-tenant-id'],
                environment: configService.get('NODE_ENV'),
            }),
        }),
    });
  ],
})
export class AppModule {}

Now every log will include these additional fields:

// Output: {
//   "message": "Some log message",
//   "userId": "user_123",
//   "tenantId": "tenant_456",
//   "environment": "production",
//   ...other default fields
// }

Log Field Grouping

You can group different types of log fields under specific keys, making logs more organized and easier to query:

ContextLoggerModule.forRoot({
  groupFields: {
    bindingsKey: 'params',    // Groups runtime bindings
    contextKey: 'metadata'    // Groups context information
  }
})

Before grouping:

{
  "userId": "123",           // context field
  "requestId": "abc",        // context field
  "correlationId": "xyz",    // binding
  "timestamp": "...",        // binding
  "level": "info",
  "msg": "User logged in"
}

After grouping:

{
  "metadata": {              // grouped context
    "userId": "123",
    "requestId": "abc"
  },
  "params": {               // grouped bindings
    "correlationId": "xyz",
    "timestamp": "..."
  },
  "level": "info",
  "msg": "User logged in"
}

Context Adaptation

Transform context data before logging to standardize format, remove sensitive data, or add computed fields:

ContextLoggerModule.forRoot({
  contextAdapter: (context) => ({
    ...context,
    sensitive: undefined,           // Remove sensitive data
    requestId: context.reqId,      // Rename fields
    timestamp: Date.now()          // Add new fields
  })
})

Bootstrap Logs Control

Control NestJS framework bootstrap logs to reduce noise during startup:

ContextLoggerModule.forRoot({
  ignoreBootstrapLogs: true  // Suppress framework bootstrap logs
})

Add custom context from anywhere

Update context from anywhere in the code 🎉. The context persists throughout the entire request execution, making it available to all services and handlers within that request.

For example, set up user context in a guard:

@Injectable()
export class ConnectAuthGuard implements CanActivate {
  private readonly logger = new ContextLogger(ConnectAuthGuard.name);

  async canActivate(context: ExecutionContext): Promise<boolean> {
    const request = context.switchToHttp().getRequest();
    const connectedUser = await this.authenticate(request);
    // 👇 Magic here 👇
    ContextLogger.updateContext({ userId: connectedUser.userId });
    return true;
  }
}

The context flows through your entire request chain:

@Injectable()
export class PaymentService {
  private readonly logger = new ContextLogger(PaymentService.name);

  async processPayment(paymentData: PaymentDto) {
    ContextLogger.updateContext({ tier: 'premium' });
    this.logger.info('Processing payment');
    // Output: {
    //   "message": "Processing payment",
    //   "userId": "user_123",  // From AuthGuard
    //   "tier": "premium",     // Added here
    //   ...other context
    // }

    await this.featureService.checkFeatures();
  }
}

@Injectable()
export class FeatureService {
  private readonly logger = new ContextLogger(FeatureService.name);

  async checkFeatures() {
    this.logger.info('Checking features');
    // Output: {
    //   "message": "Checking features",
    //   "userId": "user_123",  // Still here from AuthGuard
    //   "tier": "premium",     // Still here from PaymentService
    //   ...other context
    // }
  }
}

How does it work?

Under the hood, nestjs-context-logger leverages Node.js's AsyncLocalStorage to maintain isolated context for each request.

Storage Layer

• Uses Node.js's built-in AsyncLocalStorage for context isolation
• Each request gets its own isolated storage "bucket" that persists throughout the entire request lifecycle
• The storage is tied to the Node.js event loop and automatically cleans up when the request ends

Request Lifecycle

1. Context Initialization

   • The InitContextMiddleware creates a new storage scope for each incoming request with a generated correlationId

2. Context Propagation

   • The context automatically flows through async operations (promises, callbacks)
   • No matter how deep your call stack goes, the context remains accessible
   • Each request maintains its own isolated context, even during concurrent requests

3. Request Lifecycle

flowchart TB
    req(HTTP Request) --> mid[Init Middleware]
    
    %% ALS as external service
    mid --"Initialize request context"--> als[Async Local Storage]
    als --"Store"--> mem[Memory]
    
    %% Request flow inside ALS context
    subgraph als_context[Request Execution in ALS Context]
        flow1 --"Your Guards, Pipes, etc"--> int[Request Interceptor]
        int --"Enrich context"--> mem
        int --> app[Route Handler Code]
        
        subgraph logging[Request Execution]
            app --"Log message"--> logger[Context Logger]
            logger --"Fetch context"--> mem
            logger --> pino[Pino]
        end
    end
    
    %% Connect middleware to flow inside ALS
    als --> flow1[Continue request within dedicated memory context for execution]

    style als_context fill:#e6ffe6,stroke:#666
    style logging fill:#f5f5f5,stroke:#666
    style als fill:#e6ffe6
    style mem fill:#e6ffe6

Memory Management

• Context is stored in memory only for the duration of the request
• Automatic garbage collection when request ends (no memory leaks)
• Each request's context is completely isolated from others
• No cross-request contamination, even under high concurrency

Features in Depth

🔄 Automatic Context Injection

• Correlation ID
• Service Name
• Request Duration
• Name, Host, PID

🎯 Developer Experience

• Typed Logger (Finally!)
• Familiar NestJS logger interface (Same as @nestjs/common default experience)
• Context Isolation via middleware
• Global Request Interceptor for enriching context

⚡ Performance

• Built on Pino for high-performance logging
• Efficient context storage with async_hooks AsyncLocalStorage
• Minimal overhead compared to standard logging

🪝 Hooks

• Execute callbacks when logs are created
• Support for level-specific hooks (log, debug, warn, error)
• Global hooks that run for all log levels
• Useful for metrics, external reporting, or custom side effects

Example usage:

ContextLoggerModule.forRoot({
  hooks: {
    // Run for all log levels
    all: [
      (message, bindings) => {
        metrics.increment('log.count');
      }
    ],
    // Run only for errors
    error: [
      (message, bindings) => {
        errorReporting.notify(message, bindings);
      }
    ]
  }
})

⚠️ Note: Hooks are executed synchronously and sequentially. Use with caution as they can introduce latency to the logging process.

🔌 Integration Support (Platform Agnostic)

• Fastify compatible
• Express compatible

Configuration Options

API Reference

@WithContext Decorator

The @WithContext decorator enables context initialization for NestJS message patterns, event patterns, cron jobs, and any method that needs logging context outside of HTTP requests. It creates a new context scope that merges with any existing context.

How It Works

@WithContext creates a new AsyncLocalStorage scope for the decorated method. Any existing context is merged with the decorator's context, with the decorator's values taking precedence. When the method completes, the previous context is restored.

Basic Usage

import { WithContext } from 'nestjs-context-logger';

@Injectable()
export class UserService {
  private readonly logger = new ContextLogger(UserService.name);

  @WithContext({ service: 'UserService' })
  @MessagePattern('user.created')
  async handleUserCreated(data: CreateUserDto) {
    this.logger.log('Processing user creation');
    // Output: { service: 'UserService', message: "Processing user creation" }
  }

  @WithContext(() => ({ correlationId: uuid(), operation: 'validation' }))
  @MessagePattern('user.validate')
  async validateUser(data: ValidateUserDto) {
    this.logger.log('Validating user');
    // Output: { operation: 'validation', correlationId: 'some-uuid'}
  }
}

Static vs Dynamic Context

• Static object: @WithContext({ key: 'value' }) - Values are set once when the class is defined
• Function: @WithContext(() => ({ key: 'value' })) - Values are computed fresh for each method invocation
• Function with method arguments: @WithContext((arg1, arg2) => ({ ... })) - Access method parameters to extract context

Use functions when you need unique values like correlation IDs or timestamps for each method call.

Using Method Arguments in Context

You can access method arguments in the context function to extract relevant information:

import { Processor, WorkerHost } from '@nestjs/bullmq';
import { Job } from 'bullmq';
import { WithContext } from 'nestjs-context-logger';

@Processor('email-queue')
export class EmailProcessor extends WorkerHost {
  @WithContext((job: Job) => ({
    jobId: job.id,
    jobName: job.name,
    queueName: job.queueName,
    emailRecipient: job.data.to,
  }))
  async process(job: Job) {
    // All logs automatically include: jobId, jobName, queueName, emailRecipient
    this.logger.log('Processing email');
    await this.sendEmail(job.data);
    this.logger.log('Email sent successfully');
  }
}

This is particularly useful for:

• Queue processors (BullMQ, Bull): Extract job ID, queue name, attempts
• Event handlers: Extract event type, entity ID
• Message handlers: Extract message metadata

Context Merging Behavior

When @WithContext is used within an existing context (like HTTP requests), it merges contexts:

@Controller('users')
export class UserController {
  @Get(':id')
  async getUser(@Param('id') id: string) {
    // HTTP context: { correlationId: "req-123", requestMethod: "GET" }
    await this.userService.processUser(id);
    // Context restored after method completes
  }
}

@Injectable()
export class UserService {
  @WithContext({ service: 'UserService', operation: 'process' })
  async processUser(id: string) {
    // Merged context: { 
    //   correlationId: "req-123",     // inherited from HTTP request
    //   requestMethod: "GET",         // inherited from HTTP request  
    //   service: 'UserService',       // from decorator
    //   operation: 'process'          // from decorator
    // }
  }
}

Nested Decorators

When @WithContext methods call other @WithContext methods, each creates its own merged scope:

@Injectable()
export class NestedService {
  @WithContext({ level: 'outer' })
  async outerMethod() {
    // Context: { level: 'outer' }
    await this.innerMethod();
    // Context restored: { level: 'outer' }
  }

  @WithContext({ level: 'inner', step: 'processing' })
  async innerMethod() {
    // Merged context: { level: 'inner', step: 'processing' }
    // Note: 'level' from outer context is overridden
  }
}

Common Use Cases

Message Handlers: Add service identification and correlation tracking

@MessagePattern('user.validate')
@WithContext(() => ({ correlationId: uuid(), handler: 'user.validate' }))
async validateUser(@Payload() data: ValidateUserDto) {
  this.logger.log('Validating user credentials');
}

Cron Jobs: Track scheduled task execution

@Cron('0 2 * * *')
@WithContext(() => ({ 
  task: 'data-sync',
  executionId: uuid(),
  scheduledAt: new Date().toISOString()
}))
async syncUserData() {
  this.logger.log('Starting user data synchronization');
}

Background Operations: Isolate context for async operations

@WithContext(() => ({ operation: 'email-processing', batchId: uuid() }))
async processEmailBatch(emails: Email[]) {
  this.logger.log('Processing email batch', { count: emails.length });
}

Decorator Positioning

⚠️ IMPORTANT: When using @WithContext, it must be placed closer to the function definition than other decorators to ensure proper context initialization.

// ✅ Correct - @WithContext closer to function
@MessagePattern('user.created')
@WithContext(() => ({ correlationId: uuid() }))
async handleUserCreated(data: CreateUserDto) {
  this.logger.log('Processing user creation');
}

// ❌ Incorrect - @WithContext too far from function
@WithContext(() => ({ correlationId: uuid() }))
@MessagePattern('user.created')
async handleUserCreated(data: CreateUserDto) {
  this.logger.log('Processing user creation'); // Context may not be available
}

This applies to all NestJS decorators including @MessagePattern, @EventPattern, @Cron, etc.

When to Use @WithContext vs updateContext()

Use @WithContext for:

• Message handlers, event handlers, cron jobs
• Background tasks that need their own correlation IDs
• Methods that should have consistent base context
• Testing scenarios where you want predictable context

Use ContextLogger.updateContext() for:

• Adding fields to existing HTTP request context
• Enriching context within a method's execution
• Preserving existing correlation IDs

Benefits

• Context Merging: Inherits existing context while adding new fields
• Automatic Cleanup: Context is restored when the method completes
• Dynamic Values: Support for functions to generate fresh values per invocation
• Zero Configuration: Works immediately with any NestJS pattern
• Performance: Minimal overhead using AsyncLocalStorage
• Compatible: Works with all NestJS patterns (@MessagePattern, @EventPattern, @Cron, etc.)

Best Practices

1. Use Semantic Log Levels

   // Good
   logger.debug('Processing started', { itemCount: 42 });
   logger.error('Failed to connect to database', error);
   
   // Not so good
   logger.log('Something happened');
   

2. Add Contextual Data

   // Good
   logger.log('Order processed', {
     orderId: order.id,
     amount: order.total,
     items: order.items.length
   });
   
   // Not so good
   logger.log('Order processed');
   

3. Prefer Structured Logging

   // Good
   logger.info('Item fetched', { id: '1', time: 2000, source: 'serviceA' });
   
   // Not so good
   logger.info('Item fetched id: 1, time: 2000, source: serviceA');
   

4. Position @WithContext Correctly

   // ✅ Good - @WithContext closer to function
   @MessagePattern('user.created')
   @WithContext(() => ({ correlationId: uuid() }))
   async handleUserCreated(data: CreateUserDto) {
     this.logger.log('Processing user creation');
   }
   
   // ❌ Not good - @WithContext further from function
   @WithContext(() => ({ correlationId: uuid() }))
   @MessagePattern('user.created')
   async handleUserCreated(data: CreateUserDto) {
     this.logger.log('Processing user creation');
   }
   

Performance Considerations

This logger uses AsyncLocalStorage to maintain context, which does add an overhead. It's worth noting that nestjs-pino already uses local storage under the hood for the "name", "host", "pid" metadata it attaches to every request.

Our benchmarks showed a 20-40% increase compared to raw Pino, such that if Pino average is 114ms, then with ALS it's up to 136.8ms. This is a notable overhead, but still significantly faster than Winston or Bunyan. For reference, see Pino's benchmarks.

While you should consider this overhead for your own application, do remember that the logging is non-blocking, and should not impact service latency, while the benefits of having context in your logs is a game changer.

FAQ for nestjs-context-logger

Q: How does AsyncLocalStorage isolation work?

When you create a new AsyncLocalStorage instance, Node.js creates an isolated storage space:

// Each new instance gets its own isolated storage space
const store1 = new AsyncLocalStorage<Record<string, any>>();
const store2 = new AsyncLocalStorage<Record<string, any>>();

This isolation is maintained at the Node.js runtime level, so when nestjs-context-logger creates its instance:

// Inside nestjs-context-logger package
const globalStore = new AsyncLocalStorage<Record<string, any>>();

It's automatically isolated from any other ALS instances in:

• Your application code
• Other libraries (like nestjs-pino)
• Other parts of your system

This is why multiple packages can use ALS without conflict:

// All of these work independently
yourAppStore.run({ tenant: 'abc' }, () => {
  pinoStore.run({ pid: 123 }, () => {
    contextLoggerStore.run({ userId: '456' }, () => {
      // Each store only sees its own context
    });
  });
});

Q: How and when does AsyncLocalStorage clean up context?

ALS automatically cleans up when the execution context exits:

// When this completes, context is eligible for garbage collection
ContextLogger.runWithCtx(async () => {
  await handleRequest();
  // After this, context is cleaned up
});

This happens:

• At the end of each request
• If an error occurs (context still gets cleaned up)
• When all async operations in the chain complete
• Even if you forget to clean up manually

Q: How does nestjs-context-logger integrate with Pino?

nestjs-context-logger uses nestjs-pino under the hood, adding context management:

@Module({
  imports: [
    ContextLoggerModule.forRoot({
      // Regular Pino options
      pinoHttp: {
        level: 'info',
        transport: {
          target: 'pino-pretty'
        }
      }
    })
  ]
})

The integration:

1. Uses Pino's high-performance logging engine
2. Preserves all Pino formatting and transport options
3. Adds context management through ALS
4. Automatically merges context into Pino's log output

Your logs get both Pino's performance and rich context:

logger.info('User updated');
// Output includes both Pino's standard fields and your context:
// {
//   "level": "info",
//   "time": "2024-...",      // From Pino
//   "pid": 123,              // From Pino
//   "correlationId": "abc",  // From context-logger
//   "userId": "456",         // From your context
//   "message": "User updated"
// }

Q: Can context be unavailable or lost?

Context can be lost in several scenarios:

1. Before ContextLoggerModule initialization:

@Module({
  imports: [
    // ❌ Too early - logger not initialized yet
    OtherModule.forRoot({ 
      onModuleInit() {
        logger.info('Starting up');
      }
    }),
    
    // Logger module initialized after
    ContextLoggerModule.forRoot()
  ]
})

2. In background operations or timers:

// ❌ Context lost - new execution context
setTimeout(() => {
  logger.info('Task done');  // No context available
}, 1000);

// ✅ Use @WithContext for background operations
@WithContext(() => ({ operation: 'background-timer', correlationId: uuid() }))
async backgroundTask() {
  setTimeout(() => {
    this.logger.info('Task done');  // Context available!
  }, 1000);
}

Q: How does @WithContext interact with HTTP request context?

@WithContext merges with existing HTTP request context, inheriting existing fields while adding new ones:

@Controller('users')
export class UserController {
  @Get(':id')
  async getUser(@Param('id') id: string) {
    // HTTP context: { correlationId: "req-123", requestMethod: "GET" }
    await this.userService.processUser(id);
    // HTTP context restored: { correlationId: "req-123", requestMethod: "GET" }
  }
}

@Injectable()
export class UserService {
  @WithContext({ service: 'UserService' })
  async processUser(id: string) {
    // Merged context: { 
    //   correlationId: "req-123",  // inherited from HTTP request
    //   requestMethod: "GET",      // inherited from HTTP request
    //   service: 'UserService'     // added by decorator
    // }
  }
}

Q: What happens with nested @WithContext decorators?

Each @WithContext merges with the current context. Nested calls inherit the parent's context and add their own fields:

@Injectable()
export class NestedService {
  @WithContext({ level: 'outer', operation: 'parent' })
  async outerMethod() {
    // Context: { level: 'outer', operation: 'parent' }
    await this.innerMethod();
    // Context restored: { level: 'outer', operation: 'parent' }
  }

  @WithContext({ level: 'inner', operation: 'child' })
  async innerMethod() {
    // Merged context: { 
    //   level: 'inner',      // overrides outer 'level'
    //   operation: 'child'   // overrides outer 'operation'
    // }
  }
}

Q: When should I use @WithContext vs regular context updates?

Use @WithContext when you need:

• Method-level context setup for message handlers, cron jobs, or event handlers
• Custom method values like method execution IDs that should be fresh for each method call

## Q: Why do my bootstrap logs look different than request logs?

During application startup, you might notice logs without context that look like this:

[Nest] 12345 - 12/08/2024, 2:43:09 PM LOG [RouterExplorer] Mapped {/api/users, GET} route +0ms [Nest] 12345 - 12/08/2024, 2:43:09 PM LOG [RouterExplorer] Mapped {/api/users/:id, POST} route +0ms [Nest] 12345 - 12/08/2024, 2:43:09 PM LOG [RoutesResolver] UserController {/api/users}: +0ms

Instead of your normal contextual logs that look like this:
```json
{
  "level": "info",
  "time": "2024-12-08T14:43:09.123Z",
  "correlationId": "abc-123",
  "service": "UserService",
  "message": "Processing user request"
}

This happens because the NestJS bootstrap process is asynchronous:

1. Application starts & immediately begins logging
2. Modules initialize asynchronously, including ContextLoggerModule
3. Some logs will occur before ContextLoggerModule has a chance to set up its Pino instance
4. These early logs fall back to the default NestJS logger

These bootstrap logs are internal NestJS core component logs that happen during application setup - they don't need request context because there are no requests yet, and that it normal. However, if you want to pipe everything through a single logging system for consistency, you can do this:

async function bootstrap() {
  const bootstrapLogger = new ContextLogger('Bootstrap');
  
  const app = await NestFactory.create(AppModule, {
    bufferLogs: true,
    logger: bootstrapLogger
  });

  await app.listen(3000);
}

Testing

To mock logs for testing, we recommend automatically mocking the entire ContextLogger for all tests. You can achieve this by following these steps:

1. Create a Jest setup file:

// jest/setupFile.ts
import 'jest-expect-message';

class MockContextLogger {
  public log() {
    return jest.fn();
  }
  public debug() {
    return jest.fn();
  }
  public warn() {
    return jest.fn();
  }
  public error() {
    return jest.fn();
  }
  public static getContext() {
    return {};
  }
  public static updateContext() {
    return jest.fn();
  }
}

jest.mock('nestjs-context-logger', () => {
  return { ContextLogger: MockContextLogger };
});

2. Configure Jest to use this setup file:

// package.json
{
  "jest": {
    "setupFilesAfterEnv": [
      "jest-expect-message",
      "<rootDir>/../jest/setupFile.ts"
    ]
  }
}

3. Spy on the mocked ContextLogger

Now you can easily test logging in your services using spies:

import { ContextLogger } from 'nestjs-context-logger';
import { UserService } from './user.service';

describe('UserService', () => {
  let service: UserService;
  let logErrorSpy: jest.SpyInstance;
  let logInfoSpy: jest.SpyInstance;

  beforeEach(async () => {
    logErrorSpy = jest.spyOn(ContextLogger.prototype, 'error');
    logInfoSpy = jest.spyOn(ContextLogger.prototype, 'info');
    service = new UserService();
  });

  it('should log user creation', async () => {
    await service.createUser({ email: 'test@example.com' });

    expect(logInfoSpy).toHaveBeenCalledWith(
      'User created',
      expect.objectContaining({ email: 'test@example.com' })
    );
  });

  it('should log errors', async () => {
    const error = new Error('Database error');
    await service.handleError(error);

    expect(logErrorSpy).toHaveBeenCalledWith(
      'Operation failed',
      error
    );
  });
});

Contributing

Contributions welcome! Read our contributing guidelines to get started.

Support

• 🐛 Issue Tracker
• 💬 Discussions

License

MIT

Keywords: nestjs logger, pino logger, fastify logging, nestjs logging, correlation id, request context, microservices logging, structured logging, context logging, distributed tracing, nodejs logging, typescript logger, async context, request tracking