[
https://issues.apache.org/jira/browse/LOG4J2-1259?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=15894974#comment-15894974
]
Veit Guna edited comment on LOG4J2-1259 at 3/3/17 8:24 PM:
-----------------------------------------------------------
I just ran into the same problem using 2.8.1.
Apache Tomcat/8.0.20, jdk 1.8.0_66-b18, Windows 7.
I've added the log4j-web.jar, switched to servlet spec 3.0 in my web.xml and
I'm using Spring in my web application.
I've added an AsyncAppender in front of my SmtpAppender. Starting the webapp
and directly stopping it leads to:
{code}
Mär 03, 2017 9:17:56 PM org.apache.catalina.loader.WebappClassLoaderBase
clearReferencesThreads
WARNUNG: The web application [acme-backend] appears to have started a thread
named [AsyncAppender-asyncSmtpAppender] but has failed to stop it. This is very
likely to create a memory leak. Stack trace of thread:
sun.misc.Unsafe.park(Native Method)
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
java.util.concurrent.ArrayBlockingQueue.take(ArrayBlockingQueue.java:403)
org.apache.logging.log4j.core.appender.AsyncAppender$AsyncThread.run(AsyncAppender.java:403)
{code}
Here's the output of status=trace
{code}
2017-03-03 21:09:58,741 localhost-startStop-1 DEBUG Starting
LoggerContext[name=Acme Backend] from configuration at null
2017-03-03 21:09:58,745 localhost-startStop-1 DEBUG Starting
LoggerContext[name=Acme Backend,
org.apache.logging.log4j.core.LoggerContext@554ad7a7] with configuration
XmlConfiguration[location=E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml]...
2017-03-03 21:09:58,746 localhost-startStop-1 DEBUG Shutdown hook enabled.
Registering a new one.
2017-03-03 21:09:58,748 localhost-startStop-1 DEBUG Initializing configuration
XmlConfiguration[location=E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml]
2017-03-03 21:09:58,755 localhost-startStop-1 DEBUG Installed script engines
2017-03-03 21:09:59,396 localhost-startStop-1 DEBUG Oracle Nashorn Version:
1.8.0_66, Language: ECMAScript, Threading: Not Thread Safe, Compile: true,
Names: {nashorn, Nashorn, js, JS, JavaScript, javascript, ECMAScript,
ecmascript}
2017-03-03 21:09:59,407 localhost-startStop-1 DEBUG Took 0,009540 seconds to
load 4 plugins from package com.acme.backend.service.rest.logging.log4j2
2017-03-03 21:09:59,407 localhost-startStop-1 DEBUG PluginManager 'Core' found
117 plugins
2017-03-03 21:09:59,407 localhost-startStop-1 DEBUG PluginManager 'Level' found
0 plugins
2017-03-03 21:09:59,425 localhost-startStop-1 DEBUG Building
Plugin[name=property, class=org.apache.logging.log4j.core.config.Property].
2017-03-03 21:09:59,443 localhost-startStop-1 TRACE TypeConverterRegistry
initializing.
2017-03-03 21:09:59,444 localhost-startStop-1 DEBUG PluginManager
'TypeConverter' found 26 plugins
2017-03-03 21:09:59,458 localhost-startStop-1 DEBUG
createProperty(name="logsPath", value="logs")
2017-03-03 21:09:59,458 localhost-startStop-1 DEBUG Building
Plugin[name=properties,
class=org.apache.logging.log4j.core.config.PropertiesPlugin].
2017-03-03 21:09:59,466 localhost-startStop-1 DEBUG
configureSubstitutor(={logsPath=logs},
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml))
2017-03-03 21:09:59,467 localhost-startStop-1 DEBUG PluginManager 'Lookup'
found 14 plugins
2017-03-03 21:09:59,467 localhost-startStop-1 DEBUG Building
Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
2017-03-03 21:09:59,477 localhost-startStop-1 DEBUG
PatternLayout$Builder(pattern="%d %-5p [%mdc{RQID}] [%c{1}] - %m%n",
PatternSelector=null,
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
Replace=null, charset="null", alwaysWriteExceptions="null",
disableAnsi="null", noConsoleNoAnsi="null", header="null", footer="null")
2017-03-03 21:09:59,478 localhost-startStop-1 DEBUG PluginManager 'Converter'
found 41 plugins
2017-03-03 21:09:59,507 localhost-startStop-1 DEBUG Building
Plugin[name=SizeBasedTriggeringPolicy,
class=org.apache.logging.log4j.core.appender.rolling.SizeBasedTriggeringPolicy].
2017-03-03 21:09:59,510 localhost-startStop-1 DEBUG createPolicy(size="10 MB")
2017-03-03 21:09:59,512 localhost-startStop-1 DEBUG Building
Plugin[name=Policies,
class=org.apache.logging.log4j.core.appender.rolling.CompositeTriggeringPolicy].
2017-03-03 21:09:59,513 localhost-startStop-1 DEBUG
createPolicy(={SizeBasedTriggeringPolicy(size=10485760)})
2017-03-03 21:09:59,513 localhost-startStop-1 DEBUG Building
Plugin[name=DefaultRolloverStrategy,
class=org.apache.logging.log4j.core.appender.rolling.DefaultRolloverStrategy].
2017-03-03 21:09:59,515 localhost-startStop-1 DEBUG createStrategy(max="5",
min="null", fileIndex="null", compressionLevel="null", ={},
stopCustomActionsOnError="true",
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml))
2017-03-03 21:09:59,516 localhost-startStop-1 DEBUG Building
Plugin[name=appender,
class=org.apache.logging.log4j.core.appender.RollingRandomAccessFileAppender].
2017-03-03 21:09:59,528 localhost-startStop-1 DEBUG
RollingRandomAccessFileAppender$Builder(fileName="logs/acme-backend.log",
filePattern="logs/acme-backend-%d{yyyy-MM-dd}-%i.log", append="null",
Policies(CompositeTriggeringPolicy(policies=[SizeBasedTriggeringPolicy(size=10485760)])),
DefaultRolloverStrategy(DefaultRolloverStrategy(min=1, max=5, useMax=true)),
advertise="null", advertiseURI="null", bufferedIo="null", bufferSize="null",
immediateFlush="true", ignoreExceptions="null", PatternLayout(%d %-5p
[%mdc{RQID}] [%c{1}] - %m%n), name="rollingFileAppender",
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
Filter=null)
2017-03-03 21:09:59,539 localhost-startStop-1 TRACE RandomAccessFile
logs/acme-backend.log seek to 9220221
2017-03-03 21:09:59,539 localhost-startStop-1 DEBUG Starting
RollingRandomAccessFileManager logs/acme-backend.log
2017-03-03 21:09:59,542 localhost-startStop-1 DEBUG PluginManager
'FileConverter' found 2 plugins
2017-03-03 21:09:59,544 localhost-startStop-1 DEBUG Setting prev file time to
2017-03-03T21:01:57.387+0100
2017-03-03 21:09:59,550 localhost-startStop-1 DEBUG Initializing triggering
policy
CompositeTriggeringPolicy(policies=[SizeBasedTriggeringPolicy(size=10485760)])
2017-03-03 21:09:59,550 localhost-startStop-1 DEBUG Building
Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
2017-03-03 21:09:59,550 localhost-startStop-1 DEBUG
PatternLayout$Builder(pattern="%d{HH:mm:ss,SSS} %-5p [%mdc{RQID}] [%c{1}] -
%m%n", PatternSelector=null,
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
Replace=null, charset="null", alwaysWriteExceptions="null",
disableAnsi="null", noConsoleNoAnsi="null", header="null", footer="null")
2017-03-03 21:09:59,551 localhost-startStop-1 DEBUG Building
Plugin[name=appender,
class=org.apache.logging.log4j.core.appender.ConsoleAppender].
2017-03-03 21:09:59,554 localhost-startStop-1 DEBUG
ConsoleAppender$Builder(target="SYSTEM_OUT", follow="null", direct="null",
bufferedIo="null", bufferSize="null", immediateFlush="null",
ignoreExceptions="null", PatternLayout(%d{HH:mm:ss,SSS} %-5p [%mdc{RQID}]
[%c{1}] - %m%n), name="consoleAppender",
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
Filter=null)
2017-03-03 21:09:59,556 localhost-startStop-1 DEBUG Jansi is not installed,
cannot find org.fusesource.jansi.WindowsAnsiOutputStream
2017-03-03 21:09:59,557 localhost-startStop-1 DEBUG Starting
OutputStreamManager SYSTEM_OUT.false.false
2017-03-03 21:09:59,557 localhost-startStop-1 DEBUG Building
Plugin[name=filter,
class=com.acme.backend.service.rest.logging.log4j2.RegExFileFilter].
2017-03-03 21:09:59,559 localhost-startStop-1 DEBUG
createFilter(filePath="log4j2-regex-filters.txt",
checkModificationIntervalSecs="60", acceptOnMatch="false")
2017-03-03 21:09:59,563 localhost-startStop-1 INFO loading regexs from
'E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2-regex-filters.txt'...
2017-03-03 21:09:59,564 localhost-startStop-1 DEBUG Building
Plugin[name=filter,
class=com.acme.backend.service.rest.logging.log4j2.DeduplicateExceptionFilter].
2017-03-03 21:09:59,566 localhost-startStop-1 DEBUG createFilter(level="ERROR",
expirationIntervalMillis="60000", releaseBlockAfterSilenceOfSecs="3600",
releaseBlockAfterSecs="21600", dupsHighlightCount="10")
2017-03-03 21:09:59,593 localhost-startStop-1 DEBUG Building
Plugin[name=filters,
class=org.apache.logging.log4j.core.filter.CompositeFilter].
2017-03-03 21:09:59,595 localhost-startStop-1 DEBUG
createFilters(={RegExFileFilter, DeduplicateExceptionFilter})
2017-03-03 21:09:59,595 localhost-startStop-1 DEBUG Building
Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
2017-03-03 21:09:59,596 localhost-startStop-1 DEBUG
PatternLayout$Builder(pattern="%d %-5p [%mdc{RQID}] [%c{1}] - %m%n",
PatternSelector=null,
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
Replace=null, charset="null", alwaysWriteExceptions="null",
disableAnsi="null", noConsoleNoAnsi="null", header="null", footer="null")
2017-03-03 21:09:59,597 localhost-startStop-1 DEBUG Building
Plugin[name=appender,
class=com.acme.backend.service.rest.logging.log4j2.DeduplicateExceptionSmtpAppender].
2017-03-03 21:09:59,604 localhost-startStop-1 DEBUG
createAppender(Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
name="smtpAppender", PatternLayout(%d %-5p [%mdc{RQID}] [%c{1}] - %m%n),
Filters({RegExFileFilter, DeduplicateExceptionFilter}), ignoreExceptions="null")
2017-03-03 21:09:59,634 localhost-startStop-1 DEBUG Starting SmtpManager
SMTP:bc180b2b3c83b892f0c5ade1df40cd47
2017-03-03 21:09:59,635 localhost-startStop-1 DEBUG Building
Plugin[name=AppenderRef,
class=org.apache.logging.log4j.core.config.AppenderRef].
2017-03-03 21:09:59,636 localhost-startStop-1 DEBUG
createAppenderRef(ref="smtpAppender", level="null", Filter=null)
2017-03-03 21:09:59,636 localhost-startStop-1 DEBUG Building
Plugin[name=appender,
class=org.apache.logging.log4j.core.appender.AsyncAppender].
2017-03-03 21:09:59,640 localhost-startStop-1 DEBUG
AsyncAppender$Builder(={smtpAppender}, errorRef="null", blocking="null",
shutdownTimeout="null", bufferSize="null", name="asyncSmtpAppender",
includeLocation="null", Filter=null,
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
ignoreExceptions="null", BlockingQueueFactory=null)
2017-03-03 21:09:59,641 localhost-startStop-1 DEBUG Building
Plugin[name=appenders,
class=org.apache.logging.log4j.core.config.AppendersPlugin].
2017-03-03 21:09:59,641 localhost-startStop-1 DEBUG
createAppenders(={rollingFileAppender, consoleAppender, smtpAppender,
asyncSmtpAppender})
2017-03-03 21:09:59,641 localhost-startStop-1 DEBUG Building
Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig].
2017-03-03 21:09:59,642 localhost-startStop-1 DEBUG
createLogger(additivity="true", level="DEBUG", name="com.acme.backend",
includeLocation="null", ={}, ={},
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
Filter=null)
2017-03-03 21:09:59,646 localhost-startStop-1 DEBUG Building
Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig].
2017-03-03 21:09:59,647 localhost-startStop-1 DEBUG
createLogger(additivity="true", level="INFO",
name="org.springframework.security", includeLocation="null", ={}, ={},
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
Filter=null)
2017-03-03 21:09:59,647 localhost-startStop-1 DEBUG Building
Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig].
2017-03-03 21:09:59,648 localhost-startStop-1 DEBUG
createLogger(additivity="true", level="INFO",
name="org.springframework.transaction", includeLocation="null", ={}, ={},
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
Filter=null)
2017-03-03 21:09:59,648 localhost-startStop-1 DEBUG Building
Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig].
2017-03-03 21:09:59,648 localhost-startStop-1 DEBUG
createLogger(additivity="true", level="INFO",
name="org.springframework.orm.jpa", includeLocation="null", ={}, ={},
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
Filter=null)
2017-03-03 21:09:59,649 localhost-startStop-1 DEBUG Building
Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig].
2017-03-03 21:09:59,649 localhost-startStop-1 DEBUG
createLogger(additivity="true", level="ERROR", name="org.hibernate.SQL",
includeLocation="null", ={}, ={},
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
Filter=null)
2017-03-03 21:09:59,649 localhost-startStop-1 DEBUG Building
Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig].
2017-03-03 21:09:59,650 localhost-startStop-1 DEBUG
createLogger(additivity="true", level="ERROR", name="org.hibernate.type",
includeLocation="null", ={}, ={},
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
Filter=null)
2017-03-03 21:09:59,650 localhost-startStop-1 DEBUG Building
Plugin[name=AppenderRef,
class=org.apache.logging.log4j.core.config.AppenderRef].
2017-03-03 21:09:59,651 localhost-startStop-1 DEBUG
createAppenderRef(ref="consoleAppender", level="null", Filter=null)
2017-03-03 21:09:59,651 localhost-startStop-1 DEBUG Building
Plugin[name=AppenderRef,
class=org.apache.logging.log4j.core.config.AppenderRef].
2017-03-03 21:09:59,651 localhost-startStop-1 DEBUG
createAppenderRef(ref="rollingFileAppender", level="null", Filter=null)
2017-03-03 21:09:59,651 localhost-startStop-1 DEBUG Building
Plugin[name=filter,
class=com.acme.backend.service.rest.logging.log4j2.IsPropertySetFilter].
2017-03-03 21:09:59,652 localhost-startStop-1 DEBUG
createFilter(sysProperty="pgb.env", envProperty="PGB_ENV", value="null",
onMatch="ACCEPT", onMismatch="DENY")
2017-03-03 21:09:59,652 localhost-startStop-1 DEBUG Building
Plugin[name=AppenderRef,
class=org.apache.logging.log4j.core.config.AppenderRef].
2017-03-03 21:09:59,653 localhost-startStop-1 DEBUG
createAppenderRef(ref="asyncSmtpAppender", level="null",
IsPropertySetFilter(IsPropertySetFilter))
2017-03-03 21:09:59,653 localhost-startStop-1 DEBUG Building Plugin[name=root,
class=org.apache.logging.log4j.core.config.LoggerConfig$RootLogger].
2017-03-03 21:09:59,654 localhost-startStop-1 DEBUG
createLogger(additivity="null", level="INFO", includeLocation="null",
={consoleAppender, rollingFileAppender, asyncSmtpAppender}, ={},
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
Filter=null)
2017-03-03 21:09:59,654 localhost-startStop-1 DEBUG Building
Plugin[name=loggers, class=org.apache.logging.log4j.core.config.LoggersPlugin].
2017-03-03 21:09:59,656 localhost-startStop-1 DEBUG
createLoggers(={com.acme.backend, org.springframework.security,
org.springframework.transaction, org.springframework.orm.jpa,
org.hibernate.SQL, org.hibernate.type, root})
2017-03-03 21:09:59,656 localhost-startStop-1 DEBUG Configuration
XmlConfiguration[location=E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml]
initialized
2017-03-03 21:09:59,657 localhost-startStop-1 DEBUG Starting configuration
XmlConfiguration[location=E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml]
2017-03-03 21:09:59,661 localhost-startStop-1 DEBUG Started configuration
XmlConfiguration[location=E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml]
OK.
2017-03-03 21:09:59,662 localhost-startStop-1 TRACE Stopping
org.apache.logging.log4j.core.config.DefaultConfiguration@373492e5...
2017-03-03 21:09:59,662 localhost-startStop-1 TRACE DefaultConfiguration
notified 1 ReliabilityStrategies that config will be stopped.
2017-03-03 21:09:59,662 localhost-startStop-1 TRACE DefaultConfiguration
stopping root LoggerConfig.
2017-03-03 21:09:59,662 localhost-startStop-1 TRACE DefaultConfiguration
notifying ReliabilityStrategies that appenders will be stopped.
2017-03-03 21:09:59,663 localhost-startStop-1 TRACE DefaultConfiguration
stopping remaining Appenders.
2017-03-03 21:09:59,663 localhost-startStop-1 DEBUG Shutting down
OutputStreamManager SYSTEM_OUT.false.false-1
2017-03-03 21:09:59,663 localhost-startStop-1 DEBUG Shut down
OutputStreamManager SYSTEM_OUT.false.false-1, all resources released: true
2017-03-03 21:09:59,663 localhost-startStop-1 DEBUG Appender DefaultConsole-1
stopped with status true
2017-03-03 21:09:59,664 localhost-startStop-1 TRACE DefaultConfiguration
stopped 1 remaining Appenders.
2017-03-03 21:09:59,664 localhost-startStop-1 TRACE DefaultConfiguration
cleaning Appenders from 1 LoggerConfigs.
2017-03-03 21:09:59,664 localhost-startStop-1 DEBUG Stopped
org.apache.logging.log4j.core.config.DefaultConfiguration@373492e5 OK
2017-03-03 21:09:59,666 localhost-startStop-1 TRACE Reregistering MBeans after
reconfigure.
Selector=org.apache.logging.log4j.core.selector.ClassLoaderContextSelector@33d1ef79
2017-03-03 21:09:59,667 localhost-startStop-1 TRACE Reregistering context
(1/1): 'Acme Backend' org.apache.logging.log4j.core.LoggerContext@554ad7a7
2017-03-03 21:09:59,667 localhost-startStop-1 TRACE Unregistering but no MBeans
found matching 'org.apache.logging.log4j2:type=Acme Backend'
2017-03-03 21:09:59,667 localhost-startStop-1 TRACE Unregistering but no MBeans
found matching 'org.apache.logging.log4j2:type=Acme
Backend,component=StatusLogger'
2017-03-03 21:09:59,668 localhost-startStop-1 TRACE Unregistering but no MBeans
found matching 'org.apache.logging.log4j2:type=Acme
Backend,component=ContextSelector'
2017-03-03 21:09:59,668 localhost-startStop-1 TRACE Unregistering but no MBeans
found matching 'org.apache.logging.log4j2:type=Acme
Backend,component=Loggers,name=*'
2017-03-03 21:09:59,668 localhost-startStop-1 TRACE Unregistering but no MBeans
found matching 'org.apache.logging.log4j2:type=Acme
Backend,component=Appenders,name=*'
2017-03-03 21:09:59,669 localhost-startStop-1 TRACE Unregistering but no MBeans
found matching 'org.apache.logging.log4j2:type=Acme
Backend,component=AsyncAppenders,name=*'
2017-03-03 21:09:59,669 localhost-startStop-1 TRACE Unregistering but no MBeans
found matching 'org.apache.logging.log4j2:type=Acme
Backend,component=AsyncLoggerRingBuffer'
2017-03-03 21:09:59,669 localhost-startStop-1 TRACE Unregistering but no MBeans
found matching 'org.apache.logging.log4j2:type=Acme
Backend,component=Loggers,name=*,subtype=RingBuffer'
2017-03-03 21:09:59,672 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme Backend
2017-03-03 21:09:59,675 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme Backend,component=StatusLogger
2017-03-03 21:09:59,677 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme Backend,component=ContextSelector
2017-03-03 21:09:59,679 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme Backend,component=Loggers,name=
2017-03-03 21:09:59,680 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme
Backend,component=Loggers,name=org.hibernate.SQL
2017-03-03 21:09:59,680 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme
Backend,component=Loggers,name=org.springframework.transaction
2017-03-03 21:09:59,681 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme
Backend,component=Loggers,name=org.springframework.security
2017-03-03 21:09:59,681 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme
Backend,component=Loggers,name=com.acme.backend
2017-03-03 21:09:59,681 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme
Backend,component=Loggers,name=org.springframework.orm.jpa
2017-03-03 21:09:59,681 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme
Backend,component=Loggers,name=org.hibernate.type
2017-03-03 21:09:59,684 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme
Backend,component=Appenders,name=smtpAppender
2017-03-03 21:09:59,686 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme
Backend,component=AsyncAppenders,name=asyncSmtpAppender
2017-03-03 21:09:59,686 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme
Backend,component=Appenders,name=rollingFileAppender
2017-03-03 21:09:59,687 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme
Backend,component=Appenders,name=consoleAppender
2017-03-03 21:09:59,690 localhost-startStop-1 TRACE Using default SystemClock
for timestamps.
2017-03-03 21:09:59,692 localhost-startStop-1 TRACE Using DummyNanoClock for
nanosecond timestamps.
2017-03-03 21:09:59,692 localhost-startStop-1 DEBUG LoggerContext[name=Acme
Backend, org.apache.logging.log4j.core.LoggerContext@554ad7a7] started OK with
configuration
XmlConfiguration[location=E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml].
...
2017-03-03 21:09:59,746 localhost-startStop-1 DEBUG
AsyncLogger.ThreadNameStrategy=CACHED
2017-03-03 21:09:59,746 localhost-startStop-1 TRACE Using default SystemClock
for timestamps.
...
2017-03-03 21:10:18,759 localhost-startStop-1 DEBUG Log4jServletContextListener
ensuring that Log4j starts up properly.
2017-03-03 21:10:18,762 localhost-startStop-1 DEBUG Log4jServletFilter
initialized.
{code}
was (Author: vguna):
I just ran into the same problem using 2.8.1.
I've added the log4j-web.jar, switched to servlet spec 3.0 in my web.xml and
I'm using Spring in my web application.
I've added an AsyncAppender in front of my SmtpAppender. Starting the webapp
and directly stopping it leads to:
{code}
Mär 03, 2017 9:17:56 PM org.apache.catalina.loader.WebappClassLoaderBase
clearReferencesThreads
WARNUNG: The web application [acme-backend] appears to have started a thread
named [AsyncAppender-asyncSmtpAppender] but has failed to stop it. This is very
likely to create a memory leak. Stack trace of thread:
sun.misc.Unsafe.park(Native Method)
java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
java.util.concurrent.ArrayBlockingQueue.take(ArrayBlockingQueue.java:403)
org.apache.logging.log4j.core.appender.AsyncAppender$AsyncThread.run(AsyncAppender.java:403)
{code}
Here's the output of status=trace
{code}
2017-03-03 21:09:58,741 localhost-startStop-1 DEBUG Starting
LoggerContext[name=Acme Backend] from configuration at null
2017-03-03 21:09:58,745 localhost-startStop-1 DEBUG Starting
LoggerContext[name=Acme Backend,
org.apache.logging.log4j.core.LoggerContext@554ad7a7] with configuration
XmlConfiguration[location=E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml]...
2017-03-03 21:09:58,746 localhost-startStop-1 DEBUG Shutdown hook enabled.
Registering a new one.
2017-03-03 21:09:58,748 localhost-startStop-1 DEBUG Initializing configuration
XmlConfiguration[location=E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml]
2017-03-03 21:09:58,755 localhost-startStop-1 DEBUG Installed script engines
2017-03-03 21:09:59,396 localhost-startStop-1 DEBUG Oracle Nashorn Version:
1.8.0_66, Language: ECMAScript, Threading: Not Thread Safe, Compile: true,
Names: {nashorn, Nashorn, js, JS, JavaScript, javascript, ECMAScript,
ecmascript}
2017-03-03 21:09:59,407 localhost-startStop-1 DEBUG Took 0,009540 seconds to
load 4 plugins from package com.acme.backend.service.rest.logging.log4j2
2017-03-03 21:09:59,407 localhost-startStop-1 DEBUG PluginManager 'Core' found
117 plugins
2017-03-03 21:09:59,407 localhost-startStop-1 DEBUG PluginManager 'Level' found
0 plugins
2017-03-03 21:09:59,425 localhost-startStop-1 DEBUG Building
Plugin[name=property, class=org.apache.logging.log4j.core.config.Property].
2017-03-03 21:09:59,443 localhost-startStop-1 TRACE TypeConverterRegistry
initializing.
2017-03-03 21:09:59,444 localhost-startStop-1 DEBUG PluginManager
'TypeConverter' found 26 plugins
2017-03-03 21:09:59,458 localhost-startStop-1 DEBUG
createProperty(name="logsPath", value="logs")
2017-03-03 21:09:59,458 localhost-startStop-1 DEBUG Building
Plugin[name=properties,
class=org.apache.logging.log4j.core.config.PropertiesPlugin].
2017-03-03 21:09:59,466 localhost-startStop-1 DEBUG
configureSubstitutor(={logsPath=logs},
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml))
2017-03-03 21:09:59,467 localhost-startStop-1 DEBUG PluginManager 'Lookup'
found 14 plugins
2017-03-03 21:09:59,467 localhost-startStop-1 DEBUG Building
Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
2017-03-03 21:09:59,477 localhost-startStop-1 DEBUG
PatternLayout$Builder(pattern="%d %-5p [%mdc{RQID}] [%c{1}] - %m%n",
PatternSelector=null,
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
Replace=null, charset="null", alwaysWriteExceptions="null",
disableAnsi="null", noConsoleNoAnsi="null", header="null", footer="null")
2017-03-03 21:09:59,478 localhost-startStop-1 DEBUG PluginManager 'Converter'
found 41 plugins
2017-03-03 21:09:59,507 localhost-startStop-1 DEBUG Building
Plugin[name=SizeBasedTriggeringPolicy,
class=org.apache.logging.log4j.core.appender.rolling.SizeBasedTriggeringPolicy].
2017-03-03 21:09:59,510 localhost-startStop-1 DEBUG createPolicy(size="10 MB")
2017-03-03 21:09:59,512 localhost-startStop-1 DEBUG Building
Plugin[name=Policies,
class=org.apache.logging.log4j.core.appender.rolling.CompositeTriggeringPolicy].
2017-03-03 21:09:59,513 localhost-startStop-1 DEBUG
createPolicy(={SizeBasedTriggeringPolicy(size=10485760)})
2017-03-03 21:09:59,513 localhost-startStop-1 DEBUG Building
Plugin[name=DefaultRolloverStrategy,
class=org.apache.logging.log4j.core.appender.rolling.DefaultRolloverStrategy].
2017-03-03 21:09:59,515 localhost-startStop-1 DEBUG createStrategy(max="5",
min="null", fileIndex="null", compressionLevel="null", ={},
stopCustomActionsOnError="true",
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml))
2017-03-03 21:09:59,516 localhost-startStop-1 DEBUG Building
Plugin[name=appender,
class=org.apache.logging.log4j.core.appender.RollingRandomAccessFileAppender].
2017-03-03 21:09:59,528 localhost-startStop-1 DEBUG
RollingRandomAccessFileAppender$Builder(fileName="logs/acme-backend.log",
filePattern="logs/acme-backend-%d{yyyy-MM-dd}-%i.log", append="null",
Policies(CompositeTriggeringPolicy(policies=[SizeBasedTriggeringPolicy(size=10485760)])),
DefaultRolloverStrategy(DefaultRolloverStrategy(min=1, max=5, useMax=true)),
advertise="null", advertiseURI="null", bufferedIo="null", bufferSize="null",
immediateFlush="true", ignoreExceptions="null", PatternLayout(%d %-5p
[%mdc{RQID}] [%c{1}] - %m%n), name="rollingFileAppender",
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
Filter=null)
2017-03-03 21:09:59,539 localhost-startStop-1 TRACE RandomAccessFile
logs/acme-backend.log seek to 9220221
2017-03-03 21:09:59,539 localhost-startStop-1 DEBUG Starting
RollingRandomAccessFileManager logs/acme-backend.log
2017-03-03 21:09:59,542 localhost-startStop-1 DEBUG PluginManager
'FileConverter' found 2 plugins
2017-03-03 21:09:59,544 localhost-startStop-1 DEBUG Setting prev file time to
2017-03-03T21:01:57.387+0100
2017-03-03 21:09:59,550 localhost-startStop-1 DEBUG Initializing triggering
policy
CompositeTriggeringPolicy(policies=[SizeBasedTriggeringPolicy(size=10485760)])
2017-03-03 21:09:59,550 localhost-startStop-1 DEBUG Building
Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
2017-03-03 21:09:59,550 localhost-startStop-1 DEBUG
PatternLayout$Builder(pattern="%d{HH:mm:ss,SSS} %-5p [%mdc{RQID}] [%c{1}] -
%m%n", PatternSelector=null,
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
Replace=null, charset="null", alwaysWriteExceptions="null",
disableAnsi="null", noConsoleNoAnsi="null", header="null", footer="null")
2017-03-03 21:09:59,551 localhost-startStop-1 DEBUG Building
Plugin[name=appender,
class=org.apache.logging.log4j.core.appender.ConsoleAppender].
2017-03-03 21:09:59,554 localhost-startStop-1 DEBUG
ConsoleAppender$Builder(target="SYSTEM_OUT", follow="null", direct="null",
bufferedIo="null", bufferSize="null", immediateFlush="null",
ignoreExceptions="null", PatternLayout(%d{HH:mm:ss,SSS} %-5p [%mdc{RQID}]
[%c{1}] - %m%n), name="consoleAppender",
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
Filter=null)
2017-03-03 21:09:59,556 localhost-startStop-1 DEBUG Jansi is not installed,
cannot find org.fusesource.jansi.WindowsAnsiOutputStream
2017-03-03 21:09:59,557 localhost-startStop-1 DEBUG Starting
OutputStreamManager SYSTEM_OUT.false.false
2017-03-03 21:09:59,557 localhost-startStop-1 DEBUG Building
Plugin[name=filter,
class=com.acme.backend.service.rest.logging.log4j2.RegExFileFilter].
2017-03-03 21:09:59,559 localhost-startStop-1 DEBUG
createFilter(filePath="log4j2-regex-filters.txt",
checkModificationIntervalSecs="60", acceptOnMatch="false")
2017-03-03 21:09:59,563 localhost-startStop-1 INFO loading regexs from
'E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2-regex-filters.txt'...
2017-03-03 21:09:59,564 localhost-startStop-1 DEBUG Building
Plugin[name=filter,
class=com.acme.backend.service.rest.logging.log4j2.DeduplicateExceptionFilter].
2017-03-03 21:09:59,566 localhost-startStop-1 DEBUG createFilter(level="ERROR",
expirationIntervalMillis="60000", releaseBlockAfterSilenceOfSecs="3600",
releaseBlockAfterSecs="21600", dupsHighlightCount="10")
2017-03-03 21:09:59,593 localhost-startStop-1 DEBUG Building
Plugin[name=filters,
class=org.apache.logging.log4j.core.filter.CompositeFilter].
2017-03-03 21:09:59,595 localhost-startStop-1 DEBUG
createFilters(={RegExFileFilter, DeduplicateExceptionFilter})
2017-03-03 21:09:59,595 localhost-startStop-1 DEBUG Building
Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
2017-03-03 21:09:59,596 localhost-startStop-1 DEBUG
PatternLayout$Builder(pattern="%d %-5p [%mdc{RQID}] [%c{1}] - %m%n",
PatternSelector=null,
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
Replace=null, charset="null", alwaysWriteExceptions="null",
disableAnsi="null", noConsoleNoAnsi="null", header="null", footer="null")
2017-03-03 21:09:59,597 localhost-startStop-1 DEBUG Building
Plugin[name=appender,
class=com.acme.backend.service.rest.logging.log4j2.DeduplicateExceptionSmtpAppender].
2017-03-03 21:09:59,604 localhost-startStop-1 DEBUG
createAppender(Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
name="smtpAppender", PatternLayout(%d %-5p [%mdc{RQID}] [%c{1}] - %m%n),
Filters({RegExFileFilter, DeduplicateExceptionFilter}), ignoreExceptions="null")
2017-03-03 21:09:59,634 localhost-startStop-1 DEBUG Starting SmtpManager
SMTP:bc180b2b3c83b892f0c5ade1df40cd47
2017-03-03 21:09:59,635 localhost-startStop-1 DEBUG Building
Plugin[name=AppenderRef,
class=org.apache.logging.log4j.core.config.AppenderRef].
2017-03-03 21:09:59,636 localhost-startStop-1 DEBUG
createAppenderRef(ref="smtpAppender", level="null", Filter=null)
2017-03-03 21:09:59,636 localhost-startStop-1 DEBUG Building
Plugin[name=appender,
class=org.apache.logging.log4j.core.appender.AsyncAppender].
2017-03-03 21:09:59,640 localhost-startStop-1 DEBUG
AsyncAppender$Builder(={smtpAppender}, errorRef="null", blocking="null",
shutdownTimeout="null", bufferSize="null", name="asyncSmtpAppender",
includeLocation="null", Filter=null,
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
ignoreExceptions="null", BlockingQueueFactory=null)
2017-03-03 21:09:59,641 localhost-startStop-1 DEBUG Building
Plugin[name=appenders,
class=org.apache.logging.log4j.core.config.AppendersPlugin].
2017-03-03 21:09:59,641 localhost-startStop-1 DEBUG
createAppenders(={rollingFileAppender, consoleAppender, smtpAppender,
asyncSmtpAppender})
2017-03-03 21:09:59,641 localhost-startStop-1 DEBUG Building
Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig].
2017-03-03 21:09:59,642 localhost-startStop-1 DEBUG
createLogger(additivity="true", level="DEBUG", name="com.acme.backend",
includeLocation="null", ={}, ={},
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
Filter=null)
2017-03-03 21:09:59,646 localhost-startStop-1 DEBUG Building
Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig].
2017-03-03 21:09:59,647 localhost-startStop-1 DEBUG
createLogger(additivity="true", level="INFO",
name="org.springframework.security", includeLocation="null", ={}, ={},
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
Filter=null)
2017-03-03 21:09:59,647 localhost-startStop-1 DEBUG Building
Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig].
2017-03-03 21:09:59,648 localhost-startStop-1 DEBUG
createLogger(additivity="true", level="INFO",
name="org.springframework.transaction", includeLocation="null", ={}, ={},
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
Filter=null)
2017-03-03 21:09:59,648 localhost-startStop-1 DEBUG Building
Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig].
2017-03-03 21:09:59,648 localhost-startStop-1 DEBUG
createLogger(additivity="true", level="INFO",
name="org.springframework.orm.jpa", includeLocation="null", ={}, ={},
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
Filter=null)
2017-03-03 21:09:59,649 localhost-startStop-1 DEBUG Building
Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig].
2017-03-03 21:09:59,649 localhost-startStop-1 DEBUG
createLogger(additivity="true", level="ERROR", name="org.hibernate.SQL",
includeLocation="null", ={}, ={},
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
Filter=null)
2017-03-03 21:09:59,649 localhost-startStop-1 DEBUG Building
Plugin[name=logger, class=org.apache.logging.log4j.core.config.LoggerConfig].
2017-03-03 21:09:59,650 localhost-startStop-1 DEBUG
createLogger(additivity="true", level="ERROR", name="org.hibernate.type",
includeLocation="null", ={}, ={},
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
Filter=null)
2017-03-03 21:09:59,650 localhost-startStop-1 DEBUG Building
Plugin[name=AppenderRef,
class=org.apache.logging.log4j.core.config.AppenderRef].
2017-03-03 21:09:59,651 localhost-startStop-1 DEBUG
createAppenderRef(ref="consoleAppender", level="null", Filter=null)
2017-03-03 21:09:59,651 localhost-startStop-1 DEBUG Building
Plugin[name=AppenderRef,
class=org.apache.logging.log4j.core.config.AppenderRef].
2017-03-03 21:09:59,651 localhost-startStop-1 DEBUG
createAppenderRef(ref="rollingFileAppender", level="null", Filter=null)
2017-03-03 21:09:59,651 localhost-startStop-1 DEBUG Building
Plugin[name=filter,
class=com.acme.backend.service.rest.logging.log4j2.IsPropertySetFilter].
2017-03-03 21:09:59,652 localhost-startStop-1 DEBUG
createFilter(sysProperty="pgb.env", envProperty="PGB_ENV", value="null",
onMatch="ACCEPT", onMismatch="DENY")
2017-03-03 21:09:59,652 localhost-startStop-1 DEBUG Building
Plugin[name=AppenderRef,
class=org.apache.logging.log4j.core.config.AppenderRef].
2017-03-03 21:09:59,653 localhost-startStop-1 DEBUG
createAppenderRef(ref="asyncSmtpAppender", level="null",
IsPropertySetFilter(IsPropertySetFilter))
2017-03-03 21:09:59,653 localhost-startStop-1 DEBUG Building Plugin[name=root,
class=org.apache.logging.log4j.core.config.LoggerConfig$RootLogger].
2017-03-03 21:09:59,654 localhost-startStop-1 DEBUG
createLogger(additivity="null", level="INFO", includeLocation="null",
={consoleAppender, rollingFileAppender, asyncSmtpAppender}, ={},
Configuration(E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml),
Filter=null)
2017-03-03 21:09:59,654 localhost-startStop-1 DEBUG Building
Plugin[name=loggers, class=org.apache.logging.log4j.core.config.LoggersPlugin].
2017-03-03 21:09:59,656 localhost-startStop-1 DEBUG
createLoggers(={com.acme.backend, org.springframework.security,
org.springframework.transaction, org.springframework.orm.jpa,
org.hibernate.SQL, org.hibernate.type, root})
2017-03-03 21:09:59,656 localhost-startStop-1 DEBUG Configuration
XmlConfiguration[location=E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml]
initialized
2017-03-03 21:09:59,657 localhost-startStop-1 DEBUG Starting configuration
XmlConfiguration[location=E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml]
2017-03-03 21:09:59,661 localhost-startStop-1 DEBUG Started configuration
XmlConfiguration[location=E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml]
OK.
2017-03-03 21:09:59,662 localhost-startStop-1 TRACE Stopping
org.apache.logging.log4j.core.config.DefaultConfiguration@373492e5...
2017-03-03 21:09:59,662 localhost-startStop-1 TRACE DefaultConfiguration
notified 1 ReliabilityStrategies that config will be stopped.
2017-03-03 21:09:59,662 localhost-startStop-1 TRACE DefaultConfiguration
stopping root LoggerConfig.
2017-03-03 21:09:59,662 localhost-startStop-1 TRACE DefaultConfiguration
notifying ReliabilityStrategies that appenders will be stopped.
2017-03-03 21:09:59,663 localhost-startStop-1 TRACE DefaultConfiguration
stopping remaining Appenders.
2017-03-03 21:09:59,663 localhost-startStop-1 DEBUG Shutting down
OutputStreamManager SYSTEM_OUT.false.false-1
2017-03-03 21:09:59,663 localhost-startStop-1 DEBUG Shut down
OutputStreamManager SYSTEM_OUT.false.false-1, all resources released: true
2017-03-03 21:09:59,663 localhost-startStop-1 DEBUG Appender DefaultConsole-1
stopped with status true
2017-03-03 21:09:59,664 localhost-startStop-1 TRACE DefaultConfiguration
stopped 1 remaining Appenders.
2017-03-03 21:09:59,664 localhost-startStop-1 TRACE DefaultConfiguration
cleaning Appenders from 1 LoggerConfigs.
2017-03-03 21:09:59,664 localhost-startStop-1 DEBUG Stopped
org.apache.logging.log4j.core.config.DefaultConfiguration@373492e5 OK
2017-03-03 21:09:59,666 localhost-startStop-1 TRACE Reregistering MBeans after
reconfigure.
Selector=org.apache.logging.log4j.core.selector.ClassLoaderContextSelector@33d1ef79
2017-03-03 21:09:59,667 localhost-startStop-1 TRACE Reregistering context
(1/1): 'Acme Backend' org.apache.logging.log4j.core.LoggerContext@554ad7a7
2017-03-03 21:09:59,667 localhost-startStop-1 TRACE Unregistering but no MBeans
found matching 'org.apache.logging.log4j2:type=Acme Backend'
2017-03-03 21:09:59,667 localhost-startStop-1 TRACE Unregistering but no MBeans
found matching 'org.apache.logging.log4j2:type=Acme
Backend,component=StatusLogger'
2017-03-03 21:09:59,668 localhost-startStop-1 TRACE Unregistering but no MBeans
found matching 'org.apache.logging.log4j2:type=Acme
Backend,component=ContextSelector'
2017-03-03 21:09:59,668 localhost-startStop-1 TRACE Unregistering but no MBeans
found matching 'org.apache.logging.log4j2:type=Acme
Backend,component=Loggers,name=*'
2017-03-03 21:09:59,668 localhost-startStop-1 TRACE Unregistering but no MBeans
found matching 'org.apache.logging.log4j2:type=Acme
Backend,component=Appenders,name=*'
2017-03-03 21:09:59,669 localhost-startStop-1 TRACE Unregistering but no MBeans
found matching 'org.apache.logging.log4j2:type=Acme
Backend,component=AsyncAppenders,name=*'
2017-03-03 21:09:59,669 localhost-startStop-1 TRACE Unregistering but no MBeans
found matching 'org.apache.logging.log4j2:type=Acme
Backend,component=AsyncLoggerRingBuffer'
2017-03-03 21:09:59,669 localhost-startStop-1 TRACE Unregistering but no MBeans
found matching 'org.apache.logging.log4j2:type=Acme
Backend,component=Loggers,name=*,subtype=RingBuffer'
2017-03-03 21:09:59,672 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme Backend
2017-03-03 21:09:59,675 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme Backend,component=StatusLogger
2017-03-03 21:09:59,677 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme Backend,component=ContextSelector
2017-03-03 21:09:59,679 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme Backend,component=Loggers,name=
2017-03-03 21:09:59,680 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme
Backend,component=Loggers,name=org.hibernate.SQL
2017-03-03 21:09:59,680 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme
Backend,component=Loggers,name=org.springframework.transaction
2017-03-03 21:09:59,681 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme
Backend,component=Loggers,name=org.springframework.security
2017-03-03 21:09:59,681 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme
Backend,component=Loggers,name=com.acme.backend
2017-03-03 21:09:59,681 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme
Backend,component=Loggers,name=org.springframework.orm.jpa
2017-03-03 21:09:59,681 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme
Backend,component=Loggers,name=org.hibernate.type
2017-03-03 21:09:59,684 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme
Backend,component=Appenders,name=smtpAppender
2017-03-03 21:09:59,686 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme
Backend,component=AsyncAppenders,name=asyncSmtpAppender
2017-03-03 21:09:59,686 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme
Backend,component=Appenders,name=rollingFileAppender
2017-03-03 21:09:59,687 localhost-startStop-1 DEBUG Registering MBean
org.apache.logging.log4j2:type=Acme
Backend,component=Appenders,name=consoleAppender
2017-03-03 21:09:59,690 localhost-startStop-1 TRACE Using default SystemClock
for timestamps.
2017-03-03 21:09:59,692 localhost-startStop-1 TRACE Using DummyNanoClock for
nanosecond timestamps.
2017-03-03 21:09:59,692 localhost-startStop-1 DEBUG LoggerContext[name=Acme
Backend, org.apache.logging.log4j.core.LoggerContext@554ad7a7] started OK with
configuration
XmlConfiguration[location=E:\Coding\workspace\.metadata\.plugins\org.eclipse.wst.server.core\tmp0\wtpwebapps\acme-web-impl\WEB-INF\classes\log4j2.xml].
...
2017-03-03 21:09:59,746 localhost-startStop-1 DEBUG
AsyncLogger.ThreadNameStrategy=CACHED
2017-03-03 21:09:59,746 localhost-startStop-1 TRACE Using default SystemClock
for timestamps.
...
2017-03-03 21:10:18,759 localhost-startStop-1 DEBUG Log4jServletContextListener
ensuring that Log4j starts up properly.
2017-03-03 21:10:18,762 localhost-startStop-1 DEBUG Log4jServletFilter
initialized.
{code}
> Log4j threads are leaking on Tomcat shutdown
> --------------------------------------------
>
> Key: LOG4J2-1259
> URL: https://issues.apache.org/jira/browse/LOG4J2-1259
> Project: Log4j 2
> Issue Type: Bug
> Components: Appenders
> Affects Versions: 2.5
> Reporter: Misagh Moayyed
>
> Running log4j2 v2.5 with disruptor 3.3.x. AsyncLoggers configured. log4j-web
> also included in the web application deployed in Tomcat 8. The context
> listener is correctly starting up and shutting down, catalina.properties does
> not include the log4j*.jar entry. Yet I see:
> {code}
> 20-Jan-2016 14:59:26.322 WARNING [localhost-startStop-2]
> org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The
> web application [cas-server-webapp-4.3.0-SNAPSHOT] appears to have started a
> thread named [Log4j2-Log4j2Scheduled-5] but has failed to stop it. This is
> very likely to create a memory leak. Stack trace of thread:
> sun.misc.Unsafe.park(Native Method)
> java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
>
> java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
>
> java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:1093)
>
> java.util.concurrent.ScheduledThreadPoolExecutor$DelayedWorkQueue.take(ScheduledThreadPoolExecutor.java:809)
> java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1067)
>
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1127)
>
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
> java.lang.Thread.run(Thread.java:745)
> 20-Jan-2016 14:59:26.336 WARNING [localhost-startStop-2]
> org.apache.catalina.loader.WebappClassLoaderBase.clearReferencesThreads The
> web application [cas-server-webapp-4.3.0-SNAPSHOT] appears to have started a
> thread named [Log4j2-AsyncLoggerConfig-6] but has failed to stop it. This is
> very likely to create a memory leak. Stack trace of thread:
> sun.misc.Unsafe.park(Native Method)
> java.util.concurrent.locks.LockSupport.park(LockSupport.java:175)
>
> java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2039)
> com.lmax.disruptor.BlockingWaitStrategy.waitFor(BlockingWaitStrategy.java:45)
>
> com.lmax.disruptor.ProcessingSequenceBarrier.waitFor(ProcessingSequenceBarrier.java:56)
> com.lmax.disruptor.BatchEventProcessor.run(BatchEventProcessor.java:124)
>
> java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
>
> java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
> java.lang.Thread.run(Thread.java:745)
> {code}
--
This message was sent by Atlassian JIRA
(v6.3.15#6346)
---------------------------------------------------------------------
To unsubscribe, e-mail: log4j-dev-unsubscr...@logging.apache.org
For additional commands, e-mail: log4j-dev-h...@logging.apache.org
