怎么在Apache中通过mod_log_config记录请求处理耗时与响应往返时延-Apache-PHP中文网

%d记录apache请求生命周期耗时（微秒级），真实端到端延迟需外部工具或应用层配合；java耗时须由后端通过x-process-time响应头传递，反向代理层（如haproxy的%tr）更适测往返时延。

怎么在apache中通过mod_log_config记录请求处理耗时与响应往返时延

Apache 本身不区分“处理耗时”和“往返时延”，这两个概念需分别理解并配置：%D 记录 Apache 自身的请求生命周期耗时（微秒级），而真正的端到端往返时延（含网络、后端 Java 应用处理）必须靠外部工具或应用层配合才能捕获。

%D 是 mod_log_config 提供的原生格式符，表示从 Apache 接收到请求第一个字节，到发送完响应头为止所经过的微秒数。它不含后端应用执行时间，但轻量、稳定、无需额外模块。

Apache Superset 是一个广泛采用的开源 BI 平台，用于 SQL 探索、图表构建和仪表板交付。当代理需要查询仓库数据、组装仪表板或使用成熟的分析界面解释指标而不是临时笔记本代码时，此技能非常有用。

下载

在 httpd.conf 或虚拟主机配置中添加自定义日志格式：
LogFormat "%h %l %u %t \"%r\" %>s %b \"%{Referer}i\" \"%{User-Agent}i\" %D" timed_combined
启用该格式记录日志：
CustomLog logs/access_log timed_combined
日志样例末尾数字即为微秒值：
192.168.1.100 - - [30/Apr/2026:12:05:22 +0800] "GET /api/data HTTP/1.1" 200 142 "-" "curl/7.68.0" 89241 → 约 89 ms

Apache 无法感知 Tomcat、Spring Boot 等后端内部执行逻辑。要拿到“Java 处理耗时”，必须由 Java 应用自己计算，并通过响应头传递给 Apache。

Java 端（如 Spring Boot Filter）设置响应头：
response.setHeader("X-Process-Time", String.valueOf(System.currentTimeMillis() - startTime));
Apache 配置中捕获该头：
LogFormat "%h %l %u %t \"%r\" %>s %b \"%{Referer}i\" \"%{User-Agent}i\" %D %{X-Process-Time}o" java_timing
注意：%{X-Process-Time}o 中的 o 表示 output header，前提是 Java 确实写入了该头；否则日志对应位置显示为破折号（-）

如果想测客户端发出请求到收到首个响应字节的完整延迟（含网络传输 + Apache 接收 + 后端处理 + 响应返回），Apache 本身能力有限。更实用的做法是：

在反向代理层（如 HAProxy）开启日志，使用 %Tr 字段：它表示从 HAProxy 发完请求到收第一个响应字节的时间，即“后端处理+网络往返”总耗时
同时在 Apache 日志中记录 %D，两者比对可判断瓶颈在 Apache 层（%D 高）、后端层（%Tr − %D 差值大），还是网络层（%Tr 高但 %D 正常）
若无反代，可在客户端用 curl -w "@format.txt" 或浏览器 DevTools Network 面板查看 TTFB（Time to First Byte），作为补充参考

别用 %T 替代 %D：它只返回整秒数（向下取整），精度太低，不适合性能分析
不要尝试 %{X-My-Header}o 直接读任意响应头：Apache 2.4 及之前版本的 mod_log_config 不支持通用响应头日志，该写法多数情况下无效或留空
字段序号会随 LogFormat 改变：用 awk 统计 %D 时，务必先用 head -1 access.log | awk '{print NF; print $0}' 确认 %D 对应第几列
日志轮转时 tail -f 会断开，实时监控请改用 tail -F 并加 stdbuf -oL 避免缓冲延迟