116 lines
3.8 KiB
Markdown
116 lines
3.8 KiB
Markdown
---
|
|
id: observability-structured-logging
|
|
title: Structured Logging — JSON 첫 줄부터
|
|
category: Coding
|
|
status: draft
|
|
source_trust_level: B
|
|
verification_status: conceptual
|
|
created_at: 2026-05-09
|
|
updated_at: 2026-05-09
|
|
tags: [observability, logging, json, vibe-coding]
|
|
tech_stack: { language: "TypeScript / pino / winston", applicable_to: ["Backend"] }
|
|
applied_in: []
|
|
aliases: [JSON logs, log fields, log levels, redaction]
|
|
---
|
|
|
|
# Structured Logging
|
|
|
|
> `console.log("user " + id + " did " + action)` 은 grep 으로 찾기 어렵다. **JSON 형태 + 일관된 필드명 + 적절한 level + 민감정보 redact** 4가지 = 운영 가능한 로그.
|
|
|
|
## 📖 핵심 개념
|
|
- 한 로그 = 한 JSON 객체 (한 줄).
|
|
- 핵심 필드: `time`, `level`, `msg`, `requestId`, `userId`, `service`, `version`.
|
|
- 동적 데이터는 메시지에 끼우지 말고 **필드로**.
|
|
- 민감정보 자동 redact (token, password, card).
|
|
|
|
## 💻 코드 패턴
|
|
|
|
### pino (Node, fast)
|
|
```ts
|
|
import pino from 'pino';
|
|
|
|
export const logger = pino({
|
|
level: process.env.LOG_LEVEL || 'info',
|
|
redact: {
|
|
paths: ['req.headers.authorization', 'req.headers.cookie', '*.password', '*.token', '*.creditCard'],
|
|
censor: '[REDACTED]',
|
|
},
|
|
base: {
|
|
service: 'user-service',
|
|
version: process.env.GIT_SHA,
|
|
env: process.env.NODE_ENV,
|
|
},
|
|
timestamp: pino.stdTimeFunctions.isoTime,
|
|
});
|
|
|
|
// 사용
|
|
logger.info({ userId, action: 'login', ms: 42 }, 'user logged in');
|
|
// → {"time":"2026-05-09T...","level":"info","userId":"1","action":"login","ms":42,"msg":"user logged in", ...}
|
|
```
|
|
|
|
### Request-scoped child logger
|
|
```ts
|
|
import { AsyncLocalStorage } from 'node:async_hooks';
|
|
|
|
const als = new AsyncLocalStorage<{ requestId: string; userId?: string }>();
|
|
|
|
app.use((req, res, next) => {
|
|
const requestId = req.header('x-request-id') ?? crypto.randomUUID();
|
|
als.run({ requestId }, () => next());
|
|
});
|
|
|
|
export function log() {
|
|
const ctx = als.getStore() ?? {};
|
|
return logger.child(ctx);
|
|
}
|
|
|
|
// 어디서나
|
|
log().info({ orderId }, 'created order');
|
|
// → 자동으로 requestId 포함
|
|
```
|
|
|
|
### Level 가이드
|
|
- `fatal`: 프로세스 종료급.
|
|
- `error`: 처리 가능하지만 비정상.
|
|
- `warn`: 문제 신호 (재시도 발생, fallback 사용).
|
|
- `info`: 정상 핵심 이벤트 (사용자 가입, 결제 완료).
|
|
- `debug`: 흐름 추적. 운영에서는 끔.
|
|
- `trace`: 매우 상세. 로컬만.
|
|
|
|
### 메시지 vs 필드
|
|
```ts
|
|
// ❌ 동적 값을 메시지에
|
|
logger.info(`User ${userId} purchased ${productId} for ${amount}`);
|
|
|
|
// ✅ 필드로
|
|
logger.info({ userId, productId, amount }, 'purchase completed');
|
|
// 검색: amount > 100 같은 쿼리 가능
|
|
```
|
|
|
|
## 🤔 의사결정 기준
|
|
| 데이터 | 어디 |
|
|
|---|---|
|
|
| 식별자 (userId, orderId) | 항상 필드 |
|
|
| 타이밍 (ms, duration) | 필드 |
|
|
| 에러 객체 | `{ err }` (pino 자동 stack 추출) |
|
|
| 요청 / 응답 본문 | 필드 + redact 정책 |
|
|
| PII | 절대 X — 별도 audit log |
|
|
|
|
## ❌ 안티패턴
|
|
- **stdout 에 plain text**: 파싱 불가. JSON 한 줄.
|
|
- **stderr 와 stdout 혼용**: error 가 다른 stream 에. 한 곳으로 + level 필드.
|
|
- **민감정보 raw**: token / password / 카드번호 / 주민번호. redact 정책 필수.
|
|
- **메시지 안에 stack trace**: 한 줄 깨짐. 별도 err 필드.
|
|
- **로그 너무 verbose (debug 가 prod)**: 비용 / 노이즈. 필요시 dynamic level.
|
|
- **로그 너무 적음**: 사고 시 디버깅 불가. critical path 는 info.
|
|
- **timestamp 가 local timezone**: 분산 시스템에서 헷갈림. ISO8601 UTC.
|
|
- **한 줄에 여러 JSON 객체**: 파서 깨짐. 한 줄 = 한 JSON.
|
|
|
|
## 🤖 LLM 활용 힌트
|
|
- "console.log 금지. logger 사용. 동적 값은 필드, 메시지는 정적" 강제.
|
|
- AsyncLocalStorage 로 requestId 자동 주입.
|
|
|
|
## 🔗 관련 문서
|
|
- [[Observability_Correlation_IDs]]
|
|
- [[Observability_Error_Reporting]]
|