You are viewing a plain text version of this content. The canonical link for it is here.
Posted to notifications@logging.apache.org by "Ralph Goers (Jira)" <ji...@apache.org> on 2019/10/11 14:59:00 UTC
[jira] [Commented] (LOG4J2-2706) JsonLayout prevents logging
[ https://issues.apache.org/jira/browse/LOG4J2-2706?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=16949526#comment-16949526 ]
Ralph Goers commented on LOG4J2-2706:
-------------------------------------
Can you please provide your log4j2.xml?
> JsonLayout prevents logging
> ---------------------------
>
> Key: LOG4J2-2706
> URL: https://issues.apache.org/jira/browse/LOG4J2-2706
> Project: Log4j 2
> Issue Type: Bug
> Components: Appenders, Layouts
> Affects Versions: 2.12.1
> Environment: Windows
> Reporter: df
> Priority: Major
> Labels: JsonLayout
>
> Hi,
> I have a log4j2.xml configuration with 2 appenders:
> * Console with JsonLayout
> * RollingFile
> In the Loggers section I use an AppenderRef for RollingFile.
> Unfortunatelly, the file stays almost empty, compared to when I'm removing the console appender or when I replace the JsonLayout with a PatternLayout.
> jackson JARs are present: jackson-annotations.jar, jackson-core.jar, jackson-databind.jar (v 2.9.10)
>
>
> {code:java}
> INFO | jvm 1 | 2019/10/11 15:14:55 | DEBUG StatusLogger Unable to retrieve provider from ClassLoader java.net.URLClassLoader@11f2a1bf
> INFO | jvm 1 | 2019/10/11 15:14:55 | java.util.ServiceConfigurationError: org.apache.logging.log4j.spi.Provider: Provider org.apache.logging.log4j.core.impl.Log4jProvider not a subtype
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.util.ServiceLoader.fail(ServiceLoader.java:239)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.util.ServiceLoader.access$300(ServiceLoader.java:185)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.util.ServiceLoader$LazyIterator.nextService(ServiceLoader.java:376)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.util.ServiceLoader$LazyIterator.next(ServiceLoader.java:404)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.util.ServiceLoader$1.next(ServiceLoader.java:480)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.logging.log4j.util.ProviderUtil.loadProviders(ProviderUtil.java:109)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.logging.log4j.util.ProviderUtil.<init>(ProviderUtil.java:68)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.logging.log4j.util.ProviderUtil.lazyInit(ProviderUtil.java:150)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.logging.log4j.util.ProviderUtil.hasProviders(ProviderUtil.java:134)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.logging.log4j.LogManager.<clinit>(LogManager.java:89)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.lang.Class.forName0(Native Method)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.lang.Class.forName(Class.java:348)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.jboss.logging.LoggerProviders.tryLog4j2(LoggerProviders.java:125)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.jboss.logging.LoggerProviders.findProvider(LoggerProviders.java:91)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.jboss.logging.LoggerProviders.find(LoggerProviders.java:34)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.jboss.logging.LoggerProviders.<clinit>(LoggerProviders.java:31)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.jboss.logging.Logger.getLogger(Logger.java:2465)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.jboss.logging.Logger$1.run(Logger.java:2565)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.security.AccessController.doPrivileged(Native Method)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.jboss.logging.Logger.getMessageLogger(Logger.java:2529)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.jboss.logging.Logger.getMessageLogger(Logger.java:2516)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.jboss.weld.environment.servlet.logging.WeldServletLogger.<clinit>(WeldServletLogger.java:37)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.jboss.weld.environment.servlet.EnhancedListener.onStartup(EnhancedListener.java:58)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5135)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:717)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:690)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:705)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.startup.HostConfig.deployDirectory(HostConfig.java:1133)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.startup.HostConfig$DeployDirectory.run(HostConfig.java:1867)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.util.concurrent.FutureTask.run(FutureTask.java:266)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:112)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.startup.HostConfig.deployDirectories(HostConfig.java:1045)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:429)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1576)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:309)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.util.LifecycleBase.fireLifecycleEvent(LifecycleBase.java:123)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.util.LifecycleBase.setStateInternal(LifecycleBase.java:423)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.util.LifecycleBase.setState(LifecycleBase.java:366)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.core.ContainerBase.startInternal(ContainerBase.java:936)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.core.StandardHost.startInternal(StandardHost.java:841)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1384)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1374)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.util.concurrent.FutureTask.run(FutureTask.java:266)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:134)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.core.ContainerBase.startInternal(ContainerBase.java:909)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.core.StandardEngine.startInternal(StandardEngine.java:262)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.core.StandardService.startInternal(StandardService.java:421)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.core.StandardServer.startInternal(StandardServer.java:932)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.startup.Catalina.start(Catalina.java:633)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.lang.reflect.Method.invoke(Method.java:498)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:344)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:475)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.lang.reflect.Method.invoke(Method.java:498)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.tanukisoftware.wrapper.WrapperStartStopApp.run(WrapperStartStopApp.java:429)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.lang.Thread.run(Thread.java:748)
> INFO | jvm 1 | 2019/10/11 15:14:55 | DEBUG StatusLogger Unable to retrieve provider from ClassLoader sun.misc.Launcher$AppClassLoader@5c647e05
> INFO | jvm 1 | 2019/10/11 15:14:55 | java.util.ServiceConfigurationError: org.apache.logging.log4j.spi.Provider: Provider org.apache.logging.log4j.core.impl.Log4jProvider not a subtype
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.util.ServiceLoader.fail(ServiceLoader.java:239)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.util.ServiceLoader.access$300(ServiceLoader.java:185)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.util.ServiceLoader$LazyIterator.nextService(ServiceLoader.java:376)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.util.ServiceLoader$LazyIterator.next(ServiceLoader.java:404)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.util.ServiceLoader$1.next(ServiceLoader.java:480)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.logging.log4j.util.ProviderUtil.loadProviders(ProviderUtil.java:109)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.logging.log4j.util.ProviderUtil.<init>(ProviderUtil.java:68)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.logging.log4j.util.ProviderUtil.lazyInit(ProviderUtil.java:150)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.logging.log4j.util.ProviderUtil.hasProviders(ProviderUtil.java:134)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.logging.log4j.LogManager.<clinit>(LogManager.java:89)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.lang.Class.forName0(Native Method)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.lang.Class.forName(Class.java:348)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.jboss.logging.LoggerProviders.tryLog4j2(LoggerProviders.java:125)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.jboss.logging.LoggerProviders.findProvider(LoggerProviders.java:91)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.jboss.logging.LoggerProviders.find(LoggerProviders.java:34)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.jboss.logging.LoggerProviders.<clinit>(LoggerProviders.java:31)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.jboss.logging.Logger.getLogger(Logger.java:2465)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.jboss.logging.Logger$1.run(Logger.java:2565)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.security.AccessController.doPrivileged(Native Method)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.jboss.logging.Logger.getMessageLogger(Logger.java:2529)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.jboss.logging.Logger.getMessageLogger(Logger.java:2516)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.jboss.weld.environment.servlet.logging.WeldServletLogger.<clinit>(WeldServletLogger.java:37)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.jboss.weld.environment.servlet.EnhancedListener.onStartup(EnhancedListener.java:58)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5135)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:717)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:690)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:705)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.startup.HostConfig.deployDirectory(HostConfig.java:1133)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.startup.HostConfig$DeployDirectory.run(HostConfig.java:1867)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.util.concurrent.FutureTask.run(FutureTask.java:266)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:112)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.startup.HostConfig.deployDirectories(HostConfig.java:1045)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:429)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1576)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:309)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.util.LifecycleBase.fireLifecycleEvent(LifecycleBase.java:123)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.util.LifecycleBase.setStateInternal(LifecycleBase.java:423)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.util.LifecycleBase.setState(LifecycleBase.java:366)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.core.ContainerBase.startInternal(ContainerBase.java:936)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.core.StandardHost.startInternal(StandardHost.java:841)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1384)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1374)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.util.concurrent.FutureTask.run(FutureTask.java:266)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:134)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.core.ContainerBase.startInternal(ContainerBase.java:909)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.core.StandardEngine.startInternal(StandardEngine.java:262)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.core.StandardService.startInternal(StandardService.java:421)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.core.StandardServer.startInternal(StandardServer.java:932)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.startup.Catalina.start(Catalina.java:633)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.lang.reflect.Method.invoke(Method.java:498)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:344)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:475)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.lang.reflect.Method.invoke(Method.java:498)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at org.tanukisoftware.wrapper.WrapperStartStopApp.run(WrapperStartStopApp.java:429)
> INFO | jvm 1 | 2019/10/11 15:14:55 | at java.lang.Thread.run(Thread.java:748)
> {code}
--
This message was sent by Atlassian Jira
(v8.3.4#803005)