rpcz

学习rpcz服务。

用户能通过/rpcz看到最近请求的详细信息，并可以插入注释（annotation），不同于tracing system（如dapper）以全局视角看到整体系统的延时分布，rpcz更多是一个调试工具，虽然角色有所不同，但在brpc中rpcz和tracing的数据来源是一样的。当每秒请求数小于1万时，rpcz会记录所有的请求，超过1万时，rpcz会随机忽略一些请求把采样数控制在1万左右。rpcz可以淘汰时间窗口之前的数据，通过-span_keeping_seconds选项设置，默认1小时。一个长期运行的例子。

关于开销：我们的实现完全规避了线程竞争，开销极小，在qps 30万的测试场景中，观察不到明显的性能变化，对大部分应用而言应该是“free”的。即使采集了几千万条请求，rpcz也不会增加很多内存，一般在50兆以内。rpcz会占用一些磁盘空间（就像日志一样），如果设定为存一个小时的数据，一般在几百兆左右。

开关方法

默认不开启，加入-enable_rpcz选项会在启动后开启。

NameValueDescriptionDefined At
enable_rpcz (R)true (default:false)Turn on rpczsrc/baidu/rpc/builtin/rpcz_service.cpp
rpcz_hex_log_id (R)falseShow log_id in hexadecimalsrc/baidu/rpc/builtin/rpcz_service.cpp
rpcz_database_dir./rpc_data/rpczFor storing requests/contexts collected by rpcz.src/baidu/rpc/span.cpp
rpcz_keep_span_dbfalseDon’t remove DB of rpcz at program’s exitsrc/baidu/rpc/span.cpp
rpcz_keep_span_seconds (R)3600Keep spans for at most so many secondssrc/baidu/rpc/span.cpp

若启动时未加-enable_rpcz，则可在启动后访问SERVER_URL/rpcz/enable动态开启rpcz，访问SERVER_URL/rpcz/disable则关闭，这两个链接等价于访问SERVER_URL/flags/enable_rpcz?setvalue=true和SERVER_URL/flags/enable_rpcz?setvalue=false。在r31010之后，rpc在html版本中增加了一个按钮可视化地开启和关闭。

img

img

如果只是brpc client或没有使用brpc，看这里。

数据展现

/rpcz展现的数据分为两层。

第一层

看到最新请求的概况，点击链接进入第二层。

img

第二层

看到某系列(trace)或某个请求(span)的详细信息。一般通过点击链接进入，也可以把trace=和span=作为query-string拼出链接

img

内容说明：

  • 时间分为了绝对时间（如2015/01/21-20:20:30.817392，小数点后精确到微秒）和前一个时间的差值（如. 19，代表19微秒)。
  • trace=ID有点像“session id”，对应一个系统中完成一次对外服务牵涉到的所有服务，即上下游server都共用一个trace-id。span=ID对应一个server或client中一个请求的处理过程。trace-id和span-id在概率上唯一。
  • 第一层页面中的request=和response=后的是数据包的字节数，包括附件但不包括协议meta。第二层中request和response的字节数一般在括号里，比如”Responded(13)“中的13。
  • 点击链接可能会访问其他server上的rpcz，点浏览器后退一般会返回到之前的页面位置。
  • I’m the last call, I’m about to …都是用户的annotation。

Annotation

只要你使用了brpc，就可以使用TRACEPRINTF打印内容到事件流中，比如：

  1. TRACEPRINTF("Hello rpcz %d", 123);

这条annotation会按其发生时间插入到对应请求的rpcz中。从这个角度看，rpcz是请求级的日志。如果你用TRACEPRINTF打印了沿路的上下文，便可看到请求在每个阶段停留的时间，牵涉到的数据集和参数。这是个很有用的功能。

修改于 2024年2月5日: Release bRPC 1.8.0 (254a6bb)