logging-log4j-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Tiago Cardoso (JIRA)" <j...@apache.org>
Subject [jira] [Commented] (LOG4J2-565) Log4j2 loading time
Date Thu, 13 Mar 2014 10:35:43 GMT

    [ https://issues.apache.org/jira/browse/LOG4J2-565?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=13933072#comment-13933072
] 

Tiago Cardoso commented on LOG4J2-565:
--------------------------------------

Hi guys! Sorry for the wait (I'm from europe, so I was sleeping). 

The application logs I am getting are the following:

Starting application:1394706517669
Getting the first logger:1394706517680
2014-03-13 10:28:51,350 DEBUG Generated plugins in 0.001112616 seconds
2014-03-13 10:28:51,419 DEBUG Found Plugin Map at jar:file:/var/media/AppJar.jar!/org/apache/logging/log4j/core/config/plugins/Log4j2Plugins.dat
2014-03-13 10:29:00,957 DEBUG Generated plugins in 9.579464616 seconds
2014-03-13 10:29:06,784 DEBUG Generated plugins in 5.712379076 seconds
2014-03-13 10:29:07,442 DEBUG Calling createLayout on class org.apache.logging.log4j.core.layout.PatternLayout
for element PatternLayout with params(pattern="[%d{dd MMM yyyy - HH:mm:ss.SSS}] %p - %m%n",
Configuration(/tmp/log4j2.xml), null, charset="null", alwaysWriteExceptions="null", noConsoleNoAnsi="null")
2014-03-13 10:29:12,983 DEBUG Generated plugins in 5.526126156 seconds
2014-03-13 10:29:13,127 DEBUG Calling createAppender on class org.apache.logging.log4j.core.appender.ConsoleAppender
for element Console with params(PatternLayout([%d{dd MMM yyyy - HH:mm:ss.SSS}] %p - %m%n),
null, target="SYSTEM_OUT", name="Console", follow="null", ignoreExceptions="null")
2014-03-13 10:29:13,201 DEBUG Calling createLayout on class org.apache.logging.log4j.core.layout.PatternLayout
for element PatternLayout with params(pattern="[%d{dd MMM yyyy - HH:mm:ss.SSS}] %p - %m%n",
Configuration(/tmp/log4j2.xml), null, charset="null", alwaysWriteExceptions="null", noConsoleNoAnsi="null")
2014-03-13 10:29:13,273 DEBUG Calling createPolicy on class org.apache.logging.log4j.core.appender.rolling.SizeBasedTriggeringPolicy
for element SizeBasedTriggeringPolicy with params(size="100 KB")
2014-03-13 10:29:13,373 DEBUG Calling createPolicy on class org.apache.logging.log4j.core.appender.rolling.CompositeTriggeringPolicy
for element Policies with params(Policies={SizeBasedTriggeringPolicy(size=102400)})
2014-03-13 10:29:13,484 DEBUG Calling createStrategy on class org.apache.logging.log4j.core.appender.rolling.DefaultRolloverStrategy
for element DefaultRolloverStrategy with params(max="200", min="null", fileIndex="null", compressionLevel="1",
Configuration(/tmp/log4j2.xml))
2014-03-13 10:29:13,613 DEBUG Calling createAppender on class org.apache.logging.log4j.core.appender.RollingRandomAccessFileAppender
for element RollingRandomAccessFile with params(fileName="/tmp/teste.log", filePattern="/tmp/testingLog.PART_%i.gz",
append="null", name="RollingRandomAccessFile", immediateFlush="true", Policies(CompositeTriggeringPolicy{SizeBasedTriggeringPolicy(size=102400)}),
DefaultRolloverStrategy(DefaultRolloverStrategy(min=1, max=200)), PatternLayout([%d{dd MMM
yyyy - HH:mm:ss.SSS}] %p - %m%n), null, ignoreExceptions="null", advertise="null", advertiseURI="null",
Configuration(/tmp/log4j2.xml))
2014-03-13 10:29:13,757 TRACE RandomAccessFile /tmp/teste.log seek to 0
2014-03-13 10:29:13,773 DEBUG Starting RollingRandomAccessFileManager /tmp/teste.log
2014-03-13 10:29:20,203 DEBUG Generated plugins in 6.386125544 seconds
2014-03-13 10:29:20,260 DEBUG Calling createAppenders on class org.apache.logging.log4j.core.config.plugins.AppendersPlugin
for element Appenders with params(Appenders={Console, RollingRandomAccessFile})
2014-03-13 10:29:20,291 DEBUG Calling createAppenderRef on class org.apache.logging.log4j.core.config.AppenderRef
for element AppenderRef with params(ref="RollingRandomAccessFile", level="null", null)
2014-03-13 10:29:20,319 DEBUG Calling createAppenderRef on class org.apache.logging.log4j.core.config.AppenderRef
for element AppenderRef with params(ref="Console", level="null", null)
2014-03-13 10:29:20,379 DEBUG Calling createLogger on class org.apache.logging.log4j.core.config.LoggerConfig$RootLogger
for element Root with params(additivity="null", level="trace", includeLocation="null", AppenderRef={RollingRandomAccessFile,
Console}, Properties={}, Configuration(/tmp/log4j2.xml), null)
2014-03-13 10:29:20,429 DEBUG Calling createLoggers on class org.apache.logging.log4j.core.config.plugins.LoggersPlugin
for element Loggers with params(Loggers={root})
2014-03-13 10:29:23,011 DEBUG Registering MBean org.apache.logging.log4j2:type=sun.misc.Launcher$AppClassLoader@cac268
2014-03-13 10:29:23,110 DEBUG Registering MBean org.apache.logging.log4j2:type=sun.misc.Launcher$AppClassLoader@cac268,component=StatusLogger
2014-03-13 10:29:23,156 DEBUG Registering MBean org.apache.logging.log4j2:type=sun.misc.Launcher$AppClassLoader@cac268,component=ContextSelector
2014-03-13 10:29:23,272 DEBUG Registering MBean org.apache.logging.log4j2:type=sun.misc.Launcher$AppClassLoader@cac268,component=Loggers,name=
2014-03-13 10:29:23,364 DEBUG Registering MBean org.apache.logging.log4j2:type=sun.misc.Launcher$AppClassLoader@cac268,component=Appenders,name=Console
2014-03-13 10:29:23,428 DEBUG Registering MBean org.apache.logging.log4j2:type=sun.misc.Launcher$AppClassLoader@cac268,component=Appenders,name=RollingRandomAccessFile
2014-03-13 10:29:23,450 DEBUG Reconfiguration completed
Loggers are fully loaded:1394706563536
[13 Mar 2014 - 10:29:23.752] TRACE - Entering application.
[13 Mar 2014 - 10:29:23.817] TRACE - Going to leave application.1394706563817
2014-03-13 10:29:23,869 DEBUG Shutting down RollingRandomAccessFileManager /tmp/teste.log
2014-03-13 10:29:23,886 DEBUG Shutting down OutputStreamManager SYSTEM_OUT
2014-03-13 10:29:23,914 DEBUG Unregistering MBean org.apache.logging.log4j2:type=sun.misc.Launcher$AppClassLoader@cac268
2014-03-13 10:29:23,935 DEBUG Unregistering MBean org.apache.logging.log4j2:type=sun.misc.Launcher$AppClassLoader@cac268,component=StatusLogger
2014-03-13 10:29:23,953 DEBUG Unregistering MBean org.apache.logging.log4j2:type=sun.misc.Launcher$AppClassLoader@cac268,component=ContextSelector
2014-03-13 10:29:24,005 DEBUG Unregistering MBean org.apache.logging.log4j2:type=sun.misc.Launcher$AppClassLoader@cac268,component=Loggers,name=
2014-03-13 10:29:24,036 DEBUG Unregistering MBean org.apache.logging.log4j2:type=sun.misc.Launcher$AppClassLoader@cac268,component=Appenders,name=RollingRandomAccessFile
2014-03-13 10:29:24,051 DEBUG Unregistering MBean org.apache.logging.log4j2:type=sun.misc.Launcher$AppClassLoader@cac268,component=Appenders,name=Console


> Log4j2 loading time
> -------------------
>
>                 Key: LOG4J2-565
>                 URL: https://issues.apache.org/jira/browse/LOG4J2-565
>             Project: Log4j 2
>          Issue Type: Bug
>          Components: API
>    Affects Versions: 2.0-rc1
>         Environment: Using ejre1.6.0_25 in an ARM926EJ-S rev 5 (v5l)
>            Reporter: Tiago Cardoso
>
> Everything runs fine on the Desktop computer, but the embedded system struggles to obtain
the Logger.
> It takes 17 seconds just to run this line:
> Logger logger = LogManager.getLogger("testLogger");
> The classpath is empty and the whole applications is just:
> public static void main(String[] arg){
>     System.out.println("Starting application:" + System.currentTimeMillis());
>     Logger logger = LogManager.getLogger("testLogger");
>     logger.trace("Going to leave application." + System.currentTimeMillis());
>     System.exit(1);
> }
> The same system is able to run a client graphic application without this kind of event.



--
This message was sent by Atlassian JIRA
(v6.2#6252)

---------------------------------------------------------------------
To unsubscribe, e-mail: log4j-dev-unsubscribe@logging.apache.org
For additional commands, e-mail: log4j-dev-help@logging.apache.org


Mime
View raw message