Linux内核调试技术从printk到kgdb实战工程 - 第4节

4. printk实践

printk 是 Linux 内核中最基础、最常用的调试输出函数，其行为类似于用户空间的 printf，但运行在内核上下文。printk 将格式化字符串写入内核日志缓冲区（kernel log buffer），该缓冲区是一个固定大小的环形缓冲区（ring buffer），默认大小通常为 4 个页面（16KB 或 32KB，取决于架构和配置）。

4.1.1 在内核模块中使用 printk

以下是一个典型的内核模块示例，演示了不同日志级别的 printk 用法：

#include <linux/module.h>
#include <linux/kernel.h>
#include <linux/init.h>

static int __init printk_demo_init(void)
{
    /* 不同日志级别的 printk 输出 */
    printk(KERN_EMERG   "EMERG: 系统不可用\n");
    printk(KERN_ALERT   "ALERT: 必须立即采取行动\n");
    printk(KERN_CRIT    "CRIT: 严重条件\n");
    printk(KERN_ERR     "ERR: 错误条件\n");
    printk(KERN_WARNING "WARNING: 警告条件\n");
    printk(KERN_NOTICE  "NOTICE: 正常但重要\n");
    printk(KERN_INFO    "INFO: 信息性消息\n");
    printk(KERN_DEBUG   "DEBUG: 调试级别消息\n");

    /* 无日志级别前缀：使用默认日志级别（通常是 KERN_WARNING） */
    printk("无级别前缀的消息，使用默认日志级别\n");

    return 0;
}

static void __exit printk_demo_exit(void)
{
    printk(KERN_INFO "printk_demo 模块卸载\n");
}

module_init(printk_demo_init);
module_exit(printk_demo_exit);

MODULE_LICENSE("GPL");
MODULE_AUTHOR("Kernel Debug Expert");
MODULE_DESCRIPTION("printk 日志级别演示模块");

4.1.2 日志级别与优先级

printk 的日志级别由宏定义，对应内核源码中的 <linux/kern_levels.h>。每个级别代表消息的严重程度，数值越小优先级越高：

4.2 查看 dmesg：读取内核日志缓冲区

dmesg 是用户空间读取内核日志缓冲区的主要工具。它通过 /dev/kmsg 设备或 syslog 系统调用获取内核消息。dmesg 的输出包含所有未被用户空间日志守护进程（如 rsyslog、systemd-journald）消费的消息。

4.2.1 dmesg 常用命令

4.2.2 过滤与搜索技巧

# 查看与 USB 相关的内核消息
dmesg | grep -i usb

# 查看最近 20 条消息
dmesg | tail -20

# 查看特定时间范围内的消息（使用 -T 时间戳）
dmesg -T | grep "2025-04"

# 查看模块加载/卸载消息
dmesg | grep -E "module|init|exit"

# 实时监控内核日志（需要 root 权限）
dmesg -w

4.3 日志持久化：从环形缓冲区到磁盘文件

内核日志缓冲区是内存中的环形结构，系统重启后内容会丢失。要实现日志持久化，需要将内核消息转发到用户空间的日志系统。

4.3.1 持久化机制对比

4.3.2 配置 rsyslog 持久化内核日志

# /etc/rsyslog.conf 或 /etc/rsyslog.d/kernel.conf
# 将所有内核消息写入独立文件
kern.*    /var/log/kernel.log

# 将紧急消息同时写入控制台
kern.emerg    /dev/console

# 重启 rsyslog 服务
systemctl restart rsyslog

4.3.3 使用 systemd-journald 查看持久化内核日志

# 查看内核日志（持久化到 journal）
journalctl -k

# 查看本次启动的内核日志
journalctl -k -b 0

# 查看上次启动的内核日志
journalctl -k -b -1

# 实时跟踪内核日志
journalctl -k -f

# 导出内核日志到文件
journalctl -k > kernel_log_export.txt

4.4 循环缓冲区：内核日志的环形存储机制

内核日志缓冲区（kernel log buffer）是一个固定大小的环形缓冲区（ring buffer），由 __log_buf[] 数组实现。当缓冲区写满时，新消息会覆盖最旧的消息。这种设计保证了日志写入的 O(1) 时间复杂度，不会因为日志量过大而阻塞内核执行。

4.4.1 环形缓冲区结构

/* 内核日志缓冲区核心结构（简化） */
#define LOG_BUF_LEN (1 << CONFIG_LOG_BUF_SHIFT)  /* 默认 16KB */

static char __log_buf[LOG_BUF_LEN] __aligned(LOG_ALIGN);
static u64 log_first_seq;   /* 缓冲区中第一条消息的序列号 */
static u32 log_first_idx;   /* 第一条消息在缓冲区中的索引 */
static u64 log_next_seq;    /* 下一条消息的序列号 */
static u32 log_next_idx;    /* 下一条消息的写入位置索引 */

4.4.2 环形缓冲区工作流程

下图展示了内核日志环形缓冲区的写入与读取流程：

4.4.3 配置环形缓冲区大小

可以通过内核启动参数或内核配置选项调整环形缓冲区大小：

# 内核启动参数（在 GRUB 或 U-Boot 中设置）
log_buf_len=1M          # 设置缓冲区大小为 1MB
log_buf_len=4M          # 设置缓冲区大小为 4MB

# 内核编译选项
CONFIG_LOG_BUF_SHIFT=17  # 2^17 = 128KB
CONFIG_LOG_BUF_SHIFT=18  # 2^18 = 256KB
CONFIG_LOG_BUF_SHIFT=20  # 2^20 = 1MB

4.4.4 查看当前缓冲区状态

# 查看当前日志缓冲区大小和使用情况
cat /proc/sys/kernel/printk
# 输出示例：7       4       1       7
# 分别表示：控制台日志级别、默认日志级别、最小日志级别、启动时默认日志级别

# 查看缓冲区大小（通过 dmesg 内部统计）
dmesg | head -1
# 输出示例：[    0.000000] Linux version 5.15.0 ... (log_buf_len=1048576)

# 查看内核日志缓冲区配置
cat /proc/sys/kernel/printk_delay
# 输出：0  （printk 之间的延迟毫秒数，用于调试时序问题）

4.5 综合实践：构建一个带日志持久化的调试模块

以下是一个综合示例，演示了如何在内核模块中使用 printk，并通过用户空间工具实现日志的查看与持久化：

/* debug_logger.c - 带日志持久化的调试模块 */
#include <linux/module.h>
#include <linux/kernel.h>
#include <linux/init.h>
#include <linux/jiffies.h>
#include <linux/delay.h>

static int loop_count = 10;
module_param(loop_count, int, 0644);
MODULE_PARM_DESC(loop_count, "日志循环次数 (默认: 10)");

static int __init debug_logger_init(void)
{
    int i;
    unsigned long start_jiffies = jiffies;

    printk(KERN_INFO "debug_logger: 模块加载开始 (loop_count=%d)\n", loop_count);

    for (i = 0; i < loop_count; i++) {
        /* 不同级别的日志输出 */
        printk(KERN_DEBUG "debug_logger: 循环 %d/%d - 调试信息\n", i + 1, loop_count);
        printk(KERN_INFO  "debug_logger: 循环 %d/%d - 状态正常\n", i + 1, loop_count);

        if (i == loop_count / 2) {
            printk(KERN_WARNING "debug_logger: 循环 %d/%d - 达到中间点\n", i + 1, loop_count);
        }

        /* 模拟一些工作负载 */
        mdelay(10);
    }

    printk(KERN_NOTICE "debug_logger: 模块加载完成，耗时 %lu jiffies\n",
           jiffies - start_jiffies);

    return 0;
}

static void __exit debug_logger_exit(void)
{
    printk(KERN_INFO "debug_logger: 模块卸载\n");
}

module_init(debug_logger_init);
module_exit(debug_logger_exit);

MODULE_LICENSE("GPL");
MODULE_AUTHOR("Kernel Debug Course");
MODULE_DESCRIPTION("printk 调试与日志持久化演示模块");

4.5.1 编译与测试步骤

1. 编译模块：使用内核源码树中的 Makefile 编译 debug_logger.c
2. 加载模块：insmod debug_logger.ko loop_count=20
3. 查看实时日志：dmesg -w 或 journalctl -k -f
4. 持久化日志：dmesg -T > /var/log/debug_logger_$(date +%Y%m%d_%H%M%S).log
5. 卸载模块：rmmod debug_logger
6. 验证持久化：cat /var/log/debug_logger_*.log | grep "debug_logger"

4.6 常见问题与避坑指南