3. printk 调试技术：日志级别、控制台输出控制、动态调试与 trace_printk

printk 是 Linux 内核中最基础、最常用的调试输出手段，其行为与用户空间的 printf 有显著差异。本章深入剖析 printk 的日志级别机制、控制台输出控制、动态调试（dynamic_debug）以及专为追踪设计的 trace_printk，帮助你在不同场景下精准获取内核调试信息。

3.1 printk 日志级别与输出控制

printk 通过日志级别（loglevel）区分消息的严重程度，并决定哪些消息能够输出到控制台。内核根据 console_loglevel、default_message_loglevel 和 minimum_console_loglevel 三个关键变量控制输出行为。

3.1.1 日志级别定义

日志级别定义在 include/linux/kern_levels.h 中，共 8 个级别，数值从 0 到 7：

宏定义	数值	字符串前缀	含义
KERN_EMERG	0	"<0>"	系统不可用（紧急事件）
KERN_ALERT	1	"<1>"	必须立即采取行动
KERN_CRIT	2	"<2>"	严重错误条件
KERN_ERR	3	"<3>"	错误条件
KERN_WARNING	4	"<4>"	警告条件
KERN_NOTICE	5	"<5>"	正常但重要的消息
KERN_INFO	6	"<6>"	信息性消息
KERN_DEBUG	7	"<7>"	调试级别消息

3.1.2 控制台输出控制机制

内核通过 /proc/sys/kernel/printk 文件暴露四个关键值，允许运行时调整输出行为：

# cat /proc/sys/kernel/printk
7       4       1       7
# 字段含义：
# [0] console_loglevel  : 控制台日志级别（默认 7，即所有消息都输出）
# [1] default_message_loglevel : 未指定级别的 printk 默认级别（默认 4）
# [2] minimum_console_loglevel : 控制台日志级别可设置的最小值（默认 1）
# [3] default_console_loglevel : 系统启动时的默认控制台级别（默认 7）

调整控制台日志级别的常用方法：

• 运行时调整： echo "5 4 1 7" > /proc/sys/kernel/printk（仅输出级别 < 5 的消息）
• 启动参数： 在 kernel 命令行添加 loglevel=3 或 quiet（设置 loglevel=4）
• 忽略所有控制台输出： echo "0 4 1 7" > /proc/sys/kernel/printk

3.1.3 printk 输出流程 SVG

下图展示了 printk 消息从产生到控制台输出的完整路径：

3.2 动态调试（dynamic_debug）

动态调试（dynamic_debug）是内核提供的一种高级调试机制，允许在 不重新编译内核或模块 的情况下，动态启用或禁用特定文件、函数、行的 pr_debug()/dev_dbg() 输出。它比全局修改 printk 级别更精细、更高效。

3.2.1 启用动态调试

使用动态调试需要内核配置开启 CONFIG_DYNAMIC_DEBUG：

# 检查当前内核是否支持动态调试
cat /boot/config-$(uname -r) | grep CONFIG_DYNAMIC_DEBUG
CONFIG_DYNAMIC_DEBUG=y

动态调试通过 debugfs 文件系统控制，通常挂载在 /sys/kernel/debug/dynamic_debug/control：

# 挂载 debugfs（如果未挂载）
mount -t debugfs none /sys/kernel/debug

# 查看当前所有动态调试点
cat /sys/kernel/debug/dynamic_debug/control | head -20

3.2.2 控制语法与匹配模式

控制文件支持基于 文件、函数、行号 和 格式字符串 的匹配，使用 echo "pattern flags" > control 格式：

匹配维度	语法示例	说明
文件名	file drivers/usb/core/* +p	匹配 drivers/usb/core/ 目录下所有文件
函数名	func usb_submit_urb +p	匹配 usb_submit_urb 函数内的所有调试点
行号	file foo.c line 123 +p	匹配 foo.c 第 123 行的调试点
格式字符串	format "dma_map_sg" +p	匹配格式字符串包含 "dma_map_sg" 的调试点

常用标志（flags）：

• +p：启用该调试点的 pr_debug() 输出
• -p：禁用该调试点的输出
• +f：在输出中包含函数名
• +l：在输出中包含行号
• +m：在输出中包含模块名
• +t：在输出中包含线程 ID

3.2.3 动态调试工作流程 SVG

下图展示了动态调试从控制接口到内核输出的完整流程：

3.3 trace_printk：专为追踪设计的轻量级输出

trace_printk 是内核追踪框架（ftrace）提供的一种特殊输出函数，与 printk 相比，它具有 极低的开销 和 与追踪系统无缝集成 的优势。它专为性能敏感场景下的调试而设计。

3.3.1 trace_printk 与 printk 的对比

特性	printk	trace_printk
输出目标	内核日志缓冲区（dmesg）	ftrace 环形缓冲区（trace）
性能开销	较高（格式化和控制台 I/O）	极低（仅写入追踪缓冲区）
上下文限制	可在任何上下文使用	可在任何上下文使用（包括 NMI）
时间戳精度	依赖 jiffies 或时钟源	高精度（与 ftrace 时间戳一致）
查看方式	dmesg 或 /var/log/kern.log	cat /sys/kernel/debug/tracing/trace
动态控制	通过 printk 级别全局控制	通过 ftrace 过滤器精确控制

3.3.2 trace_printk 的使用方法

在代码中使用 trace_printk 与 printk 类似，但需要包含 linux/kernel.h：

#include <linux/kernel.h>

void my_function(void)
{
    int ret = do_something();
    trace_printk("my_function: ret = %d, data = %lu\n", ret, data);
}

查看 trace_printk 输出：

# 确保 ftrace 已启用
mount -t tracefs none /sys/kernel/debug/tracing

# 查看追踪缓冲区（包含所有 trace_printk 输出）
cat /sys/kernel/debug/tracing/trace

# 只查看特定函数的 trace_printk 输出
echo 'function:my_function' > /sys/kernel/debug/tracing/set_ftrace_filter
echo function > /sys/kernel/debug/tracing/current_tracer
cat /sys/kernel/debug/tracing/trace

3.3.3 trace_printk 与 ftrace 集成架构 SVG

下图展示了 trace_printk 在内核追踪架构中的位置和数据流：

3.4 实战：选择正确的调试输出方法

根据不同的调试场景，选择合适的输出方法可以显著提升效率：

调试场景	推荐方法	理由
早期启动阶段（early boot）	printk + earlyprintk	控制台未初始化时仍可输出
驱动初始化调试	pr_debug + dynamic_debug	按需启用，避免日志泛滥
性能关键路径追踪	trace_printk	极低开销，不影响性能测量
中断上下文（NMI）	trace_printk	printk 在 NMI 中可能死锁
长期运行系统监控	printk (KERN_INFO/KERN_WARNING)	输出到 dmesg，便于持久化
复杂函数调用链分析	trace_printk + ftrace function_graph	结合函数图追踪，定位精确