Apache日志需通过%D和%{X-Response-Time}o双字段对比定位瓶颈:前者为Apache耗时(微秒),后者为Java业务耗时(毫秒),若%D远大于后者则瓶颈在Apache层,若接近则在Java层,为空则排查头设置问题。

Apache日志本身不直接记录后端Java应用的处理时长,但可以通过“分层记录+响应头透传”方式,把后端真实耗时写入日志,再与Apache自身耗时对比,从而定位瓶颈在Apache层还是Java层。
配置Apache记录两类关键时间
必须同时记录两个字段才能做对比分析:
- %D:Apache自身耗时(微秒),从收到请求首字节到发出响应头完成的时间,反映Web服务器开销;
- %{X-Response-Time}o:捕获Java应用主动设置的响应头,代表后端真正执行业务逻辑的时间(单位毫秒,需Java侧统一输出)。
示例LogFormat配置:
LogFormat "%h %l %u %t \"%r\" %>s %b \"%{Referer}i\" \"%{User-Agent}i\" %D %{X-Response-Time}o" backend_timing启用后,日志末尾会类似:... 42891 386,分别表示Apache耗时42.9ms、Java耗时386ms。
Java应用需主动上报处理时长
Apache无法感知Java内部执行过程,必须由应用在响应前计算并注入响应头:
Apache Superset 是一个广泛采用的开源 BI 平台,用于 SQL 探索、图表构建和仪表板交付。当代理需要查询仓库数据、组装仪表板或使用成熟的分析界面解释指标而不是临时笔记本代码时,此技能非常有用。
- Spring Boot中可用Filter或Interceptor,在
chain.doFilter()前后计时,设response.setHeader("X-Response-Time", String.valueOf(ms)); - 确保头名拼写准确(区分大小写)、未被Nginx等前置代理过滤(检查
proxy_pass_request_headers on); - 避免使用压缩模块(如mod_deflate)意外截断响应头——可临时关闭验证。
从日志差异判断瓶颈位置
对比%D和%{X-Response-Time}o数值关系,能快速缩小问题范围:
- 若%D明显大于X-Response-Time(例如
150000,X-Response-Time仅 80),说明延迟主要来自Apache层:SSL解密、gzip压缩、重写规则、mod_proxy转发等待等; - 若%D与X-Response-Time接近(如
392000,X-Response-Time 385),说明Apache开销极小,瓶颈几乎肯定在Java代码层; - 若X-Response-Time为空或为0,优先排查Java是否漏设头、头名不一致、或中间件拦截。
聚合分析慢路径定位具体接口
单条日志意义有限,需用awk按路径聚合平均后端耗时:
awk '$9 ~ /^[2-5]..$/ && $(NF-1) > 0 {print $7, $(NF-1)}' access.log | awk '{sum[$1]+=$2; cnt[$1]++} END {for (p in sum) if(cnt[p]>10) print int(sum[p]/cnt[p]), p}' | sort -nr | head -10其中$7对应%U(路径),$(NF-1)对应%{X-Response-Time}o字段。结果可直接指向如/api/order/submit这类高延迟接口,再结合Java的Controller方法和数据库慢查日志进一步下钻。










