4. printk 性能陷阱：高频率 printk 对驱动性能的影响与测量方法

printk 是内核调试最常用的“瑞士军刀”，但在驱动性能调优场景中，不加节制的 printk 调用会引入严重的性能退化。本章深入分析 printk 的同步/异步行为、锁竞争与日志缓冲区瓶颈，并给出可量化的测量方法与优化策略。

4.1 printk 的同步与异步行为

printk 的行为取决于控制台是否处于 同步模式。在大多数嵌入式或虚拟化环境中，控制台默认使用 console=ttyS0 或 console=tty0，此时 printk 会等待字符逐字节输出完毕才返回。这种同步写操作在高速驱动路径中会形成巨大的延迟。

4.2 高频率 printk 的性能陷阱

当驱动在中断上下文或轮询循环中高频调用 printk 时，以下三个陷阱会迅速恶化系统性能：

• 锁竞争（console_sem）： 所有 printk 调用必须获取全局 console_sem 信号量。多核并发时，该锁成为热点，导致 CPU 核间等待。
• 串口波特率瓶颈： 115200 bps 的串口每秒最多输出约 11.5 KB 数据。若每行日志 80 字节，每秒仅能输出约 140 行。超出部分被丢弃或阻塞。
• 中断上下文阻塞： 在中断处理函数中调用 printk，若控制台忙，会导致中断延迟飙升，甚至触发 RCU stall 或 watchdog 超时。

4.3 printk 延迟的测量方法

要量化 printk 对驱动性能的影响，推荐使用 Ftrace + 高精度计时器 组合测量。以下步骤展示如何测量单次 printk 的延迟：

1. 启用 Ftrace function_graph： 跟踪 printk 及其子函数调用链。
2. 插入 ktime 打点： 在驱动热路径中记录调用 printk 前后的时间戳。
3. 统计延迟分布： 使用 trace-cmd 或脚本分析延迟最大值、平均值和 99 分位值。

/* 驱动热路径中的 printk 延迟测量示例 */
#include <linux/ktime.h>
#include <linux/sched/clock.h>

static void driver_hotpath(struct work_struct *work)
{
    u64 start, end;
    s64 delta_ns;

    start = local_clock();  /* 纳秒级时间戳 */

    /* 待测量的 printk 调用 */
    printk(KERN_INFO "drv: packet received, len=%u\n", pkt_len);

    end = local_clock();
    delta_ns = end - start;

    if (delta_ns > 1000000)  /* 超过 1 ms 则记录 */
        pr_warn("printk took %lld ns\n", delta_ns);
}

4.4 printk 性能影响的可视化

下图展示了一个典型的中断驱动场景中，高频 printk 如何逐步侵蚀 CPU 有效利用率：

4.5 性能优化策略

针对驱动调试场景，推荐以下分级优化策略：

4.6 使用 Ftrace 定位 printk 热点

Ftrace 的 function_graph 跟踪器可以精确显示 printk 在驱动路径中的调用次数和耗时。以下命令展示如何捕获驱动热路径中的 printk 开销：

# 设置 function_graph 跟踪器，过滤驱动函数
echo function_graph > /sys/kernel/tracing/current_tracer
echo my_driver_irq_handler > /sys/kernel/tracing/set_ftrace_filter
echo 1 > /sys/kernel/tracing/tracing_on

# 触发驱动工作负载后停止
cat /sys/kernel/tracing/trace | head -100

输出示例中，printk 的调用时间会以缩进层级显示，便于识别嵌套调用和延迟来源：

 2)               |  my_driver_irq_handler() {
 2)   0.500 us    |    _raw_spin_lock();
 2)   3.200 us    |    printk();          /* <-- 此处耗时 3.2 us */
 2)   0.300 us    |    _raw_spin_unlock();
 2)   4.500 us    |  }

4.7 小结