[LOG4J2-1419] Memory leak on Tomcat shutdown - ASF JIRA

Agile Board

Attach files

Attach Screenshot

Add vote

Voters

Watch issue

Watchers

Create sub-task

Link

Clone

Update Comment Author

Replace String in Comment

Update Comment Visibility

Delete Comments

XML

Word

Printable

JSON

Details

Type: Bug
Status: Open
Priority: Major
Resolution: Unresolved
Affects Version/s: 2.6
Fix Version/s: None
Component/s: Appenders, Configurators
Labels:
None

Description

Running log4j2 v2.6 with disruptor 3.3.4. AsyncLoggers configured (-DLog4jContextSelector=org.apache.logging.log4j.core.async.AsyncLoggerContextSelector), log4j-web also included. Web application deployed in Tomcat 7.0.69 with configured servlet 3.0. catalina.properties does not include the log4j*.jar entry.

Server log:

D:\servers\apache-tomcat-7.0.69\bin\catalina.bat run
[2016-06-08 12:17:40,314] Artifact TestLogging:war exploded: Server is not connected. Deploy is not available.
Using CATALINA_BASE:   "C:\system\tomcat\Unnamed_TestLogging_2"
Using CATALINA_HOME:   "D:\servers\apache-tomcat-7.0.69"
Using CATALINA_TMPDIR: "D:\servers\apache-tomcat-7.0.69\temp"
Using JRE_HOME:        "C:\Program Files\Java\jdk1.7.0_79"
Using CLASSPATH:       "D:\servers\apache-tomcat-7.0.69\bin\bootstrap.jar;D:\servers\apache-tomcat-7.0.69\bin\tomcat-juli.jar"
Connected to the target VM, address: '127.0.0.1:50643', transport: 'socket'
Jun 08, 2016 12:17:41 AM org.apache.catalina.startup.VersionLoggerListener log
INFO: Server version:        Apache Tomcat/7.0.69
Jun 08, 2016 12:17:41 AM org.apache.catalina.startup.VersionLoggerListener log
INFO: Server built:          Apr 11 2016 07:57:09 UTC
Jun 08, 2016 12:17:41 AM org.apache.catalina.startup.VersionLoggerListener log
INFO: Server number:         7.0.69.0
Jun 08, 2016 12:17:41 AM org.apache.catalina.startup.VersionLoggerListener log
INFO: OS Name:               Windows 8.1
Jun 08, 2016 12:17:41 AM org.apache.catalina.startup.VersionLoggerListener log
INFO: OS Version:            6.3
Jun 08, 2016 12:17:41 AM org.apache.catalina.startup.VersionLoggerListener log
INFO: Architecture:          amd64
Jun 08, 2016 12:17:41 AM org.apache.catalina.startup.VersionLoggerListener log
INFO: Java Home:             C:\Program Files\Java\jdk1.7.0_79\jre
Jun 08, 2016 12:17:41 AM org.apache.catalina.startup.VersionLoggerListener log
INFO: JVM Version:           1.7.0_79-b15
Jun 08, 2016 12:17:41 AM org.apache.catalina.startup.VersionLoggerListener log
INFO: JVM Vendor:            Oracle Corporation
Jun 08, 2016 12:17:41 AM org.apache.catalina.startup.VersionLoggerListener log
INFO: CATALINA_BASE:         C:\system\tomcat\Unnamed_TestLogging_2
Jun 08, 2016 12:17:41 AM org.apache.catalina.startup.VersionLoggerListener log
INFO: CATALINA_HOME:         D:\servers\apache-tomcat-7.0.69
Jun 08, 2016 12:17:41 AM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -agentlib:jdwp=transport=dt_socket,address=127.0.0.1:50643,suspend=y,server=n
Jun 08, 2016 12:17:41 AM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -DLog4jContextSelector=org.apache.logging.log4j.core.async.AsyncLoggerContextSelector
Jun 08, 2016 12:17:41 AM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Dcom.sun.management.jmxremote=
Jun 08, 2016 12:17:41 AM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Dcom.sun.management.jmxremote.port=1099
Jun 08, 2016 12:17:41 AM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Dcom.sun.management.jmxremote.ssl=false
Jun 08, 2016 12:17:41 AM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Dcom.sun.management.jmxremote.authenticate=false
Jun 08, 2016 12:17:41 AM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Djava.rmi.server.hostname=127.0.0.1
Jun 08, 2016 12:17:41 AM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Djdk.tls.ephemeralDHKeySize=2048
Jun 08, 2016 12:17:41 AM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Djava.util.logging.config.file=C:\system\tomcat\Unnamed_TestLogging_2\conf\logging.properties
Jun 08, 2016 12:17:41 AM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager
Jun 08, 2016 12:17:41 AM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Djava.endorsed.dirs=D:\servers\apache-tomcat-7.0.69\endorsed
Jun 08, 2016 12:17:41 AM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Dcatalina.base=C:\system\tomcat\Unnamed_TestLogging_2
Jun 08, 2016 12:17:41 AM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Dcatalina.home=D:\servers\apache-tomcat-7.0.69
Jun 08, 2016 12:17:41 AM org.apache.catalina.startup.VersionLoggerListener log
INFO: Command line argument: -Djava.io.tmpdir=D:\servers\apache-tomcat-7.0.69\temp
Jun 08, 2016 12:17:41 AM org.apache.catalina.core.AprLifecycleListener lifecycleEvent
INFO: Loaded APR based Apache Tomcat Native library 1.1.33 using APR version 1.5.1.
Jun 08, 2016 12:17:41 AM org.apache.catalina.core.AprLifecycleListener lifecycleEvent
INFO: APR capabilities: IPv6 [true], sendfile [true], accept filters [false], random [true].
Jun 08, 2016 12:17:42 AM org.apache.catalina.core.AprLifecycleListener initializeSSL
INFO: OpenSSL successfully initialized (OpenSSL 1.0.1m 19 Mar 2015)
Jun 08, 2016 12:17:42 AM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-apr-8080"]
Jun 08, 2016 12:17:42 AM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["ajp-apr-8009"]
Jun 08, 2016 12:17:42 AM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 1496 ms
Jun 08, 2016 12:17:42 AM org.apache.catalina.core.StandardService startInternal
INFO: Starting service Catalina
Jun 08, 2016 12:17:42 AM org.apache.catalina.core.StandardEngine startInternal
INFO: Starting Servlet Engine: Apache Tomcat/7.0.69
Jun 08, 2016 12:17:42 AM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-apr-8080"]
Jun 08, 2016 12:17:42 AM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["ajp-apr-8009"]
Jun 08, 2016 12:17:42 AM org.apache.catalina.startup.Catalina start
INFO: Server startup in 43 ms
Connected to server
[2016-06-08 12:17:42,400] Artifact TestLogging:war exploded: Artifact is being deployed, please wait...
Jun 08, 2016 12:17:44 AM org.apache.catalina.startup.TldConfig execute
INFO: At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
2016-06-08 00:17:44,696 RMI TCP Connection(3)-127.0.0.1 DEBUG Starting LoggerContext[name=AsyncContext[]] from configuration at null
2016-06-08 00:17:44,696 RMI TCP Connection(3)-127.0.0.1 TRACE [] AsyncLoggerDisruptor creating new disruptor for this context.
2016-06-08 00:17:44,698 RMI TCP Connection(3)-127.0.0.1 TRACE property AsyncLogger.WaitStrategy=TIMEOUT
2016-06-08 00:17:44,737 RMI TCP Connection(3)-127.0.0.1 DEBUG [] Starting AsyncLogger disruptor for this context with ringbufferSize=262144, waitStrategy=TimeoutBlockingWaitStrategy, exceptionHandler=org.apache.logging.log4j.core.async.AsyncLoggerDefaultExceptionHandler@3a7e5f1f...
2016-06-08 00:17:44,738 RMI TCP Connection(3)-127.0.0.1 TRACE [] AsyncLoggers use a threadlocal translator
2016-06-08 00:17:44,738 RMI TCP Connection(3)-127.0.0.1 DEBUG Starting LoggerContext[name=AsyncContext[], org.apache.logging.log4j.core.async.AsyncLoggerContext@1a28371] with configuration XmlConfiguration[location=C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml]...
2016-06-08 00:17:44,739 RMI TCP Connection(3)-127.0.0.1 DEBUG Shutdown hook enabled. Registering a new one.
2016-06-08 00:17:44,739 RMI TCP Connection(3)-127.0.0.1 DEBUG Initializing configuration XmlConfiguration[location=C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml]
2016-06-08 00:17:44,742 RMI TCP Connection(3)-127.0.0.1 DEBUG Installed script engines
2016-06-08 00:17:44,752 RMI TCP Connection(3)-127.0.0.1 DEBUG Mozilla Rhino Version: 1.7 release 3 PRERELEASE, Language: ECMAScript, Threading: MULTITHREADED, Compile: true, Names: {js, rhino, JavaScript, javascript, ECMAScript, ecmascript}
2016-06-08 00:17:44,752 RMI TCP Connection(3)-127.0.0.1 DEBUG PluginManager 'Core' found 100 plugins
2016-06-08 00:17:44,752 RMI TCP Connection(3)-127.0.0.1 DEBUG PluginManager 'Level' found 0 plugins
2016-06-08 00:17:44,756 RMI TCP Connection(3)-127.0.0.1 DEBUG Starting 1 Log4j2Scheduled threads
2016-06-08 00:17:44,757 RMI TCP Connection(3)-127.0.0.1 DEBUG PluginManager 'Lookup' found 14 plugins
2016-06-08 00:17:44,758 RMI TCP Connection(3)-127.0.0.1 DEBUG Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
2016-06-08 00:17:44,768 RMI TCP Connection(3)-127.0.0.1 TRACE TypeConverterRegistry initializing.
2016-06-08 00:17:44,769 RMI TCP Connection(3)-127.0.0.1 DEBUG PluginManager 'TypeConverter' found 23 plugins
2016-06-08 00:17:44,780 RMI TCP Connection(3)-127.0.0.1 DEBUG PatternLayout$Builder(pattern="%d [%-5p] [%c]: %m%n", PatternSelector=null, Configuration(C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml), Replace=null, charset="null", alwaysWriteExceptions="null", noConsoleNoAnsi="null", header="null", footer="null")
2016-06-08 00:17:44,780 RMI TCP Connection(3)-127.0.0.1 DEBUG PluginManager 'Converter' found 41 plugins
2016-06-08 00:17:44,795 RMI TCP Connection(3)-127.0.0.1 DEBUG Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.ConsoleAppender].
2016-06-08 00:17:44,796 RMI TCP Connection(3)-127.0.0.1 DEBUG PluginManager 'Converter' found 41 plugins
2016-06-08 00:17:44,798 RMI TCP Connection(3)-127.0.0.1 DEBUG Jansi is not installed, cannot find org.fusesource.jansi.WindowsAnsiOutputStream
2016-06-08 00:17:44,798 RMI TCP Connection(3)-127.0.0.1 DEBUG Starting OutputStreamManager SYSTEM_OUT.false-2
2016-06-08 00:17:44,803 RMI TCP Connection(3)-127.0.0.1 DEBUG ConsoleAppender$Builder(PatternLayout(%d [%-5p] [%c]: %m%n), Filter=null, target="SYSTEM_OUT", name="Console", follow="null", ignoreExceptions="null")
2016-06-08 00:17:44,804 RMI TCP Connection(3)-127.0.0.1 DEBUG Jansi is not installed, cannot find org.fusesource.jansi.WindowsAnsiOutputStream
2016-06-08 00:17:44,804 RMI TCP Connection(3)-127.0.0.1 DEBUG Starting OutputStreamManager SYSTEM_OUT.false
2016-06-08 00:17:44,805 RMI TCP Connection(3)-127.0.0.1 DEBUG Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
2016-06-08 00:17:44,806 RMI TCP Connection(3)-127.0.0.1 DEBUG PatternLayout$Builder(pattern="[%d{dd/MM/yy HH:mm:ss:sss}] [%-5p] [%c]: %m%n", PatternSelector=null, Configuration(C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml), Replace=null, charset="null", alwaysWriteExceptions="null", noConsoleNoAnsi="null", header="null", footer="null")
2016-06-08 00:17:44,807 RMI TCP Connection(3)-127.0.0.1 DEBUG Building Plugin[name=SizeBasedTriggeringPolicy, class=org.apache.logging.log4j.core.appender.rolling.SizeBasedTriggeringPolicy].
2016-06-08 00:17:44,813 RMI TCP Connection(3)-127.0.0.1 DEBUG createPolicy(size="250 MB")
2016-06-08 00:17:44,814 RMI TCP Connection(3)-127.0.0.1 DEBUG Building Plugin[name=Policies, class=org.apache.logging.log4j.core.appender.rolling.CompositeTriggeringPolicy].
2016-06-08 00:17:44,815 RMI TCP Connection(3)-127.0.0.1 DEBUG createPolicy(={SizeBasedTriggeringPolicy(size=262144000)})
2016-06-08 00:17:44,815 RMI TCP Connection(3)-127.0.0.1 DEBUG Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.RollingRandomAccessFileAppender].
2016-06-08 00:17:44,817 RMI TCP Connection(3)-127.0.0.1 DEBUG createAppender(fileName="C:\system\tomcat\Unnamed_TestLogging_2/logs/oreo.log", filePattern="C:\system\tomcat\Unnamed_TestLogging_2/logs/logs/oreo-%d{yyyy-MM-dd_HHmmss-SSS}.log.gz", append="null", name="AppLog", immediateFlush="true", bufferSize="null", Policies(CompositeTriggeringPolicy(policies=[SizeBasedTriggeringPolicy(size=262144000)])), Strategy=null, PatternLayout([%d{dd/MM/yy HH:mm:ss:sss}] [%-5p] [%c]: %m%n), Filter=null, ignoreExceptions="null", advertise="null", advertiseURI="null", Configuration(C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml))
2016-06-08 00:17:44,822 RMI TCP Connection(3)-127.0.0.1 TRACE RandomAccessFile C:\system\tomcat\Unnamed_TestLogging_2/logs/oreo.log seek to 22050
2016-06-08 00:17:44,823 RMI TCP Connection(3)-127.0.0.1 DEBUG Starting RollingRandomAccessFileManager C:\system\tomcat\Unnamed_TestLogging_2/logs/oreo.log
2016-06-08 00:17:44,823 RMI TCP Connection(3)-127.0.0.1 DEBUG PluginManager 'FileConverter' found 2 plugins
2016-06-08 00:17:44,825 RMI TCP Connection(3)-127.0.0.1 DEBUG Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
2016-06-08 00:17:44,825 RMI TCP Connection(3)-127.0.0.1 DEBUG PatternLayout$Builder(pattern="[%d{dd/MM/yy HH:mm:ss:sss}] [%-5p] [%c]: %m%n", PatternSelector=null, Configuration(C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml), Replace=null, charset="null", alwaysWriteExceptions="null", noConsoleNoAnsi="null", header="null", footer="null")
2016-06-08 00:17:44,826 RMI TCP Connection(3)-127.0.0.1 DEBUG Building Plugin[name=SizeBasedTriggeringPolicy, class=org.apache.logging.log4j.core.appender.rolling.SizeBasedTriggeringPolicy].
2016-06-08 00:17:44,826 RMI TCP Connection(3)-127.0.0.1 DEBUG createPolicy(size="250 MB")
2016-06-08 00:17:44,826 RMI TCP Connection(3)-127.0.0.1 DEBUG Building Plugin[name=Policies, class=org.apache.logging.log4j.core.appender.rolling.CompositeTriggeringPolicy].
2016-06-08 00:17:44,827 RMI TCP Connection(3)-127.0.0.1 DEBUG createPolicy(={SizeBasedTriggeringPolicy(size=262144000)})
2016-06-08 00:17:44,827 RMI TCP Connection(3)-127.0.0.1 DEBUG Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.RollingRandomAccessFileAppender].
2016-06-08 00:17:44,828 RMI TCP Connection(3)-127.0.0.1 DEBUG createAppender(fileName="C:\system\tomcat\Unnamed_TestLogging_2/logs/oreo-hibernate.log", filePattern="C:\system\tomcat\Unnamed_TestLogging_2/logs/logs/oreo-hibernate-%d{yyyy-MM-dd_HHmmss-SSS}.log.gz", append="null", name="HibernateLog", immediateFlush="true", bufferSize="null", Policies(CompositeTriggeringPolicy(policies=[SizeBasedTriggeringPolicy(size=262144000)])), Strategy=null, PatternLayout([%d{dd/MM/yy HH:mm:ss:sss}] [%-5p] [%c]: %m%n), Filter=null, ignoreExceptions="null", advertise="null", advertiseURI="null", Configuration(C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml))
2016-06-08 00:17:44,829 RMI TCP Connection(3)-127.0.0.1 TRACE RandomAccessFile C:\system\tomcat\Unnamed_TestLogging_2/logs/oreo-hibernate.log seek to 6237
2016-06-08 00:17:44,829 RMI TCP Connection(3)-127.0.0.1 DEBUG Starting RollingRandomAccessFileManager C:\system\tomcat\Unnamed_TestLogging_2/logs/oreo-hibernate.log
2016-06-08 00:17:44,830 RMI TCP Connection(3)-127.0.0.1 DEBUG PluginManager 'FileConverter' found 2 plugins
2016-06-08 00:17:44,830 RMI TCP Connection(3)-127.0.0.1 DEBUG Building Plugin[name=appenders, class=org.apache.logging.log4j.core.config.AppendersPlugin].
2016-06-08 00:17:44,830 RMI TCP Connection(3)-127.0.0.1 DEBUG createAppenders(={Console, AppLog, HibernateLog})
2016-06-08 00:17:44,830 RMI TCP Connection(3)-127.0.0.1 DEBUG Building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef].
2016-06-08 00:17:44,831 RMI TCP Connection(3)-127.0.0.1 DEBUG createAppenderRef(ref="AppLog", level="null", Filter=null)
2016-06-08 00:17:44,831 RMI TCP Connection(3)-127.0.0.1 DEBUG Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig].
2016-06-08 00:17:44,833 RMI TCP Connection(3)-127.0.0.1 DEBUG createLogger(additivity="false", level="DEBUG", name="org.tomcat.logging", includeLocation="null", ={AppLog}, ={}, Configuration(C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml), Filter=null)
2016-06-08 00:17:44,834 RMI TCP Connection(3)-127.0.0.1 DEBUG Building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef].
2016-06-08 00:17:44,834 RMI TCP Connection(3)-127.0.0.1 DEBUG createAppenderRef(ref="AppLog", level="null", Filter=null)
2016-06-08 00:17:44,835 RMI TCP Connection(3)-127.0.0.1 DEBUG Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig].
2016-06-08 00:17:44,835 RMI TCP Connection(3)-127.0.0.1 DEBUG createLogger(additivity="false", level="INFO", name="org.springframework", includeLocation="null", ={AppLog}, ={}, Configuration(C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml), Filter=null)
2016-06-08 00:17:44,836 RMI TCP Connection(3)-127.0.0.1 DEBUG Building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef].
2016-06-08 00:17:44,836 RMI TCP Connection(3)-127.0.0.1 DEBUG createAppenderRef(ref="HibernateLog", level="null", Filter=null)
2016-06-08 00:17:44,836 RMI TCP Connection(3)-127.0.0.1 DEBUG Building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef].
2016-06-08 00:17:44,837 RMI TCP Connection(3)-127.0.0.1 DEBUG createAppenderRef(ref="AppLog", level="null", Filter=null)
2016-06-08 00:17:44,837 RMI TCP Connection(3)-127.0.0.1 DEBUG Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig].
2016-06-08 00:17:44,838 RMI TCP Connection(3)-127.0.0.1 DEBUG createLogger(additivity="false", level="INFO", name="org.hibernate", includeLocation="null", ={HibernateLog, AppLog}, ={}, Configuration(C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml), Filter=null)
2016-06-08 00:17:44,838 RMI TCP Connection(3)-127.0.0.1 DEBUG Building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef].
2016-06-08 00:17:44,838 RMI TCP Connection(3)-127.0.0.1 DEBUG createAppenderRef(ref="Console", level="null", Filter=null)
2016-06-08 00:17:44,839 RMI TCP Connection(3)-127.0.0.1 DEBUG Building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef].
2016-06-08 00:17:44,839 RMI TCP Connection(3)-127.0.0.1 DEBUG createAppenderRef(ref="AppLog", level="null", Filter=null)
2016-06-08 00:17:44,839 RMI TCP Connection(3)-127.0.0.1 DEBUG Building Plugin[name=root, class=org.apache.logging.log4j.core.config.LoggerConfig$RootLogger].
2016-06-08 00:17:44,840 RMI TCP Connection(3)-127.0.0.1 DEBUG createLogger(additivity="null", level="DEBUG", includeLocation="null", ={Console, AppLog}, ={}, Configuration(C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml), Filter=null)
2016-06-08 00:17:44,840 RMI TCP Connection(3)-127.0.0.1 DEBUG Building Plugin[name=loggers, class=org.apache.logging.log4j.core.config.LoggersPlugin].
2016-06-08 00:17:44,841 RMI TCP Connection(3)-127.0.0.1 DEBUG createLoggers(={org.tomcat.logging, org.springframework, org.hibernate, root})
2016-06-08 00:17:44,842 RMI TCP Connection(3)-127.0.0.1 DEBUG Configuration XmlConfiguration[location=C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml] initialized
2016-06-08 00:17:44,843 RMI TCP Connection(3)-127.0.0.1 DEBUG Starting configuration XmlConfiguration[location=C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml]
2016-06-08 00:17:44,843 RMI TCP Connection(3)-127.0.0.1 DEBUG Started configuration XmlConfiguration[location=C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml] OK.
2016-06-08 00:17:44,844 RMI TCP Connection(3)-127.0.0.1 TRACE Stopping org.apache.logging.log4j.core.config.DefaultConfiguration@47e7cd45...
2016-06-08 00:17:44,844 RMI TCP Connection(3)-127.0.0.1 TRACE DefaultConfiguration notified 1 ReliabilityStrategies that config will be stopped.
2016-06-08 00:17:44,844 RMI TCP Connection(3)-127.0.0.1 TRACE DefaultConfiguration stopping root LoggerConfig.
2016-06-08 00:17:44,844 RMI TCP Connection(3)-127.0.0.1 TRACE DefaultConfiguration notifying ReliabilityStrategies that appenders will be stopped.
2016-06-08 00:17:44,845 RMI TCP Connection(3)-127.0.0.1 TRACE DefaultConfiguration stopping remaining Appenders.
2016-06-08 00:17:44,845 RMI TCP Connection(3)-127.0.0.1 DEBUG Shutting down OutputStreamManager SYSTEM_OUT.false-1
2016-06-08 00:17:44,845 RMI TCP Connection(3)-127.0.0.1 TRACE DefaultConfiguration stopped 1 remaining Appenders.
2016-06-08 00:17:44,845 RMI TCP Connection(3)-127.0.0.1 TRACE DefaultConfiguration cleaning Appenders from 1 LoggerConfigs.
2016-06-08 00:17:44,845 RMI TCP Connection(3)-127.0.0.1 DEBUG Stopped org.apache.logging.log4j.core.config.DefaultConfiguration@47e7cd45 OK
2016-06-08 00:17:44,847 RMI TCP Connection(3)-127.0.0.1 TRACE Reregistering MBeans after reconfigure. Selector=org.apache.logging.log4j.core.async.AsyncLoggerContextSelector@12c1f590
2016-06-08 00:17:44,847 RMI TCP Connection(3)-127.0.0.1 TRACE Reregistering context (1/1): 'AsyncContext[]' org.apache.logging.log4j.core.async.AsyncLoggerContext@1a28371
2016-06-08 00:17:44,847 RMI TCP Connection(3)-127.0.0.1 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext[]'
2016-06-08 00:17:44,847 RMI TCP Connection(3)-127.0.0.1 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext[],component=StatusLogger'
2016-06-08 00:17:44,848 RMI TCP Connection(3)-127.0.0.1 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext[],component=ContextSelector'
2016-06-08 00:17:44,848 RMI TCP Connection(3)-127.0.0.1 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext[],component=Loggers,name=*'
2016-06-08 00:17:44,848 RMI TCP Connection(3)-127.0.0.1 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext[],component=Appenders,name=*'
2016-06-08 00:17:44,849 RMI TCP Connection(3)-127.0.0.1 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext[],component=AsyncAppenders,name=*'
2016-06-08 00:17:44,849 RMI TCP Connection(3)-127.0.0.1 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext[],component=AsyncLoggerRingBuffer'
2016-06-08 00:17:44,849 RMI TCP Connection(3)-127.0.0.1 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext[],component=Loggers,name=*,subtype=RingBuffer'
2016-06-08 00:17:44,851 RMI TCP Connection(3)-127.0.0.1 DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext[]
2016-06-08 00:17:44,853 RMI TCP Connection(3)-127.0.0.1 DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext[],component=AsyncLoggerRingBuffer
2016-06-08 00:17:44,854 RMI TCP Connection(3)-127.0.0.1 DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext[],component=StatusLogger
2016-06-08 00:17:44,856 RMI TCP Connection(3)-127.0.0.1 DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext[],component=ContextSelector
2016-06-08 00:17:44,857 RMI TCP Connection(3)-127.0.0.1 DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext[],component=Loggers,name=org.hibernate
2016-06-08 00:17:44,858 RMI TCP Connection(3)-127.0.0.1 DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext[],component=Loggers,name=
2016-06-08 00:17:44,858 RMI TCP Connection(3)-127.0.0.1 DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext[],component=Loggers,name=org.tomcat.logging
2016-06-08 00:17:44,858 RMI TCP Connection(3)-127.0.0.1 DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext[],component=Loggers,name=org.springframework
2016-06-08 00:17:44,859 RMI TCP Connection(3)-127.0.0.1 DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext[],component=Appenders,name=AppLog
2016-06-08 00:17:44,860 RMI TCP Connection(3)-127.0.0.1 DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext[],component=Appenders,name=Console
2016-06-08 00:17:44,860 RMI TCP Connection(3)-127.0.0.1 DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext[],component=Appenders,name=HibernateLog
2016-06-08 00:17:44,863 RMI TCP Connection(3)-127.0.0.1 TRACE Using default SystemClock for timestamps.
2016-06-08 00:17:44,863 RMI TCP Connection(3)-127.0.0.1 TRACE Using DummyNanoClock for nanosecond timestamps.
2016-06-08 00:17:44,864 RMI TCP Connection(3)-127.0.0.1 DEBUG LoggerContext[name=AsyncContext[], org.apache.logging.log4j.core.async.AsyncLoggerContext@1a28371] started OK with configuration XmlConfiguration[location=C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml].
2016-06-08 00:17:44,864 RMI TCP Connection(3)-127.0.0.1 TRACE [] AsyncLoggers have been modified to use a vararg translator
2016-06-08 00:17:44,893 RMI TCP Connection(3)-127.0.0.1 TRACE Using default SystemClock for timestamps.
2016-06-08 00:17:44,894 RMI TCP Connection(3)-127.0.0.1 DEBUG AsyncLogger.ThreadNameStrategy=CACHED
2016-06-08 00:17:45,777 [DEBUG] [org.jboss.logging]: Logging Provider: org.jboss.logging.Log4jLoggerProvider
2016-06-08 00:17:46,220 RMI TCP Connection(3)-127.0.0.1 DEBUG Log4jServletContextListener ensuring that Log4j starts up properly.
2016-06-08 00:17:46,222 RMI TCP Connection(3)-127.0.0.1 DEBUG Log4jServletFilter initialized.
[2016-06-08 12:17:46,241] Artifact TestLogging:war exploded: Artifact is deployed successfully
[2016-06-08 12:17:46,241] Artifact TestLogging:war exploded: Deploy took 3,842 milliseconds
Jun 08, 2016 12:17:52 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory D:\servers\apache-tomcat-7.0.69\webapps\manager
Jun 08, 2016 12:17:52 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory D:\servers\apache-tomcat-7.0.69\webapps\manager has finished in 63 ms
D:\servers\apache-tomcat-7.0.69\bin\catalina.bat stop
Disconnected from the target VM, address: '127.0.0.1:50643', transport: 'socket'
Using CATALINA_BASE:   "C:\system\tomcat\Unnamed_TestLogging_2"
Using CATALINA_HOME:   "D:\servers\apache-tomcat-7.0.69"
Using CATALINA_TMPDIR: "D:\servers\apache-tomcat-7.0.69\temp"
Using JRE_HOME:        "C:\Program Files\Java\jdk1.7.0_79"
Using CLASSPATH:       "D:\servers\apache-tomcat-7.0.69\bin\bootstrap.jar;D:\servers\apache-tomcat-7.0.69\bin\tomcat-juli.jar"
Jun 08, 2016 12:18:22 AM org.apache.catalina.core.StandardServer await
INFO: A valid shutdown command was received via the shutdown port. Stopping the Server instance.
Jun 08, 2016 12:18:22 AM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["http-apr-8080"]
Jun 08, 2016 12:18:22 AM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["ajp-apr-8009"]
Jun 08, 2016 12:18:23 AM org.apache.catalina.core.StandardService stopInternal
INFO: Stopping service Catalina
2016-06-08 00:18:23,161 localhost-startStop-2 DEBUG Log4jServletFilter destroyed.
2016-06-08 00:18:23,162 localhost-startStop-2 DEBUG Log4jServletContextListener ensuring that Log4j shuts down properly.
2016-06-08 00:18:23,163 localhost-startStop-2 DEBUG Removing LoggerContext for [].
2016-06-08 00:18:23,163 localhost-startStop-2 DEBUG [] AsyncLoggerDisruptor: shutting down disruptor for this context.
2016-06-08 00:18:23,164 localhost-startStop-2 TRACE [] AsyncLoggerDisruptor: shutting down disruptor executor.
2016-06-08 00:18:23,185 localhost-startStop-2 DEBUG Stopping LoggerContext[name=AsyncContext[], org.apache.logging.log4j.core.async.AsyncLoggerContext@1a28371]...
2016-06-08 00:18:23,186 localhost-startStop-2 TRACE Unregistering 1 MBeans: [org.apache.logging.log4j2:type=AsyncContext[]]
2016-06-08 00:18:23,186 localhost-startStop-2 TRACE Unregistering 1 MBeans: [org.apache.logging.log4j2:type=AsyncContext[],component=StatusLogger]
2016-06-08 00:18:23,187 localhost-startStop-2 TRACE Unregistering 1 MBeans: [org.apache.logging.log4j2:type=AsyncContext[],component=ContextSelector]
2016-06-08 00:18:23,188 localhost-startStop-2 TRACE Unregistering 4 MBeans: [org.apache.logging.log4j2:type=AsyncContext[],component=Loggers,name=, org.apache.logging.log4j2:type=AsyncContext[],component=Loggers,name=org.hibernate, org.apache.logging.log4j2:type=AsyncContext[],component=Loggers,name=org.springframework, org.apache.logging.log4j2:type=AsyncContext[],component=Loggers,name=org.tomcat.logging]
2016-06-08 00:18:23,189 localhost-startStop-2 TRACE Unregistering 3 MBeans: [org.apache.logging.log4j2:type=AsyncContext[],component=Appenders,name=Console, org.apache.logging.log4j2:type=AsyncContext[],component=Appenders,name=AppLog, org.apache.logging.log4j2:type=AsyncContext[],component=Appenders,name=HibernateLog]
2016-06-08 00:18:23,189 localhost-startStop-2 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext[],component=AsyncAppenders,name=*'
2016-06-08 00:18:23,190 localhost-startStop-2 TRACE Unregistering 1 MBeans: [org.apache.logging.log4j2:type=AsyncContext[],component=AsyncLoggerRingBuffer]
2016-06-08 00:18:23,190 localhost-startStop-2 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext[],component=Loggers,name=*,subtype=RingBuffer'
2016-06-08 00:18:23,191 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.ui.context.support.ResourceBundleThemeSource uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,191 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.core.env.MapPropertySource uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,191 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.service.jdbc.dialect.internal.AbstractDialectResolver uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,192 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.tomcat.logging.service.impl.ContactServiceImpl uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,192 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.core.LocalVariableTableParameterNameDiscoverer uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,192 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.web.context.support.XmlWebApplicationContext uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,193 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.id.factory.internal.DefaultIdentifierGeneratorFactory uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,193 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.beans.factory.xml.DefaultBeanDefinitionDocumentReader uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,193 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.jdbc.datasource.SimpleDriverDataSource uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,193 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.jndi.JndiLocatorDelegate uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,193 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.SQL uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,194 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.context.support.DefaultLifecycleProcessor uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,194 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.jdbc.datasource.init.ScriptUtils uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,194 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.cfg.Configuration uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,194 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.core.type.filter.AnnotationTypeFilter uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,194 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.beans.factory.support.DefaultListableBeanFactory uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,194 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.beans.factory.support.DisposableBeanAdapter uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,195 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.internal.CacheImpl uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,195 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.cfg.beanvalidation.BeanValidationIntegrator uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,195 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.orm.hibernate4.HibernateTransactionManager uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,195 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.core.io.support.PathMatchingResourcePatternResolver uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,195 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.web.context.ContextLoader uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,196 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.internal.SessionFactoryRegistry uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,196 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.dialect.function.TemplateRenderer uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,196 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.jdbc.datasource.embedded.H2EmbeddedDatabaseConfigurer uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,196 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.beans.factory.xml.PluggableSchemaResolver uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,196 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.jdbc.config.SortedResourcesFactoryBean uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,197 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.engine.query.spi.QueryPlanCache uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,197 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.ui.context.support.UiApplicationContextUtils uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,197 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.service.internal.AbstractServiceRegistryImpl uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,197 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.transaction.support.TransactionSynchronizationManager uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,197 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.beans.factory.annotation.InjectionMetadata uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,198 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.context.annotation.ClassPathBeanDefinitionScanner uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,198 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.core.io.support.ResourceArrayPropertyEditor uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,198 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.engine.jdbc.internal.TypeInfoExtracter uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,198 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.web.context.support.StandardServletEnvironment uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,198 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.core.SpringProperties uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,198 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.dialect.H2Dialect uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,199 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.beans.ExtendedBeanInfo uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,199 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.MultiTenancyStrategy uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,199 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.cfg.AnnotationBinder uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,199 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.service.config.internal.ConfigurationServiceImpl uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,199 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.service.classloading.internal.ClassLoaderServiceImpl uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,199 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.stat.internal.StatisticsInitiator uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,200 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.beans.BeanUtils uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,200 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.tomcat.logging.dao.impl.ContactDaoImpl uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,200 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.context.annotation.ConfigurationClassPostProcessor uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,200 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.type.BasicTypeRegistry uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,200 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.context.annotation.CommonAnnotationBeanPostProcessor uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,201 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.core.env.PropertySource$ComparisonPropertySource uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,201 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.core.env.StandardEnvironment uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,201 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.service.jndi.internal.JndiServiceImpl uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,201 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.service.jdbc.dialect.internal.DialectResolverSet uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,201 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.core.type.classreading.RecursiveAnnotationArrayVisitor uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,201 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.type.DbTimestampType uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,202 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.service.jdbc.connections.internal.ConnectionProviderInitiator uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,202 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.engine.jdbc.spi.SqlExceptionHelper uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,202 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.beans.factory.parsing.FailFastProblemReporter uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,202 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.transaction.interceptor.TransactionInterceptor uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,202 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.cfg.SettingsFactory uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,203 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.beans.TypeConverterDelegate uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,203 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.beans.factory.xml.DefaultDocumentLoader uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,203 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.jdbc.datasource.DataSourceUtils uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,203 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.jndi.JndiTemplate uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,203 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.aop.framework.autoproxy.BeanFactoryAdvisorRetrievalHelper uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,203 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.jdbc.datasource.embedded.EmbeddedDatabaseFactory uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,204 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.dialect.Dialect uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,204 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.Version uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,204 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.context.support.DelegatingMessageSource uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,204 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.beans.factory.xml.BeanDefinitionParserDelegate uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,204 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.hql.spi.TemporaryTableBulkIdStrategy uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,204 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.id.UUIDGenerator uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,205 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.service.spi.ServiceBinding uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,205 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.beans.factory.xml.XmlBeanDefinitionReader uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,205 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.context.event.EventListenerMethodProcessor uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,205 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.internal.util.xml.DTDEntityResolver uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,205 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.orm.hibernate4.SessionFactoryUtils uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,206 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.beans.factory.xml.DefaultNamespaceHandlerResolver uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,206 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.service.jdbc.connections.internal.MultiTenantConnectionProviderInitiator uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,206 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.core.env.PropertySourcesPropertyResolver uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,206 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.engine.jdbc.internal.LobCreatorBuilder uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,206 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.engine.transaction.internal.TransactionFactoryInitiator uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,206 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.beans.factory.xml.BeansDtdResolver uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,207 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.property.BasicPropertyAccessor uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,207 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.context.annotation.ConfigurationClassUtils uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,207 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.web.context.support.ServletContextPropertySource uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,207 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.beans.factory.config.PropertiesFactoryBean uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,207 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.jboss.logging uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,208 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.context.annotation.ConfigurationClassBeanDefinitionReader uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,208 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.annotations.common.Version uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,208 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.context.support.PostProcessorRegistrationDelegate$BeanPostProcessorChecker uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,208 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.beans.factory.xml.ResourceEntityResolver uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,208 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.hql.internal.ast.ASTQueryTranslatorFactory uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,208 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.service.jta.platform.internal.JtaPlatformInitiator uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,209 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.cfg.EJB3DTDEntityResolver uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,209 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.transaction.annotation.AnnotationTransactionAttributeSource uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,209 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.jndi.JndiPropertySource uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,209 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.bytecode.internal.javassist.BytecodeProviderImpl uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,209 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.beans.AbstractNestablePropertyAccessor uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,210 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.cfg.Environment uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,210 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.internal.SessionFactoryImpl uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,210 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.engine.jdbc.internal.JdbcServicesImpl uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,210 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.core.io.support.SpringFactoriesLoader uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,210 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.aop.framework.JdkDynamicAopProxy uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,210 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.service.ServiceRegistryBuilder uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,211 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.stat.internal.ConcurrentStatisticsImpl uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,211 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.util.PropertyPlaceholderHelper uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,211 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.cfg.annotations.reflection.XMLContext uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,211 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.core.env.SystemEnvironmentPropertySource uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,211 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.type.TypeFactory uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,212 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.context.support.PostProcessorRegistrationDelegate$ApplicationListenerDetector uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,212 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.jndi.support.SimpleJndiBeanFactory uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,212 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.service.jdbc.dialect.internal.StandardDialectResolver uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,212 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.transaction.interceptor.RuleBasedTransactionAttribute uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,212 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.context.annotation.ConfigurationClassParser uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,212 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.loader.BatchFetchStyle uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,213 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.transaction.support.TransactionSynchronizationUtils uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,213 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.core.type.classreading.AnnotationAttributesReadingVisitor uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,213 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.aop.framework.autoproxy.InfrastructureAdvisorAutoProxyCreator uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,213 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.internal.util.ConfigHelper uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,213 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,214 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.beans.CachedIntrospectionResults uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,214 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.hibernate.integrator.internal.IntegratorServiceImpl uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,214 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.core.env.PropertySource$StubPropertySource uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,214 localhost-startStop-2 TRACE [AsyncContext[]] AsyncLogger org.springframework.web.context.support.ServletContextResourcePatternResolver uses org.apache.logging.log4j.core.util.DummyNanoClock@206b5c17.
2016-06-08 00:18:23,214 localhost-startStop-2 TRACE Stopping XmlConfiguration[location=C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml]...
2016-06-08 00:18:23,215 localhost-startStop-2 TRACE XmlConfiguration notified 5 ReliabilityStrategies that config will be stopped.
2016-06-08 00:18:23,215 localhost-startStop-2 TRACE XmlConfiguration stopping 4 LoggerConfigs.
2016-06-08 00:18:23,215 localhost-startStop-2 TRACE XmlConfiguration stopping root LoggerConfig.
2016-06-08 00:18:23,215 localhost-startStop-2 TRACE XmlConfiguration notifying ReliabilityStrategies that appenders will be stopped.
2016-06-08 00:18:23,215 localhost-startStop-2 TRACE XmlConfiguration stopping remaining Appenders.
2016-06-08 00:18:23,215 localhost-startStop-2 DEBUG Shutting down RollingRandomAccessFileManager C:\system\tomcat\Unnamed_TestLogging_2/logs/oreo-hibernate.log
2016-06-08 00:18:23,237 localhost-startStop-2 DEBUG Shutting down OutputStreamManager SYSTEM_OUT.false
2016-06-08 00:18:23,237 localhost-startStop-2 DEBUG Shutting down RollingRandomAccessFileManager C:\system\tomcat\Unnamed_TestLogging_2/logs/oreo.log
2016-06-08 00:18:23,256 localhost-startStop-2 TRACE XmlConfiguration stopped 3 remaining Appenders.
2016-06-08 00:18:23,257 localhost-startStop-2 TRACE XmlConfiguration cleaning Appenders from 5 LoggerConfigs.
2016-06-08 00:18:23,257 localhost-startStop-2 DEBUG Stopping Log4j2Scheduled threads.
2016-06-08 00:18:23,258 localhost-startStop-2 DEBUG Stopped XmlConfiguration[location=C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml] OK
2016-06-08 00:18:23,260 localhost-startStop-2 DEBUG Stopped LoggerContext[name=AsyncContext[], org.apache.logging.log4j.core.async.AsyncLoggerContext@1a28371]...
2016-06-08 00:18:23,278 localhost-startStop-2 DEBUG PluginManager 'Converter' found 41 plugins
2016-06-08 00:18:23,282 localhost-startStop-2 DEBUG Jansi is not installed, cannot find org.fusesource.jansi.WindowsAnsiOutputStream
2016-06-08 00:18:23,282 localhost-startStop-2 DEBUG Starting OutputStreamManager SYSTEM_OUT.false-3
2016-06-08 00:18:23,283 localhost-startStop-2 TRACE [AsyncContext@35d61702] AsyncLoggerDisruptor creating new disruptor for this context.
2016-06-08 00:18:23,283 localhost-startStop-2 TRACE property AsyncLogger.WaitStrategy=TIMEOUT
2016-06-08 00:18:23,294 localhost-startStop-2 DEBUG [AsyncContext@35d61702] Starting AsyncLogger disruptor for this context with ringbufferSize=262144, waitStrategy=TimeoutBlockingWaitStrategy, exceptionHandler=org.apache.logging.log4j.core.async.AsyncLoggerDefaultExceptionHandler@32302995...
2016-06-08 00:18:23,294 localhost-startStop-2 TRACE [AsyncContext@35d61702] AsyncLoggers use a threadlocal translator
2016-06-08 00:18:23,294 localhost-startStop-2 DEBUG Starting LoggerContext[name=AsyncContext@35d61702, org.apache.logging.log4j.core.async.AsyncLoggerContext@77fa0ef0]...
2016-06-08 00:18:23,295 localhost-startStop-2 DEBUG Reconfiguration started for context[name=AsyncContext@35d61702] at URI null (org.apache.logging.log4j.core.async.AsyncLoggerContext@77fa0ef0) with optional ClassLoader: null
2016-06-08 00:18:23,295 localhost-startStop-2 DEBUG Using configurationFactory org.apache.logging.log4j.core.config.ConfigurationFactory$Factory@41eaec19
2016-06-08 00:18:23,296 localhost-startStop-2 TRACE Trying to find [log4j2-testAsyncContext@35d61702.properties] using context class loader WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
.
2016-06-08 00:18:23,297 localhost-startStop-2 TRACE Trying to find [log4j2-testAsyncContext@35d61702.properties] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,298 localhost-startStop-2 TRACE Trying to find [log4j2-testAsyncContext@35d61702.properties] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,299 localhost-startStop-2 TRACE Trying to find [log4j2-testAsyncContext@35d61702.properties] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,299 localhost-startStop-2 TRACE Trying to find [log4j2-testAsyncContext@35d61702.yml] using context class loader WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
.
2016-06-08 00:18:23,300 localhost-startStop-2 TRACE Trying to find [log4j2-testAsyncContext@35d61702.yml] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,300 localhost-startStop-2 TRACE Trying to find [log4j2-testAsyncContext@35d61702.yml] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,301 localhost-startStop-2 TRACE Trying to find [log4j2-testAsyncContext@35d61702.yml] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,301 localhost-startStop-2 TRACE Trying to find [log4j2-testAsyncContext@35d61702.yaml] using context class loader WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
.
2016-06-08 00:18:23,302 localhost-startStop-2 TRACE Trying to find [log4j2-testAsyncContext@35d61702.yaml] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,302 localhost-startStop-2 TRACE Trying to find [log4j2-testAsyncContext@35d61702.yaml] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,303 localhost-startStop-2 TRACE Trying to find [log4j2-testAsyncContext@35d61702.yaml] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,303 localhost-startStop-2 TRACE Trying to find [log4j2-testAsyncContext@35d61702.json] using context class loader WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
.
2016-06-08 00:18:23,304 localhost-startStop-2 TRACE Trying to find [log4j2-testAsyncContext@35d61702.json] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,304 localhost-startStop-2 TRACE Trying to find [log4j2-testAsyncContext@35d61702.json] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,305 localhost-startStop-2 TRACE Trying to find [log4j2-testAsyncContext@35d61702.json] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,305 localhost-startStop-2 TRACE Trying to find [log4j2-testAsyncContext@35d61702.jsn] using context class loader WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
.
2016-06-08 00:18:23,306 localhost-startStop-2 TRACE Trying to find [log4j2-testAsyncContext@35d61702.jsn] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,306 localhost-startStop-2 TRACE Trying to find [log4j2-testAsyncContext@35d61702.jsn] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,307 localhost-startStop-2 TRACE Trying to find [log4j2-testAsyncContext@35d61702.jsn] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,307 localhost-startStop-2 TRACE Trying to find [log4j2-testAsyncContext@35d61702.xml] using context class loader WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
.
2016-06-08 00:18:23,308 localhost-startStop-2 TRACE Trying to find [log4j2-testAsyncContext@35d61702.xml] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,308 localhost-startStop-2 TRACE Trying to find [log4j2-testAsyncContext@35d61702.xml] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,309 localhost-startStop-2 TRACE Trying to find [log4j2-testAsyncContext@35d61702.xml] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,309 localhost-startStop-2 TRACE Trying to find [log4j2-test.properties] using context class loader WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
.
2016-06-08 00:18:23,310 localhost-startStop-2 TRACE Trying to find [log4j2-test.properties] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,310 localhost-startStop-2 TRACE Trying to find [log4j2-test.properties] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,311 localhost-startStop-2 TRACE Trying to find [log4j2-test.properties] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,311 localhost-startStop-2 TRACE Trying to find [log4j2-test.yml] using context class loader WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
.
2016-06-08 00:18:23,312 localhost-startStop-2 TRACE Trying to find [log4j2-test.yml] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,312 localhost-startStop-2 TRACE Trying to find [log4j2-test.yml] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,313 localhost-startStop-2 TRACE Trying to find [log4j2-test.yml] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,313 localhost-startStop-2 TRACE Trying to find [log4j2-test.yaml] using context class loader WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
.
2016-06-08 00:18:23,313 localhost-startStop-2 TRACE Trying to find [log4j2-test.yaml] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,313 localhost-startStop-2 TRACE Trying to find [log4j2-test.yaml] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,314 localhost-startStop-2 TRACE Trying to find [log4j2-test.yaml] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,314 localhost-startStop-2 TRACE Trying to find [log4j2-test.json] using context class loader WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
.
2016-06-08 00:18:23,314 localhost-startStop-2 TRACE Trying to find [log4j2-test.json] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,315 localhost-startStop-2 TRACE Trying to find [log4j2-test.json] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,315 localhost-startStop-2 TRACE Trying to find [log4j2-test.json] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,315 localhost-startStop-2 TRACE Trying to find [log4j2-test.jsn] using context class loader WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
.
2016-06-08 00:18:23,316 localhost-startStop-2 TRACE Trying to find [log4j2-test.jsn] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,316 localhost-startStop-2 TRACE Trying to find [log4j2-test.jsn] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,316 localhost-startStop-2 TRACE Trying to find [log4j2-test.jsn] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,317 localhost-startStop-2 TRACE Trying to find [log4j2-test.xml] using context class loader WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
.
2016-06-08 00:18:23,317 localhost-startStop-2 TRACE Trying to find [log4j2-test.xml] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,317 localhost-startStop-2 TRACE Trying to find [log4j2-test.xml] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,318 localhost-startStop-2 TRACE Trying to find [log4j2-test.xml] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,318 localhost-startStop-2 TRACE Trying to find [log4j2AsyncContext@35d61702.properties] using context class loader WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
.
2016-06-08 00:18:23,318 localhost-startStop-2 TRACE Trying to find [log4j2AsyncContext@35d61702.properties] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,319 localhost-startStop-2 TRACE Trying to find [log4j2AsyncContext@35d61702.properties] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,319 localhost-startStop-2 TRACE Trying to find [log4j2AsyncContext@35d61702.properties] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,319 localhost-startStop-2 TRACE Trying to find [log4j2AsyncContext@35d61702.yml] using context class loader WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
.
2016-06-08 00:18:23,319 localhost-startStop-2 TRACE Trying to find [log4j2AsyncContext@35d61702.yml] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,320 localhost-startStop-2 TRACE Trying to find [log4j2AsyncContext@35d61702.yml] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,320 localhost-startStop-2 TRACE Trying to find [log4j2AsyncContext@35d61702.yml] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,320 localhost-startStop-2 TRACE Trying to find [log4j2AsyncContext@35d61702.yaml] using context class loader WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
.
2016-06-08 00:18:23,320 localhost-startStop-2 TRACE Trying to find [log4j2AsyncContext@35d61702.yaml] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,321 localhost-startStop-2 TRACE Trying to find [log4j2AsyncContext@35d61702.yaml] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,321 localhost-startStop-2 TRACE Trying to find [log4j2AsyncContext@35d61702.yaml] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,321 localhost-startStop-2 TRACE Trying to find [log4j2AsyncContext@35d61702.json] using context class loader WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
.
2016-06-08 00:18:23,321 localhost-startStop-2 TRACE Trying to find [log4j2AsyncContext@35d61702.json] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,322 localhost-startStop-2 TRACE Trying to find [log4j2AsyncContext@35d61702.json] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,322 localhost-startStop-2 TRACE Trying to find [log4j2AsyncContext@35d61702.json] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,322 localhost-startStop-2 TRACE Trying to find [log4j2AsyncContext@35d61702.jsn] using context class loader WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
.
2016-06-08 00:18:23,323 localhost-startStop-2 TRACE Trying to find [log4j2AsyncContext@35d61702.jsn] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,323 localhost-startStop-2 TRACE Trying to find [log4j2AsyncContext@35d61702.jsn] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,323 localhost-startStop-2 TRACE Trying to find [log4j2AsyncContext@35d61702.jsn] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,323 localhost-startStop-2 TRACE Trying to find [log4j2AsyncContext@35d61702.xml] using context class loader WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
.
2016-06-08 00:18:23,324 localhost-startStop-2 TRACE Trying to find [log4j2AsyncContext@35d61702.xml] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,324 localhost-startStop-2 TRACE Trying to find [log4j2AsyncContext@35d61702.xml] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,324 localhost-startStop-2 TRACE Trying to find [log4j2AsyncContext@35d61702.xml] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,325 localhost-startStop-2 TRACE Trying to find [log4j2.properties] using context class loader WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
.
2016-06-08 00:18:23,325 localhost-startStop-2 TRACE Trying to find [log4j2.properties] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,325 localhost-startStop-2 TRACE Trying to find [log4j2.properties] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,325 localhost-startStop-2 TRACE Trying to find [log4j2.properties] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,326 localhost-startStop-2 TRACE Trying to find [log4j2.yml] using context class loader WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
.
2016-06-08 00:18:23,326 localhost-startStop-2 TRACE Trying to find [log4j2.yml] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,326 localhost-startStop-2 TRACE Trying to find [log4j2.yml] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,326 localhost-startStop-2 TRACE Trying to find [log4j2.yml] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,327 localhost-startStop-2 TRACE Trying to find [log4j2.yaml] using context class loader WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
.
2016-06-08 00:18:23,327 localhost-startStop-2 TRACE Trying to find [log4j2.yaml] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,327 localhost-startStop-2 TRACE Trying to find [log4j2.yaml] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,327 localhost-startStop-2 TRACE Trying to find [log4j2.yaml] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,328 localhost-startStop-2 TRACE Trying to find [log4j2.json] using context class loader WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
.
2016-06-08 00:18:23,328 localhost-startStop-2 TRACE Trying to find [log4j2.json] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,328 localhost-startStop-2 TRACE Trying to find [log4j2.json] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,328 localhost-startStop-2 TRACE Trying to find [log4j2.json] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,329 localhost-startStop-2 TRACE Trying to find [log4j2.jsn] using context class loader WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
.
2016-06-08 00:18:23,329 localhost-startStop-2 TRACE Trying to find [log4j2.jsn] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,329 localhost-startStop-2 TRACE Trying to find [log4j2.jsn] using WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
 class loader.
2016-06-08 00:18:23,329 localhost-startStop-2 TRACE Trying to find [log4j2.jsn] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,330 localhost-startStop-2 TRACE Trying to find [log4j2.xml] using context class loader WebappClassLoader
  context: 
  delegate: false
  repositories:
    /WEB-INF/classes/
----------> Parent Classloader:
java.net.URLClassLoader@26e35d06
.
2016-06-08 00:18:23,333 localhost-startStop-2 DEBUG Initializing configuration XmlConfiguration[location=C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml]
2016-06-08 00:18:23,334 localhost-startStop-2 DEBUG Installed script engines
2016-06-08 00:18:23,335 localhost-startStop-2 DEBUG Mozilla Rhino Version: 1.7 release 3 PRERELEASE, Language: ECMAScript, Threading: MULTITHREADED, Compile: true, Names: {js, rhino, JavaScript, javascript, ECMAScript, ecmascript}
2016-06-08 00:18:23,335 localhost-startStop-2 DEBUG PluginManager 'Core' found 100 plugins
2016-06-08 00:18:23,335 localhost-startStop-2 DEBUG PluginManager 'Level' found 0 plugins
2016-06-08 00:18:23,336 localhost-startStop-2 DEBUG Starting 1 Log4j2Scheduled threads
2016-06-08 00:18:23,336 localhost-startStop-2 DEBUG PluginManager 'Lookup' found 14 plugins
2016-06-08 00:18:23,336 localhost-startStop-2 DEBUG Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
2016-06-08 00:18:23,337 localhost-startStop-2 DEBUG PatternLayout$Builder(pattern="%d [%-5p] [%c]: %m%n", PatternSelector=null, Configuration(C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml), Replace=null, charset="null", alwaysWriteExceptions="null", noConsoleNoAnsi="null", header="null", footer="null")
2016-06-08 00:18:23,337 localhost-startStop-2 DEBUG PluginManager 'Converter' found 41 plugins
2016-06-08 00:18:23,338 localhost-startStop-2 DEBUG Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.ConsoleAppender].
2016-06-08 00:18:23,338 localhost-startStop-2 DEBUG PluginManager 'Converter' found 41 plugins
2016-06-08 00:18:23,339 localhost-startStop-2 DEBUG Jansi is not installed, cannot find org.fusesource.jansi.WindowsAnsiOutputStream
2016-06-08 00:18:23,340 localhost-startStop-2 DEBUG Starting OutputStreamManager SYSTEM_OUT.false-4
2016-06-08 00:18:23,340 localhost-startStop-2 DEBUG ConsoleAppender$Builder(PatternLayout(%d [%-5p] [%c]: %m%n), Filter=null, target="SYSTEM_OUT", name="Console", follow="null", ignoreExceptions="null")
2016-06-08 00:18:23,341 localhost-startStop-2 DEBUG Jansi is not installed, cannot find org.fusesource.jansi.WindowsAnsiOutputStream
2016-06-08 00:18:23,341 localhost-startStop-2 DEBUG Starting OutputStreamManager SYSTEM_OUT.false
2016-06-08 00:18:23,341 localhost-startStop-2 DEBUG Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
2016-06-08 00:18:23,342 localhost-startStop-2 DEBUG PatternLayout$Builder(pattern="[%d{dd/MM/yy HH:mm:ss:sss}] [%-5p] [%c]: %m%n", PatternSelector=null, Configuration(C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml), Replace=null, charset="null", alwaysWriteExceptions="null", noConsoleNoAnsi="null", header="null", footer="null")
2016-06-08 00:18:23,342 localhost-startStop-2 DEBUG Building Plugin[name=SizeBasedTriggeringPolicy, class=org.apache.logging.log4j.core.appender.rolling.SizeBasedTriggeringPolicy].
2016-06-08 00:18:23,343 localhost-startStop-2 DEBUG createPolicy(size="250 MB")
2016-06-08 00:18:23,343 localhost-startStop-2 DEBUG Building Plugin[name=Policies, class=org.apache.logging.log4j.core.appender.rolling.CompositeTriggeringPolicy].
2016-06-08 00:18:23,343 localhost-startStop-2 DEBUG createPolicy(={SizeBasedTriggeringPolicy(size=262144000)})
2016-06-08 00:18:23,343 localhost-startStop-2 DEBUG Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.RollingRandomAccessFileAppender].
2016-06-08 00:18:23,344 localhost-startStop-2 DEBUG createAppender(fileName="C:\system\tomcat\Unnamed_TestLogging_2/logs/oreo.log", filePattern="C:\system\tomcat\Unnamed_TestLogging_2/logs/logs/oreo-%d{yyyy-MM-dd_HHmmss-SSS}.log.gz", append="null", name="AppLog", immediateFlush="true", bufferSize="null", Policies(CompositeTriggeringPolicy(policies=[SizeBasedTriggeringPolicy(size=262144000)])), Strategy=null, PatternLayout([%d{dd/MM/yy HH:mm:ss:sss}] [%-5p] [%c]: %m%n), Filter=null, ignoreExceptions="null", advertise="null", advertiseURI="null", Configuration(C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml))
2016-06-08 00:18:23,345 localhost-startStop-2 TRACE RandomAccessFile C:\system\tomcat\Unnamed_TestLogging_2/logs/oreo.log seek to 25081
2016-06-08 00:18:23,345 localhost-startStop-2 DEBUG Starting RollingRandomAccessFileManager C:\system\tomcat\Unnamed_TestLogging_2/logs/oreo.log
2016-06-08 00:18:23,345 localhost-startStop-2 DEBUG PluginManager 'FileConverter' found 2 plugins
2016-06-08 00:18:23,346 localhost-startStop-2 DEBUG Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
2016-06-08 00:18:23,346 localhost-startStop-2 DEBUG PatternLayout$Builder(pattern="[%d{dd/MM/yy HH:mm:ss:sss}] [%-5p] [%c]: %m%n", PatternSelector=null, Configuration(C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml), Replace=null, charset="null", alwaysWriteExceptions="null", noConsoleNoAnsi="null", header="null", footer="null")
2016-06-08 00:18:23,347 localhost-startStop-2 DEBUG Building Plugin[name=SizeBasedTriggeringPolicy, class=org.apache.logging.log4j.core.appender.rolling.SizeBasedTriggeringPolicy].
2016-06-08 00:18:23,347 localhost-startStop-2 DEBUG createPolicy(size="250 MB")
2016-06-08 00:18:23,347 localhost-startStop-2 DEBUG Building Plugin[name=Policies, class=org.apache.logging.log4j.core.appender.rolling.CompositeTriggeringPolicy].
2016-06-08 00:18:23,347 localhost-startStop-2 DEBUG createPolicy(={SizeBasedTriggeringPolicy(size=262144000)})
2016-06-08 00:18:23,348 localhost-startStop-2 DEBUG Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.RollingRandomAccessFileAppender].
2016-06-08 00:18:23,349 localhost-startStop-2 DEBUG createAppender(fileName="C:\system\tomcat\Unnamed_TestLogging_2/logs/oreo-hibernate.log", filePattern="C:\system\tomcat\Unnamed_TestLogging_2/logs/logs/oreo-hibernate-%d{yyyy-MM-dd_HHmmss-SSS}.log.gz", append="null", name="HibernateLog", immediateFlush="true", bufferSize="null", Policies(CompositeTriggeringPolicy(policies=[SizeBasedTriggeringPolicy(size=262144000)])), Strategy=null, PatternLayout([%d{dd/MM/yy HH:mm:ss:sss}] [%-5p] [%c]: %m%n), Filter=null, ignoreExceptions="null", advertise="null", advertiseURI="null", Configuration(C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml))
2016-06-08 00:18:23,349 localhost-startStop-2 TRACE RandomAccessFile C:\system\tomcat\Unnamed_TestLogging_2/logs/oreo-hibernate.log seek to 7128
2016-06-08 00:18:23,349 localhost-startStop-2 DEBUG Starting RollingRandomAccessFileManager C:\system\tomcat\Unnamed_TestLogging_2/logs/oreo-hibernate.log
2016-06-08 00:18:23,350 localhost-startStop-2 DEBUG PluginManager 'FileConverter' found 2 plugins
2016-06-08 00:18:23,350 localhost-startStop-2 DEBUG Building Plugin[name=appenders, class=org.apache.logging.log4j.core.config.AppendersPlugin].
2016-06-08 00:18:23,350 localhost-startStop-2 DEBUG createAppenders(={Console, AppLog, HibernateLog})
2016-06-08 00:18:23,350 localhost-startStop-2 DEBUG Building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef].
2016-06-08 00:18:23,351 localhost-startStop-2 DEBUG createAppenderRef(ref="AppLog", level="null", Filter=null)
2016-06-08 00:18:23,351 localhost-startStop-2 DEBUG Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig].
2016-06-08 00:18:23,352 localhost-startStop-2 DEBUG createLogger(additivity="false", level="DEBUG", name="org.tomcat.logging", includeLocation="null", ={AppLog}, ={}, Configuration(C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml), Filter=null)
2016-06-08 00:18:23,352 localhost-startStop-2 DEBUG Building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef].
2016-06-08 00:18:23,352 localhost-startStop-2 DEBUG createAppenderRef(ref="AppLog", level="null", Filter=null)
2016-06-08 00:18:23,353 localhost-startStop-2 DEBUG Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig].
2016-06-08 00:18:23,354 localhost-startStop-2 DEBUG createLogger(additivity="false", level="INFO", name="org.springframework", includeLocation="null", ={AppLog}, ={}, Configuration(C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml), Filter=null)
2016-06-08 00:18:23,354 localhost-startStop-2 DEBUG Building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef].
2016-06-08 00:18:23,354 localhost-startStop-2 DEBUG createAppenderRef(ref="HibernateLog", level="null", Filter=null)
2016-06-08 00:18:23,354 localhost-startStop-2 DEBUG Building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef].
2016-06-08 00:18:23,355 localhost-startStop-2 DEBUG createAppenderRef(ref="AppLog", level="null", Filter=null)
2016-06-08 00:18:23,355 localhost-startStop-2 DEBUG Building Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig].
2016-06-08 00:18:23,356 localhost-startStop-2 DEBUG createLogger(additivity="false", level="INFO", name="org.hibernate", includeLocation="null", ={HibernateLog, AppLog}, ={}, Configuration(C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml), Filter=null)
2016-06-08 00:18:23,356 localhost-startStop-2 DEBUG Building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef].
2016-06-08 00:18:23,356 localhost-startStop-2 DEBUG createAppenderRef(ref="Console", level="null", Filter=null)
2016-06-08 00:18:23,356 localhost-startStop-2 DEBUG Building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef].
2016-06-08 00:18:23,357 localhost-startStop-2 DEBUG createAppenderRef(ref="AppLog", level="null", Filter=null)
2016-06-08 00:18:23,357 localhost-startStop-2 DEBUG Building Plugin[name=root, class=org.apache.logging.log4j.core.config.LoggerConfig$RootLogger].
2016-06-08 00:18:23,358 localhost-startStop-2 DEBUG createLogger(additivity="null", level="DEBUG", includeLocation="null", ={Console, AppLog}, ={}, Configuration(C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml), Filter=null)
2016-06-08 00:18:23,358 localhost-startStop-2 DEBUG Building Plugin[name=loggers, class=org.apache.logging.log4j.core.config.LoggersPlugin].
2016-06-08 00:18:23,358 localhost-startStop-2 DEBUG createLoggers(={org.tomcat.logging, org.springframework, org.hibernate, root})
2016-06-08 00:18:23,358 localhost-startStop-2 DEBUG Configuration XmlConfiguration[location=C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml] initialized
2016-06-08 00:18:23,359 localhost-startStop-2 DEBUG Starting configuration XmlConfiguration[location=C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml]
2016-06-08 00:18:23,359 localhost-startStop-2 DEBUG Started configuration XmlConfiguration[location=C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml] OK.
2016-06-08 00:18:23,359 localhost-startStop-2 TRACE Stopping org.apache.logging.log4j.core.config.DefaultConfiguration@7a25d5eb...
2016-06-08 00:18:23,359 localhost-startStop-2 TRACE DefaultConfiguration notified 1 ReliabilityStrategies that config will be stopped.
2016-06-08 00:18:23,359 localhost-startStop-2 TRACE DefaultConfiguration stopping root LoggerConfig.
2016-06-08 00:18:23,360 localhost-startStop-2 TRACE DefaultConfiguration notifying ReliabilityStrategies that appenders will be stopped.
2016-06-08 00:18:23,360 localhost-startStop-2 TRACE DefaultConfiguration stopping remaining Appenders.
2016-06-08 00:18:23,360 localhost-startStop-2 DEBUG Shutting down OutputStreamManager SYSTEM_OUT.false-3
2016-06-08 00:18:23,360 localhost-startStop-2 TRACE DefaultConfiguration stopped 1 remaining Appenders.
2016-06-08 00:18:23,360 localhost-startStop-2 TRACE DefaultConfiguration cleaning Appenders from 1 LoggerConfigs.
2016-06-08 00:18:23,360 localhost-startStop-2 DEBUG Stopped org.apache.logging.log4j.core.config.DefaultConfiguration@7a25d5eb OK
2016-06-08 00:18:23,360 localhost-startStop-2 TRACE Reregistering MBeans after reconfigure. Selector=org.apache.logging.log4j.core.async.AsyncLoggerContextSelector@12c1f590
2016-06-08 00:18:23,361 localhost-startStop-2 TRACE Reregistering context (1/1): 'AsyncContext@35d61702' org.apache.logging.log4j.core.async.AsyncLoggerContext@77fa0ef0
2016-06-08 00:18:23,361 localhost-startStop-2 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext@35d61702'
2016-06-08 00:18:23,361 localhost-startStop-2 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext@35d61702,component=StatusLogger'
2016-06-08 00:18:23,361 localhost-startStop-2 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext@35d61702,component=ContextSelector'
2016-06-08 00:18:23,362 localhost-startStop-2 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext@35d61702,component=Loggers,name=*'
2016-06-08 00:18:23,362 localhost-startStop-2 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext@35d61702,component=Appenders,name=*'
2016-06-08 00:18:23,362 localhost-startStop-2 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext@35d61702,component=AsyncAppenders,name=*'
2016-06-08 00:18:23,362 localhost-startStop-2 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext@35d61702,component=AsyncLoggerRingBuffer'
2016-06-08 00:18:23,362 localhost-startStop-2 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=AsyncContext@35d61702,component=Loggers,name=*,subtype=RingBuffer'
2016-06-08 00:18:23,363 localhost-startStop-2 DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@35d61702
2016-06-08 00:18:23,363 localhost-startStop-2 DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@35d61702,component=AsyncLoggerRingBuffer
2016-06-08 00:18:23,364 localhost-startStop-2 DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@35d61702,component=StatusLogger
2016-06-08 00:18:23,364 localhost-startStop-2 DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@35d61702,component=ContextSelector
2016-06-08 00:18:23,364 localhost-startStop-2 DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@35d61702,component=Loggers,name=org.hibernate
2016-06-08 00:18:23,364 localhost-startStop-2 DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@35d61702,component=Loggers,name=
2016-06-08 00:18:23,365 localhost-startStop-2 DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@35d61702,component=Loggers,name=org.tomcat.logging
2016-06-08 00:18:23,365 localhost-startStop-2 DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@35d61702,component=Loggers,name=org.springframework
2016-06-08 00:18:23,365 localhost-startStop-2 DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@35d61702,component=Appenders,name=AppLog
2016-06-08 00:18:23,366 localhost-startStop-2 DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@35d61702,component=Appenders,name=Console
2016-06-08 00:18:23,366 localhost-startStop-2 DEBUG Registering MBean org.apache.logging.log4j2:type=AsyncContext@35d61702,component=Appenders,name=HibernateLog
2016-06-08 00:18:23,366 localhost-startStop-2 TRACE Using DummyNanoClock for nanosecond timestamps.
2016-06-08 00:18:23,366 localhost-startStop-2 DEBUG Reconfiguration complete for context[name=AsyncContext@35d61702] at URI C:\IdeaProjects\TestLogging\target\test-logging-1.0-SNAPSHOT\WEB-INF\classes\log4j2.xml (org.apache.logging.log4j.core.async.AsyncLoggerContext@77fa0ef0) with optional ClassLoader: null
2016-06-08 00:18:23,366 localhost-startStop-2 DEBUG Shutdown hook enabled. Registering a new one.
2016-06-08 00:18:23,367 localhost-startStop-2 DEBUG LoggerContext[name=AsyncContext@35d61702, org.apache.logging.log4j.core.async.AsyncLoggerContext@77fa0ef0] started OK.
Jun 08, 2016 12:18:23 AM org.apache.catalina.loader.WebappClassLoaderBase clearReferencesJdbc
SEVERE: The web application [] registered the JDBC driver [org.h2.Driver] but failed to unregister it when the web application was stopped. To prevent a memory leak, the JDBC Driver has been forcibly unregistered.
Jun 08, 2016 12:18:23 AM org.apache.catalina.loader.WebappClassLoaderBase clearReferencesThreads
SEVERE: The web application [] appears to have started a thread named [Log4j2-AsyncLogger[AsyncContext@35d61702]3] but has failed to stop it. This is very likely to create a memory leak.
Jun 08, 2016 12:18:23 AM org.apache.catalina.loader.WebappClassLoaderBase clearReferencesThreads
SEVERE: The web application [] appears to have started a thread named [Log4j2-Log4j2Scheduled-4] but has failed to stop it. This is very likely to create a memory leak.
Jun 08, 2016 12:18:23 AM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["http-apr-8080"]
Jun 08, 2016 12:18:23 AM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["ajp-apr-8009"]
Jun 08, 2016 12:18:23 AM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["http-apr-8080"]
Jun 08, 2016 12:18:23 AM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["ajp-apr-8009"]
Disconnected from server

As you can see in line with timestamp 2016-06-08 00:18:23,260 LoggerContext was stopped. Next step what Log4j2 doing is initialize new LoggerContext (2016-06-08 00:18:23,294) and when it done (2016-06-08 00:18:23,367) we have memory leak...

My log4j2.xml:

<?xml version="1.0" encoding="UTF-8" ?>
<Configuration status="TRACE" monitorInterval="60">
    <Appenders>
        <Console name="Console" target="SYSTEM_OUT">
            <PatternLayout pattern="%d [%-5p] [%c]: %m%n" />
        </Console>

        <RollingRandomAccessFile name="AppLog"
                fileName="${sys:catalina.base}/logs/test.log"
                immediateFlush="true"
                filePattern="${sys:catalina.base}/logs/logs/test-%d{yyyy-MM-dd_HHmmss-SSS}.log.gz">
            <PatternLayout pattern="[%d{dd/MM/yy HH:mm:ss:sss}] [%-5p] [%c]: %m%n" />
            <Policies>
                <SizeBasedTriggeringPolicy size="250 MB" />
            </Policies>
        </RollingRandomAccessFile>

        <RollingRandomAccessFile name="HibernateLog"
                                 fileName="${sys:catalina.base}/logs/test-hibernate.log"
                                 immediateFlush="true"
                                 filePattern="${sys:catalina.base}/logs/logs/test-hibernate-%d{yyyy-MM-dd_HHmmss-SSS}.log.gz">
            <PatternLayout pattern="[%d{dd/MM/yy HH:mm:ss:sss}] [%-5p] [%c]: %m%n" />
            <Policies>
                <SizeBasedTriggeringPolicy size="250 MB" />
            </Policies>
        </RollingRandomAccessFile>
    </Appenders>
    <Loggers>
        <Logger name="org.simple.logging" level="DEBUG" additivity="false">
            <AppenderRef ref="AppLog"/>
        </Logger>

        <Logger name="org.springframework" level="INFO" additivity="false">
            <AppenderRef ref="AppLog"/>
        </Logger>

        <Logger name="org.hibernate" level="INFO" additivity="false">
            <AppenderRef ref="HibernateLog"/>
            <AppenderRef ref="AppLog"/>
        </Logger>

        <Root level="DEBUG">
            <AppenderRef ref="Console" />
            <AppenderRef ref="AppLog"/>
        </Root>
    </Loggers>
</Configuration>