You are viewing a plain text version of this content. The canonical link for it is here.
Posted to notifications@logging.apache.org by "ASF subversion and git services (Jira)" <ji...@apache.org> on 2020/08/01 22:39:00 UTC
[jira] [Commented] (LOG4J2-2883) Size based rolling stops working
after first time base rolling
[ https://issues.apache.org/jira/browse/LOG4J2-2883?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=17169424#comment-17169424 ]
ASF subversion and git services commented on LOG4J2-2883:
---------------------------------------------------------
Commit 1252c531844e108c4c6316ecbcda9ae9f2d1aeb4 in logging-log4j2's branch refs/heads/release-2.x from Ralph Goers
[ https://gitbox.apache.org/repos/asf?p=logging-log4j2.git;h=1252c53 ]
LOG4J2-2883 - Add unit test
> 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)