[
https://issues.apache.org/jira/browse/LOG4J2-2883?page=com.atlassian.jira.plugin.system.issuetabpanels:all-tabpanel
]
Ralph Goers resolved LOG4J2-2883.
---------------------------------
Fix Version/s: 2.14.0
Resolution: Fixed
Thanks for the good unit test. From that I was able to identify the problem and
fix it. Please verify and close.
> Size based rolling stops working after first time base rolling
> --------------------------------------------------------------
>
> Key: LOG4J2-2883
> URL: https://issues.apache.org/jira/browse/LOG4J2-2883
> Project: Log4j 2
> Issue Type: Bug
> Affects Versions: 2.13.3
> Reporter: Benjamin Asbach
> Priority: Major
> Fix For: 2.14.0
>
>
> When configuring a logger with time based rolling and size based rolling. The
> size based rolling fails after the first time based rolling.
> Note: This is slightly related to
> [LOG4J2-2875|https://issues.apache.org/jira/browse/LOG4J2-2875]. With no
> modifications the sample code below shows the problem, but also IOExceptions
> occur which seems not to be related to this issue.
> {code:java}
> import java.awt.Desktop;
> import java.io.File;
> import java.nio.file.Files;
> import java.nio.file.Path;
> import static java.util.Arrays.asList;
> import static java.util.Collections.sort;
> import java.util.List;
> import java.util.Properties;
> import org.apache.logging.log4j.core.Logger;
> import org.apache.logging.log4j.core.LoggerContext;
> import
> org.apache.logging.log4j.core.config.properties.PropertiesConfiguration;
> import
> org.apache.logging.log4j.core.config.properties.PropertiesConfigurationBuilder;
> import static org.junit.jupiter.api.Assertions.assertEquals;
> import org.junit.jupiter.api.Test;
> public class RollingTest {
> @Test
> public void sizeBasedRollingBrokenAfterTimeBasedRolling() throws Exception {
> System.setProperty("log4j2.debug", "");
> Path tempDirectory = Files.createTempDirectory("log4j2");
> System.out.println(tempDirectory);
> Properties log4j2Properties = new Properties();
> log4j2Properties.put("appender.localmsglog.type", "RollingFile");
> log4j2Properties.put("appender.localmsglog.name", "localmsglog");
> log4j2Properties.put("appender.localmsglog.filePattern",
> tempDirectory.toFile().getAbsolutePath() +
> "/%d{yyyy_MM_dd_mm}/messages-$${date:yyyy_MM_dd_HH_mm_ss_S}.log");
> log4j2Properties.put("appender.localmsglog.policies.type", "Policies");
> log4j2Properties.put("appender.localmsglog.policies.1.type",
> "SizeBasedTriggeringPolicy");
> log4j2Properties.put("appender.localmsglog.policies.1.size", "10KB");
> log4j2Properties.put("appender.localmsglog.policies.2.type",
> "TimeBasedTriggeringPolicy");
> log4j2Properties.put("rootLogger.level", "info");
> log4j2Properties.put("rootLogger.appenderRef.1.ref", "localmsglog");
> PropertiesConfiguration config = new PropertiesConfigurationBuilder()
> .setRootProperties(log4j2Properties)
> .build();
> System.out.println("Test directory: " + tempDirectory.toString());
> Desktop.getDesktop().open(tempDirectory.toFile());
> org.apache.logging.log4j.core.config.Configurator.reconfigure(config);
> LoggerContext context = LoggerContext.getContext(false);
> Logger log = context.getRootLogger();
> for (int i = 0; i < 1000; i++) {
> log.info("nHq6p9kgfvWfjzDRYbZp");
> }
> Thread.sleep(60000);
> for (int i = 0; i < 1000; i++) {
> log.info("nHq6p9kgfvWfjzDRYbZp");
> }
> File tempDirectoryAsFile = tempDirectory.toFile();
> List<File> loggingFolders = asList(tempDirectoryAsFile.listFiles());
> sort(loggingFolders);
> // Check if two folders were created
> assertEquals(2, loggingFolders.size());
>
> // Check if first folder contains 3 log files
> assertEquals(3, loggingFolders.get(0).listFiles().length);
> // Check if second folder contains 3 log files as well
> assertEquals(3, loggingFolders.get(1).listFiles().length);
> }
> }
> {code}
> Output:
> {noformat}
> -------------------------------------------------------
> T E S T S
> -------------------------------------------------------
> Running com.amadeus.rail.utils.log4j.RollingTest
> C:\Users\BENJAM~1.ASB\AppData\Local\Temp\log4j24342776539466181785
> DEBUG StatusLogger Using ShutdownCallbackRegistry class
> org.apache.logging.log4j.core.util.DefaultShutdownCallbackRegistry
> DEBUG StatusLogger Not in a ServletContext environment, thus not loading
> WebLookup plugin.
> DEBUG StatusLogger AsyncLogger.ThreadNameStrategy=UNCACHED (user specified
> null, default is UNCACHED)
> TRACE StatusLogger Using default SystemClock for timestamps.
> DEBUG StatusLogger org.apache.logging.log4j.core.util.SystemClock supports
> precise timestamps.
> Test directory:
> C:\Users\BENJAM~1.ASB\AppData\Local\Temp\log4j24342776539466181785
> DEBUG StatusLogger Not in a ServletContext environment, thus not loading
> WebLookup plugin.
> DEBUG StatusLogger Not in a ServletContext environment, thus not loading
> WebLookup plugin.
> DEBUG StatusLogger Took 0,125982 seconds to load 239 plugins from
> jdk.internal.loader.ClassLoaders$AppClassLoader@57ccce09
> DEBUG StatusLogger PluginManager 'Converter' found 46 plugins
> DEBUG StatusLogger Starting OutputStreamManager SYSTEM_OUT.false.false-1
> DEBUG StatusLogger Starting LoggerContext[name=57ccce09,
> org.apache.logging.log4j.core.LoggerContext@f72a1a9]...
> DEBUG StatusLogger Reconfiguration started for context[name=57ccce09] at URI
> null (org.apache.logging.log4j.core.LoggerContext@f72a1a9) with optional
> ClassLoader: null
> DEBUG StatusLogger Not in a ServletContext environment, thus not loading
> WebLookup plugin.
> DEBUG StatusLogger PluginManager 'ConfigurationFactory' found 5 plugins
> DEBUG StatusLogger Not in a ServletContext environment, thus not loading
> WebLookup plugin.
> DEBUG StatusLogger Not in a ServletContext environment, thus not loading
> WebLookup plugin.
> DEBUG StatusLogger Not in a ServletContext environment, thus not loading
> WebLookup plugin.
> DEBUG StatusLogger Missing dependencies for Yaml support,
> ConfigurationFactory
> org.apache.logging.log4j.core.config.yaml.YamlConfigurationFactory is inactive
> DEBUG StatusLogger Not in a ServletContext environment, thus not loading
> WebLookup plugin.
> DEBUG StatusLogger Missing dependencies for Json support,
> ConfigurationFactory
> org.apache.logging.log4j.core.config.json.JsonConfigurationFactory is inactive
> DEBUG StatusLogger Not in a ServletContext environment, thus not loading
> WebLookup plugin.
> DEBUG StatusLogger Using configurationFactory
> org.apache.logging.log4j.core.config.ConfigurationFactory$Factory@cb49d772
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.properties] using
> context class loader jdk.internal.loader.ClassLoaders$AppClassLoader@57ccce09.
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.properties] using
> jdk.internal.loader.ClassLoaders$AppClassLoader@57ccce09 class loader.
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.properties] using
> jdk.internal.loader.ClassLoaders$AppClassLoader@57ccce09 class loader.
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.properties] using
> ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.properties] using
> context class loader jdk.internal.loader.ClassLoaders$AppClassLoader@57ccce09.
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.properties] using
> jdk.internal.loader.ClassLoaders$AppClassLoader@57ccce09 class loader.
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.properties] using
> jdk.internal.loader.ClassLoaders$AppClassLoader@57ccce09 class loader.
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.properties] using
> ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.yml] using context
> class loader jdk.internal.loader.ClassLoaders$AppClassLoader@57ccce09.
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.yml] using
> jdk.internal.loader.ClassLoaders$AppClassLoader@57ccce09 class loader.
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.yml] using
> jdk.internal.loader.ClassLoaders$AppClassLoader@57ccce09 class loader.
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.yml] using
> ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.yaml] using context
> class loader jdk.internal.loader.ClassLoaders$AppClassLoader@57ccce09.
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.yaml] using
> jdk.internal.loader.ClassLoaders$AppClassLoader@57ccce09 class loader.
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.yaml] using
> jdk.internal.loader.ClassLoaders$AppClassLoader@57ccce09 class loader.
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.yaml] using
> ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.json] using context
> class loader jdk.internal.loader.ClassLoaders$AppClassLoader@57ccce09.
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.json] using
> jdk.internal.loader.ClassLoaders$AppClassLoader@57ccce09 class loader.
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.json] using
> jdk.internal.loader.ClassLoaders$AppClassLoader@57ccce09 class loader.
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.json] using
> ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.jsn] using context
> class loader jdk.internal.loader.ClassLoaders$AppClassLoader@57ccce09.
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.jsn] using
> jdk.internal.loader.ClassLoaders$AppClassLoader@57ccce09 class loader.
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.jsn] using
> jdk.internal.loader.ClassLoaders$AppClassLoader@57ccce09 class loader.
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.jsn] using
> ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.xml] using context
> class loader jdk.internal.loader.ClassLoaders$AppClassLoader@57ccce09.
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.xml] using
> jdk.internal.loader.ClassLoaders$AppClassLoader@57ccce09 class loader.
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.xml] using
> jdk.internal.loader.ClassLoaders$AppClassLoader@57ccce09 class loader.
> TRACE StatusLogger Trying to find [log4j2-test57ccce09.xml] using
> ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j2-test.properties] using context
> class loader jdk.internal.loader.ClassLoaders$AppClassLoader@57ccce09.
> DEBUG StatusLogger Not in a ServletContext environment, thus not loading
> WebLookup plugin.
> DEBUG StatusLogger Apache Log4j Core 2.13.1 initializing configuration
> org.apache.logging.log4j.core.config.properties.PropertiesConfiguration@81c96556
> DEBUG StatusLogger Installed 1 script engine
> Warning: Nashorn engine is planned to be removed from a future JDK release
> DEBUG StatusLogger Oracle Nashorn version: 11.0.6, language: ECMAScript,
> threading: Not Thread Safe, compile: true, names: [nashorn, Nashorn, js, JS,
> JavaScript, javascript, ECMAScript, ecmascript], factory class:
> jdk.nashorn.api.scripting.NashornScriptEngineFactory
> DEBUG StatusLogger PluginManager 'Core' found 133 plugins
> DEBUG StatusLogger PluginManager 'Level' found 1 plugins
> DEBUG StatusLogger PluginManager 'Lookup' found 15 plugins
> DEBUG StatusLogger Building Plugin[name=AppenderRef,
> class=org.apache.logging.log4j.core.config.AppenderRef].
> TRACE StatusLogger TypeConverterRegistry initializing.
> DEBUG StatusLogger PluginManager 'TypeConverter' found 26 plugins
> DEBUG StatusLogger createAppenderRef(ref="consoleAppender", level="null",
> Filter=null)
> DEBUG StatusLogger Building Plugin[name=AppenderRef,
> class=org.apache.logging.log4j.core.config.AppenderRef].
> DEBUG StatusLogger createAppenderRef(ref="listAppender", level="null",
> Filter=null)
> DEBUG StatusLogger Building Plugin[name=root,
> class=org.apache.logging.log4j.core.config.LoggerConfig$RootLogger].
> DEBUG StatusLogger createLogger(additivity="null", level="INFO",
> includeLocation="null", ={consoleAppender, listAppender}, ={}, Configuration,
> Filter=null)
> DEBUG StatusLogger Building Plugin[name=loggers,
> class=org.apache.logging.log4j.core.config.LoggersPlugin].
> DEBUG StatusLogger createLoggers(={root})
> DEBUG StatusLogger Building Plugin[name=layout,
> class=org.apache.logging.log4j.core.layout.PatternLayout].
> DEBUG StatusLogger PatternLayout$Builder(pattern="[%d{yy-MMM-dd
> HH:mm:ss:SSS}] [%p] [%c{1}:%L] - %m", PatternSelector=null, Configuration,
> Replace=null, charset="null", alwaysWriteExceptions="null",
> disableAnsi="null", noConsoleNoAnsi="null", header="null", footer="null")
> DEBUG StatusLogger PluginManager 'Converter' found 46 plugins
> DEBUG StatusLogger Building Plugin[name=appender,
> class=org.apache.logging.log4j.core.appender.ConsoleAppender].
> DEBUG StatusLogger ConsoleAppender$Builder(target="null", follow="null",
> direct="null", bufferedIo="null", bufferSize="null", immediateFlush="null",
> ignoreExceptions="null", PatternLayout([%d{yy-MMM-dd HH:mm:ss:SSS}] [%p]
> [%c{1}:%L] - %m), name="consoleAppender", Configuration, Filter=null, ={})
> DEBUG StatusLogger Starting OutputStreamManager SYSTEM_OUT.false.false
> DEBUG StatusLogger Building Plugin[name=layout,
> class=org.apache.logging.log4j.core.layout.PatternLayout].
> DEBUG StatusLogger PatternLayout$Builder(pattern="%m%n",
> PatternSelector=null, Configuration, Replace=null, charset="null",
> alwaysWriteExceptions="null", disableAnsi="null", noConsoleNoAnsi="null",
> header="null", footer="null")
> DEBUG StatusLogger Building Plugin[name=appender,
> class=org.apache.logging.log4j.test.appender.ListAppender].
> DEBUG StatusLogger ListAppender$Builder(name="listAppender",
> entryPerNewLine="null", raw="null", PatternLayout(%m%n), Filter=null)
> DEBUG StatusLogger Building Plugin[name=appenders,
> class=org.apache.logging.log4j.core.config.AppendersPlugin].
> DEBUG StatusLogger createAppenders(={consoleAppender, ListAppender
> [events=[], messages=[], data=[], newLine=false, raw=false,
> countDownLatch=null,
> getHandler()=org.apache.logging.log4j.core.appender.DefaultErrorHandler@a1fb8ca7,
> getLayout()=%m%n, getName()=listAppender, ignoreExceptions()=true,
> getFilter()=null, getState()=INITIALIZED]})
> DEBUG StatusLogger Configuration
> org.apache.logging.log4j.core.config.properties.PropertiesConfiguration@81c96556
> initialized
> DEBUG StatusLogger Starting configuration
> org.apache.logging.log4j.core.config.properties.PropertiesConfiguration@81c96556
> DEBUG StatusLogger Started configuration
> org.apache.logging.log4j.core.config.properties.PropertiesConfiguration@81c96556
> OK.
> TRACE StatusLogger Stopping
> org.apache.logging.log4j.core.config.DefaultConfiguration@98c0d638...
> TRACE StatusLogger DefaultConfiguration notified 1 ReliabilityStrategies that
> config will be stopped.
> TRACE StatusLogger DefaultConfiguration stopping root LoggerConfig.
> TRACE StatusLogger DefaultConfiguration notifying ReliabilityStrategies that
> appenders will be stopped.
> TRACE StatusLogger DefaultConfiguration stopping remaining Appenders.
> DEBUG StatusLogger Shutting down OutputStreamManager SYSTEM_OUT.false.false-1
> DEBUG StatusLogger OutputStream closed
> DEBUG StatusLogger Shut down OutputStreamManager SYSTEM_OUT.false.false-1,
> all resources released: true
> DEBUG StatusLogger Appender DefaultConsole-1 stopped with status true
> TRACE StatusLogger DefaultConfiguration stopped 1 remaining Appenders.
> TRACE StatusLogger DefaultConfiguration cleaning Appenders from 1
> LoggerConfigs.
> DEBUG StatusLogger Stopped
> org.apache.logging.log4j.core.config.DefaultConfiguration@98c0d638 OK
> TRACE StatusLogger Reregistering MBeans after reconfigure.
> Selector=org.apache.logging.log4j.core.selector.ClassLoaderContextSelector@bafbb692
> TRACE StatusLogger Reregistering context (1/1): '57ccce09'
> org.apache.logging.log4j.core.LoggerContext@f72a1a9
> TRACE StatusLogger Unregistering but no MBeans found matching
> 'org.apache.logging.log4j2:type=57ccce09'
> TRACE StatusLogger Unregistering but no MBeans found matching
> 'org.apache.logging.log4j2:type=57ccce09,component=StatusLogger'
> TRACE StatusLogger Unregistering but no MBeans found matching
> 'org.apache.logging.log4j2:type=57ccce09,component=ContextSelector'
> TRACE StatusLogger Unregistering but no MBeans found matching
> 'org.apache.logging.log4j2:type=57ccce09,component=Loggers,name=*'
> TRACE StatusLogger Unregistering but no MBeans found matching
> 'org.apache.logging.log4j2:type=57ccce09,component=Appenders,name=*'
> TRACE StatusLogger Unregistering but no MBeans found matching
> 'org.apache.logging.log4j2:type=57ccce09,component=AsyncAppenders,name=*'
> TRACE StatusLogger Unregistering but no MBeans found matching
> 'org.apache.logging.log4j2:type=57ccce09,component=AsyncLoggerRingBuffer'
> TRACE StatusLogger Unregistering but no MBeans found matching
> 'org.apache.logging.log4j2:type=57ccce09,component=Loggers,name=*,subtype=RingBuffer'
> DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=57ccce09
> DEBUG StatusLogger Registering MBean
> org.apache.logging.log4j2:type=57ccce09,component=StatusLogger
> DEBUG StatusLogger Registering MBean
> org.apache.logging.log4j2:type=57ccce09,component=ContextSelector
> DEBUG StatusLogger Registering MBean
> org.apache.logging.log4j2:type=57ccce09,component=Loggers,name=
> DEBUG StatusLogger Registering MBean
> org.apache.logging.log4j2:type=57ccce09,component=Appenders,name=consoleAppender
> DEBUG StatusLogger Registering MBean
> org.apache.logging.log4j2:type=57ccce09,component=Appenders,name=listAppender
> TRACE StatusLogger Using default SystemClock for timestamps.
> DEBUG StatusLogger org.apache.logging.log4j.core.util.SystemClock supports
> precise timestamps.
> TRACE StatusLogger Using DummyNanoClock for nanosecond timestamps.
> DEBUG StatusLogger Reconfiguration complete for context[name=57ccce09] at URI
> C:\dev\src\java\amadeus-bahn\log4j2-utils\target\test-classes\log4j2-test.properties
> (org.apache.logging.log4j.core.LoggerContext@f72a1a9) with optional
> ClassLoader: null
> DEBUG StatusLogger Shutdown hook enabled. Registering a new one.
> DEBUG StatusLogger LoggerContext[name=57ccce09,
> org.apache.logging.log4j.core.LoggerContext@f72a1a9] started OK.
> DEBUG StatusLogger Apache Log4j Core 2.13.1 initializing configuration
> org.apache.logging.log4j.core.config.properties.PropertiesConfiguration@79534c86
> DEBUG StatusLogger Installed 1 script engine
> Warning: Nashorn engine is planned to be removed from a future JDK release
> DEBUG StatusLogger Oracle Nashorn version: 11.0.6, language: ECMAScript,
> threading: Not Thread Safe, compile: true, names: [nashorn, Nashorn, js, JS,
> JavaScript, javascript, ECMAScript, ecmascript], factory class:
> jdk.nashorn.api.scripting.NashornScriptEngineFactory
> DEBUG StatusLogger PluginManager 'Core' found 133 plugins
> DEBUG StatusLogger PluginManager 'Level' found 1 plugins
> DEBUG StatusLogger PluginManager 'Lookup' found 15 plugins
> DEBUG StatusLogger Building Plugin[name=AppenderRef,
> class=org.apache.logging.log4j.core.config.AppenderRef].
> DEBUG StatusLogger createAppenderRef(ref="localmsglog", level="null",
> Filter=null)
> DEBUG StatusLogger Building Plugin[name=root,
> class=org.apache.logging.log4j.core.config.LoggerConfig$RootLogger].
> DEBUG StatusLogger createLogger(additivity="null", level="INFO",
> includeLocation="null", ={localmsglog}, ={}, Configuration, Filter=null)
> DEBUG StatusLogger Building Plugin[name=loggers,
> class=org.apache.logging.log4j.core.config.LoggersPlugin].
> DEBUG StatusLogger createLoggers(={root})
> DEBUG StatusLogger Building Plugin[name=SizeBasedTriggeringPolicy,
> class=org.apache.logging.log4j.core.appender.rolling.SizeBasedTriggeringPolicy].
> DEBUG StatusLogger createPolicy(size="10KB")
> DEBUG StatusLogger Building Plugin[name=TimeBasedTriggeringPolicy,
> class=org.apache.logging.log4j.core.appender.rolling.TimeBasedTriggeringPolicy].
> DEBUG StatusLogger TimeBasedTriggeringPolicy$Builder(interval="null",
> modulate="null", maxRandomDelay="null")
> DEBUG StatusLogger Building Plugin[name=Policies,
> class=org.apache.logging.log4j.core.appender.rolling.CompositeTriggeringPolicy].
> DEBUG StatusLogger createPolicy(={SizeBasedTriggeringPolicy(size=10240),
> TimeBasedTriggeringPolicy(nextRolloverMillis=0, interval=1, modulate=false)})
> DEBUG StatusLogger Building Plugin[name=appender,
> class=org.apache.logging.log4j.core.appender.RollingFileAppender].
> DEBUG StatusLogger RollingFileAppender$Builder(fileName="null",
> filePattern="C:\Users\BENJAM~1.ASB\AppData\Local\Temp\log4j24342776539466181785/%d{yyyy_MM_dd_mm}/messages-${date:yyyy_MM_dd_HH_mm_ss_S}.log",
> append="null", locking="null",
> Policies(CompositeTriggeringPolicy(policies=[SizeBasedTriggeringPolicy(size=10240),
> TimeBasedTriggeringPolicy(nextRolloverMillis=0, interval=1,
> modulate=false)])), Strategy=null, advertise="null", advertiseUri="null",
> createOnDemand="null", filePermissions="null", fileOwner="null",
> fileGroup="null", bufferedIo="null", bufferSize="null",
> immediateFlush="null", ignoreExceptions="null", Layout=null,
> name="localmsglog", Configuration, Filter=null, ={})
> DEBUG StatusLogger Not in a ServletContext environment, thus not loading
> WebLookup plugin.
> DEBUG StatusLogger PluginManager 'Converter' found 46 plugins
> DEBUG StatusLogger Starting OutputStreamManager SYSTEM_OUT.false.false-2
> DEBUG StatusLogger Starting RollingFileManager
> C:\Users\BENJAM~1.ASB\AppData\Local\Temp\log4j24342776539466181785/%d{yyyy_MM_dd_mm}/messages-${date:yyyy_MM_dd_HH_mm_ss_S}.log
> DEBUG StatusLogger PluginManager 'FileConverter' found 2 plugins
> DEBUG StatusLogger Setting prev file time to 1970-01-01T01:00:00.000+0100
> DEBUG StatusLogger Initializing triggering policy
> CompositeTriggeringPolicy(policies=[SizeBasedTriggeringPolicy(size=10240),
> TimeBasedTriggeringPolicy(nextRolloverMillis=0, interval=1, modulate=false)])
> DEBUG StatusLogger Initializing triggering policy
> SizeBasedTriggeringPolicy(size=10240)
> DEBUG StatusLogger Initializing triggering policy
> TimeBasedTriggeringPolicy(nextRolloverMillis=0, interval=1, modulate=false)
> TRACE StatusLogger PatternProcessor.getNextTime returning
> 2020/07/02-12:26:00.000, nextFileTime=2020/07/02-12:25:00.000,
> prevFileTime=1970/01/01-01:00:00.000, current=2020/07/02-12:25:52.614,
> freq=EVERY_MINUTE
> TRACE StatusLogger PatternProcessor.getNextTime returning
> 2020/07/02-12:26:00.000, nextFileTime=2020/07/02-12:25:00.000,
> prevFileTime=2020/07/02-12:25:00.000, current=2020/07/02-12:25:52.615,
> freq=EVERY_MINUTE
> DEBUG StatusLogger Formatting file name. useCurrentTime=true.
> currentFileTime=0, prevFileTime=1593685500000
> DEBUG StatusLogger Formatting file name. useCurrentTime=true.
> currentFileTime=0, prevFileTime=1593685500000
> DEBUG StatusLogger Building Plugin[name=appenders,
> class=org.apache.logging.log4j.core.config.AppendersPlugin].
> DEBUG StatusLogger createAppenders(={localmsglog})
> DEBUG StatusLogger Configuration
> org.apache.logging.log4j.core.config.properties.PropertiesConfiguration@79534c86
> initialized
> DEBUG StatusLogger Starting configuration
> org.apache.logging.log4j.core.config.properties.PropertiesConfiguration@79534c86
> DEBUG StatusLogger Started configuration
> org.apache.logging.log4j.core.config.properties.PropertiesConfiguration@79534c86
> OK.
> TRACE StatusLogger Stopping
> org.apache.logging.log4j.core.config.properties.PropertiesConfiguration@81c96556...
> TRACE StatusLogger PropertiesConfiguration notified 2 ReliabilityStrategies
> that config will be stopped.
> TRACE StatusLogger PropertiesConfiguration stopping 1 LoggerConfigs.
> TRACE StatusLogger PropertiesConfiguration stopping root LoggerConfig.
> TRACE StatusLogger PropertiesConfiguration notifying ReliabilityStrategies
> that appenders will be stopped.
> TRACE StatusLogger PropertiesConfiguration stopping remaining Appenders.
> DEBUG StatusLogger Shutting down OutputStreamManager SYSTEM_OUT.false.false
> DEBUG StatusLogger OutputStream closed
> DEBUG StatusLogger Shut down OutputStreamManager SYSTEM_OUT.false.false, all
> resources released: true
> DEBUG StatusLogger Appender consoleAppender stopped with status true
> TRACE StatusLogger PropertiesConfiguration stopped 2 remaining Appenders.
> TRACE StatusLogger PropertiesConfiguration cleaning Appenders from 2
> LoggerConfigs.
> DEBUG StatusLogger Stopped
> org.apache.logging.log4j.core.config.properties.PropertiesConfiguration@81c96556
> OK
> TRACE StatusLogger Reregistering MBeans after reconfigure.
> Selector=org.apache.logging.log4j.core.selector.ClassLoaderContextSelector@bafbb692
> TRACE StatusLogger Reregistering context (1/1): '57ccce09'
> org.apache.logging.log4j.core.LoggerContext@f72a1a9
> TRACE StatusLogger Unregistering 1 MBeans:
> [org.apache.logging.log4j2:type=57ccce09]
> TRACE StatusLogger Unregistering 1 MBeans:
> [org.apache.logging.log4j2:type=57ccce09,component=StatusLogger]
> TRACE StatusLogger Unregistering 1 MBeans:
> [org.apache.logging.log4j2:type=57ccce09,component=ContextSelector]
> TRACE StatusLogger Unregistering 1 MBeans:
> [org.apache.logging.log4j2:type=57ccce09,component=Loggers,name=]
> TRACE StatusLogger Unregistering 2 MBeans:
> [org.apache.logging.log4j2:type=57ccce09,component=Appenders,name=consoleAppender,
>
> org.apache.logging.log4j2:type=57ccce09,component=Appenders,name=listAppender]
> TRACE StatusLogger Unregistering but no MBeans found matching
> 'org.apache.logging.log4j2:type=57ccce09,component=AsyncAppenders,name=*'
> TRACE StatusLogger Unregistering but no MBeans found matching
> 'org.apache.logging.log4j2:type=57ccce09,component=AsyncLoggerRingBuffer'
> TRACE StatusLogger Unregistering but no MBeans found matching
> 'org.apache.logging.log4j2:type=57ccce09,component=Loggers,name=*,subtype=RingBuffer'
> DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=57ccce09
> DEBUG StatusLogger Registering MBean
> org.apache.logging.log4j2:type=57ccce09,component=StatusLogger
> DEBUG StatusLogger Registering MBean
> org.apache.logging.log4j2:type=57ccce09,component=ContextSelector
> DEBUG StatusLogger Registering MBean
> org.apache.logging.log4j2:type=57ccce09,component=Loggers,name=
> DEBUG StatusLogger Registering MBean
> org.apache.logging.log4j2:type=57ccce09,component=Appenders,name=localmsglog
> TRACE StatusLogger Using DummyNanoClock for nanosecond timestamps.
> DEBUG StatusLogger Formatting file name. useCurrentTime=true.
> currentFileTime=0, prevFileTime=1593685500000
> DEBUG StatusLogger Formatting file name. useCurrentTime=true.
> currentFileTime=0, prevFileTime=1593685500000
> DEBUG StatusLogger Now writing to
> C:\Users\BENJAM~1.ASB\AppData\Local\Temp\log4j24342776539466181785/2020_07_02_25/messages-2020_07_02_12_25_52_650.log
> at 2020-07-02T12:25:52.650+0200
> DEBUG StatusLogger Rolling
> C:\Users\BENJAM~1.ASB\AppData\Local\Temp\log4j24342776539466181785/2020_07_02_25/messages-2020_07_02_12_25_52_650.log
> DEBUG StatusLogger Formatting file name. useCurrentTime=true.
> currentFileTime=0, prevFileTime=1593685500000
> DEBUG StatusLogger Found 0 eligible files, max is 2147483647
> TRACE StatusLogger DirectWriteRolloverStrategy.purge() took 1.0 milliseconds
> DEBUG StatusLogger OutputStream closed
> DEBUG StatusLogger Formatting file name. useCurrentTime=true.
> currentFileTime=0, prevFileTime=1593685500000
> DEBUG StatusLogger Formatting file name. useCurrentTime=true.
> currentFileTime=0, prevFileTime=1593685500000
> DEBUG StatusLogger Now writing to
> C:\Users\BENJAM~1.ASB\AppData\Local\Temp\log4j24342776539466181785/2020_07_02_25/messages-2020_07_02_12_25_52_686.log
> at 2020-07-02T12:25:52.686+0200
> DEBUG StatusLogger Rolling
> C:\Users\BENJAM~1.ASB\AppData\Local\Temp\log4j24342776539466181785/2020_07_02_25/messages-2020_07_02_12_25_52_686.log
> DEBUG StatusLogger Formatting file name. useCurrentTime=true.
> currentFileTime=0, prevFileTime=1593685500000
> DEBUG StatusLogger Found 0 eligible files, max is 2147483647
> TRACE StatusLogger DirectWriteRolloverStrategy.purge() took 1.0 milliseconds
> DEBUG StatusLogger OutputStream closed
> DEBUG StatusLogger Formatting file name. useCurrentTime=true.
> currentFileTime=0, prevFileTime=1593685500000
> DEBUG StatusLogger Formatting file name. useCurrentTime=true.
> currentFileTime=0, prevFileTime=1593685500000
> DEBUG StatusLogger Now writing to
> C:\Users\BENJAM~1.ASB\AppData\Local\Temp\log4j24342776539466181785/2020_07_02_25/messages-2020_07_02_12_25_52_710.log
> at 2020-07-02T12:25:52.710+0200
> TRACE StatusLogger PatternProcessor.getNextTime returning
> 2020/07/02-12:27:00.000, nextFileTime=2020/07/02-12:26:00.000,
> prevFileTime=2020/07/02-12:25:00.000, current=2020/07/02-12:26:52.715,
> freq=EVERY_MINUTE
> DEBUG StatusLogger Rolling
> C:\Users\BENJAM~1.ASB\AppData\Local\Temp\log4j24342776539466181785/2020_07_02_25/messages-2020_07_02_12_25_52_710.log
> DEBUG StatusLogger Formatting file name. useCurrentTime=true.
> currentFileTime=1593685612716, prevFileTime=1593685500000
> DEBUG StatusLogger Found 0 eligible files, max is 2147483647
> TRACE StatusLogger DirectWriteRolloverStrategy.purge() took 2.0 milliseconds
> DEBUG StatusLogger OutputStream closed
> DEBUG StatusLogger Formatting file name. useCurrentTime=true.
> currentFileTime=1593685612716, prevFileTime=1593685500000
> DEBUG StatusLogger Formatting file name. useCurrentTime=true.
> currentFileTime=1593685612716, prevFileTime=1593685500000
> DEBUG StatusLogger Now writing to
> C:\Users\BENJAM~1.ASB\AppData\Local\Temp\log4j24342776539466181785/2020_07_02_26/messages-2020_07_02_12_26_52_716.log
> at 2020-07-02T12:26:52.722+0200
> DEBUG StatusLogger Rolling
> C:\Users\BENJAM~1.ASB\AppData\Local\Temp\log4j24342776539466181785/2020_07_02_26/messages-2020_07_02_12_26_52_716.log
> DEBUG StatusLogger Formatting file name. useCurrentTime=true.
> currentFileTime=1593685612716, prevFileTime=1593685560000
> DEBUG StatusLogger Found 0 eligible files, max is 2147483647
> TRACE StatusLogger DirectWriteRolloverStrategy.purge() took 2.0 milliseconds
> DEBUG StatusLogger OutputStream closed
> DEBUG StatusLogger Formatting file name. useCurrentTime=true.
> currentFileTime=1593685612716, prevFileTime=1593685560000
> DEBUG StatusLogger Formatting file name. useCurrentTime=true.
> currentFileTime=1593685612716, prevFileTime=1593685560000
> DEBUG StatusLogger Now writing to
> C:\Users\BENJAM~1.ASB\AppData\Local\Temp\log4j24342776539466181785/2020_07_02_26/messages-2020_07_02_12_26_52_716.log
> at 2020-07-02T12:26:52.761+0200
> DEBUG StatusLogger Rolling
> C:\Users\BENJAM~1.ASB\AppData\Local\Temp\log4j24342776539466181785/2020_07_02_26/messages-2020_07_02_12_26_52_716.log
> DEBUG StatusLogger Formatting file name. useCurrentTime=true.
> currentFileTime=1593685612716, prevFileTime=1593685560000
> DEBUG StatusLogger Found 0 eligible files, max is 2147483647
> TRACE StatusLogger DirectWriteRolloverStrategy.purge() took 2.0 milliseconds
> DEBUG StatusLogger OutputStream closed
> DEBUG StatusLogger Formatting file name. useCurrentTime=true.
> currentFileTime=1593685612716, prevFileTime=1593685560000
> DEBUG StatusLogger Formatting file name. useCurrentTime=true.
> currentFileTime=1593685612716, prevFileTime=1593685560000
> DEBUG StatusLogger Now writing to
> C:\Users\BENJAM~1.ASB\AppData\Local\Temp\log4j24342776539466181785/2020_07_02_26/messages-2020_07_02_12_26_52_716.log
> at 2020-07-02T12:26:52.800+0200
> Tests run: 1, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 61.866 s <<<
> FAILURE! - in com.amadeus.rail.utils.log4j.RollingTest
> sizeBasedRollingBrokenAfterTimeBasedRolling Time elapsed: 61.862 s <<<
> FAILURE!
> org.opentest4j.AssertionFailedError: expected: <3> but was: <1>
> at
> com.amadeus.rail.utils.log4j.RollingTest.sizeBasedRollingBrokenAfterTimeBasedRolling(RollingTest.java:68)
> DEBUG StatusLogger Stopping LoggerContext[name=57ccce09,
> org.apache.logging.log4j.core.LoggerContext@f72a1a9]
> DEBUG StatusLogger Stopping LoggerContext[name=57ccce09,
> org.apache.logging.log4j.core.LoggerContext@f72a1a9]...
> TRACE StatusLogger Unregistering 1 MBeans:
> [org.apache.logging.log4j2:type=57ccce09]
> TRACE StatusLogger Unregistering 1 MBeans:
> [org.apache.logging.log4j2:type=57ccce09,component=StatusLogger]
> TRACE StatusLogger Unregistering 1 MBeans:
> [org.apache.logging.log4j2:type=57ccce09,component=ContextSelector]
> TRACE StatusLogger Unregistering 1 MBeans:
> [org.apache.logging.log4j2:type=57ccce09,component=Loggers,name=]
> TRACE StatusLogger Unregistering 1 MBeans:
> [org.apache.logging.log4j2:type=57ccce09,component=Appenders,name=localmsglog]
> TRACE StatusLogger Unregistering but no MBeans found matching
> 'org.apache.logging.log4j2:type=57ccce09,component=AsyncAppenders,name=*'
> TRACE StatusLogger Unregistering but no MBeans found matching
> 'org.apache.logging.log4j2:type=57ccce09,component=AsyncLoggerRingBuffer'
> TRACE StatusLogger Unregistering but no MBeans found matching
> 'org.apache.logging.log4j2:typ[e=57ccce09,coImNpFoOne]n t=
> L
> oggers,name=*,subtype=R[ingBIuNfFfOe] rR'
> es
> ults:
> Failures:
> RollingTest.sizeBasedRollingBrokenAfterTimeBasedRolling:68 expected: <3>
> but was: <1>
> Tests run: 1, Failures: 1, Errors: 0, Skipped: 0
> ------------------------------------------------------------------------
> BUILD FAILURE
> ------------------------------------------------------------------------
> {noformat}
--
This message was sent by Atlassian Jira
(v8.3.4#803005)
