You are viewing a plain text version of this content. The canonical link for it is here.
Posted to notifications@logging.apache.org by "Matt Sicker (Jira)" <ji...@apache.org> on 2019/09/03 19:33:00 UTC

[jira] [Commented] (LOG4J2-2687) Log4j2 initialize time consuming 5.5s+

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

Matt Sicker commented on LOG4J2-2687:
-------------------------------------

Do you have {{localhost}} defined in your {{/etc/hosts}} file? I've had problems in the past where a lookup of the localhost hostname can take a few seconds on certain operating systems when there's no alias for {{localhost}} in the hosts file. A real DNS request is made to try and figure out the hostname of the local host in that case. That functionality is not specific to Log4j (it's a Java API).

> Log4j2 initialize time consuming 5.5s+
> --------------------------------------
>
>                 Key: LOG4J2-2687
>                 URL: https://issues.apache.org/jira/browse/LOG4J2-2687
>             Project: Log4j 2
>          Issue Type: Improvement
>    Affects Versions: 2.10.0, 2.11.0, 2.11.1, 2.12.0, 2.12.1
>         Environment: Mac OSX 10.14.4 / Java 1.8.0_172-b11
> VSCode 1.37.1
>  * Debugger for Java 0.21.0
>  * Java Test Runner 0.19.0
>  * Java Extension pack 0.8.0
>  
>            Reporter: Curtis Patrick
>            Priority: Minor
>         Attachments: SlowLog4j2Startup-2019-09-03.gif
>
>
> I'm just starting to use log4j2 and added it to a new project with a single test.
> Testing time went from practically instantaneous to nearly 6 seconds.
> Is this expected?
> The log line immediately before the long delay is:
> {code:java}
> DEBUG StatusLogger Not in a ServletContext environment, thus not loading WebLookup plugin.
> {code}
>  
> Debugging the tests In VSCode adds another 9 seconds (15 total) to initialize the logger. 
> I've attached is a gif capture of the test run from a terminal window.
> app.java
> {code:java}
> package patrick.curtis;
> import org.apache.logging.log4j.LogManager;
> import org.apache.logging.log4j.Logger;
> public final class App {
>     private static Logger log = null;    
>     public void initlogger() {
>         log = LogManager.getLogger(); 
>     }    
>     public void foo() {
>         log.debug("debug");
>         log.info("info");
>         log.warn("warn");
>         log.error("error");
>         log.fatal("fatal");
>     }
> }
> {code}
> apptest.java
> {code:java}
> package patrick.curtis;
> import org.junit.Test;
> import java.util.Date;
> /**
>  * Unit test for simple App.
>  */
> public class AppTest {
>     /**
>      * Rigorous Test.
>      */
>     @Test
>     public void appTest() {
>         long t = new Date().getTime();
>         System.out.println(String.format("Initializing app (ms): %d", t));
>         App app = new App();
>         t = new Date().getTime() - t;
>         System.out.println(String.format("...app initialization duration (ms): %d", t));
>         t = new Date().getTime();
>         System.out.println(String.format("Initializing logger (ms): %d", t));
>         app.initlogger();
>         t = new Date().getTime() - t;
>         System.out.println(String.format("...logger initialization duration (ms): %d", t));
>         t = new Date().getTime();
>         System.out.println(String.format("Executing foo() (ms): %d", t));
>         app.foo();
>         t = new Date().getTime() - t;
>         System.out.println(String.format("...foo() execution duration (ms): %d", t));
>     }
> }
> {code}
>  
>  
>  
> {code:java}
> $ mvn -Dlog4j2.debug test
> -------------------------------------------------------
>  T E S T S
> -------------------------------------------------------
> Running patrick.curtis.AppTest
> Initializing app (ms): 1567533450393
> ...app initialization duration (ms): 2
> Initializing logger (ms): 1567533450395
> 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=CACHED
> TRACE StatusLogger Using default SystemClock for timestamps.
> DEBUG StatusLogger Not in a ServletContext environment, thus not loading WebLookup plugin.
> DEBUG StatusLogger Took 0.059542 seconds to load 202 plugins from sun.misc.Launcher$AppClassLoader@4aa298b7
> DEBUG StatusLogger PluginManager 'Converter' found 42 plugins
> DEBUG StatusLogger Starting OutputStreamManager SYSTEM_OUT.false.false-1
> DEBUG StatusLogger Starting LoggerContext[name=4aa298b7, org.apache.logging.log4j.core.LoggerContext@480bdb19]...
> DEBUG StatusLogger Reconfiguration started for context[name=4aa298b7] at URI null (org.apache.logging.log4j.core.LoggerContext@480bdb19) with optional ClassLoader: null
> DEBUG StatusLogger Not in a ServletContext environment, thus not loading WebLookup plugin.
> DEBUG StatusLogger PluginManager 'ConfigurationFactory' found 4 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 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@51e2adc7
> TRACE StatusLogger Trying to find [log4j2-test4aa298b7.properties] using context class loader sun.misc.Launcher$AppClassLoader@4aa298b7.
> TRACE StatusLogger Trying to find [log4j2-test4aa298b7.properties] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2-test4aa298b7.properties] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2-test4aa298b7.properties] using ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j2-test4aa298b7.yml] using context class loader sun.misc.Launcher$AppClassLoader@4aa298b7.
> TRACE StatusLogger Trying to find [log4j2-test4aa298b7.yml] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2-test4aa298b7.yml] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2-test4aa298b7.yml] using ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j2-test4aa298b7.yaml] using context class loader sun.misc.Launcher$AppClassLoader@4aa298b7.
> TRACE StatusLogger Trying to find [log4j2-test4aa298b7.yaml] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2-test4aa298b7.yaml] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2-test4aa298b7.yaml] using ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j2-test4aa298b7.json] using context class loader sun.misc.Launcher$AppClassLoader@4aa298b7.
> TRACE StatusLogger Trying to find [log4j2-test4aa298b7.json] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2-test4aa298b7.json] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2-test4aa298b7.json] using ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j2-test4aa298b7.jsn] using context class loader sun.misc.Launcher$AppClassLoader@4aa298b7.
> TRACE StatusLogger Trying to find [log4j2-test4aa298b7.jsn] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2-test4aa298b7.jsn] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2-test4aa298b7.jsn] using ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j2-test4aa298b7.xml] using context class loader sun.misc.Launcher$AppClassLoader@4aa298b7.
> TRACE StatusLogger Trying to find [log4j2-test4aa298b7.xml] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2-test4aa298b7.xml] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2-test4aa298b7.xml] using ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j2-test.properties] using context class loader sun.misc.Launcher$AppClassLoader@4aa298b7.
> TRACE StatusLogger Trying to find [log4j2-test.properties] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2-test.properties] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2-test.properties] using ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j2-test.yml] using context class loader sun.misc.Launcher$AppClassLoader@4aa298b7.
> TRACE StatusLogger Trying to find [log4j2-test.yml] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2-test.yml] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2-test.yml] using ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j2-test.yaml] using context class loader sun.misc.Launcher$AppClassLoader@4aa298b7.
> TRACE StatusLogger Trying to find [log4j2-test.yaml] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2-test.yaml] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2-test.yaml] using ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j2-test.json] using context class loader sun.misc.Launcher$AppClassLoader@4aa298b7.
> TRACE StatusLogger Trying to find [log4j2-test.json] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2-test.json] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2-test.json] using ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j2-test.jsn] using context class loader sun.misc.Launcher$AppClassLoader@4aa298b7.
> TRACE StatusLogger Trying to find [log4j2-test.jsn] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2-test.jsn] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2-test.jsn] using ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j2-test.xml] using context class loader sun.misc.Launcher$AppClassLoader@4aa298b7.
> TRACE StatusLogger Trying to find [log4j2-test.xml] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2-test.xml] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2-test.xml] using ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j24aa298b7.properties] using context class loader sun.misc.Launcher$AppClassLoader@4aa298b7.
> TRACE StatusLogger Trying to find [log4j24aa298b7.properties] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j24aa298b7.properties] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j24aa298b7.properties] using ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j24aa298b7.yml] using context class loader sun.misc.Launcher$AppClassLoader@4aa298b7.
> TRACE StatusLogger Trying to find [log4j24aa298b7.yml] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j24aa298b7.yml] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j24aa298b7.yml] using ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j24aa298b7.yaml] using context class loader sun.misc.Launcher$AppClassLoader@4aa298b7.
> TRACE StatusLogger Trying to find [log4j24aa298b7.yaml] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j24aa298b7.yaml] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j24aa298b7.yaml] using ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j24aa298b7.json] using context class loader sun.misc.Launcher$AppClassLoader@4aa298b7.
> TRACE StatusLogger Trying to find [log4j24aa298b7.json] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j24aa298b7.json] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j24aa298b7.json] using ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j24aa298b7.jsn] using context class loader sun.misc.Launcher$AppClassLoader@4aa298b7.
> TRACE StatusLogger Trying to find [log4j24aa298b7.jsn] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j24aa298b7.jsn] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j24aa298b7.jsn] using ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j24aa298b7.xml] using context class loader sun.misc.Launcher$AppClassLoader@4aa298b7.
> TRACE StatusLogger Trying to find [log4j24aa298b7.xml] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j24aa298b7.xml] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j24aa298b7.xml] using ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j2.properties] using context class loader sun.misc.Launcher$AppClassLoader@4aa298b7.
> TRACE StatusLogger Trying to find [log4j2.properties] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2.properties] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2.properties] using ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j2.yml] using context class loader sun.misc.Launcher$AppClassLoader@4aa298b7.
> TRACE StatusLogger Trying to find [log4j2.yml] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2.yml] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2.yml] using ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j2.yaml] using context class loader sun.misc.Launcher$AppClassLoader@4aa298b7.
> TRACE StatusLogger Trying to find [log4j2.yaml] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2.yaml] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2.yaml] using ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j2.json] using context class loader sun.misc.Launcher$AppClassLoader@4aa298b7.
> TRACE StatusLogger Trying to find [log4j2.json] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2.json] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2.json] using ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j2.jsn] using context class loader sun.misc.Launcher$AppClassLoader@4aa298b7.
> TRACE StatusLogger Trying to find [log4j2.jsn] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2.jsn] using sun.misc.Launcher$AppClassLoader@4aa298b7 class loader.
> TRACE StatusLogger Trying to find [log4j2.jsn] using ClassLoader.getSystemResource().
> TRACE StatusLogger Trying to find [log4j2.xml] using context class loader sun.misc.Launcher$AppClassLoader@4aa298b7.
> DEBUG StatusLogger Not in a ServletContext environment, thus not loading WebLookup plugin.
> DEBUG StatusLogger Initializing configuration XmlConfiguration[location=/Users/cpatrick/working/incubation/unidata-independence/phoenix/log4j2-vscode-very-slow/target/classes/log4j2.xml]
> DEBUG StatusLogger Installed 1 script engine
> DEBUG StatusLogger Oracle Nashorn version: 1.8.0_172, 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 115 plugins
> DEBUG StatusLogger PluginManager 'Level' found 0 plugins
> DEBUG StatusLogger Building Plugin[name=property, class=org.apache.logging.log4j.core.config.Property].
> TRACE StatusLogger TypeConverterRegistry initializing.
> DEBUG StatusLogger PluginManager 'TypeConverter' found 26 plugins
> DEBUG StatusLogger createProperty(name="LOG_PATTERN", value="%d{yyyy-MM-dd HH:mm:ss.SSS} %5p %X{mdcData} --- %-40.40c{1.} : %m%n%ex")
> DEBUG StatusLogger Building Plugin[name=properties, class=org.apache.logging.log4j.core.config.PropertiesPlugin].
> DEBUG StatusLogger configureSubstitutor(={LOG_PATTERN=%d{yyyy-MM-dd HH:mm:ss.SSS} %5p %X{mdcData} --- %-40.40c{1.} : %m%n%ex}, Configuration(/Users/cpatrick/working/incubation/unidata-independence/phoenix/log4j2-vscode-very-slow/target/classes/log4j2.xml))
> DEBUG StatusLogger PluginManager 'Lookup' found 13 plugins
> DEBUG StatusLogger Building Plugin[name=layout, class=org.apache.logging.log4j.core.layout.PatternLayout].
> DEBUG StatusLogger PatternLayout$Builder(pattern="%d{HH:mm:ss.SSS} [%t] %-5level %logger{36} - %msg%n", PatternSelector=null, Configuration(/Users/cpatrick/working/incubation/unidata-independence/phoenix/log4j2-vscode-very-slow/target/classes/log4j2.xml), Replace=null, charset="null", alwaysWriteExceptions="null", disableAnsi="null", noConsoleNoAnsi="null", header="null", footer="null")
> DEBUG StatusLogger PluginManager 'Converter' found 42 plugins
> DEBUG StatusLogger Building Plugin[name=appender, class=org.apache.logging.log4j.core.appender.ConsoleAppender].
> DEBUG StatusLogger 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="LogToConsole", Configuration(/Users/cpatrick/working/incubation/unidata-independence/phoenix/log4j2-vscode-very-slow/target/classes/log4j2.xml), Filter=null)
> DEBUG StatusLogger Starting OutputStreamManager SYSTEM_OUT.false.false
> DEBUG StatusLogger Building Plugin[name=appenders, class=org.apache.logging.log4j.core.config.AppendersPlugin].
> DEBUG StatusLogger createAppenders(={LogToConsole})
> DEBUG StatusLogger Building Plugin[name=AppenderRef, class=org.apache.logging.log4j.core.config.AppenderRef].
> DEBUG StatusLogger createAppenderRef(ref="LogToConsole", 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="TRACE", includeLocation="null", ={LogToConsole}, ={}, Configuration(/Users/cpatrick/working/incubation/unidata-independence/phoenix/log4j2-vscode-very-slow/target/classes/log4j2.xml), Filter=null)
> DEBUG StatusLogger Building Plugin[name=loggers, class=org.apache.logging.log4j.core.config.LoggersPlugin].
> DEBUG StatusLogger createLoggers(={root})
> DEBUG StatusLogger Configuration XmlConfiguration[location=/Users/cpatrick/working/incubation/unidata-independence/phoenix/log4j2-vscode-very-slow/target/classes/log4j2.xml] initialized
> DEBUG StatusLogger Starting configuration XmlConfiguration[location=/Users/cpatrick/working/incubation/unidata-independence/phoenix/log4j2-vscode-very-slow/target/classes/log4j2.xml]
> DEBUG StatusLogger Started configuration XmlConfiguration[location=/Users/cpatrick/working/incubation/unidata-independence/phoenix/log4j2-vscode-very-slow/target/classes/log4j2.xml] OK.
> TRACE StatusLogger Stopping org.apache.logging.log4j.core.config.DefaultConfiguration@2133c8f8...
> 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 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@2133c8f8 OK
> TRACE StatusLogger Reregistering MBeans after reconfigure. Selector=org.apache.logging.log4j.core.selector.ClassLoaderContextSelector@f79e
> TRACE StatusLogger Reregistering context (1/1): '4aa298b7' org.apache.logging.log4j.core.LoggerContext@480bdb19
> TRACE StatusLogger Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=4aa298b7'
> TRACE StatusLogger Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=4aa298b7,component=StatusLogger'
> TRACE StatusLogger Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=4aa298b7,component=ContextSelector'
> TRACE StatusLogger Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=4aa298b7,component=Loggers,name=*'
> TRACE StatusLogger Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=4aa298b7,component=Appenders,name=*'
> TRACE StatusLogger Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=4aa298b7,component=AsyncAppenders,name=*'
> TRACE StatusLogger Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=4aa298b7,component=AsyncLoggerRingBuffer'
> TRACE StatusLogger Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=4aa298b7,component=Loggers,name=*,subtype=RingBuffer'
> DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=4aa298b7
> DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=4aa298b7,component=StatusLogger
> DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=4aa298b7,component=ContextSelector
> DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=4aa298b7,component=Loggers,name=
> DEBUG StatusLogger Registering MBean org.apache.logging.log4j2:type=4aa298b7,component=Appenders,name=LogToConsole
> TRACE StatusLogger Using default SystemClock for timestamps.
> TRACE StatusLogger Using DummyNanoClock for nanosecond timestamps.
> DEBUG StatusLogger Reconfiguration complete for context[name=4aa298b7] at URI /Users/cpatrick/working/incubation/unidata-independence/phoenix/log4j2-vscode-very-slow/target/classes/log4j2.xml (org.apache.logging.log4j.core.LoggerContext@480bdb19) with optional ClassLoader: null
> DEBUG StatusLogger Shutdown hook enabled. Registering a new one.
> DEBUG StatusLogger LoggerContext[name=4aa298b7, org.apache.logging.log4j.core.LoggerContext@480bdb19] started OK.
> ...logger initialization duration (ms): 5609
> Executing foo() (ms): 1567533456004
> 12:57:36.005 [main] DEBUG patrick.curtis.App - debug
> 12:57:36.008 [main] INFO  patrick.curtis.App - info
> 12:57:36.008 [main] WARN  patrick.curtis.App - warn
> 12:57:36.008 [main] ERROR patrick.curtis.App - error
> 12:57:36.008 [main] FATAL patrick.curtis.App - fatal
> ...foo() execution duration (ms): 4
> Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 5.657 sec
> DEBUG StatusLogger Stopping LoggerContext[name=4aa298b7, org.apache.logging.log4j.core.LoggerContext@480bdb19]
> DEBUG StatusLogger Stopping LoggerContext[name=4aa298b7, org.apache.logging.log4j.core.LoggerContext@480bdb19]...
> TRACE StatusLogger Unregistering 1 MBeans: [org.apache.logging.log4j2:type=4aa298b7]
> TRACE StatusLogger Unregistering 1 MBeans: [org.apache.logging.log4j2:type=4aa298b7,component=StatusLogger]
> TRACE StatusLogger Unregistering 1 MBeans: [org.apache.logging.log4j2:type=4aa298b7,component=ContextSelector]
> TRACE StatusLogger Unregistering 1 MBeans: [org.apache.logging.log4j2:type=4aa298b7,component=Loggers,name=]
> TRACE StatusLogger Unregistering 1 MBeans: [org.apache.logging.log4j2:type=4aa298b7,component=Appenders,name=LogToConsole]
> TRACE StatusLogger Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=4aa298b7,component=AsyncAppenders,name=*'
> TRACE StatusLogger Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=4aa298b7,component=AsyncLoggerRingBuffer'
> TRACE StatusLogger Unregistering but no MBeans found matching 'org.apache.logging.log4j2:type=4aa298b7,component=Loggers,name=*,subtype=RingBuffer'
> TRACE StatusLogger Stopping XmlConfiguration[location=/Users/cpatrick/working/incubation/unidata-independence/phoenix/log4j2-vscode-very-slow/target/classes/log4j2.xml]...
> TRACE StatusLogger XmlConfiguration notified 2 ReliabilityStrategies that config will be stopped.
> TRACE StatusLogger XmlConfiguration stopping 1 LoggerConfigs.
> TRACE StatusLogger XmlConfiguration stopping root LoggerConfig.
> TRACE StatusLogger XmlConfiguration notifying ReliabilityStrategies that appenders will be stopped.
> TRACE StatusLogger XmlConfiguration stopping remaining Appenders.
> DEBUG StatusLogger Shutting down OutputStreamManager SYSTEM_OUT.false.false
> DEBUG StatusLogger Shut down OutputStreamManager SYSTEM_OUT.false.false, all resources released: true
> DEBUG StatusLogger Appender LogToConsole stopped with status true
> TRACE StatusLogger XmlConfiguration stopped 1 remaining Appenders.
> TRACE StatusLogger XmlConfiguration cleaning Appenders from 2 LoggerConfigs.
> DEBUG StatusLogger Stopped XmlConfiguration[location=/Users/cpatrick/working/incubation/unidata-independence/phoenix/log4j2-vscode-very-slow/target/classes/log4j2.xml] OK
> DEBUG StatusLogger Stopped LoggerContext[name=4aa298b7, org.apache.logging.log4j.core.LoggerContext@480bdb19] with status trueResults :Tests run: 1, Failures: 0, Errors: 0, Skipped: 0[INFO] ------------------------------------------------------------------------
> [INFO] BUILD SUCCESS
> [INFO] ------------------------------------------------------------------------
> [INFO] Total time:  6.920 s
> [INFO] Finished at: 2019-09-03T12:57:36-05:00
> [INFO] ------------------------------------------------------------------------
> {code}
>  
>  
>  
>  



--
This message was sent by Atlassian Jira
(v8.3.2#803003)