[LOG4J2-1165] Improve Log4j initialization status messages

• Type: Improvement
• Status: Resolved
• Priority: Major
• Resolution: Fixed
• Affects Version/s: 2.4.1
• Fix Version/s: 2.5
• Component/s: Configurators
• Assignee: Remko Popma
• Reporter: Remko Popma
• Created: 18/Oct/15 08:49
• Updated: 18/Oct/15 15:12
• Resolved: 18/Oct/15 10:43

Description

The status messages produced when Log4j initializes are too verbose. Many can be omitted without loss of information and while still providing enough help during troubleshooting.

For example, as of 2.4.1, the startup log for this configuration

<?xml version="1.0" encoding="UTF-8"?>
<Configuration status="trace" monitorInterval="5">
  <Appenders>
    <Console name="Console" target="SYSTEM_OUT">
      <PatternLayout pattern="%-5p %d{ABSOLUTE} [%t] %c - %m%n" />
    </Console>
    <File name="appFile" fileName="logs/appFile.log" bufferSize="32768" immediateFlush="false" append="false">
      <PatternLayout pattern="%-5p %d{ABSOLUTE} [%t] %c - %m%n" />
    </File>
    <File name="errorFile" fileName="logs/errorFile.log" bufferSize="32768" immediateFlush="false" append="false">
      <PatternLayout pattern="%-5p %d{ABSOLUTE} [%t] %c - %m%n" />
    </File>
  </Appenders>
  <Loggers>
    <Root level="trace">
      <AppenderRef ref="Console" level="error" />
      <AppenderRef ref="errorFile" level="error" />
      <AppenderRef ref="appFile" level="debug" />
    </Root>
  </Loggers>
</Configuration>

looks like this:

2015-10-18 13:04:34,939 main DEBUG Initializing configuration XmlConfiguration[location=C:\Users\remko\workspace\log4j-issues\bin\log4j2.xml]
2015-10-18 13:04:34,954 main DEBUG Installed script engines
2015-10-18 13:04:35,579 main DEBUG Oracle Nashorn Version: 1.8.0_51, Language: ECMAScript, Threading: Not Thread Safe, Compile: true, Names: {nashorn, Nashorn, js, JS, JavaScript, javascript, ECMAScript, ecmascript}
2015-10-18 13:04:35,579 main DEBUG PluginManager 'Core' found 92 plugins
2015-10-18 13:04:35,579 main DEBUG PluginManager 'Level' found 1 plugins
2015-10-18 13:04:39,730 main DEBUG PluginManager 'Lookup' found 13 plugins
2015-10-18 13:04:39,741 main DEBUG Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout]. Searching for builder factory method...
2015-10-18 13:04:39,757 main TRACE Found builder factory method [newBuilder]: public static org.apache.logging.log4j.core.layout.PatternLayout$Builder org.apache.logging.log4j.core.layout.PatternLayout.newBuilder().
2015-10-18 13:04:39,788 main TRACE TypeConverterRegistry initializing.
2015-10-18 13:04:39,788 main DEBUG PluginManager 'TypeConverter' found 21 plugins
2015-10-18 13:04:39,819 main DEBUG Calling build() on class class org.apache.logging.log4j.core.layout.PatternLayout$Builder for element PatternLayout with params(pattern="%-5p %d{ABSOLUTE} [%t] %c - %m%n", null, Configuration(C:\Users\remko\workspace\log4j-issues\bin\log4j2.xml), null, charset="null", alwaysWriteExceptions="null", noConsoleNoAnsi="null", header="null", footer="null")
2015-10-18 13:04:39,819 main DEBUG PluginManager 'Converter' found 38 plugins
2015-10-18 13:04:39,819 main DEBUG Built Plugin[name=layout] OK from builder factory method.
2015-10-18 13:04:39,819 main DEBUG Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.ConsoleAppender]. Searching for builder factory method...
2015-10-18 13:04:39,819 main DEBUG Not in a ServletContext environment, thus not loading WebLookup plugin.
2015-10-18 13:04:39,819 main DEBUG PluginManager 'Converter' found 38 plugins
2015-10-18 13:04:39,819 main DEBUG Jansi is not installed, cannot find org.fusesource.jansi.WindowsAnsiOutputStream
2015-10-18 13:04:39,819 main TRACE Found builder factory method [newBuilder]: public static org.apache.logging.log4j.core.appender.ConsoleAppender$Builder org.apache.logging.log4j.core.appender.ConsoleAppender.newBuilder().
2015-10-18 13:04:39,835 main DEBUG No PluginVisitorStrategy found on annotation [interface org.apache.logging.log4j.core.config.plugins.validation.constraints.Required]. Ignoring.
2015-10-18 13:04:39,835 main DEBUG Encountered type [org.apache.logging.log4j.core.layout.PatternLayout] which can only be checked for null.
2015-10-18 13:04:39,835 main DEBUG No PluginVisitorStrategy found on annotation [interface org.apache.logging.log4j.core.config.plugins.validation.constraints.Required]. Ignoring.
2015-10-18 13:04:39,835 main DEBUG Encountered type [org.apache.logging.log4j.core.appender.ConsoleAppender$Target] which can only be checked for null.
2015-10-18 13:04:39,835 main DEBUG No PluginVisitorStrategy found on annotation [interface org.apache.logging.log4j.core.config.plugins.validation.constraints.Required]. Ignoring.
2015-10-18 13:04:39,835 main DEBUG Calling build() on class class org.apache.logging.log4j.core.appender.ConsoleAppender$Builder for element Console with params(PatternLayout(%-5p %d{ABSOLUTE} [%t] %c - %m%n), null, target="SYSTEM_OUT", name="Console", follow="null", ignoreExceptions="null")
2015-10-18 13:04:39,835 main DEBUG Jansi is not installed, cannot find org.fusesource.jansi.WindowsAnsiOutputStream
2015-10-18 13:04:39,850 main DEBUG Built Plugin[name=appender] OK from builder factory method.
2015-10-18 13:04:39,850 main DEBUG Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout]. Searching for builder factory method...
2015-10-18 13:04:39,850 main TRACE Found builder factory method [newBuilder]: public static org.apache.logging.log4j.core.layout.PatternLayout$Builder org.apache.logging.log4j.core.layout.PatternLayout.newBuilder().
2015-10-18 13:04:39,850 main DEBUG Calling build() on class class org.apache.logging.log4j.core.layout.PatternLayout$Builder for element PatternLayout with params(pattern="%-5p %d{ABSOLUTE} [%t] %c - %m%n", null, Configuration(C:\Users\remko\workspace\log4j-issues\bin\log4j2.xml), null, charset="null", alwaysWriteExceptions="null", noConsoleNoAnsi="null", header="null", footer="null")
2015-10-18 13:04:39,850 main DEBUG Built Plugin[name=layout] OK from builder factory method.
2015-10-18 13:04:39,850 main DEBUG Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.FileAppender]. Searching for builder factory method...
2015-10-18 13:04:39,850 main DEBUG No builder factory method found in class org.apache.logging.log4j.core.appender.FileAppender. Going to try finding a factory method instead.
2015-10-18 13:04:39,850 main DEBUG Still building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.FileAppender]. Searching for factory method...
2015-10-18 13:04:39,850 main DEBUG Found factory method [createAppender]: public static org.apache.logging.log4j.core.appender.FileAppender org.apache.logging.log4j.core.appender.FileAppender.createAppender(java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.String,org.apache.logging.log4j.core.Layout,org.apache.logging.log4j.core.Filter,java.lang.String,java.lang.String,org.apache.logging.log4j.core.config.Configuration).
2015-10-18 13:04:57,216 main DEBUG Calling createAppender on class org.apache.logging.log4j.core.appender.FileAppender for element File with params(fileName="logs/appFile.log", append="false", locking="null", name="appFile", immediateFlush="false", ignoreExceptions="null", bufferedIo="null", bufferSize="32768", PatternLayout(%-5p %d{ABSOLUTE} [%t] %c - %m%n), null, advertise="null", advertiseUri="null", Configuration(C:\Users\remko\workspace\log4j-issues\bin\log4j2.xml))
2015-10-18 13:04:57,224 main DEBUG Starting FileManager logs/appFile.log
2015-10-18 13:04:57,224 main DEBUG Built Plugin[name=appender] OK from factory method.
2015-10-18 13:04:57,224 main DEBUG Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout]. Searching for builder factory method...
2015-10-18 13:04:57,224 main TRACE Found builder factory method [newBuilder]: public static org.apache.logging.log4j.core.layout.PatternLayout$Builder org.apache.logging.log4j.core.layout.PatternLayout.newBuilder().
2015-10-18 13:04:57,224 main DEBUG Calling build() on class class org.apache.logging.log4j.core.layout.PatternLayout$Builder for element PatternLayout with params(pattern="%-5p %d{ABSOLUTE} [%t] %c - %m%n", null, Configuration(C:\Users\remko\workspace\log4j-issues\bin\log4j2.xml), null, charset="null", alwaysWriteExceptions="null", noConsoleNoAnsi="null", header="null", footer="null")
2015-10-18 13:04:57,224 main DEBUG Built Plugin[name=layout] OK from builder factory method.
2015-10-18 13:04:57,224 main DEBUG Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.FileAppender]. Searching for builder factory method...
2015-10-18 13:04:57,224 main DEBUG No builder factory method found in class org.apache.logging.log4j.core.appender.FileAppender. Going to try finding a factory method instead.
2015-10-18 13:04:57,240 main DEBUG Still building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.FileAppender]. Searching for factory method...
2015-10-18 13:04:57,240 main DEBUG Found factory method [createAppender]: public static org.apache.logging.log4j.core.appender.FileAppender org.apache.logging.log4j.core.appender.FileAppender.createAppender(java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.String,java.lang.String,org.apache.logging.log4j.core.Layout,org.apache.logging.log4j.core.Filter,java.lang.String,java.lang.String,org.apache.logging.log4j.core.config.Configuration).
2015-10-18 13:05:20,210 main DEBUG Calling createAppender on class org.apache.logging.log4j.core.appender.FileAppender for element File with params(fileName="logs/errorFile.log", append="false", locking="null", name="errorFile", immediateFlush="false", ignoreExceptions="null", bufferedIo="null", bufferSize="32768", PatternLayout(%-5p %d{ABSOLUTE} [%t] %c - %m%n), null, advertise="null", advertiseUri="null", Configuration(C:\Users\remko\workspace\log4j-issues\bin\log4j2.xml))
2015-10-18 13:05:20,220 main DEBUG Starting FileManager logs/errorFile.log
2015-10-18 13:05:20,220 main DEBUG Built Plugin[name=appender] OK from factory method.
2015-10-18 13:05:20,221 main DEBUG Building Plugin[name=appenders, class=org.apache.logging.log4j.core.config.AppendersPlugin]. Searching for builder factory method...
2015-10-18 13:05:20,221 main DEBUG No builder factory method found in class org.apache.logging.log4j.core.config.AppendersPlugin. Going to try finding a factory method instead.
2015-10-18 13:05:20,221 main DEBUG Still building Plugin[name=appenders, class=org.apache.logging.log4j.core.config.AppendersPlugin]. Searching for factory method...
2015-10-18 13:05:20,221 main DEBUG Found factory method [createAppenders]: public static java.util.concurrent.ConcurrentMap org.apache.logging.log4j.core.config.AppendersPlugin.createAppenders(org.apache.logging.log4j.core.Appender[]).
2015-10-18 13:05:20,221 main DEBUG Calling createAppenders on class org.apache.logging.log4j.core.config.AppendersPlugin for element Appenders with params(={Console, appFile, errorFile})
2015-10-18 13:05:20,221 main DEBUG Built Plugin[name=appenders] OK from factory method.
2015-10-18 13:05:20,221 main DEBUG Building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef]. Searching for builder factory method...
2015-10-18 13:05:20,221 main DEBUG No builder factory method found in class org.apache.logging.log4j.core.config.AppenderRef. Going to try finding a factory method instead.
2015-10-18 13:05:20,221 main DEBUG Still building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef]. Searching for factory method...
2015-10-18 13:05:20,221 main DEBUG Found factory method [createAppenderRef]: public static org.apache.logging.log4j.core.config.AppenderRef org.apache.logging.log4j.core.config.AppenderRef.createAppenderRef(java.lang.String,org.apache.logging.log4j.Level,org.apache.logging.log4j.core.Filter).
2015-10-18 13:05:20,221 main DEBUG Calling createAppenderRef on class org.apache.logging.log4j.core.config.AppenderRef for element AppenderRef with params(ref="Console", level="ERROR", null)
2015-10-18 13:05:20,221 main DEBUG Built Plugin[name=AppenderRef] OK from factory method.
2015-10-18 13:05:20,221 main DEBUG Building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef]. Searching for builder factory method...
2015-10-18 13:05:20,221 main DEBUG No builder factory method found in class org.apache.logging.log4j.core.config.AppenderRef. Going to try finding a factory method instead.
2015-10-18 13:05:20,221 main DEBUG Still building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef]. Searching for factory method...
2015-10-18 13:05:20,221 main DEBUG Found factory method [createAppenderRef]: public static org.apache.logging.log4j.core.config.AppenderRef org.apache.logging.log4j.core.config.AppenderRef.createAppenderRef(java.lang.String,org.apache.logging.log4j.Level,org.apache.logging.log4j.core.Filter).
2015-10-18 13:05:20,221 main DEBUG Calling createAppenderRef on class org.apache.logging.log4j.core.config.AppenderRef for element AppenderRef with params(ref="errorFile", level="ERROR", null)
2015-10-18 13:05:20,221 main DEBUG Built Plugin[name=AppenderRef] OK from factory method.
2015-10-18 13:05:20,221 main DEBUG Building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef]. Searching for builder factory method...
2015-10-18 13:05:20,221 main DEBUG No builder factory method found in class org.apache.logging.log4j.core.config.AppenderRef. Going to try finding a factory method instead.
2015-10-18 13:05:20,221 main DEBUG Still building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef]. Searching for factory method...
2015-10-18 13:05:20,221 main DEBUG Found factory method [createAppenderRef]: public static org.apache.logging.log4j.core.config.AppenderRef org.apache.logging.log4j.core.config.AppenderRef.createAppenderRef(java.lang.String,org.apache.logging.log4j.Level,org.apache.logging.log4j.core.Filter).
2015-10-18 13:05:20,236 main DEBUG Calling createAppenderRef on class org.apache.logging.log4j.core.config.AppenderRef for element AppenderRef with params(ref="appFile", level="DEBUG", null)
2015-10-18 13:05:20,236 main DEBUG Built Plugin[name=AppenderRef] OK from factory method.
2015-10-18 13:05:20,236 main DEBUG Building Plugin[name=root, class=org.apache.logging.log4j.core.config.LoggerConfig$RootLogger]. Searching for builder factory method...
2015-10-18 13:05:20,236 main DEBUG No builder factory method found in class org.apache.logging.log4j.core.config.LoggerConfig$RootLogger. Going to try finding a factory method instead.
2015-10-18 13:05:20,236 main DEBUG Still building Plugin[name=root, class=org.apache.logging.log4j.core.config.LoggerConfig$RootLogger]. Searching for factory method...
2015-10-18 13:05:20,236 main DEBUG Found factory method [createLogger]: public static org.apache.logging.log4j.core.config.LoggerConfig org.apache.logging.log4j.core.config.LoggerConfig$RootLogger.createLogger(java.lang.String,org.apache.logging.log4j.Level,java.lang.String,org.apache.logging.log4j.core.config.AppenderRef[],org.apache.logging.log4j.core.config.Property[],org.apache.logging.log4j.core.config.Configuration,org.apache.logging.log4j.core.Filter).
2015-10-18 13:05:20,236 main DEBUG Calling createLogger on class org.apache.logging.log4j.core.config.LoggerConfig$RootLogger for element Root with params(additivity="null", level="TRACE", includeLocation="null", ={Console, errorFile, appFile}, ={}, Configuration(C:\Users\remko\workspace\log4j-issues\bin\log4j2.xml), null)
2015-10-18 13:05:20,236 main DEBUG Built Plugin[name=root] OK from factory method.
2015-10-18 13:05:20,236 main DEBUG Building Plugin[name=loggers, class=org.apache.logging.log4j.core.config.LoggersPlugin]. Searching for builder factory method...
2015-10-18 13:05:20,236 main DEBUG No builder factory method found in class org.apache.logging.log4j.core.config.LoggersPlugin. Going to try finding a factory method instead.
2015-10-18 13:05:20,236 main DEBUG Still building Plugin[name=loggers, class=org.apache.logging.log4j.core.config.LoggersPlugin]. Searching for factory method...
2015-10-18 13:05:20,236 main DEBUG Found factory method [createLoggers]: public static org.apache.logging.log4j.core.config.Loggers org.apache.logging.log4j.core.config.LoggersPlugin.createLoggers(org.apache.logging.log4j.core.config.LoggerConfig[]).
2015-10-18 13:05:20,252 main DEBUG Calling createLoggers on class org.apache.logging.log4j.core.config.LoggersPlugin for element Loggers with params(={root})
2015-10-18 13:05:20,252 main DEBUG Built Plugin[name=loggers] OK from factory method.
2015-10-18 13:05:20,252 main DEBUG Configuration XmlConfiguration[location=C:\Users\remko\workspace\log4j-issues\bin\log4j2.xml] initialized
2015-10-18 13:05:20,252 main DEBUG Starting configuration XmlConfiguration[location=C:\Users\remko\workspace\log4j-issues\bin\log4j2.xml]
2015-10-18 13:05:20,252 main DEBUG Started configuration XmlConfiguration[location=C:\Users\remko\workspace\log4j-issues\bin\log4j2.xml] OK.
2015-10-18 13:05:20,252 main TRACE Stopping org.apache.logging.log4j.core.config.DefaultConfiguration@7bd7d6d6...
2015-10-18 13:05:20,252 main TRACE DefaultConfiguration notified 0 ReliabilityStrategies that config will be stopped.
2015-10-18 13:05:20,252 main TRACE DefaultConfiguration stopped 0 AsyncLoggerConfigs.
2015-10-18 13:05:20,252 main TRACE DefaultConfiguration stopped 0 AsyncAppenders.
2015-10-18 13:05:20,252 main TRACE DefaultConfiguration notified 0 ReliabilityStrategies that appenders will be stopped.
2015-10-18 13:05:20,252 main TRACE DefaultConfiguration stopped 1 Appenders.
2015-10-18 13:05:20,252 main TRACE DefaultConfiguration stopped 0 LoggerConfigs.
2015-10-18 13:05:20,252 main DEBUG Stopped org.apache.logging.log4j.core.config.DefaultConfiguration@7bd7d6d6 OK
2015-10-18 13:05:20,361 main DEBUG Registering MBean org.apache.logging.log4j2:type=349885916
2015-10-18 13:05:20,377 main DEBUG Registering MBean org.apache.logging.log4j2:type=349885916,component=StatusLogger
2015-10-18 13:05:20,377 main DEBUG Registering MBean org.apache.logging.log4j2:type=349885916,component=ContextSelector
2015-10-18 13:05:20,377 main DEBUG Registering MBean org.apache.logging.log4j2:type=349885916,component=Loggers,name=
2015-10-18 13:05:20,377 main DEBUG Registering MBean org.apache.logging.log4j2:type=349885916,component=Appenders,name=Console
2015-10-18 13:05:20,377 main DEBUG Registering MBean org.apache.logging.log4j2:type=349885916,component=Appenders,name=errorFile
2015-10-18 13:05:20,377 main DEBUG Registering MBean org.apache.logging.log4j2:type=349885916,component=Appenders,name=appFile
2015-10-18 13:05:20,393 main DEBUG Using default SystemClock for timestamps
2015-10-18 13:05:20,408 main DEBUG Using default SystemClock for timestamps
2015-10-18 13:05:20,408 main DEBUG AsyncLogger.ThreadNameStrategy=CACHED
2015-10-18 13:05:20,408 main DEBUG property AsyncLogger.WaitStrategy=null
2015-10-18 13:05:20,408 main DEBUG disruptor event handler uses BlockingWaitStrategy
2015-10-18 13:05:20,471 main DEBUG No AsyncLogger.ExceptionHandler specified
2015-10-18 13:05:20,471 main DEBUG Starting AsyncLogger disruptor with ringbuffer size 262144...
2015-10-18 13:05:20,486 main DEBUG Reconfiguration complete for context[name=349885916] at URI null (org.apache.logging.log4j.core.LoggerContext@6f6745d6) with optional ClassLoader: null
2015-10-18 13:05:20,486 main DEBUG Shutdown hook enabled. Registering a new one.
2015-10-18 13:05:20,486 main DEBUG LoggerContext[name=349885916, org.apache.logging.log4j.core.LoggerContext@6f6745d6] started OK.

An improved version could look like this:

2015-10-18 17:11:53,063 main DEBUG Initializing configuration XmlConfiguration[location=C:\Users\remko\workspace\log4j-issues\bin\log4j2.xml]
2015-10-18 17:11:53,078 main DEBUG Installed script engines
2015-10-18 17:11:53,656 main DEBUG Oracle Nashorn Version: 1.8.0_51, Language: ECMAScript, Threading: Not Thread Safe, Compile: true, Names: {nashorn, Nashorn, js, JS, JavaScript, javascript, ECMAScript, ecmascript}
2015-10-18 17:11:53,663 main DEBUG PluginManager 'Core' found 92 plugins
2015-10-18 17:11:53,663 main DEBUG PluginManager 'Level' found 1 plugins
2015-10-18 17:11:53,665 main DEBUG PluginManager 'Lookup' found 13 plugins
2015-10-18 17:11:53,727 main DEBUG Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
2015-10-18 17:11:53,743 main TRACE TypeConverterRegistry initializing.
2015-10-18 17:11:53,743 main DEBUG PluginManager 'TypeConverter' found 21 plugins
2015-10-18 17:11:53,758 main DEBUG PatternLayout$Builder(pattern="%-5p %d{ABSOLUTE} [%t] %c - %m%n", PatternSelector=null, Configuration(C:\Users\remko\workspace\log4j-issues\bin\log4j2.xml), Replace=null, charset="null", alwaysWriteExceptions="null", noConsoleNoAnsi="null", header="null", footer="null")
2015-10-18 17:11:53,758 main DEBUG PluginManager 'Converter' found 38 plugins
2015-10-18 17:11:53,758 main DEBUG Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.ConsoleAppender].
2015-10-18 17:11:53,758 main DEBUG Not in a ServletContext environment, thus not loading WebLookup plugin.
2015-10-18 17:11:53,774 main DEBUG Jansi is not installed, cannot find org.fusesource.jansi.WindowsAnsiOutputStream
2015-10-18 17:11:53,774 main DEBUG ConsoleAppender$Builder(PatternLayout(%-5p %d{ABSOLUTE} [%t] %c - %m%n), Filter=null, target="SYSTEM_OUT", name="Console", follow="null", ignoreExceptions="null")
2015-10-18 17:11:53,774 main DEBUG Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
2015-10-18 17:11:53,774 main DEBUG PatternLayout$Builder(pattern="%-5p %d{ABSOLUTE} [%t] %c - %m%n", PatternSelector=null, Configuration(C:\Users\remko\workspace\log4j-issues\bin\log4j2.xml), Replace=null, charset="null", alwaysWriteExceptions="null", noConsoleNoAnsi="null", header="null", footer="null")
2015-10-18 17:11:53,774 main DEBUG Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.FileAppender].
2015-10-18 17:11:53,805 main DEBUG createAppender(fileName="logs/appFile.log", append="false", locking="null", name="appFile", immediateFlush="false", ignoreExceptions="null", bufferedIo="null", bufferSize="32768", PatternLayout(%-5p %d{ABSOLUTE} [%t] %c - %m%n), Filter=null, advertise="null", advertiseUri="null", Configuration(C:\Users\remko\workspace\log4j-issues\bin\log4j2.xml))
2015-10-18 17:11:53,805 main DEBUG Starting FileManager logs/appFile.log
2015-10-18 17:11:53,805 main DEBUG Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
2015-10-18 17:11:53,805 main DEBUG PatternLayout$Builder(pattern="%-5p %d{ABSOLUTE} [%t] %c - %m%n", PatternSelector=null, Configuration(C:\Users\remko\workspace\log4j-issues\bin\log4j2.xml), Replace=null, charset="null", alwaysWriteExceptions="null", noConsoleNoAnsi="null", header="null", footer="null")
2015-10-18 17:11:53,805 main DEBUG Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.FileAppender].
2015-10-18 17:11:53,821 main DEBUG createAppender(fileName="logs/errorFile.log", append="false", locking="null", name="errorFile", immediateFlush="false", ignoreExceptions="null", bufferedIo="null", bufferSize="32768", PatternLayout(%-5p %d{ABSOLUTE} [%t] %c - %m%n), Filter=null, advertise="null", advertiseUri="null", Configuration(C:\Users\remko\workspace\log4j-issues\bin\log4j2.xml))
2015-10-18 17:11:53,821 main DEBUG Starting FileManager logs/errorFile.log
2015-10-18 17:11:53,821 main DEBUG Building Plugin[name=appenders, class=org.apache.logging.log4j.core.config.AppendersPlugin].
2015-10-18 17:11:53,830 main DEBUG createAppenders(={Console, appFile, errorFile})
2015-10-18 17:11:53,831 main DEBUG Building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef].
2015-10-18 17:11:53,832 main DEBUG createAppenderRef(ref="Console", level="ERROR", Filter=null)
2015-10-18 17:11:53,832 main DEBUG Building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef].
2015-10-18 17:11:53,833 main DEBUG createAppenderRef(ref="errorFile", level="ERROR", Filter=null)
2015-10-18 17:11:53,834 main DEBUG Building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef].
2015-10-18 17:11:53,835 main DEBUG createAppenderRef(ref="appFile", level="DEBUG", Filter=null)
2015-10-18 17:11:53,836 main DEBUG Building Plugin[name=root, class=org.apache.logging.log4j.core.config.LoggerConfig$RootLogger].
2015-10-18 17:11:53,837 main DEBUG createLogger(additivity="null", level="TRACE", includeLocation="null", ={Console, errorFile, appFile}, ={}, Configuration(C:\Users\remko\workspace\log4j-issues\bin\log4j2.xml), Filter=null)
2015-10-18 17:11:53,842 main DEBUG Building Plugin[name=loggers, class=org.apache.logging.log4j.core.config.LoggersPlugin].
2015-10-18 17:11:53,845 main DEBUG createLoggers(={root})
2015-10-18 17:11:53,847 main DEBUG Configuration XmlConfiguration[location=C:\Users\remko\workspace\log4j-issues\bin\log4j2.xml] initialized
2015-10-18 17:11:53,847 main DEBUG Starting configuration XmlConfiguration[location=C:\Users\remko\workspace\log4j-issues\bin\log4j2.xml]
2015-10-18 17:11:53,851 main DEBUG Started configuration XmlConfiguration[location=C:\Users\remko\workspace\log4j-issues\bin\log4j2.xml] OK.
2015-10-18 17:11:53,852 main TRACE Stopping org.apache.logging.log4j.core.config.DefaultConfiguration@4148db48...
2015-10-18 17:11:53,852 main TRACE DefaultConfiguration notified 0 ReliabilityStrategies that config will be stopped.
2015-10-18 17:11:53,853 main TRACE DefaultConfiguration stopped 0 AsyncLoggerConfigs.
2015-10-18 17:11:53,853 main TRACE DefaultConfiguration stopped 0 AsyncAppenders.
2015-10-18 17:11:53,854 main TRACE DefaultConfiguration notified 0 ReliabilityStrategies that appenders will be stopped.
2015-10-18 17:11:53,854 main TRACE DefaultConfiguration stopped 1 Appenders.
2015-10-18 17:11:53,855 main TRACE DefaultConfiguration stopped 0 LoggerConfigs.
2015-10-18 17:11:53,855 main DEBUG Stopped org.apache.logging.log4j.core.config.DefaultConfiguration@4148db48 OK
2015-10-18 17:11:53,937 main DEBUG Registering MBean org.apache.logging.log4j2:type=1554547125
2015-10-18 17:11:53,953 main DEBUG Registering MBean org.apache.logging.log4j2:type=1554547125,component=StatusLogger
2015-10-18 17:11:53,953 main DEBUG Registering MBean org.apache.logging.log4j2:type=1554547125,component=ContextSelector
2015-10-18 17:11:53,953 main DEBUG Registering MBean org.apache.logging.log4j2:type=1554547125,component=Loggers,name=
2015-10-18 17:11:53,969 main DEBUG Registering MBean org.apache.logging.log4j2:type=1554547125,component=Appenders,name=Console
2015-10-18 17:11:53,969 main DEBUG Registering MBean org.apache.logging.log4j2:type=1554547125,component=Appenders,name=errorFile
2015-10-18 17:11:53,969 main DEBUG Registering MBean org.apache.logging.log4j2:type=1554547125,component=Appenders,name=appFile
2015-10-18 17:11:53,969 main DEBUG Using default SystemClock for timestamps
2015-10-18 17:11:53,984 main DEBUG AsyncLogger.ThreadNameStrategy=CACHED
2015-10-18 17:11:53,984 main DEBUG property AsyncLogger.WaitStrategy=null
2015-10-18 17:11:53,984 main DEBUG disruptor event handler uses BlockingWaitStrategy
2015-10-18 17:11:54,047 main DEBUG No AsyncLogger.ExceptionHandler specified
2015-10-18 17:11:54,047 main DEBUG Starting AsyncLogger disruptor with ringbuffer size 262144...
2015-10-18 17:11:54,047 main DEBUG Reconfiguration complete for context[name=1554547125] at URI null (org.apache.logging.log4j.core.LoggerContext@482cd91f) with optional ClassLoader: null
2015-10-18 17:11:54,047 main DEBUG Shutdown hook enabled. Registering a new one.
2015-10-18 17:11:54,047 main DEBUG LoggerContext[name=1554547125, org.apache.logging.log4j.core.LoggerContext@482cd91f] started OK.