シェルスクリプトのログ出力設計｜ログレベル関数・タイムスタンプ・二重出力の実装パターン

この記事のポイント

・ INFOERRORのログレベル関数はbash組み込み機能だけで実装できる
・ exec 2>（teeでファイルとターミナルに同時出力できる
・ LOG_LEVEL環境変数1つでDEBUGと本番の出力を切り替えられる
・ findの-mtime +30で古いログを自動削除してディスクを守れる

#!/usr/bin/env bash
# ---------- ログ関数ライブラリ ----------
LOG_LEVEL="${LOG_LEVEL:-INFO}"  # DEBUG / INFO / WARN / ERROR
SCRIPT_NAME="$(basename "$0")"
PID="$$"

_log_level_num() {
    case "$1" in
        DEBUG) echo 0 ;;
        INFO)  echo 1 ;;
        WARN)  echo 2 ;;
        ERROR) echo 3 ;;
        *)     echo 1 ;;
    esac
}

log() {
    local level="$1"; shift
    local message="$*"
    local ts
    ts="$(date '+%Y-%m-%d %H:%M:%S')"

    if [ "$(_log_level_num "$level")" -lt "$(_log_level_num "$LOG_LEVEL")" ]; then
        return 0
    fi

    echo "[${ts}] [${level}] [${SCRIPT_NAME}:${PID}] ${message}" >&2
}

log_debug() { log DEBUG "$@"; }
log_info()  { log INFO  "$@"; }
log_warn()  { log WARN  "$@"; }
log_error() { log ERROR "$@"; }
# ----------------------------------------

$ LOG_LEVEL=DEBUG ./backup.sh
[2026-08-19 14:23:45] [DEBUG] [backup.sh:12345] バックアップ設定を読み込みました
[2026-08-19 14:23:45] [INFO]  [backup.sh:12345] バックアップ開始
[2026-08-19 14:23:46] [INFO]  [backup.sh:12345] rsync完了
[2026-08-19 14:23:46] [WARN]  [backup.sh:12345] 転送ファイルが0件（空ディレクトリの可能性）
[2026-08-19 14:23:46] [INFO]  [backup.sh:12345] バックアップ正常終了

# ファイル行数を数えて返す関数（結果はstdout、ログはstderr）
count_lines() {
    local file="$1"
    log_info "行数カウント開始: ${file}"   # stderr へ
    wc -l < "${file}"                      # stdout へ（数値だけ）
}

# 呼び出し側ではstdoutだけを変数に受け取る
line_count="$(count_lines /var/log/messages)"
log_info "行数: ${line_count}"

# 開発時: DEBUGを含む全ログを出力
$ LOG_LEVEL=DEBUG ./backup.sh

# 本番時: INFO以上のみ出力（デフォルト）
$ ./backup.sh

# 警告・エラーだけを見たい場合
$ LOG_LEVEL=WARN ./backup.sh

# スクリプト内での使い方
log_debug "変数の値: SRC=${SRC}, DEST=${DEST}"  # DEBUGなので本番では無視
log_info  "バックアップ開始"                      # INFOなので通常は出力
log_warn  "転送ファイルが0件"                     # WARNは必ず出力
log_error "rsync失敗 (exit: $?)"                 # ERRORは必ず出力

#!/usr/bin/env bash
LOG_DIR="/var/log/backup"
LOG_FILE="${LOG_DIR}/backup_$(date +%Y%m%d).log"

setup_log() {
    mkdir -p "${LOG_DIR}"
    # 以降のstderr出力をファイルとターミナルに二重出力
    exec 2> >(tee -a "${LOG_FILE}" >&2)
    log_info "ログファイル: ${LOG_FILE}"
}

# ログファイルの内容を確認
$ cat /var/log/backup/backup_20260819.log
[2026-08-19 14:23:45] [INFO]  [backup.sh:12345] ログファイル: /var/log/backup/backup_20260819.log
[2026-08-19 14:23:45] [INFO]  [backup.sh:12345] バックアップ開始
[2026-08-19 14:23:46] [INFO]  [backup.sh:12345] rsync完了
[2026-08-19 14:23:46] [INFO]  [backup.sh:12345] バックアップ正常終了

# ERRORだけ絞り込む
$ grep '\[ERROR\]' /var/log/backup/*.log

# 複数スクリプトが同時に動いている場合の出力例
[2026-08-19 02:00:01] [INFO] [backup.sh:23456]    バックアップ開始
[2026-08-19 02:00:01] [INFO] [logrotate.sh:23457]  ローテーション開始
[2026-08-19 02:00:03] [ERROR][backup.sh:23456]    rsync失敗 (exit: 23)
[2026-08-19 02:00:05] [INFO] [logrotate.sh:23457]  ローテーション完了

# 日付付きログファイル名（スクリプト起動時の日付で固定）
LOG_FILE="${LOG_DIR}/$(basename "$0" .sh)_$(date +%Y%m%d).log"

# 結果例
#   /var/log/backup/backup_20260819.log  （今日）
#   /var/log/backup/backup_20260820.log  （翌日は自動で切替）

rotate_logs() {
    local deleted
    deleted="$(find "${LOG_DIR}" -name "*.log" -mtime +30 -print)"
    if [ -n "${deleted}" ]; then
        find "${LOG_DIR}" -name "*.log" -mtime +30 -delete
        log_info "30日超過ログを削除しました"
    else
        log_debug "削除対象のログなし"
    fi
}

#!/usr/bin/env bash
set -euo pipefail

# ===== ログ設定 =====
LOG_DIR="/var/log/backup"
LOG_FILE="${LOG_DIR}/backup_$(date +%Y%m%d).log"
LOG_LEVEL="${LOG_LEVEL:-INFO}"
SCRIPT_NAME="$(basename "$0")"
PID="$$"

_log_level_num() {
    case "$1" in DEBUG) echo 0;; INFO) echo 1;; WARN) echo 2;; ERROR) echo 3;; *) echo 1;; esac
}
log() {
    local l="$1"; shift
    [ "$(_log_level_num "$l")" -ge "$(_log_level_num "$LOG_LEVEL")" ]         && echo "[$(date '+%Y-%m-%d %H:%M:%S')] [${l}] [${SCRIPT_NAME}:${PID}] $*" >&2         || true
}
log_debug() { log DEBUG "$@"; }
log_info()  { log INFO  "$@"; }
log_warn()  { log WARN  "$@"; }
log_error() { log ERROR "$@"; }

setup_log() {
    mkdir -p "${LOG_DIR}"
    exec 2> >(tee -a "${LOG_FILE}" >&2)
    log_info "ログファイル: ${LOG_FILE}"
}

rotate_logs() {
    find "${LOG_DIR}" -name "*.log" -mtime +30 -delete         && log_info "30日超過ログを削除しました" || true
}

# ===== メイン処理 =====
SRC="/var/www/html"
DEST="/backup/html"

setup_log
log_info "バックアップ開始: ${SRC} -> ${DEST}"

if [ ! -d "${SRC}" ]; then
    log_error "バックアップ元が存在しません: ${SRC}"
    exit 1
fi

if rsync -av --delete "${SRC}/" "${DEST}/"; then
    log_info "rsync完了"
else
    log_error "rsync失敗 (exit: $?)"
    exit 1
fi

rotate_logs
log_info "バックアップ正常終了"

# crontab -e で設定
SHELL=/bin/bash
LOG_LEVEL=INFO

# 毎日午前2時にバックアップ実行（2>/dev/null は付けない）
0 2 * * * /usr/local/bin/backup.sh

# ログディレクトリの存在と権限を確認
$ ls -ld /var/log/myapp/
drwxr-xr-x. 2 ec2-user ec2-user 4096  8月 19 14:00 /var/log/myapp/

# ディレクトリが存在しない場合
$ ls -ld /var/log/myapp/
ls: cannot access '/var/log/myapp/': No such file or directory

# 実行ユーザーを確認してから作成する
$ whoami
ec2-user
$ mkdir -p /var/log/myapp/

# crontab の先頭でSHELLを明示する
SHELL=/bin/bash
LOG_LEVEL=INFO

# 毎日午前2時に実行
0 2 * * * /usr/local/bin/backup.sh

# または、フルパスでbashを指定して呼び出す方法
0 2 * * * /bin/bash /usr/local/bin/backup.sh