4、慢查询日志实战：模拟生成慢查询、查看慢查询日志、分析日志中的耗时与命令

慢查询日志是 Redis 性能诊断的第一手现场证据。本章通过完整的实战流程，从模拟慢查询、配置日志参数、查看日志内容，到逐行分析耗时与命令特征，帮助运维人员建立从“现象”到“根因”的闭环分析能力。

4.1 模拟慢查询：构造延迟场景

在生产环境中，慢查询通常由 大 Key 操作、复杂聚合命令 或 批量写入 触发。为了在实验环境中复现，我们使用 DEBUG SLEEP 命令人为制造延迟，该命令会使当前客户端连接阻塞指定的秒数，从而被慢查询日志捕获。

模拟慢查询步骤：

1. 连接 Redis 实例：redis-cli -h 127.0.0.1 -p 6379
2. 执行一个耗时 2 秒的阻塞命令：DEBUG SLEEP 2
3. 执行一个耗时 5 秒的阻塞命令：DEBUG SLEEP 5
4. 执行一个正常命令作为对照：SET key "hello"
5. 执行一个模拟大集合操作的命令：SADD myset a b c d e f g h i j

127.0.0.1:6379> DEBUG SLEEP 2
OK
(2.00s)
127.0.0.1:6379> DEBUG SLEEP 5
OK
(5.00s)
127.0.0.1:6379> SET key "hello"
OK
127.0.0.1:6379> SADD myset a b c d e f g h i j
(integer) 10

4.2 查看慢查询日志：SLOWLOG 命令家族

Redis 提供了 SLOWLOG 命令组来管理慢查询日志，包括查看、计数和重置。日志存储在内存中，默认最多保留 128 条，超过后最旧的记录会被丢弃。

核心命令速查表：

查看慢查询日志：

127.0.0.1:6379> SLOWLOG GET 3
1) 1) (integer) 2
   2) (integer) 1712345678
   3) (integer) 5000000
   4) 1) "DEBUG"
      2) "SLEEP"
      3) "5"
   5) "127.0.0.1:6379"
   6) "user=default"

2) 1) (integer) 1
   2) (integer) 1712345676
   3) (integer) 2000000
   4) 1) "DEBUG"
      2) "SLEEP"
      3) "2"
   5) "127.0.0.1:6379"
   6) "user=default"

3) 1) (integer) 0
   2) (integer) 1712345670
   3) (integer) 12000
   4) 1) "SADD"
      2) "myset"
      3) "a"
      4) "b"
      5) "c"
      6) "d"
      7) "e"
      8) "f"
      9) "g"
      10) "h"
      11) "i"
      12) "j"
   5) "127.0.0.1:6379"
   6) "user=default"

4.3 日志字段深度解析

每条慢查询日志记录包含 6 个字段，理解每个字段的含义是分析的基础。

4.4 日志分析：从耗时到根因

分析慢查询日志的核心思路是：先看耗时，再看命令，最后关联上下文。以下是对上述模拟日志的分析过程。

分析流程：

1. 排序与筛选：按耗时降序排列，优先处理最慢的查询。本例中 DEBUG SLEEP 5 耗时 5 秒，为最严重记录。
2. 命令识别：识别耗时命令的类型。DEBUG SLEEP 是测试命令，生产环境应关注 KEYS、SMEMBERS、HGETALL、LRANGE、ZRANGE 等可能遍历大 Key 的命令。
3. 参数分析：检查命令参数是否涉及大量元素。例如 SADD myset a b c ... 虽然只添加了 10 个元素，但如果集合本身巨大，SADD 也可能变慢。
4. 客户端定位：根据客户端 IP 和名称，找到对应的应用服务或模块。

4.5 慢查询日志分析流程图

下图展示了从日志采集到根因定位的完整分析链路，帮助运维人员建立系统化的排查思维。

4.6 慢查询日志配置与优化建议

合理配置慢查询参数，既能捕获问题，又不会对 Redis 性能产生负面影响。

4.7 常见慢查询命令与优化方向

根据生产环境统计，以下命令最容易成为慢查询的源头，需要重点监控。

4.8 慢查询日志分析工具与脚本

对于大量慢查询日志，手动逐条分析效率低下。推荐使用以下方法进行批量处理。

Shell 脚本示例：统计耗时分布

#!/bin/bash
# 导出慢查询日志并统计耗时区间
redis-cli SLOWLOG GET 1000 | awk '
  /^3\) \(integer\)/ {
    time_us = $3
    gsub(/[^0-9]/, "", time_us)
    if (time_us < 10000) print "<10ms"
    else if (time_us < 100000) print "10-100ms"
    else if (time_us < 1000000) print "100ms-1s"
    else print ">1s"
  }' | sort | uniq -c | sort -rn

Python 脚本示例：解析日志并输出 Top 10 慢命令

import redis
import time

r = redis.Redis(host='127.0.0.1', port=6379, decode_responses=True)
logs = r.slowlog_get(100)

# 按耗时排序
sorted_logs = sorted(logs, key=lambda x: x['duration'], reverse=True)

print(f"{'排名':<4} {'耗时(ms)':<10} {'命令':<20} {'参数':<30}")
print("="*64)
for i, log in enumerate(sorted_logs[:10], 1):
    cmd = log['args'][0] if log['args'] else 'N/A'
    args = ' '.join(log['args'][1:4]) if len(log['args']) > 1 else ''
    duration_ms = log['duration'] / 1000
    print(f"{i:<4} {duration_ms:<10.2f} {cmd:<20} {args:<30}")