Druid连接池 慢SQL日志记录

时间 2014-08-27 13:02:05  ITeye-博客

原文  http://quiet-boy.iteye.com/blog/2109507

主题 SQL

由于工作原因，这里先不说连接数据库部分的配置，后面会补上，直接进入慢SQL日志记录。

1.applicationContext.xml中增加如下配置：

<bean abstract="true" id="mysql_database" class="com.alibaba.druid.pool.DruidDataSource" init-method="init" destroy-method="close"> <!-- 监控统计拦截的filters,日志记录 edited by zhangpl 20140824 start --><!-- 并在filters属性中配置了log4j --><property name="proxyFilters">    <list><ref bean="stat-filter" /><ref bean="log-filter" />    </list> </property> <!-- 监控统计拦截的filters,日志记录 edited by zhangpl 20140824 end --><property name="filters">    <value>stat,wall,log4j</value></property><!-- 其它基本配置略...--></bean><!-- 慢SQL记录  edited by zhangpl 20140824  start--><bean id="stat-filter" class="com.alibaba.druid.filter.stat.StatFilter"><property name="mergeSql" value="true" /><property name="slowSqlMillis" value="1000" /><property name="logSlowSql" value="true" /></bean>    <bean id="log-filter" class="com.alibaba.druid.filter.logging.Log4jFilter"><!-- <property name="resultSetLogEnabled" value="false" /> --><!-- <property name="statementExecutableSqlLogEnable" value="true" /> --></bean><!-- 慢SQL记录  edited by zhangpl 20140824  end-->

2.log4j.properties中配置如下：

log4j.rootLogger=warn,A1,A2#1.打印druid连接池SQL语句，info级别的语句打印到info_slowsql.log，warn级别语句打印到warn_slowlog.log；#2.将日志写到文件中； # Druidlog4j.logger.druid.sql=info,A1,A2log4j.logger.druid.sql.DataSource=info,A1,A2log4j.logger.druid.sql.Connection=info,A1,A2log4j.logger.druid.sql.Statement=info,A1,A2log4j.logger.druid.sql.ResultSet=info,A1,A2log4j.appender.A1=org.apache.log4j.DailyRollingFileAppenderlog4j.appender.A1.File=../druid-logs/info_slowsql.loglog4j.appender.A1.DatePattern='.'yyyy-MM-dd#log4j.appender.A1.File=${catalina.home}/logs/info_slowsql.log#log4j.appender.A1.File=${WebApp.TomcatHome}/logs/info_slowsql.loglog4j.appender.A1.ImmediateFlush=truelog4j.appender.A1.Append=truelog4j.appender.A1.Threshold=infolog4j.appender.A1.layout=org.apache.log4j.PatternLayoutlog4j.appender.A1.layout.ConversionPattern=[%d{yyyy-MM-dd HH:mm:ss}] [%p] [%c] - %m%nlog4j.appender.A2=org.apache.log4j.DailyRollingFileAppenderlog4j.appender.A2.File=../druid-logs/warn_slowsql.loglog4j.appender.A2.DatePattern='.'yyyy-MM-dd#log4j.appender.A2.File=${catalina.home}/logs/warn_slowsql.log#log4j.appender.A2.File=${WebApp.TomcatHome}/logs/warn_slowsql.loglog4j.appender.A2.ImmediateFlush=truelog4j.appender.A2.Append=truelog4j.appender.A2.Threshold=warnlog4j.appender.A2.layout=org.apache.log4j.PatternLayoutlog4j.appender.A2.layout.ConversionPattern=[%d{yyyy-MM-dd HH:mm:ss}] [%p] [%c] - %m%n

经过上面的配置，就会在文件中打印，如下的慢SQL语句：

......[2014-08-26 11:12:59] [ERROR] [com.alibaba.druid.filter.stat.StatFilter] - slow sql 3121 millis. select count(1) from person where schoolid=? and isDeleted=0   and GET_FIRST_PINYIN_CHAR(username) =?  ["145","a"]......

有问题欢迎指正，可参考官方文档，以官方文档为准。

参考：http://www.oschina.net/question/97503_117693

过程中遇到日志报错“

Communications link failure，The last packet successfully received from the server was *** millisecon

”

使用Connector/J连接MySQL数据库，程序运行较长时间后就会报以下错误：

Communications link failure，The last packet successfully received from the server was * **millisecond ago. 
The last packet successfully sent to the server was * **millisecond ago。

其中错误还会提示你修改wait_timeout或是使用Connector/J的autoReconnect属性避免该错误。

后来查了一些资料，才发现遇到这个问题的人还真不少，大部分都是使用连接池方式时才会出现这个问题，短连接应该很难出现这个问题。这个问题的原因：

1. 按照错误的提示，可以在JDBC URL中使用autoReconnect属性，实际测试时使用了autoReconnect=true&failOverReadOnly=false，不过并未起作用，使用的是5.1版本，可能真像网上所说的只对4之前的版本有效。

2. 没办法，只能修改MySQL的参数了，wait_timeout最大为31536000即1年，在my.cnf中加入：

[mysqld]  wait_timeout=31536000  interactive_timeout=31536000  

重启生效，需要同时修改这两个参数。

---

Caused by: com.mysql.jdbc.exceptions.jdbc4.CommunicationsException: Communications link failure  [09:49:36.821]  [09:49:36.821]Last packet sent to the server was 0 ms ago.