Logback配置文件这么写,TPS提高10倍( 二 )

3、异步输出日志性能测试既然能提高性能的话,必须进行一次测试比对,同步和异步输出日志性能到底能提升多少倍?
服务器硬件

  • CPU 六核
  • 内存 8G
测试工具Apache Jmeter
同步输出日志
  • 线程数:100
  • Ramp-Up Loop(可以理解为启动线程所用时间) :0 可以理解为100个线程同时启用
  • 测试结果

Logback配置文件这么写,TPS提高10倍

文章插图
 
重点关注指标Throughput【TPS】吞吐量:系统在单位时间内处理请求的数量,在同步输出日志中TPS为44.2/sec 。坑爹的日志无法按天切割问题,这个大家也得注意下 。
异步输出日志
  • 线程数 100
  • Ramp-Up Loop:0
  • 测试结果

Logback配置文件这么写,TPS提高10倍

文章插图
 
TPS为497.5/sec,性能提升了10多倍!!!
4、异步日志输出原理从logback框架下的Logger.info方法开始追踪 。一路的方法调用路径2T架构师学习资料干货分享如下图所示:
Logback配置文件这么写,TPS提高10倍

文章插图
 
异步输出日志中最关键的就是配置文件中ch.qos.logback.classic包下AsyncAppenderBase类中的append方法,查看该方法的源码:
protected void append(E eventObject) {if(!this.isQueueBelowDiscardingThreshold() || !this.isDiscardable(eventObject)) {this.preprocess(eventObject);this.put(eventObject);}}通过队列情况判断是否需要丢弃日志,不丢弃的话将它放到阻塞队列中,通过查看代码,这个阻塞队列为ArrayBlockingQueueu,默认大小为256,可以通过配置文件进行修改 。
Logger.info(...)到append(...)就结束了,只做了将日志塞入到阻塞队列的事,然后继续执行Logger.info(...)下面的语句了 。在AsyncAppenderBase类中定义了一个Worker线程,run方法中的关键部分代码如下:
E e = parent.blockingQueue.take();aai.appendLoopOnAppenders(e);从阻塞队列中取出一个日志,并调用AppenderAttachableImpl类中的appendLoopOnAppenders方法维护一个Append列表 。Worker线程中调用方法过程主要如下图:
Logback配置文件这么写,TPS提高10倍

文章插图
 
最主要的两个方法就是encode和write方法,前一个法方会根据配置文件中encode指定的方式转化为字节码,后一个方法将转化成的字节码写入到文件中去 。
所以写文件是通过新起一个线程去完成的,主线程将日志扔到阻塞队列中,然后又去做其他事情了 。
【Logback配置文件这么写,TPS提高10倍】 
作者:何甜甜在吗
链接:juejin.im/post/5d4d61326fb9a06aff5e5ff5




推荐阅读