apache mina 学习（四）-----日志配置

用Log4J 1.2.x的框架时，我们需要三个jar包：log4j-1.2.3.jar slf4j-api-1.6.2jar slf4j-log4j12-1.6.2jar 从名字上可以看出第三个是主要的jar包，提供了前两者的增强功能。

下面我们以一个例子来说明日志过滤器的配置方法：

首先我们创建一个log4j的配置文件并设置日志输出级别为debug：

log4j.appender.MINA=org.apache.log4j.ConsoleAppender log4j.appender.MINA.layout=org.apache.log4j.PatternLayout log4j.appender.MINA.layout.ConversionPattern=%d{yyyy-MM-dd HH:mm:ss} %m%n #to console# log4j.appender.stdout=org.apache.log4j.ConsoleAppender log4j.appender.stdout.Target=System.out log4j.appender.stdout.layout=org.apache.log4j.PatternLayout log4j.appender.stdout.layout.ConversionPattern=%d{yyyy-MM-dd HH:mm:ss} %m%n #to file# log4j.appender.file=org.apache.log4j.FileAppender log4j.appender.file.File=./log/minademos.log log4j.appender.file.layout=org.apache.log4j.PatternLayout log4j.appender.file.layout.ConversionPattern=%d{yyyy-MM-dd HH:mm:ss} %l %m%n #error/warn/info/debug# log4j.rootLogger=debug, stdout,MINA,file

接下来我们写一个服务器端的代码，在8080端口启动一个服务：

package com.yushh.test.logging; import java.io.IOException; import java.net.InetSocketAddress; import org.apache.mina.core.filterchain.DefaultIoFilterChainBuilder; import org.apache.mina.core.service.IoAcceptor; import org.apache.mina.filter.logging.LogLevel; import org.apache.mina.filter.logging.LoggingFilter; import org.apache.mina.transport.socket.nio.NioSocketAcceptor; public class EchoServer { private static final int PORT = 8080; public static void main(String[] args) throws IOException { IoAcceptor acceptor = new NioSocketAcceptor(); DefaultIoFilterChainBuilder chain = acceptor.getFilterChain(); LoggingFilter loggingFilter = new LoggingFilter(); chain.addLast("logging", loggingFilter); acceptor.setDefaultLocalAddress(new InetSocketAddress(PORT)); acceptor.setHandler(new EchoProtocolHandler()); acceptor.bind(); System.out.println("Listening on port " + PORT); } }

注意此处没有具体配置loggingFilter，下面我们用dos telnet本机的8080端口得到如下的日志信息：

Listening on port 8080 2012-03-15 14:34:56 CREATED 2012-03-15 14:34:56 CREATED 2012-03-15 14:34:56 OPENED 2012-03-15 14:34:56 OPENED 2012-03-15 14:34:58 RECEIVED: HeapBuffer[pos=0 lim=1 cap=2048: 61] 2012-03-15 14:34:58 RECEIVED: HeapBuffer[pos=0 lim=1 cap=2048: 61] 2012-03-15 14:35:00 RECEIVED: HeapBuffer[pos=0 lim=1 cap=2048: 62] 2012-03-15 14:35:00 RECEIVED: HeapBuffer[pos=0 lim=1 cap=2048: 62] 2012-03-15 14:35:01 RECEIVED: HeapBuffer[pos=0 lim=1 cap=1024: 63] 2012-03-15 14:35:01 RECEIVED: HeapBuffer[pos=0 lim=1 cap=1024: 63] 2012-03-15 14:35:02 RECEIVED: HeapBuffer[pos=0 lim=1 cap=1024: 41] 2012-03-15 14:35:02 RECEIVED: HeapBuffer[pos=0 lim=1 cap=1024: 41] 2012-03-15 14:35:05 CLOSED 2012-03-15 14:35:05 CLOSED

可见session的几种生命周期都被答应出来，从输出的RECEIVED信息中可以看见我在客户端输入的字符为‘abcA’，呵呵这个以后再讨论。

下面我们修改一下服务器代码：

package com.yushh.test.logging; import java.io.IOException; import java.net.InetSocketAddress; import org.apache.mina.core.filterchain.DefaultIoFilterChainBuilder; import org.apache.mina.core.service.IoAcceptor; import org.apache.mina.filter.logging.LogLevel; import org.apache.mina.filter.logging.LoggingFilter; import org.apache.mina.transport.socket.nio.NioSocketAcceptor; public class EchoServer { private static final int PORT = 8080; public static void main(String[] args) throws IOException { IoAcceptor acceptor = new NioSocketAcceptor(); DefaultIoFilterChainBuilder chain = acceptor.getFilterChain(); LoggingFilter loggingFilter = new LoggingFilter(); loggingFilter.setSessionClosedLogLevel(LogLevel.NONE); loggingFilter.setSessionCreatedLogLevel(LogLevel.DEBUG); loggingFilter.setSessionOpenedLogLevel(LogLevel.INFO); chain.addLast("logging", loggingFilter); acceptor.setDefaultLocalAddress(new InetSocketAddress(PORT)); acceptor.setHandler(new EchoProtocolHandler()); acceptor.bind(); System.out.println("Listening on port " + PORT); } }

我们在LoggingFilter 增加了日志输出级别，设置当session关闭时不打印任何日志，当session建立时日志为debug级别，当session打开的时候日志为info级别，同样的客户端测试结果如下：

Listening on port 8080 2012-03-15 14:38:03 CREATED 2012-03-15 14:38:03 CREATED 2012-03-15 14:38:03 OPENED 2012-03-15 14:38:03 OPENED 2012-03-15 14:38:05 RECEIVED: HeapBuffer[pos=0 lim=1 cap=2048: 61] 2012-03-15 14:38:05 RECEIVED: HeapBuffer[pos=0 lim=1 cap=2048: 61] 2012-03-15 14:38:05 RECEIVED: HeapBuffer[pos=0 lim=1 cap=2048: 62] 2012-03-15 14:38:05 RECEIVED: HeapBuffer[pos=0 lim=1 cap=2048: 62] 2012-03-15 14:38:08 RECEIVED: HeapBuffer[pos=0 lim=1 cap=1024: 63] 2012-03-15 14:38:08 RECEIVED: HeapBuffer[pos=0 lim=1 cap=1024: 63] 2012-03-15 14:38:09 RECEIVED: HeapBuffer[pos=0 lim=1 cap=1024: 41] 2012-03-15 14:38:09 RECEIVED: HeapBuffer[pos=0 lim=1 cap=1024: 41]

可见mina优先使用代码中的 日志输出级别。