OSB日志排查求助：定位WAF与OSB中间件间请求延迟问题

以下是几种可直接落地的方案，帮你精准获取请求到达OSB服务的时间节点，进而区分网络（WAF）与中间件层面的延迟：

• 增强OSB自身日志配置
  在OSB的代理服务/业务服务流水线起始阶段，添加日志动作，直接记录请求进入OSB的时间戳。可使用OSB内置的$system.currentTime变量（毫秒级时间戳），同时在日志中加入唯一请求ID（比如$inbound.TransportHeaders.x-request-id，若请求头携带该标识），方便后续关联上下游日志。示例日志格式：Request [${request-id}] arrived at OSB at ${system.currentTime}。

• 开启中间件访问日志的时间戳记录
  若OSB部署在WebLogic、Tomcat这类中间件上，直接开启中间件的访问日志并配置包含请求到达时间的格式。比如WebLogic的access.log可配置格式：%h %l %u %t "%r" %s %b %D，其中%t是请求到达中间件的时间（格式为[dd/MMM/yyyy:HH:mm:ss Z]），%D是中间件处理请求的耗时（毫秒）。将这个时间与OSB内部日志的时间对比，即可看出中间件到OSB的流转耗时。

• TCP抓包分析网络到服务的时间差
  在OSB服务器上执行抓包命令，捕获目标端口的流量：

  tcpdump -i 网卡名称 port OSB服务端口 -w osb_traffic.pcap
  

  用Wireshark打开抓包文件，找到对应请求的SYN包（客户端发起连接）和ACK包，再结合OSB日志中请求到达的时间，两者的差值就是网络传输（含WAF转发）的耗时，剩余部分即为中间件/OSB的处理延迟。

• 自定义拦截器注入时间戳
  开发一个OSB自定义拦截器（或在中间件层面添加Servlet过滤器），在请求刚进入服务容器时就记录时间戳并存入请求上下文。比如在Java过滤器中：

  long arrivalTime = System.currentTimeMillis();
  request.setAttribute("osb_arrival_time", arrivalTime);
  

  后续在OSB的流水线中可取出这个时间戳，写入日志或用于计算耗时，确保时间节点的记录点尽可能靠近服务入口。

• 校准时间后对比WAF与OSB日志
  先确保WAF服务器和OSB服务器的时间通过NTP同步（误差控制在100ms内），然后从WAF获取请求离开WAF的时间戳，和OSB这边的请求到达时间戳做差值，直接得到网络传输的耗时。若这个差值很大，说明延迟来自WAF或网络；若差值小，延迟则大概率在中间件或OSB内部。

内容的提问来源于stack exchange，提问作者Bassel Al Halabi