[ 
https://issues.apache.org/jira/browse/LOG4J2-1259?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=15319663#comment-15319663
 ] 

Vasia commented on LOG4J2-1259:
-------------------------------

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

Server log:
{CODE}
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-testasynccont...@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-testasynccont...@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-testasynccont...@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-testasynccont...@35d61702.yml] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,301 localhost-startStop-2 TRACE Trying to find 
[log4j2-testasynccont...@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-testasynccont...@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-testasynccont...@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-testasynccont...@35d61702.yaml] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,303 localhost-startStop-2 TRACE Trying to find 
[log4j2-testasynccont...@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-testasynccont...@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-testasynccont...@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-testasynccont...@35d61702.json] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,305 localhost-startStop-2 TRACE Trying to find 
[log4j2-testasynccont...@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-testasynccont...@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-testasynccont...@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-testasynccont...@35d61702.jsn] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,307 localhost-startStop-2 TRACE Trying to find 
[log4j2-testasynccont...@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-testasynccont...@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-testasynccont...@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-testasynccont...@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 
[log4j2asynccont...@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 
[log4j2asynccont...@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 
[log4j2asynccont...@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 
[log4j2asynccont...@35d61702.yml] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,320 localhost-startStop-2 TRACE Trying to find 
[log4j2asynccont...@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 
[log4j2asynccont...@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 
[log4j2asynccont...@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 
[log4j2asynccont...@35d61702.yaml] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,321 localhost-startStop-2 TRACE Trying to find 
[log4j2asynccont...@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 
[log4j2asynccont...@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 
[log4j2asynccont...@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 
[log4j2asynccont...@35d61702.json] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,322 localhost-startStop-2 TRACE Trying to find 
[log4j2asynccont...@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 
[log4j2asynccont...@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 
[log4j2asynccont...@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 
[log4j2asynccont...@35d61702.jsn] using ClassLoader.getSystemResource().
2016-06-08 00:18:23,323 localhost-startStop-2 TRACE Trying to find 
[log4j2asynccont...@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 
[log4j2asynccont...@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 
[log4j2asynccont...@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 
[log4j2asynccont...@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
{CODE}

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:
{CODE}
<?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>
{CODE}

> Log4j threads are leaking on tomcat shutdown
> --------------------------------------------
>
>                 Key: LOG4J2-1259
>                 URL: https://issues.apache.org/jira/browse/LOG4J2-1259
>             Project: Log4j 2
>          Issue Type: Bug
>          Components: Appenders
>    Affects Versions: 2.5
>            Reporter: Misagh Moayyed
>
> Running log4j2 v2.5 with disruptor 3.3.x. AsyncLoggers configured. log4j-web 
> also included in the web application deployed in Tomcat 8. The context 
> listener is correctly starting up and shutting down, catalina.properties does 
> not include the log4j*.jar entry. Yet I see:
> {code}
> 20-Jan-2016 14:59:26.322 WARNING [localhost-startStop-2] 
> org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The 
> web application [cas-server-webapp-4.3.0-SNAPSHOT] appears to have started a 
> thread named [Log4j2-Log4j2Scheduled-5] but has failed to stop it. This is 
> very likely to create a memory leak. Stack trace of thread:
>  sun.misc.Unsafe.park(Native Method)
>  java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
>  
> java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
>  
> java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093)
>  
> java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809)
>  java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
>  
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
>  
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
>  java.lang.Thread.run(Thread.java:745)
> 20-Jan-2016 14:59:26.336 WARNING [localhost-startStop-2] 
> org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The 
> web application [cas-server-webapp-4.3.0-SNAPSHOT] appears to have started a 
> thread named [Log4j2-AsyncLoggerConfig-6] but has failed to stop it. This is 
> very likely to create a memory leak. Stack trace of thread:
>  sun.misc.Unsafe.park(Native Method)
>  java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
>  
> java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
>  com.lmax.disruptor.BlockingWaitStrategy.waitFor(BlockingWaitStrategy.java:45)
>  
> com.lmax.disruptor.ProcessingSequenceBarrier.waitFor(ProcessingSequenceBarrier.java:56)
>  com.lmax.disruptor.BatchEventProcessor.run(BatchEventProcessor.java:124)
>  
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
>  
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
>  java.lang.Thread.run(Thread.java:745)
> {code}



--
This message was sent by Atlassian JIRA
(v6.3.4#6332)

---------------------------------------------------------------------
To unsubscribe, e-mail: log4j-dev-unsubscr...@logging.apache.org
For additional commands, e-mail: log4j-dev-h...@logging.apache.org

Reply via email to