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