You are viewing a plain text version of this content. The canonical link for it is here.
Posted to notifications@logging.apache.org by "Curtis Patrick (Jira)" <ji...@apache.org> on 2019/09/03 19:18:00 UTC
[jira] [Created] (LOG4J2-2687) Log4j2 initialize time consuming
5.5s+
Curtis Patrick created LOG4J2-2687:
--------------------------------------
Summary: 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.12.1, 2.12.0, 2.11.1, 2.11.0, 2.10.0
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
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)