[logback-user] NPE when configuring AsyncLogger in logback.groovy after using logback.xml -> logback.groovy conversion tool

Thu Mar 5 21:37:27 CET 2015

Hi Mik,

The groovy produced by the translator is incorrect. It should be

appender("ASYNC-FILE", AsyncAppender) {
     appenderRef(["FILE"])
}

Note the "appenderRef" instead of appender-ref

Best regards,
--
Ceki

On 3/5/2015 17:07, Mik Quinlan wrote:
> Hi All
>
> Versions used:
>
> Java: 1.8.0_25
> logback-core/classic: 1.1.2
> groovy-all: 2.4.1
>
> I hope somebody can help.  I used the logback.xml -> logback.groovy
> script to convert my log configuration, which produced:
>
> //
> // Built on Thu Mar 05 16:07:54 CET 2015 by logback-translator
> // For more information on configuration files in Groovy
> // please seehttp://logback.qos.ch/manual/groovy.html
>
> // For assistance related to this tool or configuration files
> // in general, please contact the logback user mailing list at
> //http://qos.ch/mailman/listinfo/logback-user
>
> // For professional support please see
> //http://www.qos.ch/shop/products/professionalSupport
>
> importch.qos.logback.classic.AsyncAppender
> importch.qos.logback.classic.encoder.PatternLayoutEncoder
> importch.qos.logback.core.rolling.RollingFileAppender
> importch.qos.logback.core.rolling.TimeBasedRollingPolicy
> importch.qos.logback.core.status.OnConsoleStatusListener
>
> import staticch.qos.logback.classic.Level.INFO
>
> statusListener(OnConsoleStatusListener)
>
> appender("FILE", RollingFileAppender) {
>      file ="target/uwd.log"
>      rollingPolicy(TimeBasedRollingPolicy) {
>          fileNamePattern ="logFile.%d{yyyy-MM-dd}.log"
>          maxHistory =30
>      }
>      encoder(PatternLayoutEncoder) {
>          pattern ="%date{ISO8601} %-5level %thread [%logger{5}] - %msg%n"
>      }
> }
> appender("ASYNC-FILE", AsyncAppender) {
>      appender-ref("FILE")
> }
>
> root(INFO, ["ASYNC-FILE"])
>
>
> However, when I run Logback, I get a cryptic NPE.
>
> What have I done wrong with the config?
>
> Thanks
>
> Mik
>
> *Stack trace*
> *==========*
>
> /Library/Java/JavaVirtualMachines/jdk1.8.0_25.jdk/Contents/Home/bin/java
> ...
> 15:56:00,342 |-INFO in
> ch.qos.logback.classic.gaffer.ConfigurationDelegate at 2c7b5824 - Added
> status listener of type [ch.qos.logback.core.status.OnConsoleStatusListener]
> 15:56:00,376 |-INFO in
> ch.qos.logback.classic.gaffer.ConfigurationDelegate at 2c7b5824 - About to
> instantiate appender of type
> [ch.qos.logback.core.rolling.RollingFileAppender]
> 15:56:00,377 |-INFO in
> ch.qos.logback.classic.gaffer.ConfigurationDelegate at 2c7b5824 - Naming
> appender as [FILE]
> 15:56:00,468 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy - No
> compression will be used
> 15:56:00,471 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy - Will
> use the pattern logFile.%d{yyyy-MM-dd}.log for the active file
> 15:56:00,476 |-INFO in
> c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - The
> date pattern is 'yyyy-MM-dd' from file name pattern
> 'logFile.%d{yyyy-MM-dd}.log'.
> 15:56:00,476 |-INFO in
> c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy -
> Roll-over at midnight.
> 15:56:00,479 |-INFO in
> c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy -
> Setting initial period to Thu Mar 05 15:55:35 GMT 2015
> 15:56:00,510 |-INFO in
> ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file
> name: target/uwd.log
> 15:56:00,510 |-INFO in
> ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is
> set to [target/uwd.log]
> 15:56:00,519 |-INFO in
> ch.qos.logback.classic.gaffer.ConfigurationDelegate at 2c7b5824 - About to
> instantiate appender of type [ch.qos.logback.classic.AsyncAppender]
> 15:56:00,519 |-INFO in
> ch.qos.logback.classic.gaffer.ConfigurationDelegate at 2c7b5824 - Naming
> appender as [ASYNC-FILE]
> Failed to instantiate [ch.qos.logback.classic.LoggerContext]
> Reported exception:
> java.lang.NullPointerException
> at
> ch.qos.logback.core.AsyncAppenderBase.addAppender(AsyncAppenderBase.java:184)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:483)
> at org.codehaus.groovy.reflection.CachedMethod.invoke(CachedMethod.java:90)
> at groovy.lang.MetaMethod.doMethodInvoke(MetaMethod.java:233)
> at groovy.lang.MetaClassImpl.invokeMethod(MetaClassImpl.java:1082)
> at groovy.lang.MetaClassImpl.invokeMethod(MetaClassImpl.java:906)
> at
> org.codehaus.groovy.runtime.InvokerHelper.invokePojoMethod(InvokerHelper.java:836)
> at
> org.codehaus.groovy.runtime.InvokerHelper.invokeMethod(InvokerHelper.java:827)
> at
> org.codehaus.groovy.runtime.ScriptBytecodeAdapter.invokeMethodN(ScriptBytecodeAdapter.java:164)
> at ch.qos.logback.classic.gaffer.PropertyUtil.attach(PropertyUtil.groovy:48)
> at ch.qos.logback.classic.gaffer.PropertyUtil$attach.call(Unknown Source)
> at
> org.codehaus.groovy.runtime.callsite.CallSiteArray.defaultCall(CallSiteArray.java:45)
> at ch.qos.logback.classic.gaffer.PropertyUtil$attach.call(Unknown Source)
> at
> ch.qos.logback.classic.gaffer.ComponentDelegate.propertyMissing(ComponentDelegate.groovy:94)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:483)
> at org.codehaus.groovy.reflection.CachedMethod.invoke(CachedMethod.java:90)
> at groovy.lang.MetaClassImpl.invokeMissingProperty(MetaClassImpl.java:778)
> at groovy.lang.MetaClassImpl.getProperty(MetaClassImpl.java:1615)
> at groovy.lang.MetaClassImpl.getProperty(MetaClassImpl.java:3343)
> at
> ch.qos.logback.classic.gaffer.ComponentDelegate.getProperty(ComponentDelegate.groovy)
> at
> org.codehaus.groovy.runtime.InvokerHelper.getProperty(InvokerHelper.java:169)
> at groovy.lang.Closure.getPropertyTryThese(Closure.java:322)
> at groovy.lang.Closure.getPropertyDelegateFirst(Closure.java:312)
> at groovy.lang.Closure.getProperty(Closure.java:297)
> at
> org.codehaus.groovy.runtime.callsite.PogoGetPropertySite.getProperty(PogoGetPropertySite.java:47)
> at
> org.codehaus.groovy.runtime.callsite.AbstractCallSite.callGroovyObjectGetProperty(AbstractCallSite.java:231)
> at Script1$_run_closure2.doCall(Script1.groovy:51)
> at Script1$_run_closure2.doCall(Script1.groovy)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:483)
> at org.codehaus.groovy.reflection.CachedMethod.invoke(CachedMethod.java:90)
> at groovy.lang.MetaMethod.doMethodInvoke(MetaMethod.java:233)
> at
> org.codehaus.groovy.runtime.metaclass.ClosureMetaClass.invokeMethod(ClosureMetaClass.java:272)
> at groovy.lang.MetaClassImpl.invokeMethod(MetaClassImpl.java:906)
> at
> org.codehaus.groovy.runtime.callsite.PogoMetaClassSite.call(PogoMetaClassSite.java:39)
> at
> org.codehaus.groovy.runtime.callsite.CallSiteArray.defaultCall(CallSiteArray.java:45)
> at
> org.codehaus.groovy.runtime.callsite.PogoMetaClassSite.call(PogoMetaClassSite.java:54)
> at
> org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:112)
> at
> ch.qos.logback.classic.gaffer.ConfigurationDelegate.appender(ConfigurationDelegate.groovy:141)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:483)
> at org.codehaus.groovy.reflection.CachedMethod.invoke(CachedMethod.java:90)
> at
> org.codehaus.groovy.runtime.metaclass.MixinInstanceMetaMethod.invoke(MixinInstanceMetaMethod.java:53)
> at
> org.codehaus.groovy.runtime.callsite.PogoMetaMethodSite$PogoMetaMethodSiteNoUnwrapNoCoerce.invoke(PogoMetaMethodSite.java:313)
> at
> org.codehaus.groovy.runtime.callsite.PogoMetaMethodSite.callCurrent(PogoMetaMethodSite.java:52)
> at
> org.codehaus.groovy.runtime.callsite.CallSiteArray.defaultCallCurrent(CallSiteArray.java:49)
> at
> org.codehaus.groovy.runtime.callsite.AbstractCallSite.callCurrent(AbstractCallSite.java:133)
> at
> org.codehaus.groovy.runtime.callsite.AbstractCallSite.callCurrent(AbstractCallSite.java:149)
> at Script1.run(Script1.groovy:50)
> at Script1$run.call(Unknown Source)
> at
> org.codehaus.groovy.runtime.callsite.CallSiteArray.defaultCall(CallSiteArray.java:45)
> at
> org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:108)
> at
> org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:112)
> at
> ch.qos.logback.classic.gaffer.GafferConfigurator.run(GafferConfigurator.groovy:77)
> at
> ch.qos.logback.classic.gaffer.GafferConfigurator$run.callCurrent(Unknown
> Source)
> at
> org.codehaus.groovy.runtime.callsite.CallSiteArray.defaultCallCurrent(CallSiteArray.java:49)
> at
> org.codehaus.groovy.runtime.callsite.AbstractCallSite.callCurrent(AbstractCallSite.java:133)
> at
> org.codehaus.groovy.runtime.callsite.AbstractCallSite.callCurrent(AbstractCallSite.java:141)
> at
> ch.qos.logback.classic.gaffer.GafferConfigurator.run(GafferConfigurator.groovy:44)
> at
> ch.qos.logback.classic.gaffer.GafferUtil.runGafferConfiguratorOn(GafferUtil.java:43)
> at
> ch.qos.logback.classic.util.ContextInitializer.configureByResource(ContextInitializer.java:66)
> at
> ch.qos.logback.classic.util.ContextInitializer.autoConfig(ContextInitializer.java:150)
> at org.slf4j.impl.StaticLoggerBinder.init(StaticLoggerBinder.java:85)
> at org.slf4j.impl.StaticLoggerBinder.<clinit>(StaticLoggerBinder.java:55)
> at org.slf4j.LoggerFactory.bind(LoggerFactory.java:129)
> at org.slf4j.LoggerFactory.performInitialization(LoggerFactory.java:108)
> at org.slf4j.LoggerFactory.getILoggerFactory(LoggerFactory.java:302)
> at org.slf4j.LoggerFactory.getLogger(LoggerFactory.java:276)
> at
> com.github.tomakehurst.wiremock.common.Slf4jNotifier.<clinit>(Slf4jNotifier.java:23)
> at
> com.github.tomakehurst.wiremock.core.WireMockConfiguration.<init>(WireMockConfiguration.java:52)
> at
> com.github.tomakehurst.wiremock.core.WireMockConfiguration.wireMockConfig(WireMockConfiguration.java:65)
> at com.....RunIntegrationTest.<clinit>(RunIntegrationTest.java:19)
> at sun.misc.Unsafe.ensureClassInitialized(Native Method)
> at
> sun.reflect.UnsafeFieldAccessorFactory.newFieldAccessor(UnsafeFieldAccessorFactory.java:43)
> at
> sun.reflect.ReflectionFactory.newFieldAccessor(ReflectionFactory.java:142)
> at java.lang.reflect.Field.acquireFieldAccessor(Field.java:1082)
> at java.lang.reflect.Field.getFieldAccessor(Field.java:1063)
> at java.lang.reflect.Field.get(Field.java:387)
> at org.junit.runners.model.FrameworkField.get(FrameworkField.java:69)
> at
> org.junit.runners.model.TestClass.getAnnotatedFieldValues(TestClass.java:156)
> at org.junit.runners.ParentRunner.classRules(ParentRunner.java:215)
> at org.junit.runners.ParentRunner.withClassRules(ParentRunner.java:203)
> at org.junit.runners.ParentRunner.classBlock(ParentRunner.java:163)
> at org.junit.runners.ParentRunner.run(ParentRunner.java:308)
> at cucumber.api.junit.Cucumber.run(Cucumber.java:98)
> at org.junit.runner.JUnitCore.run(JUnitCore.java:160)
> at
> com.intellij.junit4.JUnit4IdeaTestRunner.startRunnerWithArgs(JUnit4IdeaTestRunner.java:74)
> at
> com.intellij.rt.execution.junit.JUnitStarter.prepareStreamsAndStart(JUnitStarter.java:211)
> at com.intellij.rt.execution.junit.JUnitStarter.main(JUnitStarter.java:67)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at
> sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> at
> sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:483)
> at com.intellij.rt.execution.application.AppMain.main(AppMain.java:134)
>
> Thanks
>
> Mik
>
>
>
>
>
>
> _______________________________________________
> Logback-user mailing list
> Logback-user at qos.ch
> http://mailman.qos.ch/mailman/listinfo/logback-user
>