You are viewing a plain text version of this content. The canonical link for it is here.
Posted to log4j-dev@logging.apache.org by "Chris McGee (JIRA)" <ji...@apache.org> on 2016/10/10 13:59:20 UTC
[jira] [Updated] (LOG4J2-1640) RollingFileAppender with
CronTriggeringPolicy broken?
[ https://issues.apache.org/jira/browse/LOG4J2-1640?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel ]
Chris McGee updated LOG4J2-1640:
--------------------------------
Description:
If this isn't actually a bug, then I apologize for reporting it, but I cannot figure out how it could be anything else currently. Full disclosure: I am still a newbie.
I've been using the log4j 2.6.x series for a while, but noticed that the CronTriggeringPolicy when used with RollingFileAppender causes that infinite rollover bug. I noted that this bug was to be fixed in 2.7, so I downloaded that the day it was released and replaced the 2.6 version with it. Now, however, without changing anything else in my code nor in my log4j2.xml file, I am getting exceptions regarding them.
Here's the interesting bit: Since I was trying to see if the rollover would occur at midnight, I manually changed my computer's clock to just a minute before, logged some info, let it roll to past midnight, and let it log some more info. All of that info got logged into the main file; nothing rolled over. Here's the stacktrace from that execution:
{noformat}
2016-10-10 09:40:47,521 main DEBUG Initializing configuration XmlConfiguration[location=/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml]
2016-10-10 09:40:47,526 main DEBUG Installed script engines
2016-10-10 09:40:47,955 main DEBUG Oracle Nashorn Version: 1.8.0_74, Language: ECMAScript, Threading: Not Thread Safe, Compile: true, Names: {nashorn, Nashorn, js, JS, JavaScript, javascript, ECMAScript, ecmascript}
2016-10-10 09:40:48,307 main DEBUG AppleScriptEngine Version: 1.1, Language: AppleScript, Threading: Not Thread Safe, Compile: false, Names: {AppleScriptEngine, AppleScript, OSA}
2016-10-10 09:40:48,308 main DEBUG PluginManager 'Core' found 107 plugins
2016-10-10 09:40:48,308 main DEBUG PluginManager 'Level' found 0 plugins
2016-10-10 09:40:48,312 main DEBUG 2 starting Log4j2 ConfigurationScheduler threads
2016-10-10 09:40:48,314 main DEBUG Building Plugin[name=property, class=org.apache.logging.log4j.core.config.Property].
2016-10-10 09:40:48,323 main TRACE TypeConverterRegistry initializing.
2016-10-10 09:40:48,324 main DEBUG PluginManager 'TypeConverter' found 23 plugins
2016-10-10 09:40:48,330 main DEBUG createProperty(name="filename", value="logs/artdept.log")
2016-10-10 09:40:48,330 main DEBUG Building Plugin[name=property, class=org.apache.logging.log4j.core.config.Property].
2016-10-10 09:40:48,331 main DEBUG createProperty(name="baseDir", value="/Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs")
2016-10-10 09:40:48,331 main DEBUG Building Plugin[name=properties, class=org.apache.logging.log4j.core.config.PropertiesPlugin].
2016-10-10 09:40:48,334 main DEBUG configureSubstitutor(={filename=logs/artdept.log, baseDir=/Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs}, Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml))
2016-10-10 09:40:48,335 main DEBUG PluginManager 'Lookup' found 13 plugins
2016-10-10 09:40:48,335 main DEBUG Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
2016-10-10 09:40:48,341 main DEBUG PatternLayout$Builder(pattern="%d{HH:mm:ss.SSS} [%t] %-5level %logger{36} - %msg%n", PatternSelector=null, Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), Replace=null, charset="null", alwaysWriteExceptions="null", noConsoleNoAnsi="null", header="null", footer="null")
2016-10-10 09:40:48,341 main DEBUG PluginManager 'Converter' found 41 plugins
2016-10-10 09:40:48,342 main DEBUG Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.ConsoleAppender].
2016-10-10 09:40:48,347 main DEBUG ConsoleAppender$Builder(target="SYSTEM_OUT", follow="null", direct="null", bufferedIo="null", bufferSize="null", immediateFlush="null", ignoreExceptions="null", PatternLayout(%d{HH:mm:ss.SSS} [%t] %-5level %logger{36} - %msg%n), name="Console", Filter=null)
2016-10-10 09:40:48,347 main DEBUG Starting OutputStreamManager SYSTEM_OUT.false.false
2016-10-10 09:40:48,348 main DEBUG Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
2016-10-10 09:40:48,348 main DEBUG PatternLayout$Builder(pattern="%d %p %C{1.} [%t] %m%n", PatternSelector=null, Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), Replace=null, charset="null", alwaysWriteExceptions="null", noConsoleNoAnsi="null", header="null", footer="null")
2016-10-10 09:40:48,360 main DEBUG Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.FileAppender].
2016-10-10 09:40:48,364 main DEBUG FileAppender$Builder(fileName="logs/artdept.log", append="null", locking="null", advertise="null", advertiseUri="null", createOnDemand="null", Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), bufferedIo="null", bufferSize="null", immediateFlush="null", ignoreExceptions="null", PatternLayout(%d %p %C{1.} [%t] %m%n), name="MyFile", Filter=null)
2016-10-10 09:40:48,365 main DEBUG Starting FileManager logs/artdept.log
2016-10-10 09:40:48,366 main DEBUG Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
2016-10-10 09:40:48,366 main DEBUG PatternLayout$Builder(pattern="%d %p %C{1.} [%t] %m%n", PatternSelector=null, Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), Replace=null, charset="null", alwaysWriteExceptions="null", noConsoleNoAnsi="null", header="null", footer="null")
2016-10-10 09:40:48,367 main DEBUG Building Plugin[name=CronTriggeringPolicy, class=org.apache.logging.log4j.core.appender.rolling.CronTriggeringPolicy].
2016-10-10 09:40:48,368 main DEBUG createPolicy(Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), evaluateOnStartup="true", schedule="0 0 0 * * ?")
2016-10-10 09:40:48,370 main DEBUG Building Plugin[name=SizeBasedTriggeringPolicy, class=org.apache.logging.log4j.core.appender.rolling.SizeBasedTriggeringPolicy].
2016-10-10 09:40:48,371 main DEBUG createPolicy(size="250 MB")
2016-10-10 09:40:48,372 main DEBUG Building Plugin[name=Policies, class=org.apache.logging.log4j.core.appender.rolling.CompositeTriggeringPolicy].
2016-10-10 09:40:48,373 main DEBUG createPolicy(={CronTriggeringPolicy(schedule=0 0 0 * * ?), SizeBasedTriggeringPolicy(size=262144000)})
2016-10-10 09:40:48,373 main DEBUG Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.RollingFileAppender].
2016-10-10 09:40:48,376 main DEBUG RollingFileAppender$Builder(fileName="/Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/Launcher.log", filePattern="/Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/%d{dd} - Launcher-%i.log", append="null", locking="null", Policies(CompositeTriggeringPolicy(policies=[CronTriggeringPolicy(schedule=0 0 0 * * ?), SizeBasedTriggeringPolicy(size=262144000)])), Strategy=null, advertise="null", advertiseUri="null", createOnDemand="null", Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), bufferedIo="null", bufferSize="null", immediateFlush="null", ignoreExceptions="null", PatternLayout(%d %p %C{1.} [%t] %m%n), name="DailyRoll", Filter=null)
2016-10-10 09:40:48,512 main TRACE New file '/Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/Launcher.log' created = false
2016-10-10 09:40:48,515 main DEBUG Starting RollingFileManager /Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/Launcher.log
2016-10-10 09:40:48,516 main DEBUG PluginManager 'FileConverter' found 2 plugins
2016-10-10 09:40:48,520 main DEBUG Building Plugin[name=appenders, class=org.apache.logging.log4j.core.config.AppendersPlugin].
2016-10-10 09:40:48,521 main DEBUG createAppenders(={Console, MyFile, DailyRoll})
2016-10-10 09:40:48,521 main DEBUG Building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef].
2016-10-10 09:40:48,521 main DEBUG createAppenderRef(ref="DailyRoll", level="null", Filter=null)
2016-10-10 09:40:48,522 main DEBUG Building Plugin[name=root, class=org.apache.logging.log4j.core.config.LoggerConfig$RootLogger].
2016-10-10 09:40:48,522 main DEBUG createLogger(additivity="null", level="TRACE", includeLocation="null", ={DailyRoll}, ={}, Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), Filter=null)
2016-10-10 09:40:48,524 main DEBUG Building Plugin[name=loggers, class=org.apache.logging.log4j.core.config.LoggersPlugin].
2016-10-10 09:40:48,525 main DEBUG createLoggers(={root})
2016-10-10 09:40:48,525 main DEBUG Configuration XmlConfiguration[location=/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml] initialized
2016-10-10 09:40:48,525 main DEBUG Starting configuration XmlConfiguration[location=/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml]
2016-10-10 09:40:48,526 main DEBUG Started configuration XmlConfiguration[location=/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml] OK.
2016-10-10 09:40:48,526 main TRACE Stopping org.apache.logging.log4j.core.config.DefaultConfiguration@76ccd017...
2016-10-10 09:40:48,527 main TRACE DefaultConfiguration notified 1 ReliabilityStrategies that config will be stopped.
2016-10-10 09:40:48,527 main TRACE DefaultConfiguration stopping root LoggerConfig.
2016-10-10 09:40:48,527 main TRACE DefaultConfiguration notifying ReliabilityStrategies that appenders will be stopped.
2016-10-10 09:40:48,527 main TRACE DefaultConfiguration stopping remaining Appenders.
2016-10-10 09:40:48,527 main DEBUG Shutting down OutputStreamManager SYSTEM_OUT.false.false-1
2016-10-10 09:40:48,528 main DEBUG Shut down OutputStreamManager SYSTEM_OUT.false.false-1, all resources released: true
2016-10-10 09:40:48,528 main TRACE DefaultConfiguration stopped 1 remaining Appenders.
2016-10-10 09:40:48,528 main TRACE DefaultConfiguration cleaning Appenders from 1 LoggerConfigs.
2016-10-10 09:40:48,528 main DEBUG Stopped org.apache.logging.log4j.core.config.DefaultConfiguration@76ccd017 OK
2016-10-10 09:40:48,572 main TRACE Reregistering MBeans after reconfigure. Selector=org.apache.logging.log4j.core.selector.ClassLoaderContextSelector@1bd4fdd
2016-10-10 09:40:48,572 main TRACE Reregistering context (1/1): '2a139a55' org.apache.logging.log4j.core.LoggerContext@55183b20
2016-10-10 09:40:48,574 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55'
2016-10-10 09:40:48,574 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=StatusLogger'
2016-10-10 09:40:48,574 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=ContextSelector'
2016-10-10 09:40:48,575 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=Loggers,name=*'
2016-10-10 09:40:48,575 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=Appenders,name=*'
2016-10-10 09:40:48,575 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=AsyncAppenders,name=*'
2016-10-10 09:40:48,576 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=AsyncLoggerRingBuffer'
2016-10-10 09:40:48,576 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=Loggers,name=*,subtype=RingBuffer'
2016-10-10 09:40:48,578 main DEBUG Registering MBean org.apache.logging.log4j2:type=2a139a55
2016-10-10 09:40:48,580 main DEBUG Registering MBean org.apache.logging.log4j2:type=2a139a55,component=StatusLogger
2016-10-10 09:40:48,581 main DEBUG Registering MBean org.apache.logging.log4j2:type=2a139a55,component=ContextSelector
2016-10-10 09:40:48,582 main DEBUG Registering MBean org.apache.logging.log4j2:type=2a139a55,component=Loggers,name=
2016-10-10 09:40:48,583 main DEBUG Registering MBean org.apache.logging.log4j2:type=2a139a55,component=Appenders,name=Console
2016-10-10 09:40:48,583 main DEBUG Registering MBean org.apache.logging.log4j2:type=2a139a55,component=Appenders,name=MyFile
2016-10-10 09:40:48,583 main DEBUG Registering MBean org.apache.logging.log4j2:type=2a139a55,component=Appenders,name=DailyRoll
2016-10-10 09:40:48,585 main TRACE Using default SystemClock for timestamps.
2016-10-10 09:40:48,585 main TRACE Using DummyNanoClock for nanosecond timestamps.
2016-10-10 09:40:48,585 main DEBUG Reconfiguration complete for context[name=2a139a55] at URI /Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml (org.apache.logging.log4j.core.LoggerContext@55183b20) with optional ClassLoader: null
2016-10-10 09:40:48,585 main DEBUG Shutdown hook enabled. Registering a new one.
2016-10-10 09:40:48,586 main DEBUG LoggerContext[name=2a139a55, org.apache.logging.log4j.core.LoggerContext@55183b20] started OK.
Debug Log menu item clicked; Logging is now true
2016-10-11 00:00:25,734 pool-1-thread-1 DEBUG Stopping LoggerContext[name=2a139a55, org.apache.logging.log4j.core.LoggerContext@55183b20]
2016-10-11 00:00:25,734 pool-1-thread-1 DEBUG Stopping LoggerContext[name=2a139a55, org.apache.logging.log4j.core.LoggerContext@55183b20]...
2016-10-11 00:00:25,735 pool-1-thread-1 TRACE Unregistering 1 MBeans: [org.apache.logging.log4j2:type=2a139a55]
2016-10-11 00:00:25,735 pool-1-thread-1 TRACE Unregistering 1 MBeans: [org.apache.logging.log4j2:type=2a139a55,component=StatusLogger]
2016-10-11 00:00:25,735 pool-1-thread-1 TRACE Unregistering 1 MBeans: [org.apache.logging.log4j2:type=2a139a55,component=ContextSelector]
2016-10-11 00:00:25,736 pool-1-thread-1 TRACE Unregistering 1 MBeans: [org.apache.logging.log4j2:type=2a139a55,component=Loggers,name=]
2016-10-11 00:00:25,736 pool-1-thread-1 TRACE Unregistering 3 MBeans: [org.apache.logging.log4j2:type=2a139a55,component=Appenders,name=MyFile, org.apache.logging.log4j2:type=2a139a55,component=Appenders,name=Console, org.apache.logging.log4j2:type=2a139a55,component=Appenders,name=DailyRoll]
2016-10-11 00:00:25,736 pool-1-thread-1 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=AsyncAppenders,name=*'
2016-10-11 00:00:25,736 pool-1-thread-1 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=AsyncLoggerRingBuffer'
2016-10-11 00:00:25,736 pool-1-thread-1 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=Loggers,name=*,subtype=RingBuffer'
2016-10-11 00:00:25,737 pool-1-thread-1 TRACE Stopping XmlConfiguration[location=/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml]...
2016-10-11 00:00:25,737 pool-1-thread-1 TRACE XmlConfiguration notified 2 ReliabilityStrategies that config will be stopped.
2016-10-11 00:00:25,737 pool-1-thread-1 TRACE XmlConfiguration stopping 1 LoggerConfigs.
2016-10-11 00:00:25,737 pool-1-thread-1 TRACE XmlConfiguration stopping root LoggerConfig.
2016-10-11 00:00:25,737 pool-1-thread-1 TRACE XmlConfiguration notifying ReliabilityStrategies that appenders will be stopped.
2016-10-11 00:00:25,737 pool-1-thread-1 TRACE XmlConfiguration stopping remaining Appenders.
2016-10-11 00:00:25,737 pool-1-thread-1 DEBUG Shutting down RollingFileManager /Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/Launcher.log
2016-10-11 00:00:25,988 pool-1-thread-1 DEBUG Shut down RollingFileManager /Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/Launcher.log, all resources released: true
2016-10-11 00:00:25,988 pool-1-thread-1 DEBUG Shutting down FileManager logs/artdept.log
2016-10-11 00:00:25,988 pool-1-thread-1 DEBUG Shut down FileManager logs/artdept.log, all resources released: true
2016-10-11 00:00:25,989 pool-1-thread-1 DEBUG Shutting down OutputStreamManager SYSTEM_OUT.false.false
2016-10-11 00:00:25,989 pool-1-thread-1 DEBUG Shut down OutputStreamManager SYSTEM_OUT.false.false, all resources released: true
2016-10-11 00:00:25,989 pool-1-thread-1 TRACE XmlConfiguration stopped 3 remaining Appenders.
2016-10-11 00:00:25,989 pool-1-thread-1 TRACE XmlConfiguration cleaning Appenders from 2 LoggerConfigs.
2016-10-11 00:00:25,989 pool-1-thread-1 DEBUG Log4j2 ConfigurationScheduler shutting down threads in java.util.concurrent.ScheduledThreadPoolExecutor@11d33cbe[Running, pool size = 2, active threads = 0, queued tasks = 2, completed tasks = 7]
2016-10-11 00:00:25,989 pool-1-thread-1 DEBUG Stopped XmlConfiguration[location=/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml] OK
2016-10-11 00:00:25,990 pool-1-thread-1 DEBUG Stopped LoggerContext[name=2a139a55, org.apache.logging.log4j.core.LoggerContext@55183b20]...
{noformat}
No exceptions recorded.
However, if I re-start the application _while the computer's clock is still showing past Midnight on the following day_, I am given _this_ stacktrace, which _does_ have exceptions recorded:
{noformat}
2016-10-11 00:01:02,843 main DEBUG Initializing configuration XmlConfiguration[location=/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml]
2016-10-11 00:01:02,848 main DEBUG Installed script engines
2016-10-11 00:01:03,071 main DEBUG Oracle Nashorn Version: 1.8.0_74, Language: ECMAScript, Threading: Not Thread Safe, Compile: true, Names: {nashorn, Nashorn, js, JS, JavaScript, javascript, ECMAScript, ecmascript}
2016-10-11 00:01:03,097 main DEBUG AppleScriptEngine Version: 1.1, Language: AppleScript, Threading: Not Thread Safe, Compile: false, Names: {AppleScriptEngine, AppleScript, OSA}
2016-10-11 00:01:03,097 main DEBUG PluginManager 'Core' found 107 plugins
2016-10-11 00:01:03,097 main DEBUG PluginManager 'Level' found 0 plugins
2016-10-11 00:01:03,102 main DEBUG 2 starting Log4j2 ConfigurationScheduler threads
2016-10-11 00:01:03,104 main DEBUG Building Plugin[name=property, class=org.apache.logging.log4j.core.config.Property].
2016-10-11 00:01:03,113 main TRACE TypeConverterRegistry initializing.
2016-10-11 00:01:03,114 main DEBUG PluginManager 'TypeConverter' found 23 plugins
2016-10-11 00:01:03,121 main DEBUG createProperty(name="filename", value="logs/artdept.log")
2016-10-11 00:01:03,121 main DEBUG Building Plugin[name=property, class=org.apache.logging.log4j.core.config.Property].
2016-10-11 00:01:03,122 main DEBUG createProperty(name="baseDir", value="/Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs")
2016-10-11 00:01:03,122 main DEBUG Building Plugin[name=properties, class=org.apache.logging.log4j.core.config.PropertiesPlugin].
2016-10-11 00:01:03,126 main DEBUG configureSubstitutor(={filename=logs/artdept.log, baseDir=/Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs}, Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml))
2016-10-11 00:01:03,127 main DEBUG PluginManager 'Lookup' found 13 plugins
2016-10-11 00:01:03,128 main DEBUG Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
2016-10-11 00:01:03,132 main DEBUG PatternLayout$Builder(pattern="%d{HH:mm:ss.SSS} [%t] %-5level %logger{36} - %msg%n", PatternSelector=null, Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), Replace=null, charset="null", alwaysWriteExceptions="null", noConsoleNoAnsi="null", header="null", footer="null")
2016-10-11 00:01:03,132 main DEBUG PluginManager 'Converter' found 41 plugins
2016-10-11 00:01:03,133 main DEBUG Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.ConsoleAppender].
2016-10-11 00:01:03,139 main DEBUG ConsoleAppender$Builder(target="SYSTEM_OUT", follow="null", direct="null", bufferedIo="null", bufferSize="null", immediateFlush="null", ignoreExceptions="null", PatternLayout(%d{HH:mm:ss.SSS} [%t] %-5level %logger{36} - %msg%n), name="Console", Filter=null)
2016-10-11 00:01:03,139 main DEBUG Starting OutputStreamManager SYSTEM_OUT.false.false
2016-10-11 00:01:03,140 main DEBUG Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
2016-10-11 00:01:03,140 main DEBUG PatternLayout$Builder(pattern="%d %p %C{1.} [%t] %m%n", PatternSelector=null, Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), Replace=null, charset="null", alwaysWriteExceptions="null", noConsoleNoAnsi="null", header="null", footer="null")
2016-10-11 00:01:03,150 main DEBUG Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.FileAppender].
2016-10-11 00:01:03,153 main DEBUG FileAppender$Builder(fileName="logs/artdept.log", append="null", locking="null", advertise="null", advertiseUri="null", createOnDemand="null", Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), bufferedIo="null", bufferSize="null", immediateFlush="null", ignoreExceptions="null", PatternLayout(%d %p %C{1.} [%t] %m%n), name="MyFile", Filter=null)
2016-10-11 00:01:03,154 main DEBUG Starting FileManager logs/artdept.log
2016-10-11 00:01:03,154 main DEBUG Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
2016-10-11 00:01:03,155 main DEBUG PatternLayout$Builder(pattern="%d %p %C{1.} [%t] %m%n", PatternSelector=null, Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), Replace=null, charset="null", alwaysWriteExceptions="null", noConsoleNoAnsi="null", header="null", footer="null")
2016-10-11 00:01:03,155 main DEBUG Building Plugin[name=CronTriggeringPolicy, class=org.apache.logging.log4j.core.appender.rolling.CronTriggeringPolicy].
2016-10-11 00:01:03,157 main DEBUG createPolicy(Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), evaluateOnStartup="true", schedule="0 0 0 * * ?")
2016-10-11 00:01:03,160 main DEBUG Building Plugin[name=SizeBasedTriggeringPolicy, class=org.apache.logging.log4j.core.appender.rolling.SizeBasedTriggeringPolicy].
2016-10-11 00:01:03,160 main DEBUG createPolicy(size="250 MB")
2016-10-11 00:01:03,161 main DEBUG Building Plugin[name=Policies, class=org.apache.logging.log4j.core.appender.rolling.CompositeTriggeringPolicy].
2016-10-11 00:01:03,162 main DEBUG createPolicy(={CronTriggeringPolicy(schedule=0 0 0 * * ?), SizeBasedTriggeringPolicy(size=262144000)})
2016-10-11 00:01:03,162 main DEBUG Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.RollingFileAppender].
2016-10-11 00:01:03,165 main DEBUG RollingFileAppender$Builder(fileName="/Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/Launcher.log", filePattern="/Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/%d{dd} - Launcher-%i.log", append="null", locking="null", Policies(CompositeTriggeringPolicy(policies=[CronTriggeringPolicy(schedule=0 0 0 * * ?), SizeBasedTriggeringPolicy(size=262144000)])), Strategy=null, advertise="null", advertiseUri="null", createOnDemand="null", Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), bufferedIo="null", bufferSize="null", immediateFlush="null", ignoreExceptions="null", PatternLayout(%d %p %C{1.} [%t] %m%n), name="DailyRoll", Filter=null)
2016-10-11 00:01:03,175 main TRACE New file '/Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/Launcher.log' created = false
2016-10-11 00:01:03,177 main DEBUG Starting RollingFileManager /Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/Launcher.log
2016-10-11 00:01:03,178 main DEBUG PluginManager 'FileConverter' found 2 plugins
2016-10-11 00:01:03,182 main TRACE Using default SystemClock for timestamps.
2016-10-11 00:01:03,191 main TRACE DefaultRolloverStrategy.purge() took 10.0 milliseconds
2016-10-11 00:01:03,193 main DEBUG RollingFileManager executing synchronous FileRenameAction[/Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/Launcher.log to /Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/11 - Launcher-2.log, renameEmptyFiles=false]
2016-10-11 00:01:03,203 main TRACE Renamed file /Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/Launcher.log to /Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/11 - Launcher-2.log with Files.move
2016-10-11 00:01:03,205 main ERROR Unable to inject fields into builder class for plugin type class org.apache.logging.log4j.core.appender.RollingFileAppender, element RollingFile. java.lang.NullPointerException
at org.apache.logging.log4j.core.appender.rolling.CronTriggeringPolicy.rollover(CronTriggeringPolicy.java:133)
at org.apache.logging.log4j.core.appender.rolling.CronTriggeringPolicy.initialize(CronTriggeringPolicy.java:69)
at org.apache.logging.log4j.core.appender.rolling.CompositeTriggeringPolicy.initialize(CompositeTriggeringPolicy.java:52)
at org.apache.logging.log4j.core.appender.rolling.RollingFileManager.initialize(RollingFileManager.java:104)
at org.apache.logging.log4j.core.appender.RollingFileAppender$Builder.build(RollingFileAppender.java:142)
at org.apache.logging.log4j.core.appender.RollingFileAppender$Builder.build(RollingFileAppender.java:58)
at org.apache.logging.log4j.core.config.plugins.util.PluginBuilder.build(PluginBuilder.java:122)
at org.apache.logging.log4j.core.config.AbstractConfiguration.createPluginObject(AbstractConfiguration.java:942)
at org.apache.logging.log4j.core.config.AbstractConfiguration.createConfiguration(AbstractConfiguration.java:882)
at org.apache.logging.log4j.core.config.AbstractConfiguration.createConfiguration(AbstractConfiguration.java:874)
at org.apache.logging.log4j.core.config.AbstractConfiguration.doConfigure(AbstractConfiguration.java:498)
at org.apache.logging.log4j.core.config.AbstractConfiguration.initialize(AbstractConfiguration.java:227)
at org.apache.logging.log4j.core.config.AbstractConfiguration.start(AbstractConfiguration.java:239)
at org.apache.logging.log4j.core.LoggerContext.setConfiguration(LoggerContext.java:530)
at org.apache.logging.log4j.core.LoggerContext.reconfigure(LoggerContext.java:603)
at org.apache.logging.log4j.core.LoggerContext.reconfigure(LoggerContext.java:620)
at org.apache.logging.log4j.core.LoggerContext.start(LoggerContext.java:226)
at org.apache.logging.log4j.core.impl.Log4jContextFactory.getContext(Log4jContextFactory.java:242)
at org.apache.logging.log4j.core.impl.Log4jContextFactory.getContext(Log4jContextFactory.java:45)
at org.apache.logging.log4j.LogManager.getContext(LogManager.java:174)
at org.apache.logging.log4j.LogManager.getLogger(LogManager.java:618)
at info.chrismcgee.sky.artdept.ArtDept.<clinit>(ArtDept.java:85)
2016-10-11 00:01:03,206 main ERROR Unable to invoke factory method in class class org.apache.logging.log4j.core.appender.RollingFileAppender for element RollingFile. java.lang.IllegalStateException: No factory method found for class org.apache.logging.log4j.core.appender.RollingFileAppender
at org.apache.logging.log4j.core.config.plugins.util.PluginBuilder.findFactoryMethod(PluginBuilder.java:224)
at org.apache.logging.log4j.core.config.plugins.util.PluginBuilder.build(PluginBuilder.java:130)
at org.apache.logging.log4j.core.config.AbstractConfiguration.createPluginObject(AbstractConfiguration.java:942)
at org.apache.logging.log4j.core.config.AbstractConfiguration.createConfiguration(AbstractConfiguration.java:882)
at org.apache.logging.log4j.core.config.AbstractConfiguration.createConfiguration(AbstractConfiguration.java:874)
at org.apache.logging.log4j.core.config.AbstractConfiguration.doConfigure(AbstractConfiguration.java:498)
at org.apache.logging.log4j.core.config.AbstractConfiguration.initialize(AbstractConfiguration.java:227)
at org.apache.logging.log4j.core.config.AbstractConfiguration.start(AbstractConfiguration.java:239)
at org.apache.logging.log4j.core.LoggerContext.setConfiguration(LoggerContext.java:530)
at org.apache.logging.log4j.core.LoggerContext.reconfigure(LoggerContext.java:603)
at org.apache.logging.log4j.core.LoggerContext.reconfigure(LoggerContext.java:620)
at org.apache.logging.log4j.core.LoggerContext.start(LoggerContext.java:226)
at org.apache.logging.log4j.core.impl.Log4jContextFactory.getContext(Log4jContextFactory.java:242)
at org.apache.logging.log4j.core.impl.Log4jContextFactory.getContext(Log4jContextFactory.java:45)
at org.apache.logging.log4j.LogManager.getContext(LogManager.java:174)
at org.apache.logging.log4j.LogManager.getLogger(LogManager.java:618)
at info.chrismcgee.sky.artdept.ArtDept.<clinit>(ArtDept.java:85)
2016-10-11 00:01:03,207 main DEBUG Building Plugin[name=appenders, class=org.apache.logging.log4j.core.config.AppendersPlugin].
2016-10-11 00:01:03,207 main ERROR Null object returned for RollingFile in Appenders.
2016-10-11 00:01:03,207 main DEBUG createAppenders(={Console, MyFile, })
2016-10-11 00:01:03,207 main DEBUG Building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef].
2016-10-11 00:01:03,208 main DEBUG createAppenderRef(ref="DailyRoll", level="null", Filter=null)
2016-10-11 00:01:03,208 main DEBUG Building Plugin[name=root, class=org.apache.logging.log4j.core.config.LoggerConfig$RootLogger].
2016-10-11 00:01:03,209 main DEBUG createLogger(additivity="null", level="TRACE", includeLocation="null", ={DailyRoll}, ={}, Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), Filter=null)
2016-10-11 00:01:03,211 main DEBUG Building Plugin[name=loggers, class=org.apache.logging.log4j.core.config.LoggersPlugin].
2016-10-11 00:01:03,211 main DEBUG createLoggers(={root})
2016-10-11 00:01:03,211 main ERROR Unable to locate appender "DailyRoll" for logger config "root"
2016-10-11 00:01:03,212 main DEBUG Configuration XmlConfiguration[location=/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml] initialized
2016-10-11 00:01:03,212 main DEBUG Starting configuration XmlConfiguration[location=/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml]
2016-10-11 00:01:03,213 main DEBUG Started configuration XmlConfiguration[location=/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml] OK.
2016-10-11 00:01:03,214 main TRACE Stopping org.apache.logging.log4j.core.config.DefaultConfiguration@76ccd017...
2016-10-11 00:01:03,214 main TRACE DefaultConfiguration notified 1 ReliabilityStrategies that config will be stopped.
2016-10-11 00:01:03,214 main TRACE DefaultConfiguration stopping root LoggerConfig.
2016-10-11 00:01:03,214 main TRACE DefaultConfiguration notifying ReliabilityStrategies that appenders will be stopped.
2016-10-11 00:01:03,214 main TRACE DefaultConfiguration stopping remaining Appenders.
2016-10-11 00:01:03,214 main DEBUG Shutting down OutputStreamManager SYSTEM_OUT.false.false-1
2016-10-11 00:01:03,215 main DEBUG Shut down OutputStreamManager SYSTEM_OUT.false.false-1, all resources released: true
2016-10-11 00:01:03,215 main TRACE DefaultConfiguration stopped 1 remaining Appenders.
2016-10-11 00:01:03,215 main TRACE DefaultConfiguration cleaning Appenders from 1 LoggerConfigs.
2016-10-11 00:01:03,215 main DEBUG Stopped org.apache.logging.log4j.core.config.DefaultConfiguration@76ccd017 OK
2016-10-11 00:01:03,265 main TRACE Reregistering MBeans after reconfigure. Selector=org.apache.logging.log4j.core.selector.ClassLoaderContextSelector@689604d9
2016-10-11 00:01:03,266 main TRACE Reregistering context (1/1): '2a139a55' org.apache.logging.log4j.core.LoggerContext@18078bef
2016-10-11 00:01:03,267 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55'
2016-10-11 00:01:03,268 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=StatusLogger'
2016-10-11 00:01:03,268 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=ContextSelector'
2016-10-11 00:01:03,268 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=Loggers,name=*'
2016-10-11 00:01:03,269 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=Appenders,name=*'
2016-10-11 00:01:03,269 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=AsyncAppenders,name=*'
2016-10-11 00:01:03,269 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=AsyncLoggerRingBuffer'
2016-10-11 00:01:03,269 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=Loggers,name=*,subtype=RingBuffer'
2016-10-11 00:01:03,270 main DEBUG Registering MBean org.apache.logging.log4j2:type=2a139a55
2016-10-11 00:01:03,272 main DEBUG Registering MBean org.apache.logging.log4j2:type=2a139a55,component=StatusLogger
2016-10-11 00:01:03,273 main DEBUG Registering MBean org.apache.logging.log4j2:type=2a139a55,component=ContextSelector
2016-10-11 00:01:03,274 main DEBUG Registering MBean org.apache.logging.log4j2:type=2a139a55,component=Loggers,name=
2016-10-11 00:01:03,276 main DEBUG Registering MBean org.apache.logging.log4j2:type=2a139a55,component=Appenders,name=Console
2016-10-11 00:01:03,276 main DEBUG Registering MBean org.apache.logging.log4j2:type=2a139a55,component=Appenders,name=MyFile
2016-10-11 00:01:03,276 main TRACE Using DummyNanoClock for nanosecond timestamps.
2016-10-11 00:01:03,277 main DEBUG Reconfiguration complete for context[name=2a139a55] at URI /Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml (org.apache.logging.log4j.core.LoggerContext@18078bef) with optional ClassLoader: null
2016-10-11 00:01:03,277 main DEBUG Shutdown hook enabled. Registering a new one.
2016-10-11 00:01:03,278 main DEBUG LoggerContext[name=2a139a55, org.apache.logging.log4j.core.LoggerContext@18078bef] started OK.
Debug Log menu item clicked; Logging is now true
2016-10-11 00:02:05,448 pool-1-thread-1 DEBUG Stopping LoggerContext[name=2a139a55, org.apache.logging.log4j.core.LoggerContext@18078bef]
2016-10-11 00:02:05,449 pool-1-thread-1 DEBUG Stopping LoggerContext[name=2a139a55, org.apache.logging.log4j.core.LoggerContext@18078bef]...
2016-10-11 00:02:05,449 pool-1-thread-1 TRACE Unregistering 1 MBeans: [org.apache.logging.log4j2:type=2a139a55]
2016-10-11 00:02:05,449 pool-1-thread-1 TRACE Unregistering 1 MBeans: [org.apache.logging.log4j2:type=2a139a55,component=StatusLogger]
2016-10-11 00:02:05,449 pool-1-thread-1 TRACE Unregistering 1 MBeans: [org.apache.logging.log4j2:type=2a139a55,component=ContextSelector]
2016-10-11 00:02:05,450 pool-1-thread-1 TRACE Unregistering 1 MBeans: [org.apache.logging.log4j2:type=2a139a55,component=Loggers,name=]
2016-10-11 00:02:05,450 pool-1-thread-1 TRACE Unregistering 2 MBeans: [org.apache.logging.log4j2:type=2a139a55,component=Appenders,name=MyFile, org.apache.logging.log4j2:type=2a139a55,component=Appenders,name=Console]
2016-10-11 00:02:05,450 pool-1-thread-1 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=AsyncAppenders,name=*'
2016-10-11 00:02:05,450 pool-1-thread-1 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=AsyncLoggerRingBuffer'
2016-10-11 00:02:05,451 pool-1-thread-1 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=Loggers,name=*,subtype=RingBuffer'
2016-10-11 00:02:05,451 pool-1-thread-1 TRACE Stopping XmlConfiguration[location=/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml]...
2016-10-11 00:02:05,451 pool-1-thread-1 TRACE XmlConfiguration notified 2 ReliabilityStrategies that config will be stopped.
2016-10-11 00:02:05,451 pool-1-thread-1 TRACE XmlConfiguration stopping 1 LoggerConfigs.
2016-10-11 00:02:05,451 pool-1-thread-1 TRACE XmlConfiguration stopping root LoggerConfig.
2016-10-11 00:02:05,451 pool-1-thread-1 TRACE XmlConfiguration notifying ReliabilityStrategies that appenders will be stopped.
2016-10-11 00:02:05,452 pool-1-thread-1 TRACE XmlConfiguration stopping remaining Appenders.
2016-10-11 00:02:05,452 pool-1-thread-1 DEBUG Shutting down FileManager logs/artdept.log
2016-10-11 00:02:05,452 pool-1-thread-1 DEBUG Shut down FileManager logs/artdept.log, all resources released: true
2016-10-11 00:02:05,452 pool-1-thread-1 DEBUG Shutting down OutputStreamManager SYSTEM_OUT.false.false
2016-10-11 00:02:05,452 pool-1-thread-1 DEBUG Shut down OutputStreamManager SYSTEM_OUT.false.false, all resources released: true
2016-10-11 00:02:05,452 pool-1-thread-1 TRACE XmlConfiguration stopped 2 remaining Appenders.
2016-10-11 00:02:05,453 pool-1-thread-1 TRACE XmlConfiguration cleaning Appenders from 2 LoggerConfigs.
2016-10-11 00:02:05,453 pool-1-thread-1 DEBUG Log4j2 ConfigurationScheduler shutting down threads in java.util.concurrent.ScheduledThreadPoolExecutor@4fba9a33[Running, pool size = 2, active threads = 0, queued tasks = 1, completed tasks = 2]
2016-10-11 00:02:05,453 pool-1-thread-1 DEBUG Stopped XmlConfiguration[location=/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml] OK
2016-10-11 00:02:05,454 pool-1-thread-1 DEBUG Stopped LoggerContext[name=2a139a55, org.apache.logging.log4j.core.LoggerContext@18078bef]...
{noformat}
The rollover _does_ occur, but then nothing is recorded into the current log file.
For reference, the code in my ArtDept.java file on line 85 (as seen in the exception violation lines in the stacktrace above) is just a simple field declaration & initialization:
{code:title=ArtDept.java|borderStyle=solid}
static final Logger log = LogManager.getLogger(ArtDept.class.getName());
{code}
Finally, for reference, my log4j2.xml file:
{code:xml|title=log4j2.xml|borderStyle=solid}
<?xml version="1.0" encoding="UTF-8"?>
<Configuration status="TRACE" monitorInterval="30">
<Properties>
<Property name="filename">logs/artdept.log</Property>
<Property name="baseDir">/Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs</Property>
</Properties>
<Appenders>
<Console name="Console" target="SYSTEM_OUT">
<PatternLayout pattern="%d{HH:mm:ss.SSS} [%t] %-5level %logger{36} - %msg%n"/>
</Console>
<File name="MyFile" fileName="${filename}">
<PatternLayout>
<pattern>%d %p %C{1.} [%t] %m%n</pattern>
</PatternLayout>
</File>
<RollingFile name="DailyRoll" fileName="${baseDir}/${env:USER}/${date:yyyy}/${date:MM} - ${date:MMM}/Launcher.log"
filePattern="${baseDir}/${env:USER}/${date:yyyy}/${date:MM} - ${date:MMM}/%d{dd} - Launcher-%i.log">
<PatternLayout>
<Pattern>%d %p %C{1.} [%t] %m%n</Pattern>
</PatternLayout>
<Policies>
<CronTriggeringPolicy schedule="0 0 0 * * ?" evaluateOnStartup="true" />
<SizeBasedTriggeringPolicy size="250 MB" />
</Policies>
</RollingFile>
</Appenders>
<Loggers>
<Root level="trace">
<AppenderRef ref="DailyRoll"/>
</Root>
</Loggers>
</Configuration>
{code}
*EDIT:* Provided some more detailed info and more detailed stacktraces.
was:
If this isn't actually a bug, then I apologize for reporting it, but I cannot figure out how it could be anything else currently. Full disclosure: I am still a newbie.
I've been using the log4j 2.6.x series for a while, but noticed that the CronTriggeringPolicy when used with RollingFileAppender causes that infinite rollover bug. I noted that this bug was to be fixed in 2.7, so I downloaded that the day it was released and replaced the 2.6 version with it. Now, however, without changing anything else in my code nor in my log4j2.xml file, I am getting exceptions regarding them.
The stacktrace:
{noformat}
2016-10-10 08:26:14,108 main ERROR Unable to inject fields into builder class for plugin type class org.apache.logging.log4j.core.appender.RollingFileAppender, element RollingFile. java.lang.NullPointerException
at org.apache.logging.log4j.core.appender.rolling.CronTriggeringPolicy.rollover(CronTriggeringPolicy.java:133)
at org.apache.logging.log4j.core.appender.rolling.CronTriggeringPolicy.initialize(CronTriggeringPolicy.java:69)
at org.apache.logging.log4j.core.appender.rolling.CompositeTriggeringPolicy.initialize(CompositeTriggeringPolicy.java:52)
at org.apache.logging.log4j.core.appender.rolling.RollingFileManager.initialize(RollingFileManager.java:104)
at org.apache.logging.log4j.core.appender.RollingFileAppender$Builder.build(RollingFileAppender.java:142)
at org.apache.logging.log4j.core.appender.RollingFileAppender$Builder.build(RollingFileAppender.java:58)
at org.apache.logging.log4j.core.config.plugins.util.PluginBuilder.build(PluginBuilder.java:122)
at org.apache.logging.log4j.core.config.AbstractConfiguration.createPluginObject(AbstractConfiguration.java:942)
at org.apache.logging.log4j.core.config.AbstractConfiguration.createConfiguration(AbstractConfiguration.java:882)
at org.apache.logging.log4j.core.config.AbstractConfiguration.createConfiguration(AbstractConfiguration.java:874)
at org.apache.logging.log4j.core.config.AbstractConfiguration.doConfigure(AbstractConfiguration.java:498)
at org.apache.logging.log4j.core.config.AbstractConfiguration.initialize(AbstractConfiguration.java:227)
at org.apache.logging.log4j.core.config.AbstractConfiguration.start(AbstractConfiguration.java:239)
at org.apache.logging.log4j.core.LoggerContext.setConfiguration(LoggerContext.java:530)
at org.apache.logging.log4j.core.LoggerContext.reconfigure(LoggerContext.java:603)
at org.apache.logging.log4j.core.LoggerContext.reconfigure(LoggerContext.java:620)
at org.apache.logging.log4j.core.LoggerContext.start(LoggerContext.java:226)
at org.apache.logging.log4j.core.impl.Log4jContextFactory.getContext(Log4jContextFactory.java:242)
at org.apache.logging.log4j.core.impl.Log4jContextFactory.getContext(Log4jContextFactory.java:45)
at org.apache.logging.log4j.LogManager.getContext(LogManager.java:174)
at org.apache.logging.log4j.LogManager.getLogger(LogManager.java:618)
at info.chrismcgee.sky.artdept.ArtDept.<clinit>(ArtDept.java:85)
2016-10-10 08:26:14,112 main ERROR Unable to invoke factory method in class class org.apache.logging.log4j.core.appender.RollingFileAppender for element RollingFile. java.lang.IllegalStateException: No factory method found for class org.apache.logging.log4j.core.appender.RollingFileAppender
at org.apache.logging.log4j.core.config.plugins.util.PluginBuilder.findFactoryMethod(PluginBuilder.java:224)
at org.apache.logging.log4j.core.config.plugins.util.PluginBuilder.build(PluginBuilder.java:130)
at org.apache.logging.log4j.core.config.AbstractConfiguration.createPluginObject(AbstractConfiguration.java:942)
at org.apache.logging.log4j.core.config.AbstractConfiguration.createConfiguration(AbstractConfiguration.java:882)
at org.apache.logging.log4j.core.config.AbstractConfiguration.createConfiguration(AbstractConfiguration.java:874)
at org.apache.logging.log4j.core.config.AbstractConfiguration.doConfigure(AbstractConfiguration.java:498)
at org.apache.logging.log4j.core.config.AbstractConfiguration.initialize(AbstractConfiguration.java:227)
at org.apache.logging.log4j.core.config.AbstractConfiguration.start(AbstractConfiguration.java:239)
at org.apache.logging.log4j.core.LoggerContext.setConfiguration(LoggerContext.java:530)
at org.apache.logging.log4j.core.LoggerContext.reconfigure(LoggerContext.java:603)
at org.apache.logging.log4j.core.LoggerContext.reconfigure(LoggerContext.java:620)
at org.apache.logging.log4j.core.LoggerContext.start(LoggerContext.java:226)
at org.apache.logging.log4j.core.impl.Log4jContextFactory.getContext(Log4jContextFactory.java:242)
at org.apache.logging.log4j.core.impl.Log4jContextFactory.getContext(Log4jContextFactory.java:45)
at org.apache.logging.log4j.LogManager.getContext(LogManager.java:174)
at org.apache.logging.log4j.LogManager.getLogger(LogManager.java:618)
at info.chrismcgee.sky.artdept.ArtDept.<clinit>(ArtDept.java:85)
2016-10-10 08:26:14,113 main ERROR Null object returned for RollingFile in Appenders.
2016-10-10 08:26:14,117 main ERROR Unable to locate appender "DailyRoll" for logger config "root"
{noformat}
For reference, the code in my ArtDept.java file on line 85 (as seen in the stacktrace above) is just a simple field declaration & initialization:
{code:title=ArtDept.java|borderStyle=solid}
static final Logger log = LogManager.getLogger(ArtDept.class.getName());
{code}
Finally, for reference, my log4j2.xml file:
{code:xml|title=log4j2.xml|borderStyle=solid}
<?xml version="1.0" encoding="UTF-8"?>
<Configuration status="WARN" monitorInterval="30">
<Properties>
<Property name="filename">logs/artdept.log</Property>
<Property name="baseDir">/Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs</Property>
</Properties>
<Appenders>
<Console name="Console" target="SYSTEM_OUT">
<PatternLayout pattern="%d{HH:mm:ss.SSS} [%t] %-5level %logger{36} - %msg%n"/>
</Console>
<File name="MyFile" fileName="${filename}">
<PatternLayout>
<pattern>%d %p %C{1.} [%t] %m%n</pattern>
</PatternLayout>
</File>
<RollingFile name="DailyRoll" fileName="${baseDir}/${env:USER}/${date:yyyy}/${date:MM} - ${date:MMM}/Launcher.log"
filePattern="${baseDir}/${env:USER}/${date:yyyy}/${date:MM} - ${date:MMM}/%d{dd} - Launcher-%i.log">
<PatternLayout>
<Pattern>%d %p %C{1.} [%t] %m%n</Pattern>
</PatternLayout>
<Policies>
<CronTriggeringPolicy schedule="0 0 0 * * ?" evaluateOnStartup="true" />
<SizeBasedTriggeringPolicy size="250 MB" />
</Policies>
</RollingFile>
</Appenders>
<Loggers>
<Root level="trace">
<AppenderRef ref="DailyRoll"/>
</Root>
</Loggers>
</Configuration>
{code}
> RollingFileAppender with CronTriggeringPolicy broken?
> -----------------------------------------------------
>
> Key: LOG4J2-1640
> URL: https://issues.apache.org/jira/browse/LOG4J2-1640
> Project: Log4j 2
> Issue Type: Bug
> Components: Core
> Affects Versions: 2.7
> Environment: Mac OS X 10.11.6 running Eclipse Neon 4.6.0 with JDK 1.8.0_74
> Reporter: Chris McGee
> Priority: Blocker
> Labels: CronTriggeringPolicy, RollingFile, RollingFileAppender, newbie
>
> If this isn't actually a bug, then I apologize for reporting it, but I cannot figure out how it could be anything else currently. Full disclosure: I am still a newbie.
> I've been using the log4j 2.6.x series for a while, but noticed that the CronTriggeringPolicy when used with RollingFileAppender causes that infinite rollover bug. I noted that this bug was to be fixed in 2.7, so I downloaded that the day it was released and replaced the 2.6 version with it. Now, however, without changing anything else in my code nor in my log4j2.xml file, I am getting exceptions regarding them.
> Here's the interesting bit: Since I was trying to see if the rollover would occur at midnight, I manually changed my computer's clock to just a minute before, logged some info, let it roll to past midnight, and let it log some more info. All of that info got logged into the main file; nothing rolled over. Here's the stacktrace from that execution:
> {noformat}
> 2016-10-10 09:40:47,521 main DEBUG Initializing configuration XmlConfiguration[location=/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml]
> 2016-10-10 09:40:47,526 main DEBUG Installed script engines
> 2016-10-10 09:40:47,955 main DEBUG Oracle Nashorn Version: 1.8.0_74, Language: ECMAScript, Threading: Not Thread Safe, Compile: true, Names: {nashorn, Nashorn, js, JS, JavaScript, javascript, ECMAScript, ecmascript}
> 2016-10-10 09:40:48,307 main DEBUG AppleScriptEngine Version: 1.1, Language: AppleScript, Threading: Not Thread Safe, Compile: false, Names: {AppleScriptEngine, AppleScript, OSA}
> 2016-10-10 09:40:48,308 main DEBUG PluginManager 'Core' found 107 plugins
> 2016-10-10 09:40:48,308 main DEBUG PluginManager 'Level' found 0 plugins
> 2016-10-10 09:40:48,312 main DEBUG 2 starting Log4j2 ConfigurationScheduler threads
> 2016-10-10 09:40:48,314 main DEBUG Building Plugin[name=property, class=org.apache.logging.log4j.core.config.Property].
> 2016-10-10 09:40:48,323 main TRACE TypeConverterRegistry initializing.
> 2016-10-10 09:40:48,324 main DEBUG PluginManager 'TypeConverter' found 23 plugins
> 2016-10-10 09:40:48,330 main DEBUG createProperty(name="filename", value="logs/artdept.log")
> 2016-10-10 09:40:48,330 main DEBUG Building Plugin[name=property, class=org.apache.logging.log4j.core.config.Property].
> 2016-10-10 09:40:48,331 main DEBUG createProperty(name="baseDir", value="/Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs")
> 2016-10-10 09:40:48,331 main DEBUG Building Plugin[name=properties, class=org.apache.logging.log4j.core.config.PropertiesPlugin].
> 2016-10-10 09:40:48,334 main DEBUG configureSubstitutor(={filename=logs/artdept.log, baseDir=/Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs}, Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml))
> 2016-10-10 09:40:48,335 main DEBUG PluginManager 'Lookup' found 13 plugins
> 2016-10-10 09:40:48,335 main DEBUG Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
> 2016-10-10 09:40:48,341 main DEBUG PatternLayout$Builder(pattern="%d{HH:mm:ss.SSS} [%t] %-5level %logger{36} - %msg%n", PatternSelector=null, Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), Replace=null, charset="null", alwaysWriteExceptions="null", noConsoleNoAnsi="null", header="null", footer="null")
> 2016-10-10 09:40:48,341 main DEBUG PluginManager 'Converter' found 41 plugins
> 2016-10-10 09:40:48,342 main DEBUG Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.ConsoleAppender].
> 2016-10-10 09:40:48,347 main DEBUG ConsoleAppender$Builder(target="SYSTEM_OUT", follow="null", direct="null", bufferedIo="null", bufferSize="null", immediateFlush="null", ignoreExceptions="null", PatternLayout(%d{HH:mm:ss.SSS} [%t] %-5level %logger{36} - %msg%n), name="Console", Filter=null)
> 2016-10-10 09:40:48,347 main DEBUG Starting OutputStreamManager SYSTEM_OUT.false.false
> 2016-10-10 09:40:48,348 main DEBUG Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
> 2016-10-10 09:40:48,348 main DEBUG PatternLayout$Builder(pattern="%d %p %C{1.} [%t] %m%n", PatternSelector=null, Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), Replace=null, charset="null", alwaysWriteExceptions="null", noConsoleNoAnsi="null", header="null", footer="null")
> 2016-10-10 09:40:48,360 main DEBUG Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.FileAppender].
> 2016-10-10 09:40:48,364 main DEBUG FileAppender$Builder(fileName="logs/artdept.log", append="null", locking="null", advertise="null", advertiseUri="null", createOnDemand="null", Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), bufferedIo="null", bufferSize="null", immediateFlush="null", ignoreExceptions="null", PatternLayout(%d %p %C{1.} [%t] %m%n), name="MyFile", Filter=null)
> 2016-10-10 09:40:48,365 main DEBUG Starting FileManager logs/artdept.log
> 2016-10-10 09:40:48,366 main DEBUG Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
> 2016-10-10 09:40:48,366 main DEBUG PatternLayout$Builder(pattern="%d %p %C{1.} [%t] %m%n", PatternSelector=null, Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), Replace=null, charset="null", alwaysWriteExceptions="null", noConsoleNoAnsi="null", header="null", footer="null")
> 2016-10-10 09:40:48,367 main DEBUG Building Plugin[name=CronTriggeringPolicy, class=org.apache.logging.log4j.core.appender.rolling.CronTriggeringPolicy].
> 2016-10-10 09:40:48,368 main DEBUG createPolicy(Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), evaluateOnStartup="true", schedule="0 0 0 * * ?")
> 2016-10-10 09:40:48,370 main DEBUG Building Plugin[name=SizeBasedTriggeringPolicy, class=org.apache.logging.log4j.core.appender.rolling.SizeBasedTriggeringPolicy].
> 2016-10-10 09:40:48,371 main DEBUG createPolicy(size="250 MB")
> 2016-10-10 09:40:48,372 main DEBUG Building Plugin[name=Policies, class=org.apache.logging.log4j.core.appender.rolling.CompositeTriggeringPolicy].
> 2016-10-10 09:40:48,373 main DEBUG createPolicy(={CronTriggeringPolicy(schedule=0 0 0 * * ?), SizeBasedTriggeringPolicy(size=262144000)})
> 2016-10-10 09:40:48,373 main DEBUG Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.RollingFileAppender].
> 2016-10-10 09:40:48,376 main DEBUG RollingFileAppender$Builder(fileName="/Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/Launcher.log", filePattern="/Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/%d{dd} - Launcher-%i.log", append="null", locking="null", Policies(CompositeTriggeringPolicy(policies=[CronTriggeringPolicy(schedule=0 0 0 * * ?), SizeBasedTriggeringPolicy(size=262144000)])), Strategy=null, advertise="null", advertiseUri="null", createOnDemand="null", Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), bufferedIo="null", bufferSize="null", immediateFlush="null", ignoreExceptions="null", PatternLayout(%d %p %C{1.} [%t] %m%n), name="DailyRoll", Filter=null)
> 2016-10-10 09:40:48,512 main TRACE New file '/Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/Launcher.log' created = false
> 2016-10-10 09:40:48,515 main DEBUG Starting RollingFileManager /Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/Launcher.log
> 2016-10-10 09:40:48,516 main DEBUG PluginManager 'FileConverter' found 2 plugins
> 2016-10-10 09:40:48,520 main DEBUG Building Plugin[name=appenders, class=org.apache.logging.log4j.core.config.AppendersPlugin].
> 2016-10-10 09:40:48,521 main DEBUG createAppenders(={Console, MyFile, DailyRoll})
> 2016-10-10 09:40:48,521 main DEBUG Building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef].
> 2016-10-10 09:40:48,521 main DEBUG createAppenderRef(ref="DailyRoll", level="null", Filter=null)
> 2016-10-10 09:40:48,522 main DEBUG Building Plugin[name=root, class=org.apache.logging.log4j.core.config.LoggerConfig$RootLogger].
> 2016-10-10 09:40:48,522 main DEBUG createLogger(additivity="null", level="TRACE", includeLocation="null", ={DailyRoll}, ={}, Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), Filter=null)
> 2016-10-10 09:40:48,524 main DEBUG Building Plugin[name=loggers, class=org.apache.logging.log4j.core.config.LoggersPlugin].
> 2016-10-10 09:40:48,525 main DEBUG createLoggers(={root})
> 2016-10-10 09:40:48,525 main DEBUG Configuration XmlConfiguration[location=/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml] initialized
> 2016-10-10 09:40:48,525 main DEBUG Starting configuration XmlConfiguration[location=/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml]
> 2016-10-10 09:40:48,526 main DEBUG Started configuration XmlConfiguration[location=/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml] OK.
> 2016-10-10 09:40:48,526 main TRACE Stopping org.apache.logging.log4j.core.config.DefaultConfiguration@76ccd017...
> 2016-10-10 09:40:48,527 main TRACE DefaultConfiguration notified 1 ReliabilityStrategies that config will be stopped.
> 2016-10-10 09:40:48,527 main TRACE DefaultConfiguration stopping root LoggerConfig.
> 2016-10-10 09:40:48,527 main TRACE DefaultConfiguration notifying ReliabilityStrategies that appenders will be stopped.
> 2016-10-10 09:40:48,527 main TRACE DefaultConfiguration stopping remaining Appenders.
> 2016-10-10 09:40:48,527 main DEBUG Shutting down OutputStreamManager SYSTEM_OUT.false.false-1
> 2016-10-10 09:40:48,528 main DEBUG Shut down OutputStreamManager SYSTEM_OUT.false.false-1, all resources released: true
> 2016-10-10 09:40:48,528 main TRACE DefaultConfiguration stopped 1 remaining Appenders.
> 2016-10-10 09:40:48,528 main TRACE DefaultConfiguration cleaning Appenders from 1 LoggerConfigs.
> 2016-10-10 09:40:48,528 main DEBUG Stopped org.apache.logging.log4j.core.config.DefaultConfiguration@76ccd017 OK
> 2016-10-10 09:40:48,572 main TRACE Reregistering MBeans after reconfigure. Selector=org.apache.logging.log4j.core.selector.ClassLoaderContextSelector@1bd4fdd
> 2016-10-10 09:40:48,572 main TRACE Reregistering context (1/1): '2a139a55' org.apache.logging.log4j.core.LoggerContext@55183b20
> 2016-10-10 09:40:48,574 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55'
> 2016-10-10 09:40:48,574 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=StatusLogger'
> 2016-10-10 09:40:48,574 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=ContextSelector'
> 2016-10-10 09:40:48,575 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=Loggers,name=*'
> 2016-10-10 09:40:48,575 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=Appenders,name=*'
> 2016-10-10 09:40:48,575 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=AsyncAppenders,name=*'
> 2016-10-10 09:40:48,576 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=AsyncLoggerRingBuffer'
> 2016-10-10 09:40:48,576 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=Loggers,name=*,subtype=RingBuffer'
> 2016-10-10 09:40:48,578 main DEBUG Registering MBean org.apache.logging.log4j2:type=2a139a55
> 2016-10-10 09:40:48,580 main DEBUG Registering MBean org.apache.logging.log4j2:type=2a139a55,component=StatusLogger
> 2016-10-10 09:40:48,581 main DEBUG Registering MBean org.apache.logging.log4j2:type=2a139a55,component=ContextSelector
> 2016-10-10 09:40:48,582 main DEBUG Registering MBean org.apache.logging.log4j2:type=2a139a55,component=Loggers,name=
> 2016-10-10 09:40:48,583 main DEBUG Registering MBean org.apache.logging.log4j2:type=2a139a55,component=Appenders,name=Console
> 2016-10-10 09:40:48,583 main DEBUG Registering MBean org.apache.logging.log4j2:type=2a139a55,component=Appenders,name=MyFile
> 2016-10-10 09:40:48,583 main DEBUG Registering MBean org.apache.logging.log4j2:type=2a139a55,component=Appenders,name=DailyRoll
> 2016-10-10 09:40:48,585 main TRACE Using default SystemClock for timestamps.
> 2016-10-10 09:40:48,585 main TRACE Using DummyNanoClock for nanosecond timestamps.
> 2016-10-10 09:40:48,585 main DEBUG Reconfiguration complete for context[name=2a139a55] at URI /Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml (org.apache.logging.log4j.core.LoggerContext@55183b20) with optional ClassLoader: null
> 2016-10-10 09:40:48,585 main DEBUG Shutdown hook enabled. Registering a new one.
> 2016-10-10 09:40:48,586 main DEBUG LoggerContext[name=2a139a55, org.apache.logging.log4j.core.LoggerContext@55183b20] started OK.
> Debug Log menu item clicked; Logging is now true
> 2016-10-11 00:00:25,734 pool-1-thread-1 DEBUG Stopping LoggerContext[name=2a139a55, org.apache.logging.log4j.core.LoggerContext@55183b20]
> 2016-10-11 00:00:25,734 pool-1-thread-1 DEBUG Stopping LoggerContext[name=2a139a55, org.apache.logging.log4j.core.LoggerContext@55183b20]...
> 2016-10-11 00:00:25,735 pool-1-thread-1 TRACE Unregistering 1 MBeans: [org.apache.logging.log4j2:type=2a139a55]
> 2016-10-11 00:00:25,735 pool-1-thread-1 TRACE Unregistering 1 MBeans: [org.apache.logging.log4j2:type=2a139a55,component=StatusLogger]
> 2016-10-11 00:00:25,735 pool-1-thread-1 TRACE Unregistering 1 MBeans: [org.apache.logging.log4j2:type=2a139a55,component=ContextSelector]
> 2016-10-11 00:00:25,736 pool-1-thread-1 TRACE Unregistering 1 MBeans: [org.apache.logging.log4j2:type=2a139a55,component=Loggers,name=]
> 2016-10-11 00:00:25,736 pool-1-thread-1 TRACE Unregistering 3 MBeans: [org.apache.logging.log4j2:type=2a139a55,component=Appenders,name=MyFile, org.apache.logging.log4j2:type=2a139a55,component=Appenders,name=Console, org.apache.logging.log4j2:type=2a139a55,component=Appenders,name=DailyRoll]
> 2016-10-11 00:00:25,736 pool-1-thread-1 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=AsyncAppenders,name=*'
> 2016-10-11 00:00:25,736 pool-1-thread-1 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=AsyncLoggerRingBuffer'
> 2016-10-11 00:00:25,736 pool-1-thread-1 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=Loggers,name=*,subtype=RingBuffer'
> 2016-10-11 00:00:25,737 pool-1-thread-1 TRACE Stopping XmlConfiguration[location=/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml]...
> 2016-10-11 00:00:25,737 pool-1-thread-1 TRACE XmlConfiguration notified 2 ReliabilityStrategies that config will be stopped.
> 2016-10-11 00:00:25,737 pool-1-thread-1 TRACE XmlConfiguration stopping 1 LoggerConfigs.
> 2016-10-11 00:00:25,737 pool-1-thread-1 TRACE XmlConfiguration stopping root LoggerConfig.
> 2016-10-11 00:00:25,737 pool-1-thread-1 TRACE XmlConfiguration notifying ReliabilityStrategies that appenders will be stopped.
> 2016-10-11 00:00:25,737 pool-1-thread-1 TRACE XmlConfiguration stopping remaining Appenders.
> 2016-10-11 00:00:25,737 pool-1-thread-1 DEBUG Shutting down RollingFileManager /Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/Launcher.log
> 2016-10-11 00:00:25,988 pool-1-thread-1 DEBUG Shut down RollingFileManager /Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/Launcher.log, all resources released: true
> 2016-10-11 00:00:25,988 pool-1-thread-1 DEBUG Shutting down FileManager logs/artdept.log
> 2016-10-11 00:00:25,988 pool-1-thread-1 DEBUG Shut down FileManager logs/artdept.log, all resources released: true
> 2016-10-11 00:00:25,989 pool-1-thread-1 DEBUG Shutting down OutputStreamManager SYSTEM_OUT.false.false
> 2016-10-11 00:00:25,989 pool-1-thread-1 DEBUG Shut down OutputStreamManager SYSTEM_OUT.false.false, all resources released: true
> 2016-10-11 00:00:25,989 pool-1-thread-1 TRACE XmlConfiguration stopped 3 remaining Appenders.
> 2016-10-11 00:00:25,989 pool-1-thread-1 TRACE XmlConfiguration cleaning Appenders from 2 LoggerConfigs.
> 2016-10-11 00:00:25,989 pool-1-thread-1 DEBUG Log4j2 ConfigurationScheduler shutting down threads in java.util.concurrent.ScheduledThreadPoolExecutor@11d33cbe[Running, pool size = 2, active threads = 0, queued tasks = 2, completed tasks = 7]
> 2016-10-11 00:00:25,989 pool-1-thread-1 DEBUG Stopped XmlConfiguration[location=/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml] OK
> 2016-10-11 00:00:25,990 pool-1-thread-1 DEBUG Stopped LoggerContext[name=2a139a55, org.apache.logging.log4j.core.LoggerContext@55183b20]...
> {noformat}
> No exceptions recorded.
> However, if I re-start the application _while the computer's clock is still showing past Midnight on the following day_, I am given _this_ stacktrace, which _does_ have exceptions recorded:
> {noformat}
> 2016-10-11 00:01:02,843 main DEBUG Initializing configuration XmlConfiguration[location=/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml]
> 2016-10-11 00:01:02,848 main DEBUG Installed script engines
> 2016-10-11 00:01:03,071 main DEBUG Oracle Nashorn Version: 1.8.0_74, Language: ECMAScript, Threading: Not Thread Safe, Compile: true, Names: {nashorn, Nashorn, js, JS, JavaScript, javascript, ECMAScript, ecmascript}
> 2016-10-11 00:01:03,097 main DEBUG AppleScriptEngine Version: 1.1, Language: AppleScript, Threading: Not Thread Safe, Compile: false, Names: {AppleScriptEngine, AppleScript, OSA}
> 2016-10-11 00:01:03,097 main DEBUG PluginManager 'Core' found 107 plugins
> 2016-10-11 00:01:03,097 main DEBUG PluginManager 'Level' found 0 plugins
> 2016-10-11 00:01:03,102 main DEBUG 2 starting Log4j2 ConfigurationScheduler threads
> 2016-10-11 00:01:03,104 main DEBUG Building Plugin[name=property, class=org.apache.logging.log4j.core.config.Property].
> 2016-10-11 00:01:03,113 main TRACE TypeConverterRegistry initializing.
> 2016-10-11 00:01:03,114 main DEBUG PluginManager 'TypeConverter' found 23 plugins
> 2016-10-11 00:01:03,121 main DEBUG createProperty(name="filename", value="logs/artdept.log")
> 2016-10-11 00:01:03,121 main DEBUG Building Plugin[name=property, class=org.apache.logging.log4j.core.config.Property].
> 2016-10-11 00:01:03,122 main DEBUG createProperty(name="baseDir", value="/Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs")
> 2016-10-11 00:01:03,122 main DEBUG Building Plugin[name=properties, class=org.apache.logging.log4j.core.config.PropertiesPlugin].
> 2016-10-11 00:01:03,126 main DEBUG configureSubstitutor(={filename=logs/artdept.log, baseDir=/Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs}, Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml))
> 2016-10-11 00:01:03,127 main DEBUG PluginManager 'Lookup' found 13 plugins
> 2016-10-11 00:01:03,128 main DEBUG Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
> 2016-10-11 00:01:03,132 main DEBUG PatternLayout$Builder(pattern="%d{HH:mm:ss.SSS} [%t] %-5level %logger{36} - %msg%n", PatternSelector=null, Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), Replace=null, charset="null", alwaysWriteExceptions="null", noConsoleNoAnsi="null", header="null", footer="null")
> 2016-10-11 00:01:03,132 main DEBUG PluginManager 'Converter' found 41 plugins
> 2016-10-11 00:01:03,133 main DEBUG Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.ConsoleAppender].
> 2016-10-11 00:01:03,139 main DEBUG ConsoleAppender$Builder(target="SYSTEM_OUT", follow="null", direct="null", bufferedIo="null", bufferSize="null", immediateFlush="null", ignoreExceptions="null", PatternLayout(%d{HH:mm:ss.SSS} [%t] %-5level %logger{36} - %msg%n), name="Console", Filter=null)
> 2016-10-11 00:01:03,139 main DEBUG Starting OutputStreamManager SYSTEM_OUT.false.false
> 2016-10-11 00:01:03,140 main DEBUG Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
> 2016-10-11 00:01:03,140 main DEBUG PatternLayout$Builder(pattern="%d %p %C{1.} [%t] %m%n", PatternSelector=null, Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), Replace=null, charset="null", alwaysWriteExceptions="null", noConsoleNoAnsi="null", header="null", footer="null")
> 2016-10-11 00:01:03,150 main DEBUG Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.FileAppender].
> 2016-10-11 00:01:03,153 main DEBUG FileAppender$Builder(fileName="logs/artdept.log", append="null", locking="null", advertise="null", advertiseUri="null", createOnDemand="null", Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), bufferedIo="null", bufferSize="null", immediateFlush="null", ignoreExceptions="null", PatternLayout(%d %p %C{1.} [%t] %m%n), name="MyFile", Filter=null)
> 2016-10-11 00:01:03,154 main DEBUG Starting FileManager logs/artdept.log
> 2016-10-11 00:01:03,154 main DEBUG Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
> 2016-10-11 00:01:03,155 main DEBUG PatternLayout$Builder(pattern="%d %p %C{1.} [%t] %m%n", PatternSelector=null, Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), Replace=null, charset="null", alwaysWriteExceptions="null", noConsoleNoAnsi="null", header="null", footer="null")
> 2016-10-11 00:01:03,155 main DEBUG Building Plugin[name=CronTriggeringPolicy, class=org.apache.logging.log4j.core.appender.rolling.CronTriggeringPolicy].
> 2016-10-11 00:01:03,157 main DEBUG createPolicy(Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), evaluateOnStartup="true", schedule="0 0 0 * * ?")
> 2016-10-11 00:01:03,160 main DEBUG Building Plugin[name=SizeBasedTriggeringPolicy, class=org.apache.logging.log4j.core.appender.rolling.SizeBasedTriggeringPolicy].
> 2016-10-11 00:01:03,160 main DEBUG createPolicy(size="250 MB")
> 2016-10-11 00:01:03,161 main DEBUG Building Plugin[name=Policies, class=org.apache.logging.log4j.core.appender.rolling.CompositeTriggeringPolicy].
> 2016-10-11 00:01:03,162 main DEBUG createPolicy(={CronTriggeringPolicy(schedule=0 0 0 * * ?), SizeBasedTriggeringPolicy(size=262144000)})
> 2016-10-11 00:01:03,162 main DEBUG Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.RollingFileAppender].
> 2016-10-11 00:01:03,165 main DEBUG RollingFileAppender$Builder(fileName="/Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/Launcher.log", filePattern="/Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/%d{dd} - Launcher-%i.log", append="null", locking="null", Policies(CompositeTriggeringPolicy(policies=[CronTriggeringPolicy(schedule=0 0 0 * * ?), SizeBasedTriggeringPolicy(size=262144000)])), Strategy=null, advertise="null", advertiseUri="null", createOnDemand="null", Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), bufferedIo="null", bufferSize="null", immediateFlush="null", ignoreExceptions="null", PatternLayout(%d %p %C{1.} [%t] %m%n), name="DailyRoll", Filter=null)
> 2016-10-11 00:01:03,175 main TRACE New file '/Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/Launcher.log' created = false
> 2016-10-11 00:01:03,177 main DEBUG Starting RollingFileManager /Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/Launcher.log
> 2016-10-11 00:01:03,178 main DEBUG PluginManager 'FileConverter' found 2 plugins
> 2016-10-11 00:01:03,182 main TRACE Using default SystemClock for timestamps.
> 2016-10-11 00:01:03,191 main TRACE DefaultRolloverStrategy.purge() took 10.0 milliseconds
> 2016-10-11 00:01:03,193 main DEBUG RollingFileManager executing synchronous FileRenameAction[/Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/Launcher.log to /Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/11 - Launcher-2.log, renameEmptyFiles=false]
> 2016-10-11 00:01:03,203 main TRACE Renamed file /Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/Launcher.log to /Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs/apache/2016/10 - Oct/11 - Launcher-2.log with Files.move
> 2016-10-11 00:01:03,205 main ERROR Unable to inject fields into builder class for plugin type class org.apache.logging.log4j.core.appender.RollingFileAppender, element RollingFile. java.lang.NullPointerException
> at org.apache.logging.log4j.core.appender.rolling.CronTriggeringPolicy.rollover(CronTriggeringPolicy.java:133)
> at org.apache.logging.log4j.core.appender.rolling.CronTriggeringPolicy.initialize(CronTriggeringPolicy.java:69)
> at org.apache.logging.log4j.core.appender.rolling.CompositeTriggeringPolicy.initialize(CompositeTriggeringPolicy.java:52)
> at org.apache.logging.log4j.core.appender.rolling.RollingFileManager.initialize(RollingFileManager.java:104)
> at org.apache.logging.log4j.core.appender.RollingFileAppender$Builder.build(RollingFileAppender.java:142)
> at org.apache.logging.log4j.core.appender.RollingFileAppender$Builder.build(RollingFileAppender.java:58)
> at org.apache.logging.log4j.core.config.plugins.util.PluginBuilder.build(PluginBuilder.java:122)
> at org.apache.logging.log4j.core.config.AbstractConfiguration.createPluginObject(AbstractConfiguration.java:942)
> at org.apache.logging.log4j.core.config.AbstractConfiguration.createConfiguration(AbstractConfiguration.java:882)
> at org.apache.logging.log4j.core.config.AbstractConfiguration.createConfiguration(AbstractConfiguration.java:874)
> at org.apache.logging.log4j.core.config.AbstractConfiguration.doConfigure(AbstractConfiguration.java:498)
> at org.apache.logging.log4j.core.config.AbstractConfiguration.initialize(AbstractConfiguration.java:227)
> at org.apache.logging.log4j.core.config.AbstractConfiguration.start(AbstractConfiguration.java:239)
> at org.apache.logging.log4j.core.LoggerContext.setConfiguration(LoggerContext.java:530)
> at org.apache.logging.log4j.core.LoggerContext.reconfigure(LoggerContext.java:603)
> at org.apache.logging.log4j.core.LoggerContext.reconfigure(LoggerContext.java:620)
> at org.apache.logging.log4j.core.LoggerContext.start(LoggerContext.java:226)
> at org.apache.logging.log4j.core.impl.Log4jContextFactory.getContext(Log4jContextFactory.java:242)
> at org.apache.logging.log4j.core.impl.Log4jContextFactory.getContext(Log4jContextFactory.java:45)
> at org.apache.logging.log4j.LogManager.getContext(LogManager.java:174)
> at org.apache.logging.log4j.LogManager.getLogger(LogManager.java:618)
> at info.chrismcgee.sky.artdept.ArtDept.<clinit>(ArtDept.java:85)
> 2016-10-11 00:01:03,206 main ERROR Unable to invoke factory method in class class org.apache.logging.log4j.core.appender.RollingFileAppender for element RollingFile. java.lang.IllegalStateException: No factory method found for class org.apache.logging.log4j.core.appender.RollingFileAppender
> at org.apache.logging.log4j.core.config.plugins.util.PluginBuilder.findFactoryMethod(PluginBuilder.java:224)
> at org.apache.logging.log4j.core.config.plugins.util.PluginBuilder.build(PluginBuilder.java:130)
> at org.apache.logging.log4j.core.config.AbstractConfiguration.createPluginObject(AbstractConfiguration.java:942)
> at org.apache.logging.log4j.core.config.AbstractConfiguration.createConfiguration(AbstractConfiguration.java:882)
> at org.apache.logging.log4j.core.config.AbstractConfiguration.createConfiguration(AbstractConfiguration.java:874)
> at org.apache.logging.log4j.core.config.AbstractConfiguration.doConfigure(AbstractConfiguration.java:498)
> at org.apache.logging.log4j.core.config.AbstractConfiguration.initialize(AbstractConfiguration.java:227)
> at org.apache.logging.log4j.core.config.AbstractConfiguration.start(AbstractConfiguration.java:239)
> at org.apache.logging.log4j.core.LoggerContext.setConfiguration(LoggerContext.java:530)
> at org.apache.logging.log4j.core.LoggerContext.reconfigure(LoggerContext.java:603)
> at org.apache.logging.log4j.core.LoggerContext.reconfigure(LoggerContext.java:620)
> at org.apache.logging.log4j.core.LoggerContext.start(LoggerContext.java:226)
> at org.apache.logging.log4j.core.impl.Log4jContextFactory.getContext(Log4jContextFactory.java:242)
> at org.apache.logging.log4j.core.impl.Log4jContextFactory.getContext(Log4jContextFactory.java:45)
> at org.apache.logging.log4j.LogManager.getContext(LogManager.java:174)
> at org.apache.logging.log4j.LogManager.getLogger(LogManager.java:618)
> at info.chrismcgee.sky.artdept.ArtDept.<clinit>(ArtDept.java:85)
> 2016-10-11 00:01:03,207 main DEBUG Building Plugin[name=appenders, class=org.apache.logging.log4j.core.config.AppendersPlugin].
> 2016-10-11 00:01:03,207 main ERROR Null object returned for RollingFile in Appenders.
> 2016-10-11 00:01:03,207 main DEBUG createAppenders(={Console, MyFile, })
> 2016-10-11 00:01:03,207 main DEBUG Building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef].
> 2016-10-11 00:01:03,208 main DEBUG createAppenderRef(ref="DailyRoll", level="null", Filter=null)
> 2016-10-11 00:01:03,208 main DEBUG Building Plugin[name=root, class=org.apache.logging.log4j.core.config.LoggerConfig$RootLogger].
> 2016-10-11 00:01:03,209 main DEBUG createLogger(additivity="null", level="TRACE", includeLocation="null", ={DailyRoll}, ={}, Configuration(/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml), Filter=null)
> 2016-10-11 00:01:03,211 main DEBUG Building Plugin[name=loggers, class=org.apache.logging.log4j.core.config.LoggersPlugin].
> 2016-10-11 00:01:03,211 main DEBUG createLoggers(={root})
> 2016-10-11 00:01:03,211 main ERROR Unable to locate appender "DailyRoll" for logger config "root"
> 2016-10-11 00:01:03,212 main DEBUG Configuration XmlConfiguration[location=/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml] initialized
> 2016-10-11 00:01:03,212 main DEBUG Starting configuration XmlConfiguration[location=/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml]
> 2016-10-11 00:01:03,213 main DEBUG Started configuration XmlConfiguration[location=/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml] OK.
> 2016-10-11 00:01:03,214 main TRACE Stopping org.apache.logging.log4j.core.config.DefaultConfiguration@76ccd017...
> 2016-10-11 00:01:03,214 main TRACE DefaultConfiguration notified 1 ReliabilityStrategies that config will be stopped.
> 2016-10-11 00:01:03,214 main TRACE DefaultConfiguration stopping root LoggerConfig.
> 2016-10-11 00:01:03,214 main TRACE DefaultConfiguration notifying ReliabilityStrategies that appenders will be stopped.
> 2016-10-11 00:01:03,214 main TRACE DefaultConfiguration stopping remaining Appenders.
> 2016-10-11 00:01:03,214 main DEBUG Shutting down OutputStreamManager SYSTEM_OUT.false.false-1
> 2016-10-11 00:01:03,215 main DEBUG Shut down OutputStreamManager SYSTEM_OUT.false.false-1, all resources released: true
> 2016-10-11 00:01:03,215 main TRACE DefaultConfiguration stopped 1 remaining Appenders.
> 2016-10-11 00:01:03,215 main TRACE DefaultConfiguration cleaning Appenders from 1 LoggerConfigs.
> 2016-10-11 00:01:03,215 main DEBUG Stopped org.apache.logging.log4j.core.config.DefaultConfiguration@76ccd017 OK
> 2016-10-11 00:01:03,265 main TRACE Reregistering MBeans after reconfigure. Selector=org.apache.logging.log4j.core.selector.ClassLoaderContextSelector@689604d9
> 2016-10-11 00:01:03,266 main TRACE Reregistering context (1/1): '2a139a55' org.apache.logging.log4j.core.LoggerContext@18078bef
> 2016-10-11 00:01:03,267 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55'
> 2016-10-11 00:01:03,268 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=StatusLogger'
> 2016-10-11 00:01:03,268 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=ContextSelector'
> 2016-10-11 00:01:03,268 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=Loggers,name=*'
> 2016-10-11 00:01:03,269 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=Appenders,name=*'
> 2016-10-11 00:01:03,269 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=AsyncAppenders,name=*'
> 2016-10-11 00:01:03,269 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=AsyncLoggerRingBuffer'
> 2016-10-11 00:01:03,269 main TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=Loggers,name=*,subtype=RingBuffer'
> 2016-10-11 00:01:03,270 main DEBUG Registering MBean org.apache.logging.log4j2:type=2a139a55
> 2016-10-11 00:01:03,272 main DEBUG Registering MBean org.apache.logging.log4j2:type=2a139a55,component=StatusLogger
> 2016-10-11 00:01:03,273 main DEBUG Registering MBean org.apache.logging.log4j2:type=2a139a55,component=ContextSelector
> 2016-10-11 00:01:03,274 main DEBUG Registering MBean org.apache.logging.log4j2:type=2a139a55,component=Loggers,name=
> 2016-10-11 00:01:03,276 main DEBUG Registering MBean org.apache.logging.log4j2:type=2a139a55,component=Appenders,name=Console
> 2016-10-11 00:01:03,276 main DEBUG Registering MBean org.apache.logging.log4j2:type=2a139a55,component=Appenders,name=MyFile
> 2016-10-11 00:01:03,276 main TRACE Using DummyNanoClock for nanosecond timestamps.
> 2016-10-11 00:01:03,277 main DEBUG Reconfiguration complete for context[name=2a139a55] at URI /Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml (org.apache.logging.log4j.core.LoggerContext@18078bef) with optional ClassLoader: null
> 2016-10-11 00:01:03,277 main DEBUG Shutdown hook enabled. Registering a new one.
> 2016-10-11 00:01:03,278 main DEBUG LoggerContext[name=2a139a55, org.apache.logging.log4j.core.LoggerContext@18078bef] started OK.
> Debug Log menu item clicked; Logging is now true
> 2016-10-11 00:02:05,448 pool-1-thread-1 DEBUG Stopping LoggerContext[name=2a139a55, org.apache.logging.log4j.core.LoggerContext@18078bef]
> 2016-10-11 00:02:05,449 pool-1-thread-1 DEBUG Stopping LoggerContext[name=2a139a55, org.apache.logging.log4j.core.LoggerContext@18078bef]...
> 2016-10-11 00:02:05,449 pool-1-thread-1 TRACE Unregistering 1 MBeans: [org.apache.logging.log4j2:type=2a139a55]
> 2016-10-11 00:02:05,449 pool-1-thread-1 TRACE Unregistering 1 MBeans: [org.apache.logging.log4j2:type=2a139a55,component=StatusLogger]
> 2016-10-11 00:02:05,449 pool-1-thread-1 TRACE Unregistering 1 MBeans: [org.apache.logging.log4j2:type=2a139a55,component=ContextSelector]
> 2016-10-11 00:02:05,450 pool-1-thread-1 TRACE Unregistering 1 MBeans: [org.apache.logging.log4j2:type=2a139a55,component=Loggers,name=]
> 2016-10-11 00:02:05,450 pool-1-thread-1 TRACE Unregistering 2 MBeans: [org.apache.logging.log4j2:type=2a139a55,component=Appenders,name=MyFile, org.apache.logging.log4j2:type=2a139a55,component=Appenders,name=Console]
> 2016-10-11 00:02:05,450 pool-1-thread-1 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=AsyncAppenders,name=*'
> 2016-10-11 00:02:05,450 pool-1-thread-1 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=AsyncLoggerRingBuffer'
> 2016-10-11 00:02:05,451 pool-1-thread-1 TRACE Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=2a139a55,component=Loggers,name=*,subtype=RingBuffer'
> 2016-10-11 00:02:05,451 pool-1-thread-1 TRACE Stopping XmlConfiguration[location=/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml]...
> 2016-10-11 00:02:05,451 pool-1-thread-1 TRACE XmlConfiguration notified 2 ReliabilityStrategies that config will be stopped.
> 2016-10-11 00:02:05,451 pool-1-thread-1 TRACE XmlConfiguration stopping 1 LoggerConfigs.
> 2016-10-11 00:02:05,451 pool-1-thread-1 TRACE XmlConfiguration stopping root LoggerConfig.
> 2016-10-11 00:02:05,451 pool-1-thread-1 TRACE XmlConfiguration notifying ReliabilityStrategies that appenders will be stopped.
> 2016-10-11 00:02:05,452 pool-1-thread-1 TRACE XmlConfiguration stopping remaining Appenders.
> 2016-10-11 00:02:05,452 pool-1-thread-1 DEBUG Shutting down FileManager logs/artdept.log
> 2016-10-11 00:02:05,452 pool-1-thread-1 DEBUG Shut down FileManager logs/artdept.log, all resources released: true
> 2016-10-11 00:02:05,452 pool-1-thread-1 DEBUG Shutting down OutputStreamManager SYSTEM_OUT.false.false
> 2016-10-11 00:02:05,452 pool-1-thread-1 DEBUG Shut down OutputStreamManager SYSTEM_OUT.false.false, all resources released: true
> 2016-10-11 00:02:05,452 pool-1-thread-1 TRACE XmlConfiguration stopped 2 remaining Appenders.
> 2016-10-11 00:02:05,453 pool-1-thread-1 TRACE XmlConfiguration cleaning Appenders from 2 LoggerConfigs.
> 2016-10-11 00:02:05,453 pool-1-thread-1 DEBUG Log4j2 ConfigurationScheduler shutting down threads in java.util.concurrent.ScheduledThreadPoolExecutor@4fba9a33[Running, pool size = 2, active threads = 0, queued tasks = 1, completed tasks = 2]
> 2016-10-11 00:02:05,453 pool-1-thread-1 DEBUG Stopped XmlConfiguration[location=/Users/apache/Dropbox/eclipse/workspace/ArtDept/bin/log4j2.xml] OK
> 2016-10-11 00:02:05,454 pool-1-thread-1 DEBUG Stopped LoggerContext[name=2a139a55, org.apache.logging.log4j.core.LoggerContext@18078bef]...
> {noformat}
> The rollover _does_ occur, but then nothing is recorded into the current log file.
> For reference, the code in my ArtDept.java file on line 85 (as seen in the exception violation lines in the stacktrace above) is just a simple field declaration & initialization:
> {code:title=ArtDept.java|borderStyle=solid}
> static final Logger log = LogManager.getLogger(ArtDept.class.getName());
> {code}
> Finally, for reference, my log4j2.xml file:
> {code:xml|title=log4j2.xml|borderStyle=solid}
> <?xml version="1.0" encoding="UTF-8"?>
> <Configuration status="TRACE" monitorInterval="30">
> <Properties>
> <Property name="filename">logs/artdept.log</Property>
> <Property name="baseDir">/Volumes/ArtDept/ArtDept/Scripts/sky-artdept/logs</Property>
> </Properties>
> <Appenders>
> <Console name="Console" target="SYSTEM_OUT">
> <PatternLayout pattern="%d{HH:mm:ss.SSS} [%t] %-5level %logger{36} - %msg%n"/>
> </Console>
> <File name="MyFile" fileName="${filename}">
> <PatternLayout>
> <pattern>%d %p %C{1.} [%t] %m%n</pattern>
> </PatternLayout>
> </File>
> <RollingFile name="DailyRoll" fileName="${baseDir}/${env:USER}/${date:yyyy}/${date:MM} - ${date:MMM}/Launcher.log"
> filePattern="${baseDir}/${env:USER}/${date:yyyy}/${date:MM} - ${date:MMM}/%d{dd} - Launcher-%i.log">
> <PatternLayout>
> <Pattern>%d %p %C{1.} [%t] %m%n</Pattern>
> </PatternLayout>
> <Policies>
> <CronTriggeringPolicy schedule="0 0 0 * * ?" evaluateOnStartup="true" />
> <SizeBasedTriggeringPolicy size="250 MB" />
> </Policies>
> </RollingFile>
> </Appenders>
> <Loggers>
> <Root level="trace">
> <AppenderRef ref="DailyRoll"/>
> </Root>
> </Loggers>
> </Configuration>
> {code}
> *EDIT:* Provided some more detailed info and more detailed stacktraces.
--
This message was sent by Atlassian JIRA
(v6.3.4#6332)
---------------------------------------------------------------------
To unsubscribe, e-mail: log4j-dev-unsubscribe@logging.apache.org
For additional commands, e-mail: log4j-dev-help@logging.apache.org