Java日志终极指南

jopen 9年前

Java日志基础

Java使用了一种自定义的、可扩展的方法来输出日志。虽然Java通过java.util.logging包提供了一套基本的日志处理API,但你可以很轻松的使用一种或者多种其它日志解决方案。这些解决方案尽管使用不同的方法来创建日志数据,但它们的最终目标是一样的,即将日志从你的应用程序输出到目标地址。

在这一节中,我们会探索Java日志背后的原理,并说明如何通过日志来让你成为一个更好的Java开发人员。

Java日志组件

Java日志API由以下三个核心组件组成:

  • Loggers:Logger负责捕捉事件并将其发送给合适的Appender。
  • Appenders:也被称为Handlers,负责将日志事件记录到目标位置。在将日志事件输出之前,Appenders使用Layouts来对事件进行格式化处理。
  • Layouts:也被称为Formatters,它负责对日志事件中的数据进行转换和格式化。Layouts决定了数据在一条日志记录中的最终形式。

当Logger记录一个事件时,它将事件转发给适当的Appender。然后Appender使用Layout来对日志记录进行格式化,并将其发送给控制台、文件或者其它目标位置。另外,Filters可以让你进一步指定一个Appender是否可以应用在一条特定的日志记录上。在日志配置中,Filters并不是必需的,但可以让你更灵活控制日志消息的流动。

Java日志终极指南

日志框架

在Java中,输出日志需要使用一个或者多个日志框架,这些框架提供了必要的对象、方法和配置来传输消息。Java在java.util.logging包中提供了一个默认的框架除此之外,还有很多其它第三方框架,包括Log4jLogback以及tinylog。还有其它一些开发包,例如SLF4JApache Commons Logging,它们提供了一些抽象层,对你的代码和日志框架进行解耦,从而允许你在不同的日志框架中进行切换。

如何选择一个日志解决方案,这取决于你的日志需求的复杂度、和其它日志解决方案的兼容性、易用性以及个人喜好。Logback基于Log4j之前的版本开发(版本1),因此它们的功能集合都非常类似。然而,Log4j在最新版本(版本2)中引用了一些改进,例如支持多API,并提升了在用Disruptor库的性能。而tinylog,由于缺少了一些功能,运行特别快,非常适合小项目。

另外一个考虑因素是框架在基于Java的各种不同项目上的支持程度。例如Android程序只能使用Log4jLogback或者第三方包来记录日志, Apache Tomcat可以使用Log4j来记录内部消息,但只能使用版本1的Log4j。

抽象层

诸如SLF4J这样的抽象层,会将你的应用程序从日志框架中解耦。应 用程序可以在运行时选择绑定到一个特定的日志框架(例如java.util.logging、Log4j或者Logback),这通过在应用程序的类路径 中添加对应的日志框架来实现。如果在类路径中配置的日志框架不可用,抽象层就会立刻取消调用日志的相应逻辑。抽象层可以让我们更加容易地改变项目现有的日志框架,或者集成那些使用了不同日志框架的项目。

配置

尽管所有的Java日志框架都可以通过代码进行配置,但是大部分配置还是通过外部配置文件完成的。这些文件决定了日志消息在何时通过什么方式进行处理,日志框架可以在运行时加载这些文件。在这一节中提供的大部分配置示例都使用了配置文件。

java.util.logging

默认的Java日志框架将其配置存储到一个名为 logging.properties 的文件中。在这个文件中,每行是一个配置项,配置项使用点标记(dot notation)的形 式。Java在其安装目录的lib文件夹下面安装了一个全局配置文件,但在启动一个Java程序时,你可以通过指定 java.util.logging.config.file 属性的方式来使用一个单独的日志配置文件,同样也可以在个人项目中创建和存储 logging.properties 文件。

下面的示例描述了如何在全局的logging.properties文件中定义一个Appender:

# default file output is in user's home directory.  java.util.logging.FileHandler.pattern = %h/java%u.log  java.util.logging.FileHandler.limit = 50000  java.util.logging.FileHandler.count = 1  java.util.logging.FileHandler.formatter = java.util.logging.XmlFormatter

Log4j

Log4j版本1使 用的语法和 java.util.logging 的语法很类似。使用了Log4j的程序会在项目目录中寻找一个名为 log4j.properties 的文件。默认情况下,Log4j配置会将所有日志消息输出到控制台上。Log4j同样也支持XML格式的配置文件,对应的配置信息会存储到 log4j.xml 文件中。

Log4j版本2支持XML、JSON和YAML格式的配置,这些配置会分别存储到 log4j2.xml、log4j2.json 和 log4j2.yaml 文件中。和版本1类似,版本2也会在工程目录中寻找这些文件。你可以在每个版本的文档中找到相应的配置文件示例。

Logback

对于Logback来说,大部分配置都是在 logback.xml 文件中完成的,这个文件使用了和Log4j类似的XML语法。Logback同时也支持通过Groovy语言的方式来进行配置,配置信息会存储到 logback.groovy 文件中。你可以通过每种类型配置文件的链接找到对应的配置文件示例。

Loggers

Loggers是用来触发日志事件的对象,在我们的Java应用程序中被创建和调用,然后Loggers才会将事件传递给Appender。一个类 中可以包含针对不同事件的多个独立的Loggers,你也可以在一个Loggers里面内嵌一个Loggers,从而创建一种Loggers层次结构

创建新Logger

在不同的日志框架下面创建新Logger过程大同小异,尽管调用的具体方法名称可能不同。在使用 java.util.logging 时,你可以通过 Logger.getLogger().getLogger() 方法创建新Logger,这个方法接收一个string参数,用于指定Logger的名字。如果指定名字的Logger已经存在,那么只需要返回已经存在 的Logger;否则,程序会创建一个新Logger。通常情况下,一种好的做法是我们在当前类下使用 class.getName() 作为新Logger的名字。

Logger logger = Logger.getLogger(MyClass.class.getName());

记录日志事件

Logger提供了几种方法来触发日志事件。然而 在你记录一个事件之前,你还需要设置级别。日志级别用来确定日志的严重程度,它可以用来过滤日志事件或者将其发送给不同的Appender(想了解更多信息,请参考“日志级别”一节),Logger.log() 方法除了日志消息以外,还需要一个日志级别作为参数:

logger.log(Level.WARNING, “This is a warning!”);

大部分日志框架都针对输出特定级别日志提供了快捷方式。例如,下面语句的作用和上面语句的作用是一样的:

logger.warning(“This is a warning!”);

你还可以阻止Logger输出低于指定日志级别的消息下面的示例中,Logger只能输出 高于WARNING 级别的日志消息,并丢弃日志级别低于WARNING的消息:

logger.setLevel(Level.WARNING);

我们还有另外一些方法可以用来记录额外的信息。logp()(精确日志)可以让你指定每条日志记录的源类(source class)和方法,而 logrb()(使用资源绑定的日志)可以让你指定用于提取日志消息的资源。entering() 和 exiting() 方法可以让你记录方法调用信息,从而追踪程序的执行过程。

Appenders

Appenders将日志消息转发给期望的输出它负责接收日志事件,使用Layout格式化事件,然后将其发送给对应的目标。对于一个日志事件,我们可以使用多个Appenders来将事件发送到不同的目标位置。例如,我们可以在控制台上显示一个简单的日志事件的同时,将其通过邮件的方式发送给指定的接收者。

请注意,在java.util.logging中,Appenders被称作Handlers。

增加Appender

大部分日志框架的Appender都会执行类似的功能,但在实现方面大相径庭。如果使用 java.util.logging,你可以使用 Logger.addHandler() 方法将Appender添加到Logger中。例如,下面的代码添加了一个新的ConsoleHandler,它会将日志输出到控制台:

logger.addHandler(new ConsoleHandler());

一种更常用的添加Appender的方式是使用配置文件如果使用 java.util.logging,Appenders会定义一个以逗号隔开的列表,下面的示例将日志事件输出到控制台和文件:

handlers=java.util.logging.ConsoleHandler, java.util.logging.FileHandler

如果使用基于XML的配置文件,Appenders会被添加到<Appenders>元素下面,如果使用Log4j,我们可以很容易地添加一个新ConsoleAppender来将日志消息发送到System.out:

<Console name="console" target="SYSTEM_OUT">    <PatternLayout pattern="[%p] %t: %m%n" />  </Console>

Appenders类型

这一节描述了一些更通用的Appenders,以及它们在各种日志框架中是如何实现的。

ConsoleAppender

ConsoleAppender是最常用的Appenders之一,它只是将日志消息显示到控制台上。许多日志框架都将其作为默认的Appender,并且在基本的配置中进行预配置。例如,在Log4j中ConsoleAppender的配置参数如下所示。

参数 描述
filter 用于决定是否需要使用该Appender来处理日志事件
layout 用于决定如何对日志记录进行格式化,默认情况下使用“%m%n”,它会在每一行显示一条日志记录
follow 用于决定Appender是否需要了解输出(system.out或者system.err)的变化,默认情况是不需要跟踪这种变化
name 用于设置Appender的名字
ignoreExceptions 用于决定是否需要记录在日志事件处理过程中出现的异常
target 用于指定输出目标位置,默认情况下使用SYSTEM_OUT,但也可以修改成SYSTEM_ERR

一个完整的Log4j2的配置文件如下所示:

<?xml version="1.0" encoding="UTF-8"?>   <Configuration status="warn" name="MyApp">     <Appenders>       <Console name="MyAppender" target="SYSTEM_OUT">         <PatternLayout pattern="%m%n"/>       </Console>     </Appenders>     <Loggers>       <Root level="error">         <AppenderRef ref="MyAppender"/>       </Root>     </Loggers>   </Configuration>

这个配置文件创建了一个名为MyAppender的ConsoleAppender,它使用PatternLayout来对日志事件进行格式化,然 后再将其输出到System.out。<Loggers>元素对定义在程序代码中的Loggers进行了配置。在这里,我们只配置了一个 LoggerConfig,即名为Root的Logger,它会接收哪些日志级别在ERROR以上的日志消息。如果我们使用logger.error() 来记录一个消息,那么它就会出现在控制台上,就像这样:

An unexpected error occurred.

你也可以使用Logback实现完全一样的效果:

<configuration>    <appender name="MyAppender" class="ch.qos.Logback.core.ConsoleAppender">      <encoder>        <pattern>%m%n</pattern>      </encoder>    </appender>    <root level="error">      <appender-ref ref="MyAppender" />    </root>  </configuration>

FileAppenders

FileAppenders将日志记录写入到文件中,它负责打开、关闭文件,向文件中追加日志记录,并对文件进行加锁,以免数据被破坏或者覆盖

在Log4j中,如果想创建一个FileAppender,需要指定目标文件的名字,写入方式是追加还是覆盖,以及是否需要在写入日志时对文件进行加锁:

...  <Appenders>    <File name="MyFileAppender" fileName="myLog.log" append="true" locking="true">      <PatternLayout pattern="%m%n"/>    </File>  </Appenders>  ...

这样我们创建了一个名为MyFileAppender的FileAppender,并且在向文件中追加日志时会对文件进行加锁操作。

如果使用Logback,你可以同时启用prudent模式来保证文件的完整性。虽然Prudent模式增加了写入文件所花费的时间,但它可以保证在多个FileAppender甚至多个Java程序向同一个文件写入日志时,文件的完整性。

...  <appender name="FileAppender" class="ch.qos.Logback.core.FileAppender">    <file>myLog.log</file>    <append>true</append>    <prudent>true</prudent>    <encoder>      <pattern>%m%n</pattern>    </encoder>  </appender>  ...

SyslogAppender

SyslogAppenders将日志记录发送给本地或者远程系统的日志服务。syslog是一个接收日志事件服务,这些日志事件来自操作系统、进程、其它服务或者其它设备。事件的范围可以从诊断信息到用户登录硬件失败等。syslog的事件按照设备进行分类,它指定了正在记录的事件的类型。例如,auth facility表明这个事件是和安全以及认证有关。

Log4j和Logback都内置支持SyslogAppenders在Log4j中,我们创建SyslogAppender时,需要指定syslog服务监听的主机号、端口号以及协议。下面的示例演示了如何设定装置

...  <Appenders>    <Syslog name="SyslogAppender" host="localhost" port="514" protocol="UDP" facility="Auth" />  </Appenders>  ...

在Logback中,我们可以实现同样的效果:

...  <appender name="SyslogAppender" class="ch.qos.Logback.classic.net.SyslogAppender">    <syslogHost>localhost</syslogHost>    <port>514</port>    <facility>Auth</facility>  </appender>  ...

其它Appender

我们已经介绍了一些经常用到的Appenders,还有很多其它Appender它们添加了新功能或者在其它的 一些Appender基础上实现了新功能。例如,Log4j中的RollingFileAppender扩展了FileAppender,它可以在满足特 定条件时自动创建新的日志文件;SMTPAppender会将日志内容以邮件的形式发送出去;FailoverAppender会在处理日志的过程中,如 果一个或者多个Appender失败,自动切换到其他Appender上。

如果想了解更多关于其他Appender的信息,可以查看Log4j Appender参考以及Logback Appender参考

Layouts

Layouts将日志记录的内容从一种数据形式转换成另外一种。日志框架为纯文本、HTML、syslog、XML、JSON、序列化以及其它日志提供了Layouts。

请注意在java.util.logging中Layouts也被称为Formatters。

例如,java.util.logging提供了两种Layouts:SimpleFormatter和XMLFormatter。默认情况下,ConsoleHandlers使用SimpleFormatter,它输出的纯文本日志记录就像这样:

Mar 31, 2015 10:47:51 AM MyClass main  SEVERE: An exception occurred.

而默认情况下,FileHandlers使用XMLFormatter,它的输出就像这样:

<?xml version="1.0" encoding="UTF-8" standalone="no"?>  <!DOCTYPE log SYSTEM "logger.dtd">  <log>  <record>    <date>2015-03-31T10:47:51</date>    <millis>1427903275893</millis>    <sequence>0</sequence>    <logger>MyClass</logger>    <level>SEVERE</level>    <class>MyClass</class>    <method>main</method>    <thread>1</thread>    <message>An exception occurred.</message>  </record>  </log>

配置Layout

我们通常使用配置文件对Layouts进行配置从Java 7开始,我们也可以使用system property来配置SimpleFormatter。

例如,在Log4j和Logback中最常用的Layouts是PatternLayout。它可以让你决定日志事件中的哪些部分需要输出,这是通过转换模式(Conversion Pattern)完成的,转换模式在每一条日志事件的数据中扮演了“占位符”的角色。例如,Log4j默认的PatternLayout使用了如下转换模式:

<PatternLayout pattern="%d{HH:mm:ss.SSS} [%t] %-5level %logger{36} - %msg%n"/>

%d{HH:mm:ss.SSS} 将日期转换成时、分、秒和毫秒的形式,%level显示日志事件的严重程度,%C显示生成日志事件的类的名字,%t显示Logger的当前线程,%m显示时间的消息,最后,%n为下一个日志事件进行了换行。

改变Layouts

如果在java.util.logging中使用一个不同的Layout,需要将Appender的formatter属性设置成你想要的Layout在 代码中,你可以创建一个新的Handler,调用setFormatter方法,然后通过logger.AddHandler()方法将Handler放 到Logger上面。下面的示例创建了一个ConsoleAppender,它使用XMLFormatter来对日志进行格式化,而不是使用默认的 SimpleFormatter:

Handler ch = new ConsoleHandler();  ch.setFormatter(new XMLFormatter());  logger.addHandler(ch);

这样Logger会将 下面的信息输出到控制台上:

<?xml version="1.0" encoding="UTF-8" standalone="no"?>  <!DOCTYPE log SYSTEM "logger.dtd">  <log>  <record>    <date>2015-03-31T10:47:51</date>    <millis>1427813271000</millis>    <sequence>0</sequence>    <logger>MyClass</logger>    <level>SEVERE</level>    <class>MyClass</class>    <method>main</method>    <thread>1</thread>    <message>An exception occurred.</message>  </record>

如果想了解更多信息,你可以查看Log4j Layouts参考以及Logback Layouts参考

使用自定义Layouts

自定义Layouts可以让你指定Appender应该如何输出日志记录。从Java SE 7开始,尽管你可以调整SimpleLogger的输出,但有一个限制,即只能够调整简单的纯文本消息。对于更高级的格式,例如HTML或者JSON,你需要一个自定义Layout或者一个单独的框架。

如果想了解更多使用java.util.logging创建自定义Layouts的信息,你可以查看Jakob Jenkov的Java日志指南中的Java Logging: Formatters章节

日志级别

日志级别提供了一种方式,我们可以用它来根据严重程度对日志进行分类和识别。java.util.logging 按照严重程度从重到轻,提供了以下级别:

  • SEVERE(最高级别)
  • WARNING
  • INFO
  • CONFIG
  • FINE
  • FINER
  • FINEST(最低级别)

另外, 还有两个日志级别:ALL和OFF。ALL会让Logger输出所有消息,而OFF则会关闭日志功能。

设置日志级别

在设定日志级别后,Logger会自动忽略那些低于设定级别的日志消息。例如,下面的语句会让Logger忽略那些低于WARNING级别的日志消息:

logger.setLevel(Level.WARNING);

然后,Logger会记录任何WARNING或者更高级别的日志消息。我们也可以在配置文件中设置Logger的日志级别:

...  <Loggers>    <Logger name="MyLogger" level="warning">    ...

转换模式

Log4j和Logback中的PatternLayout类都支持转换模式,它决定了我们如何从每一条日志事件中提取信息以及如何对信息进行格式 化。下面显示了这些模式的一个子集,对于Log4j和Logback来说,虽然这些特定的字段都是一样的,但是并不是所有的字段都会使用相同的模式。想要 了解更多信息,可以查看Log4jLogback的PatternLayout文档。

字段名称 Log4j/Logback 模式
消息 %m
级别/严重程度 %p
异常 %ex
线程 %t
Logger %c
方法 %M

例如,下面的PatternLayout会在中括号内x显示日志级别,后面是线程名字和日志事件的消息:

[%p] %t: %m

下面是使用了上述转换模式后的日志输出示例:

[INFO] main: initializing worker threads  [DEBUG] worker: listening on port 12222[INFO] worker: received request from 192.168.1.200[ERROR] worker: unknown request ID from 192.168.1.200

记录栈跟踪信息

如果你在Java程序中使用过异常,那么很有可能已经看到过栈跟踪信息它提供了一个程序中方法调用的快照,让你准确定位程序执行的位置。例如,下面的栈跟踪信息是程序试图打开一个不存在的文件后生成的:

[ERROR] main: Unable to open file! java.io.FileNotFoundException: foo.file (No such file or directory)    at java.io.FileInputStream.open(Native Method) ~[?:1.7.0_79]    at java.io.FileInputStream.<init>(FileInputStream.java:146) ~[?:1.7.0_79]    at java.io.FileInputStream.<init>(FileInputStream.java:101) ~[?:1.7.0_79]    at java.io.FileReader.<init>(FileReader.java:58) ~[?:1.7.0_79]    at FooClass.main(FooClass.java:47)

这个示例使用了一个名为FooClass的类,它包含一个main方法在程序第47行,FileReader独享试图打开一个名为foo.file的文件,由于在程序目录下没有名字是foo.file的文件,因此Java虚拟机抛出了一个FileNotFoundException。因为这个方法调用被放到了try-catch语块中,所以我们能够捕获这个异常并记录它,或者至少可以阻止程序崩溃。

使用PatternLayout记录栈跟踪信息

在写本篇文章时最新版本的Log4j和Logback中,如果在Layout中没有和可抛异常相关的信息,那么都会自动将%xEx(这种栈跟踪信息包含了每次方法调用的包信息)添加到PatternLayout中。如果对于普通的日志信息的模式如下:

[%p] %t: %m

它会变为:

[%p] %t: %m%xEx

这样不仅仅错误信息会被记录下来,完整的栈跟踪信息也会被记录:

[ERROR] main: Unable to open file! java.io.FileNotFoundException: foo.file (No such file or directory)    at java.io.FileInputStream.open(Native Method) ~[?:1.7.0_79]    at java.io.FileInputStream.<init>(FileInputStream.java:146) ~[?:1.7.0_79]    at java.io.FileInputStream.<init>(FileInputStream.java:101) ~[?:1.7.0_79]    at java.io.FileReader.<init>(FileReader.java:58) ~[?:1.7.0_79]    at FooClass.main(FooClass.java:47)

%xEx中的包查询是一个代价昂贵的操作,如果你频繁的记录异常信息,那么可能会碰到性能问题,例如:

  // ...    } catch (FileNotFoundException ex) {      logger.error(“Unable to open file!”, ex);  }

一种解决方法是在模式中显式的包含%ex,这样就只会请求异常的栈跟踪信息:

[%p] %t: %m%ex

另外一种方法是通过追加%xEx(none)的方法排除(在Log4j)中所有的异常信息:

[%p] %t: %m%xEx{none}

或者在Logback中使用%nopex:

[%p] %t: %m%nopex

使用结构化布局输出栈跟踪信息

如你在“解析多行栈跟踪信息”一节中所见,对于站跟踪信息来说,使用结构化布局来记录是最合适的方式,例如JSON和XML。 这些布局会自动将栈跟踪信息按照核心组件进行分解,这样我们可以很容易将其导出到其他程序或者日志服务中。对于上述站跟踪信息,如果使用JSON格式,部分信息显示如下:

...  "loggerName" : "FooClass",    "message" : "Foo, oh no! ",    "thrown" : {      "commonElementCount" : 0,      "localizedMessage" : "foo.file (No such file or directory)",      "message" : "foo.file (No such file or directory)",      "name" : "java.io.FileNotFoundException",      "extendedStackTrace" : [ {      "class" : "java.io.FileInputStream",      "method" : "open",      "file" : "FileInputStream.java",      ...

记录未捕获异常

通常情况下,我们通过捕获的方式来处理异常如果一个异常没有被捕获,那么它可能会导致程序终止。如果能够留存任何日志,那么这是一个可以帮助我们调试为什么会发生异常的好办法,这样你就可以找到发生异常的根本原因并解决它。下面来说明我们如何建立一个默认的异常处理器来记录这些错误。

Thread类中有两个方法,我们可以用它来为未捕获的异常指定一个ExceptionHandler:

setDefaultUncaughtExceptionHandler 可以让你在任何线程上处理任何异常setUncaughtExceptionHandler可以让你针对一个指定的线程设定一个不同的处理方法。而ThreadGroup则允许你设定一个处理方法。大部分人会使用默认的异常处理方法。

下面是一个示例,它设定了一个默认的异常处理方法,来创建一个日志事件。它要求你传入一个UncaughtExceptionHandler:

import java.util.logging.*;  public class ExceptionDemo {    private static final Logger logger = Logger.getLogger(ExceptionDemo.class);    public static void main(String[] args) {      Thread.setDefaultUncaughtExceptionHandler(new Thread.UncaughtExceptionHandler() {        public void uncaughtException(Thread t, Throwable e) {          logger.log(Level.SEVERE, t + " ExceptionDemo threw an exception: ", e);        };    });    class adminThread implements Runnable {      public void run() {        throw new RuntimeException();      }    }    Thread t = new Thread(new adminThread());    t.start();    }  }

下面是一个未处理异常的输出示例:

May 29, 2015 2:21:15 PM ExceptionDemo$1 uncaughtException  SEVERE: Thread[Thread-1,5,main] ExceptionDemo threw an exception:  java.lang.RuntimeException    at ExceptionDemo$1adminThread.run(ExceptionDemo.java:15)    at java.lang.Thread.run(Thread.java:745)

JSON

JSON(JavaScript Object Notation)是一种用来存储结构化数据的格式,它将数据存储成键值对的集合,类似于HashMap或者Hashtable。JSON具有的可移植性和通用性,大部分现代语言都内置支持它或者通过已经准备好的第三方类库来支持它。

JSON支持许多基本数据类型,包括字符串、数字、布尔、数组和null。例如,你可以使用下面的JSON格式来表示一个电脑:

{    "manufacturer": "Dell",    "model": "Inspiron",    "hardware": {      "cpu": "Intel Core i7",      "ram": 16384,      “cdrom”: null    },    "peripherals": [      {        "type": "monitor",        "manufacturer": "Acer",        "model": "S231HL"      }    ]  }

JSON的可移植性使得它非常适合存储日志记录,使用JSON后,Java日志可以被任何数目的JSON解释器所读取。因为数据已经是结构化的,所以解析JSON日志要远比解析纯文本日志容易。

Java中的JSON

对于Java来说,有大量的JSON实现,其中一个是JSON.simpleJSON.simple是轻量级的、易于使用,并且全部符合JSON标准。

如果想将上面的computer对象转换成可用的Java对象,我们可以从文件中读取JSON内容,将其传递给JSON.simple,然后返回一个Object,接着我们可以将Object转换成JSONObject:

Object computer = JSONValue.parse(new FileReader("computer.json"));  JSONObject computerJSON = (JSONObject)computer;

另外,为了取得键值对的信息,你可以使用任何日志框架来记录一个JSONObject,JSONObject对象包含一个toString()方法, 它可以将JSON转换成文本:

2015-05-06 14:54:32,878 INFO  JSONTest main {"peripherals":[{"model":"S231HL","manufacturer":"Acer","type":"monitor"}],"model":"Inspiron","hardware":{"cdrom":null,"ram":16384,"cpu":"Intel Core i7"},"manufacturer":"Dell"}

虽然这样做可以很容易的打印JSONObject,但如果你使用结构化的Layouts,例如JSONLayout或者XMLLayout,可能会导致意想不到的结果:

...  "message" : "{"peripherals":[{"model":"S231HL","manufacturer":"Acer","type":"monitor"}],"model":"Inspiron","hardware":{"cdrom":null,"ram":16384,"cpu":"Intel Core i7"},"manufacturer":"Dell"}",  ...

Log4j中的JSONLayout并没有内置支持内嵌JSON对象,但你可以通过创建自定义Layout的方式来添加一个JSONObject字段,这个Layout会继承或者替换JSONLayout然 而,如果你使用一个日志管理系统,需要记住许多日志管理系统会针对某些字段使用预定义的数据类型。如果你创建一个Layout并将JSONObject存 储到message字段中,那么它可能会和日志系统中使用的String数据类型相冲突。一种解决办法是将JSON数据存储到一个字段中,然后将字符串类 型的日志消息存储到另外一个字段中。

其它JSON库

除了JSON.simple,Java中还有很多其它JSON库。JSON-java是由JSON创建者开发的一个参考实现,它包含了额外的一些功能,可以转换其它数据类型,包括web元素。但是目前JSON-java已经没有人来维护和提供支持了。

如果想将JSON对象转换成Java对象或者逆向转换,Google提供了一个Gson库使 用Gson时,可以很简单使用 toJson() 和 fromJson() 方法来解析JSON,这两个方法分别用来将Java对象转换成JSON字符串以及将JSON字符串转换成Java对象。Gson甚至可以应用在内存对象 中,允许你映射到那些没有源代码的对象上。

Jackson

Jackson是一个强大的、流行的、功能丰富的库,它可以在Java中管理JSON对象。有一些框架甚至使用Jackson作为它们的JSONLayouts。尽管它很大并且复杂,但Jackson对于初学者和高级用户来说,是很容易使用的。

Logback通过logback-jackson和logback-json-classic库继承了Jackson,这两个库也是logback-contrib项目的一部分。在集成了Jackson后,你可以将日志以JSON的格式导出到任何Appender中。

Logback Wiki详细解释了如何将JSON添加到logback中,在Wiki页面中的示例使用了LogglyAppender,这里的配置也可以应用到其他Appender上。下面的示例说明了如何将JSON格式化的日志记录写入到名为myLog.json的文件中:

...  <appender name="file" class="ch.qos.Logback.core.FileAppender">    <file>myLog.json</file>    <encoder class="ch.qos.Logback.core.encoder.LayoutWrappingEncoder">    <layout class="ch.qos.Logback.contrib.json.classic.JsonLayout">      <jsonFormatter class="ch.qos.Logback.contrib.jackson.JacksonJsonFormatter"/>      </layout>    </encoder>  </appender>   ...

你也可以通过FasterXML Wiki找到更多关于Jackson的深度介绍。

了解更多JSON相关信息

你可以通过JSON主页学习更多JSON相关信息,或者通过CodeAcademy来通过学习一个交互式的快速上手教程(请注意这个课程是基于JavaScript的,而不是Java)有一些在线工具例如JSONLintJSON在线编辑器可以帮助你解析、验证以及格式化JSON代码。

NDCMDC以及ThreadContext

当处理多线程应用程序,特别是web服务时,跟踪事件可能会变得困难。当针对多个同时存在的多个用户生成日志记录时,你如何区分哪个行为和哪个日志 事件有关呢?如何两个用户没有成功打开一个相同的文件,或者在同一时间没有成功登陆,那么怎么处理日志记录?你可能需要一种方式来将日志记录和程序中的唯 一标示符关联起来,这些标识符可能是用户ID,会话ID或者设备ID。而这就是NDC、MDC以及ThreadContext的用武之地。

NDC、MDC和ThreadContext通过向单独的日志记录中添加独一无二的数据戳,来创建日志足迹(log trails)。这些数据戳也被称为鱼标记(fish tagging),我们可以通过一个或者多个独一无二的值来区分日志。这些数据戳在每个线程级别上进行管理,并且一直持续到线程结束,或者直到数据戳被删 掉。例如,如果你的Web应用程序为每个用户生成一个新的线程,那么你可以使用这个用户的ID来标记日志记录。当你想在一个复杂的系统中跟踪特定的请求、 事务或者用户,这是一种非常有用的方法。

嵌套诊断上下文(NDC)

NDC或者嵌套诊断上下文(Nested Diagnostic Context)是基于栈的思想,信息可以被放到栈上或者从栈中移除。而栈中的值可以被Logger访问,并且Logger无需显示想日志方法中传入任何值。

下面的代码示例使用NDC和Log4j来将用户姓名和一条日志记录关联起来。NDC是一个静态类,因此我们可以直接访问它的方法,而无需实例化一个 NDC对象。在这个示例中, NDC.oush(username) 和 NDC.push(sessionID) 方法在栈中存储了当前的用户名(admin)和会话ID(1234),而NDC.pop()方法将一些项从栈中移除,NDC.remove()方法让 Java回收内存,以免造成内存溢出。

Java日志终极指南

import java.io.FileReader;  import org.apache.Log4j.Logger;  import org.apache.Log4j.NDC;  ...  String username = "admin";  String sessionID = "1234";  NDC.push(username);  NDC.push(sessionID);  try {    // tmpFile doesn't exist, causing an exception.    FileReader fr = new FileReader("tmpFile");  }  catch (Exception ex) {    logger.error("Unable to open file.");  }  finally {    NDC.pop();    NDC.pop();    NDC.remove();  }

Log4j的PatternLayout类通过%x转换字符从NDC中提取如果一个日志事件被触发,那么完整的NDC栈就被传到Log4j:

<PatternLayout pattern="%x %-5p - %m%n" />

运行程序后,我们可以得出下面的输出:

"admin 1234 ERROR – Unable to open file."

映射诊断上下文(MDC)

MDC或者映射诊断上下文和NDC很相似,不同之处在于MDC将值存储在键值对中,而不是栈中。这样你可以很容易的在Layout中引用一个单独的 键。MDC.put(key,value) 方法将一个新的键值对添加到上下文中,而 MDC.remove(key) 方法会移除指定的键值对。

如果想在日志中同样显示用户名和会话ID,我们需要使用 MDC.put() 方法将这两个变量存储成键值对:

import java.io.FileReader;  import org.apache.Log4j.Logger;  import org.apache.Log4j.MDC;  ...  MDC.put("username", "admin");  MDC.put("sessionID", "1234");  try {    // tmpFile doesn't exist, causing an exception.    FileReader fr = new FileReader("tmpFile");  }  catch (Exception ex) {    logger.error("Unable to open file!");  }  finally {    MDC.clear();  }

这里再一次强调,在不需要使用Context后,我们需要使用 MDC.clear() 方法将所有的键值对从MDC中移除,这样会降低内存的使用量,并阻止MDC在后面试图调用那些已经过期的数据。

在日志框架中访问MDC的值时,也稍微有些区别。对于存储在上下文中的任何键,我们可以使用%X(键)的方式来访问对应的值。这样,我们可以使用 %X(username) 和 %X(sessionID) 来获取对应的用户名和会话ID:

<PatternLayout pattern="%X{username} %X{sessionID} %-5p - %m%n" />  "admin 1234 ERROR – Unable to open file!"

如果我们没有指定任何键,那么MDC上下文就会被以 {(key, value),(key, value)} 的方式传递给Appender。

Logback中的NDC和MDC

和Log4j不同,Logback内置没有实现NDC。但是slf4j-ext包提供了一个NDC实现,它使用MDC作为基础。在Logback内部,你可以使用 MDC.put()MDC.remove() 和 MDC.clear() 方法来访问和管理MDC:

import org.slf4j.MDC;  ...  Logger logger = LoggerFactory.getLogger(MDCLogback.class);  ...  MDC.put("username", "admin");  MDC.put("sessionID", "1234");  try {    FileReader fr = new FileReader("tmpFile");  }  catch (Exception ex) {    logger.error("Unable to open file.");  }  finally {    MDC.clear();  }

在Logback中,你可以在Logback.xml中将如下模式应用到Appender上,它可以输出和上面Log4j相同的结果:

<Pattern>[%X{username}] %X{sessionID} %-5p - %m%n</Pattern>  "[admin] 1234 ERROR - Unable to open file."

针对MDC的访问并不仅仅限制在PatternLayout上,例如,当使用JSONFormatter时,MDC中的所有值都会被导出:

{  "timestamp":"1431970324945",  "level":"ERROR",  "thread":"main",  "mdc":{  "username":"admin",  "sessionID":"1234"  },  "logger":"MyClass",  "message":"Unable to open file.",  "context":"default"  }

ThreadContext

Version 2 of Log4j merged MDC and NDC into a single concept known as the Thread Context. The Thread Context is an evolution of MDC and NDC, presenting them respectively as the Thread Context Map and Thread Context Stack. The Thread Context is managed through the static ThreadContext class, which is implemented similar to Log4j 1’s MDC and NDC classes.

Log4j版本2中将MDC和NDC合并到一个单独的组件中,这个组件被称为线程上下文。线程上下文是针对MDC和NDC的进化,它分别用线程上下文Map映射线程上下文栈来表示MDC和NDC。我们可以通过ThreadContext静态类来管理线程上下文,这个类在实现上类似于Log4j版本1中的MDC和NDC。

When using the Thread Context Stack, data is pushed to and popped from a stack just like with NDC:

当使用线程上下文栈时,我们可以向NDC那样向栈中添加或者删除数据:

import org.apache.logging.Log4j.ThreadContext;  ...  ThreadContext.push(username);  ThreadContext.push(sessionID);  // Logging methods go here  ThreadContext.pop();  ...

当使用线程上下文映射时,我们可以像MDC那样将值和键结合在一起:

import org.apache.logging.Log4j.ThreadContext;  ...  ThreadContext.put(“username”,"admin");  ThreadContext.put("sessionID", "1234");  // Logging methods go here  ThreadContext.clearMap();  ...

ThreadContext类提供了一些方法,用于清除栈、清除MDC、清除存储在上下文中的所有值,对应的方法是ThreadContext.clearAll()ThreadContext.clearMap()和ThreadContext.clearStack()。

和在MDC以及NDC中一样,我们可以使用Layouts在线程上下文中访问这些值。使用PatternLayout时,%x转换模式会从栈中获取值,%X和%X(键)会从图中获取值。

ThreadContext过滤

一些框架允许你基于某些属性对日志进行过滤例如,Log4j的DynamicThresholdFilter 会在键满足特定条件的情况下,自动调整日志级别。再比如,如果我们想要触发TRACE级别的日志消息,我们可以创建一个名为trace-logging-enabled的键,并向log4j配置文件中添加一个过滤器:

<Configuration name="MyApp">  <DynamicThresholdFilter key="trace-logging-enabled" onMatch="ACCEPT" onMismatch="NEUTRAL">  <KeyValuePair key="true" value="TRACE" />  </DynamicThresholdFilter>  ...

如果ThreadContext包含一个名为trace-logging-enabled的键,onMatch 和 onMismatch 会决定如何处理它。关于 onMatch 和 onMismatch,我们有三个可选项:ACCEPT,它会处理过滤器的规则;DENY,它会忽略过滤器的规则;NEUTRAL,它会推迟到下一个过滤 器。除了这些,我们还定义一个键值对,当值为true时,我们启用TRACE级别的日志。

现在,当trace-logging-enabled被设置成true时,即使根Logger设置的日志级别高于TRACE,Appender也会记录TRACE级别的消息。

你可能还想过滤一些特定的日志到特定的Appender中,Log4j中提供了ThreadContextMapFilter来实现这一点。如果我们想要限制某个特定的Appender只记录针对某个用户的TRACE级别的消息,我们可以基于username键添加一个ThreadContextMapFilter:

<Console name="ConsoleAppender" target="SYSTEM_OUT">  <ThreadContextMapFilter onMatch="ACCEPT" onMismatch="DENY">  <KeyValuePair key="username" value="admin" />  </ThreadContextMapFilter>  ...

如果想了解更多信息,你可以查看Log4jLogback文档中关于DynamicThresholdFilter部分。

Markers

Markers允许你对单独的日志记录添加一些独一无二的数据它可以用来对日志记录进行分组,触发一些行为或者对日志记录进行过滤并将过滤结果输出到指定的Appender中。你甚至可以将Markers和ThreadContext结合在一起使用,以提高搜索和过滤日志数据的能力。

例如,假设我们有一个可以连接到数据库的类,如果在打开数据库的时候发生了异常,我们需要把异常记录成fatal错误。我们可以创建一个名为DB_ERROR的Marker,然后将其应用到日志事件中:

import org.apache.logging.Log4j.Marker;  import org.apache.logging.Log4j.MarkerManager;  ...  final static Marker DB_ERROR = MarkerManager.getMarker("DATABASE_ERROR");  ...  logger.fatal(DB_ERROR, "An exception occurred.");

为了在日志输出中显示Marker信息,我们需要在PatternLayout中添加%marker转换模式:

<PatternLayout pattern="%p %marker: %m%n" />  [FATAL] DATABASE_ERROR: An exception occurred.

或者对于JSON和XML格式的Layouts,会自动在输出中包含Marker信息:

...  "thread" : "main",  "level" : "FATAL",  "loggerName" : "DBClass",  "marker" : {    "name" : "DATABASE_ERROR"  },  "message" : "An exception occurred.",  ...

通过对Marker数据进行自动解析和排序,集中式的日志服务可以很容易对日志进行搜索处理。

Markers过滤

Marker过滤器可以让你决定哪些Marker由哪些Logger来处理。marker字段会比较在日志事件里面的Marker名字,如果名字匹配,那么Logger会执行后续的行为。例如,在Log4j中,我们可以配置一个Appender来只显示哪些使用了DB_ERROR Marker的消息,这可以通过log4j2.xml中的Appender添加如下信息来实现:

<MarkerFilter marker="DATABASE_ERROR" onMatch="ACCEPT" onMismatch="DENY" />

如果日志记录中某一条的Marker可以匹配这里的marker字段,那么onMatch会决定如何处理这条记录。如果不能够匹配,或者日志记录中 没有Marker信息,那么onMismatch就会决定如何处理这条记录。对于onMatch和onMismatch来说,有3个可选 项:ACCEPT,它允许记录事件;DENY,它会阻塞事件;NEUTRAL,它不会对事件进行任何处理。

在Logback中,我们需要更多一些设置。首先,想Appender中添加一个新的EvaluatorFilter,并如上所述指定onMatch和onMismatch行为然后,添加一个OnMarkerEvaluator并将Marker的名字传递给它:

<filter class="ch.qos.Logback.core.filter.EvaluatorFilter">    <evaluator class="ch.qos.Logback.classic.boolex.OnMarkerEvaluator">      <marker>DATABASE_ERROR</marker>    </evaluator>    <onMatch>ACCEPT</onMatch>    <onMismatch>DENY</onMismatch>  </filter>

将Markers和NDCMDC以及ThreadContext结合使用

Marker的功能和ThreadContext类似,它们都是向日志记录中添加独一无二的数据,这些数据可以被Appender访问。如果把这两者结合使用,可以让你更容易的对日志数据进行索引和搜索。如果能够知道何时使用哪一种技术,会对我们有所帮助。

NDCMDC和ThreadContext被用于将相关日志记录结 合在一起。如果你的应用程序会处理多个同时存在的用户,ThreadContext可以让你将针对某个特定用户的一组日志记录组合在一起。因为 ThreadContext针对每个线程都是不一样的,所以你可以使用同样的方法来对相关的日志记录进行自动分组。

另一方面,Marker通常用于标记或者高亮显示某些特殊事件。在上述示例中,我们使用DB_ERROR Marker来标明在方法中发生的SQL相关异常。我们可以使用DB_ERROR Marker来将这些事件的处理过程和其他事件区分开来,例如我们可以使用SMTP Appender来将这些事件通过邮件发送给数据库管理员。

额外资源

指南和教程

  • Java Logging(Jakob Jenkov)——使用Java Logging API进行日志开发教程
  • Java Logging Overview(Oracle)—— Oracle提供的在Java中进行日志开发的指南
  • Log4J Tutorial(Tutorials Point)——使用log4j 版本1进行日志开发的指南

日志抽象层

  • Apache Commons Logging(Apache)——针对Log4jAvalon LogKit和java.util.logging的抽象层
  • SLF4J(QOS.ch)——一个流程的抽象层,应用在多个日志框架上,包括Log4jLogback以及java.util.logging

日志框架

  • Java Logging API(Oracle)—— Java默认的日志框架
  • Log4j(Apache)——开源日志框架
  • Logback(Logback Project)——开源项目,被设计成Log4j版本1的后续版本
  • tinylog(tinylog)——轻量级开源logger
来源: ImportNew