Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

logback.groovy configuration file not found with Logback 1.2.7 and Tomcat 9.0.43 in Grails 5.3.3 #13124

Open
1442acb42ee0e3a727d7330e9755eb94 opened this issue Sep 12, 2023 · 2 comments
Open

logback.groovy configuration file not found with Logback 1.2.7 and Tomcat 9.0.43 in Grails 5.3.3 #13124

1442acb42ee0e3a727d7330e9755eb94 opened this issue Sep 12, 2023 · 2 comments
Assignees
@guillermocalvo

Comments

@1442acb42ee0e3a727d7330e9755eb94
Copy link

Expected Behavior

When the war is deployed/copied from the build directory of the project to the tomcat's deploy directory of the linux server (for example located at /var/lib/tomcat9/webapps/ROOT.war) the Grails application should start without throwing an exception and logging should work.

Note: This problem occurs only when deploying the app via a WAR file to a tomcat instance on a server. When running the WAR as standalone application the error does not occur.

Actual Behaviour

The Grails app can't find/locate logback.groovy and therefore throws a FileNotFoundException.

[2023-09-12 12:05:28] [info] Deploying web application archive [/var/lib/tomcat9/webapps/ROOT.war]
[2023-09-12 12:05:30] [info] At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
[2023-09-12 12:05:31] [info] 12:05:30,331 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
[2023-09-12 12:05:31] [info] 12:05:30,331 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.groovy] at [file:/var/lib/tomcat9/webapps/ROOT/WEB-INF/classes/logback.groovy]
[2023-09-12 12:05:31] [info] 12:05:31,015 |-INFO in ch.qos.logback.classic.gaffer.ConfigurationDelegate@6ed5d493 - registering conversion word clr with class [org.springframework.boot.logging.logback.ColorConverter]
[2023-09-12 12:05:31] [info] 12:05:31,020 |-INFO in ch.qos.logback.classic.gaffer.ConfigurationDelegate@6ed5d493 - registering conversion word wex with class [org.springframework.boot.logging.logback.WhitespaceThrowableProxyConverter]
[2023-09-12 12:05:31] [info] 12:05:31,024 |-INFO in ch.qos.logback.classic.gaffer.ConfigurationDelegate@6ed5d493 - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
[2023-09-12 12:05:31] [info] 12:05:31,026 |-INFO in ch.qos.logback.classic.gaffer.ConfigurationDelegate@6ed5d493 - Naming appender as [STDOUT]
[2023-09-12 12:05:31] [info] 12:05:31,172 |-INFO in ch.qos.logback.classic.gaffer.ConfigurationDelegate@6ed5d493 - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
[2023-09-12 12:05:31] [info] 12:05:31,172 |-INFO in ch.qos.logback.classic.gaffer.ConfigurationDelegate@6ed5d493 - Naming appender as [ROLLING]
[2023-09-12 12:05:31] [info] 12:05:31,179 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@1396161965 - setting totalSizeCap to 2 GB
[2023-09-12 12:05:31] [info] 12:05:31,180 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@1396161965 - No compression will be used
[2023-09-12 12:05:31] [info] 12:05:31,181 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@1396161965 - Will use the pattern /tmp/development-projectname-%d{yyyy-MM-dd}.log for the active file
[2023-09-12 12:05:31] [info] 12:05:31,182 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - The date pattern is 'yyyy-MM-dd' from file name pattern '/tmp/development-projectname-%d{yyyy-MM-dd}.log'.
[2023-09-12 12:05:31] [info] 12:05:31,182 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Roll-over at midnight.
[2023-09-12 12:05:31] [info] 12:05:31,182 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Setting initial period to Tue Sep 12 12:05:31 CEST 2023
[2023-09-12 12:05:31] [info] 12:05:31,182 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[ROLLING] - Active log file name: /tmp/development-projectname-2023-09-12.log
[2023-09-12 12:05:31] [info] 12:05:31,182 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[ROLLING] - File property is set to [null]
[2023-09-12 12:05:31] [info] 12:05:31,183 |-ERROR in ch.qos.logback.core.rolling.RollingFileAppender[ROLLING] - openFile(null,true) call failed. java.io.FileNotFoundException: /tmp/development-projectname-2023-09-12.log (Keine Berechtigung)
[2023-09-12 12:05:31] [info] #011at java.io.FileNotFoundException: /tmp/development-projectname-2023-09-12.log (Keine Berechtigung)
[2023-09-12 12:05:31] [info] #011at #011at java.base/java.io.FileOutputStream.open0(Native Method)
[2023-09-12 12:05:31] [info] #011at #011at java.base/java.io.FileOutputStream.open(FileOutputStream.java:298)
[2023-09-12 12:05:31] [info] #011at #011at java.base/java.io.FileOutputStream.<init>(FileOutputStream.java:237)
[2023-09-12 12:05:31] [info] #011at #011at ch.qos.logback.core.recovery.ResilientFileOutputStream.<init>(ResilientFileOutputStream.java:26)
[2023-09-12 12:05:31] [info] #011at #011at ch.qos.logback.core.FileAppender.openFile(FileAppender.java:204)
[2023-09-12 12:05:31] [info] #011at #011at ch.qos.logback.core.FileAppender.start(FileAppender.java:127)
[2023-09-12 12:05:31] [info] #011at #011at ch.qos.logback.core.rolling.RollingFileAppender.start(RollingFileAppender.java:100)
[2023-09-12 12:05:31] [info] #011at #011at ch.qos.logback.core.spi.LifeCycle$start.call(Unknown Source)
[2023-09-12 12:05:31] [info] #011at #011at org.codehaus.groovy.runtime.callsite.CallSiteArray.defaultCall(CallSiteArray.java:47)
[2023-09-12 12:05:31] [info] #011at #011at ch.qos.logback.core.spi.LifeCycle$start.call(Unknown Source)
[2023-09-12 12:05:31] [info] #011at #011at ch.qos.logback.classic.gaffer.ConfigurationDelegate.appender(ConfigurationDelegate.groovy:146)
[2023-09-12 12:05:31] [info] #011at #011at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[2023-09-12 12:05:31] [info] #011at #011at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[2023-09-12 12:05:31] [info] #011at #011at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[2023-09-12 12:05:31] [info] #011at #011at java.base/java.lang.reflect.Method.invoke(Method.java:566)
[2023-09-12 12:05:31] [info] #011at #011at org.codehaus.groovy.reflection.CachedMethod.invoke(CachedMethod.java:107)
[2023-09-12 12:05:31] [info] #011at #011at org.codehaus.groovy.runtime.metaclass.MixinInstanceMetaMethod.invoke(MixinInstanceMetaMethod.java:56)
[2023-09-12 12:05:31] [info] #011at #011at org.codehaus.groovy.runtime.callsite.PogoMetaMethodSite$PogoMetaMethodSiteNoUnwrapNoCoerce.invoke(PogoMetaMethodSite.java:226)
[2023-09-12 12:05:31] [info] #011at #011at org.codehaus.groovy.runtime.callsite.PogoMetaMethodSite.callCurrent(PogoMetaMethodSite.java:61)
[2023-09-12 12:05:31] [info] #011at #011at org.codehaus.groovy.runtime.callsite.CallSiteArray.defaultCallCurrent(CallSiteArray.java:51)
[2023-09-12 12:05:31] [info] #011at #011at org.codehaus.groovy.runtime.callsite.AbstractCallSite.callCurrent(AbstractCallSite.java:171)
[2023-09-12 12:05:31] [info] #011at #011at org.codehaus.groovy.runtime.callsite.AbstractCallSite.callCurrent(AbstractCallSite.java:203)
[2023-09-12 12:05:31] [info] #011at #011at Script1.run(Script1.groovy:40)
[2023-09-12 12:05:31] [info] #011at #011at Script1$run.call(Unknown Source)
[2023-09-12 12:05:31] [info] #011at #011at org.codehaus.groovy.runtime.callsite.CallSiteArray.defaultCall(CallSiteArray.java:47)
[2023-09-12 12:05:31] [info] #011at #011at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:125)
[2023-09-12 12:05:31] [info] #011at #011at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:130)
[2023-09-12 12:05:31] [info] #011at #011at ch.qos.logback.classic.gaffer.GafferConfigurator.run(GafferConfigurator.groovy:77)
[2023-09-12 12:05:31] [info] #011at #011at ch.qos.logback.classic.gaffer.GafferConfigurator$run.callCurrent(Unknown Source)
[2023-09-12 12:05:31] [info] #011at #011at org.codehaus.groovy.runtime.callsite.CallSiteArray.defaultCallCurrent(CallSiteArray.java:51)
[2023-09-12 12:05:31] [info] #011at #011at org.codehaus.groovy.runtime.callsite.AbstractCallSite.callCurrent(AbstractCallSite.java:171)
[2023-09-12 12:05:31] [info] #011at #011at org.codehaus.groovy.runtime.callsite.AbstractCallSite.callCurrent(AbstractCallSite.java:185)
[2023-09-12 12:05:31] [info] #011at #011at ch.qos.logback.classic.gaffer.GafferConfigurator.run(GafferConfigurator.groovy:44)
[2023-09-12 12:05:31] [info] #011at #011at ch.qos.logback.classic.gaffer.GafferUtil.runGafferConfiguratorOn(GafferUtil.java:43)
[2023-09-12 12:05:31] [info] #011at #011at ch.qos.logback.classic.util.ContextInitializer.configureByResource(ContextInitializer.java:67)
[2023-09-12 12:05:31] [info] #011at #011at ch.qos.logback.classic.util.ContextInitializer.autoConfig(ContextInitializer.java:150)
[2023-09-12 12:05:31] [info] #011at #011at org.slf4j.impl.StaticLoggerBinder.init(StaticLoggerBinder.java:84)
[2023-09-12 12:05:31] [info] #011at #011at org.slf4j.impl.StaticLoggerBinder.<clinit>(StaticLoggerBinder.java:55)
[2023-09-12 12:05:31] [info] #011at #011at org.slf4j.LoggerFactory.bind(LoggerFactory.java:150)
[2023-09-12 12:05:31] [info] #011at #011at org.slf4j.LoggerFactory.performInitialization(LoggerFactory.java:124)
[2023-09-12 12:05:31] [info] #011at #011at org.slf4j.LoggerFactory.getILoggerFactory(LoggerFactory.java:417)
[2023-09-12 12:05:31] [info] #011at #011at org.slf4j.LoggerFactory.getLogger(LoggerFactory.java:362)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.commons.logging.impl.SLF4JLogFactory.getInstance(SLF4JLogFactory.java:155)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.commons.logging.impl.SLF4JLogFactory.getInstance(SLF4JLogFactory.java:132)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.commons.logging.LogFactory.getLog(LogFactory.java:273)
[2023-09-12 12:05:31] [info] #011at #011at org.springframework.boot.web.servlet.support.SpringBootServletInitializer.onStartup(SpringBootServletInitializer.java:96)
[2023-09-12 12:05:31] [info] #011at #011at org.springframework.web.SpringServletContainerInitializer.onStartup(SpringServletContainerInitializer.java:174)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5166)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:717)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:690)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:706)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:946)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.startup.HostConfig$DeployWar.run(HostConfig.java:1792)
[2023-09-12 12:05:31] [info] #011at #011at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
[2023-09-12 12:05:31] [info] #011at #011at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75)
[2023-09-12 12:05:31] [info] #011at #011at java.base/java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:118)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.startup.HostConfig.deployWARs(HostConfig.java:757)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:426)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.startup.HostConfig.check(HostConfig.java:1565)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:305)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.util.LifecycleBase.fireLifecycleEvent(LifecycleBase.java:123)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.core.ContainerBase.backgroundProcess(ContainerBase.java:1151)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.processChildren(ContainerBase.java:1353)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.processChildren(ContainerBase.java:1357)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.run(ContainerBase.java:1335)
[2023-09-12 12:05:31] [info] #011at #011at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
[2023-09-12 12:05:31] [info] #011at #011at java.base/java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305)
[2023-09-12 12:05:31] [info] #011at #011at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305)
[2023-09-12 12:05:31] [info] #011at #011at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
[2023-09-12 12:05:31] [info] #011at #011at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
[2023-09-12 12:05:31] [info] #011at #011at java.base/java.lang.Thread.run(Thread.java:829)
[2023-09-12 12:05:31] [info] 12:05:31,183 |-INFO in ch.qos.logback.classic.gaffer.ConfigurationDelegate@6ed5d493 - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
[2023-09-12 12:05:31] [info] 12:05:31,183 |-INFO in ch.qos.logback.classic.gaffer.ConfigurationDelegate@6ed5d493 - Naming appender as [FULL_STACKTRACE]
[2023-09-12 12:05:31] [info] 12:05:31,186 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@17356102 - setting totalSizeCap to 2 GB
[2023-09-12 12:05:31] [info] 12:05:31,186 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@17356102 - No compression will be used
[2023-09-12 12:05:31] [info] 12:05:31,186 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy@17356102 - Will use the pattern /tmp/development-stacktrace-%d{yyyy-MM-dd}.log for the active file
[2023-09-12 12:05:31] [info] 12:05:31,186 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - The date pattern is 'yyyy-MM-dd' from file name pattern '/tmp/development-stacktrace-%d{yyyy-MM-dd}.log'.
[2023-09-12 12:05:31] [info] 12:05:31,186 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Roll-over at midnight.
[2023-09-12 12:05:31] [info] 12:05:31,186 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Setting initial period to Tue Sep 12 12:05:31 CEST 2023
[2023-09-12 12:05:31] [info] 12:05:31,186 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FULL_STACKTRACE] - Active log file name: /tmp/development-stacktrace-2023-09-12.log
[2023-09-12 12:05:31] [info] 12:05:31,186 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FULL_STACKTRACE] - File property is set to [null]
[2023-09-12 12:05:31] [info] 12:05:31,186 |-ERROR in ch.qos.logback.core.rolling.RollingFileAppender[FULL_STACKTRACE] - openFile(null,true) call failed. java.io.FileNotFoundException: /tmp/development-stacktrace-2023-09-12.log (Keine Berechtigung)
[2023-09-12 12:05:31] [info] #011at java.io.FileNotFoundException: /tmp/development-stacktrace-2023-09-12.log (Keine Berechtigung)
[2023-09-12 12:05:31] [info] #011at #011at java.base/java.io.FileOutputStream.open0(Native Method)
[2023-09-12 12:05:31] [info] #011at #011at java.base/java.io.FileOutputStream.open(FileOutputStream.java:298)
[2023-09-12 12:05:31] [info] #011at #011at java.base/java.io.FileOutputStream.<init>(FileOutputStream.java:237)
[2023-09-12 12:05:31] [info] #011at #011at ch.qos.logback.core.recovery.ResilientFileOutputStream.<init>(ResilientFileOutputStream.java:26)
[2023-09-12 12:05:31] [info] #011at #011at ch.qos.logback.core.FileAppender.openFile(FileAppender.java:204)
[2023-09-12 12:05:31] [info] #011at #011at ch.qos.logback.core.FileAppender.start(FileAppender.java:127)
[2023-09-12 12:05:31] [info] #011at #011at ch.qos.logback.core.rolling.RollingFileAppender.start(RollingFileAppender.java:100)
[2023-09-12 12:05:31] [info] #011at #011at ch.qos.logback.core.spi.LifeCycle$start.call(Unknown Source)
[2023-09-12 12:05:31] [info] #011at #011at ch.qos.logback.classic.gaffer.ConfigurationDelegate.appender(ConfigurationDelegate.groovy:146)
[2023-09-12 12:05:31] [info] #011at #011at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[2023-09-12 12:05:31] [info] #011at #011at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[2023-09-12 12:05:31] [info] #011at #011at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[2023-09-12 12:05:31] [info] #011at #011at java.base/java.lang.reflect.Method.invoke(Method.java:566)
[2023-09-12 12:05:31] [info] #011at #011at org.codehaus.groovy.reflection.CachedMethod.invoke(CachedMethod.java:107)
[2023-09-12 12:05:31] [info] #011at #011at org.codehaus.groovy.runtime.metaclass.MixinInstanceMetaMethod.invoke(MixinInstanceMetaMethod.java:56)
[2023-09-12 12:05:31] [info] #011at #011at org.codehaus.groovy.runtime.callsite.PogoMetaMethodSite$PogoMetaMethodSiteNoUnwrapNoCoerce.invoke(PogoMetaMethodSite.java:226)
[2023-09-12 12:05:31] [info] #011at #011at org.codehaus.groovy.runtime.callsite.PogoMetaMethodSite.callCurrent(PogoMetaMethodSite.java:61)
[2023-09-12 12:05:31] [info] #011at #011at org.codehaus.groovy.runtime.callsite.CallSiteArray.defaultCallCurrent(CallSiteArray.java:51)
[2023-09-12 12:05:31] [info] #011at #011at org.codehaus.groovy.runtime.callsite.AbstractCallSite.callCurrent(AbstractCallSite.java:171)
[2023-09-12 12:05:31] [info] #011at #011at org.codehaus.groovy.runtime.callsite.AbstractCallSite.callCurrent(AbstractCallSite.java:203)
[2023-09-12 12:05:31] [info] #011at #011at Script1.run(Script1.groovy:51)
[2023-09-12 12:05:31] [info] #011at #011at Script1$run.call(Unknown Source)
[2023-09-12 12:05:31] [info] #011at #011at org.codehaus.groovy.runtime.callsite.CallSiteArray.defaultCall(CallSiteArray.java:47)
[2023-09-12 12:05:31] [info] #011at #011at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:125)
[2023-09-12 12:05:31] [info] #011at #011at org.codehaus.groovy.runtime.callsite.AbstractCallSite.call(AbstractCallSite.java:130)
[2023-09-12 12:05:31] [info] #011at #011at ch.qos.logback.classic.gaffer.GafferConfigurator.run(GafferConfigurator.groovy:77)
[2023-09-12 12:05:31] [info] #011at #011at ch.qos.logback.classic.gaffer.GafferConfigurator$run.callCurrent(Unknown Source)
[2023-09-12 12:05:31] [info] #011at #011at org.codehaus.groovy.runtime.callsite.CallSiteArray.defaultCallCurrent(CallSiteArray.java:51)
[2023-09-12 12:05:31] [info] #011at #011at org.codehaus.groovy.runtime.callsite.AbstractCallSite.callCurrent(AbstractCallSite.java:171)
[2023-09-12 12:05:31] [info] #011at #011at org.codehaus.groovy.runtime.callsite.AbstractCallSite.callCurrent(AbstractCallSite.java:185)
[2023-09-12 12:05:31] [info] #011at #011at ch.qos.logback.classic.gaffer.GafferConfigurator.run(GafferConfigurator.groovy:44)
[2023-09-12 12:05:31] [info] #011at #011at ch.qos.logback.classic.gaffer.GafferUtil.runGafferConfiguratorOn(GafferUtil.java:43)
[2023-09-12 12:05:31] [info] #011at #011at ch.qos.logback.classic.util.ContextInitializer.configureByResource(ContextInitializer.java:67)
[2023-09-12 12:05:31] [info] #011at #011at ch.qos.logback.classic.util.ContextInitializer.autoConfig(ContextInitializer.java:150)
[2023-09-12 12:05:31] [info] #011at #011at org.slf4j.impl.StaticLoggerBinder.init(StaticLoggerBinder.java:84)
[2023-09-12 12:05:31] [info] #011at #011at org.slf4j.impl.StaticLoggerBinder.<clinit>(StaticLoggerBinder.java:55)
[2023-09-12 12:05:31] [info] #011at #011at org.slf4j.LoggerFactory.bind(LoggerFactory.java:150)
[2023-09-12 12:05:31] [info] #011at #011at org.slf4j.LoggerFactory.performInitialization(LoggerFactory.java:124)
[2023-09-12 12:05:31] [info] #011at #011at org.slf4j.LoggerFactory.getILoggerFactory(LoggerFactory.java:417)
[2023-09-12 12:05:31] [info] #011at #011at org.slf4j.LoggerFactory.getLogger(LoggerFactory.java:362)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.commons.logging.impl.SLF4JLogFactory.getInstance(SLF4JLogFactory.java:155)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.commons.logging.impl.SLF4JLogFactory.getInstance(SLF4JLogFactory.java:132)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.commons.logging.LogFactory.getLog(LogFactory.java:273)
[2023-09-12 12:05:31] [info] #011at #011at org.springframework.boot.web.servlet.support.SpringBootServletInitializer.onStartup(SpringBootServletInitializer.java:96)
[2023-09-12 12:05:31] [info] #011at #011at org.springframework.web.SpringServletContainerInitializer.onStartup(SpringServletContainerInitializer.java:174)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5166)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:717)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:690)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:706)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:946)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.startup.HostConfig$DeployWar.run(HostConfig.java:1792)
[2023-09-12 12:05:31] [info] #011at #011at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
[2023-09-12 12:05:31] [info] #011at #011at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75)
[2023-09-12 12:05:31] [info] #011at #011at java.base/java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:118)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.startup.HostConfig.deployWARs(HostConfig.java:757)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:426)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.startup.HostConfig.check(HostConfig.java:1565)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:305)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.util.LifecycleBase.fireLifecycleEvent(LifecycleBase.java:123)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.core.ContainerBase.backgroundProcess(ContainerBase.java:1151)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.processChildren(ContainerBase.java:1353)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.processChildren(ContainerBase.java:1357)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.run(ContainerBase.java:1335)
[2023-09-12 12:05:31] [info] #011at #011at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
[2023-09-12 12:05:31] [info] #011at #011at java.base/java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305)
[2023-09-12 12:05:31] [info] #011at #011at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305)
[2023-09-12 12:05:31] [info] #011at #011at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
[2023-09-12 12:05:31] [info] #011at #011at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
[2023-09-12 12:05:31] [info] #011at #011at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
[2023-09-12 12:05:31] [info] #011at #011at java.base/java.lang.Thread.run(Thread.java:829)
[2023-09-12 12:05:31] [info] 12:05:31,187 |-INFO in ch.qos.logback.classic.gaffer.ConfigurationDelegate@6ed5d493 - Setting level of logger [grails.plugin.springsecurity.web.access.intercept] to ERROR
[2023-09-12 12:05:31] [info] 12:05:31,189 |-INFO in ch.qos.logback.classic.gaffer.ConfigurationDelegate@6ed5d493 - Setting level of logger [org.springframework.mock.web.MockServletContext] to ERROR
[2023-09-12 12:05:31] [info] 12:05:31,189 |-INFO in ch.qos.logback.classic.gaffer.ConfigurationDelegate@6ed5d493 - Setting level of logger [org.apache.tomcat.jdbc.pool.PooledConnection] to ERROR
[2023-09-12 12:05:31] [info] 12:05:31,189 |-INFO in ch.qos.logback.classic.gaffer.ConfigurationDelegate@6ed5d493 - Setting level of logger [org.hibernate.id.UUIDHexGenerator] to ERROR
[2023-09-12 12:05:31] [info] 12:05:31,189 |-INFO in ch.qos.logback.classic.gaffer.ConfigurationDelegate@6ed5d493 - Setting level of logger [org.hibernate.orm.deprecation] to ERROR
[2023-09-12 12:05:31] [info] 12:05:31,189 |-INFO in ch.qos.logback.classic.gaffer.ConfigurationDelegate@6ed5d493 - Setting level of logger [org.springframework.beans.GenericTypeAwarePropertyDescriptor] to ERROR
[2023-09-12 12:05:31] [info] 12:05:31,189 |-INFO in ch.qos.logback.classic.gaffer.ConfigurationDelegate@6ed5d493 - Setting level of logger [grails.plugin.sentry.SentryGrailsPlugin] to ERROR
[2023-09-12 12:05:31] [info] 12:05:31,189 |-INFO in ch.qos.logback.classic.gaffer.ConfigurationDelegate@6ed5d493 - Setting level of logger [StackTrace] to ERROR
[2023-09-12 12:05:31] [info] 12:05:31,191 |-INFO in ch.qos.logback.classic.gaffer.ConfigurationDelegate@6ed5d493 - Attaching appender named [FULL_STACKTRACE] to Logger[StackTrace]
[2023-09-12 12:05:31] [info] 12:05:31,191 |-INFO in ch.qos.logback.classic.gaffer.ConfigurationDelegate@6ed5d493 - Setting level of logger [grails.app.services] to INFO
[2023-09-12 12:05:31] [info] 12:05:31,191 |-INFO in ch.qos.logback.classic.gaffer.ConfigurationDelegate@6ed5d493 - Attaching appender named [ROLLING] to Logger[grails.app.services]
[2023-09-12 12:05:31] [info] 12:05:31,191 |-INFO in ch.qos.logback.classic.gaffer.ConfigurationDelegate@6ed5d493 - Setting level of logger [grails.app.controllers] to INFO
[2023-09-12 12:05:31] [info] 12:05:31,191 |-INFO in ch.qos.logback.classic.gaffer.ConfigurationDelegate@6ed5d493 - Attaching appender named [ROLLING] to Logger[grails.app.controllers]
[2023-09-12 12:05:31] [info] 12:05:31,192 |-INFO in ch.qos.logback.classic.gaffer.ConfigurationDelegate@6ed5d493 - Setting level of logger [ROOT] to WARN
[2023-09-12 12:05:31] [info] 12:05:31,193 |-INFO in ch.qos.logback.classic.gaffer.ConfigurationDelegate@6ed5d493 - Attaching appender named [STDOUT] to Logger[ROOT]
[2023-09-12 12:05:31] [info] SLF4J: A number (3) of logging calls during the initialization phase have been intercepted and are
[2023-09-12 12:05:31] [info] SLF4J: now being replayed. These are subject to the filtering rules of the underlying logging system.
[2023-09-12 12:05:31] [info] SLF4J: See also http://www.slf4j.org/codes.html#replay
[2023-09-12 12:05:31] [info] Logging system failed to initialize using configuration from 'grails-app/conf/logback.groovy'
[2023-09-12 12:05:31] [info] java.io.FileNotFoundException: /var/lib/tomcat9/grails-app/conf/logback.groovy (Datei oder Verzeichnis nicht gefunden)
[2023-09-12 12:05:31] [info] #011at java.base/java.io.FileInputStream.open0(Native Method)
[2023-09-12 12:05:31] [info] #011at java.base/java.io.FileInputStream.open(FileInputStream.java:219)
[2023-09-12 12:05:31] [info] #011at java.base/java.io.FileInputStream.<init>(FileInputStream.java:157)
[2023-09-12 12:05:31] [info] #011at java.base/java.io.FileInputStream.<init>(FileInputStream.java:112)
[2023-09-12 12:05:31] [info] #011at java.base/sun.net.www.protocol.file.FileURLConnection.connect(FileURLConnection.java:86)
[2023-09-12 12:05:31] [info] #011at java.base/sun.net.www.protocol.file.FileURLConnection.getInputStream(FileURLConnection.java:184)
[2023-09-12 12:05:31] [info] #011at org.codehaus.groovy.runtime.ResourceGroovyMethods.configuredInputStream(ResourceGroovyMethods.java:2222)
[2023-09-12 12:05:31] [info] #011at org.codehaus.groovy.runtime.ResourceGroovyMethods.newReader(ResourceGroovyMethods.java:2308)
[2023-09-12 12:05:31] [info] #011at org.codehaus.groovy.runtime.ResourceGroovyMethods.getText(ResourceGroovyMethods.java:640)
[2023-09-12 12:05:31] [info] #011at org.codehaus.groovy.runtime.ResourceGroovyMethods.getText(ResourceGroovyMethods.java:603)
[2023-09-12 12:05:31] [info] #011at org.codehaus.groovy.runtime.dgm$1069.doMethodInvoke(Unknown Source)
[2023-09-12 12:05:31] [info] #011at org.codehaus.groovy.reflection.GeneratedMetaMethod$Proxy.doMethodInvoke(GeneratedMetaMethod.java:83)
[2023-09-12 12:05:31] [info] #011at org.codehaus.groovy.runtime.metaclass.MethodMetaProperty$GetBeanMethodMetaProperty.getProperty(MethodMetaProperty.java:76)
[2023-09-12 12:05:31] [info] #011at org.codehaus.groovy.runtime.callsite.GetEffectivePojoPropertySite.getProperty(GetEffectivePojoPropertySite.java:63)
[2023-09-12 12:05:31] [info] #011at org.codehaus.groovy.runtime.callsite.AbstractCallSite.callGetProperty(AbstractCallSite.java:329)
[2023-09-12 12:05:31] [info] #011at ch.qos.logback.classic.gaffer.GafferConfigurator.run(GafferConfigurator.groovy:44)
[2023-09-12 12:05:31] [info] #011at ch.qos.logback.classic.gaffer.GafferUtil.runGafferConfiguratorOn(GafferUtil.java:43)
[2023-09-12 12:05:31] [info] #011at ch.qos.logback.classic.util.ContextInitializer.configureByResource(ContextInitializer.java:67)
[2023-09-12 12:05:31] [info] #011at org.springframework.boot.logging.logback.LogbackLoggingSystem.configureByResourceUrl(LogbackLoggingSystem.java:191)
[2023-09-12 12:05:31] [info] #011at org.springframework.boot.logging.logback.LogbackLoggingSystem.loadConfiguration(LogbackLoggingSystem.java:165)
[2023-09-12 12:05:31] [info] #011at org.springframework.boot.logging.AbstractLoggingSystem.initializeWithSpecificConfig(AbstractLoggingSystem.java:66)
[2023-09-12 12:05:31] [info] #011at org.springframework.boot.logging.AbstractLoggingSystem.initialize(AbstractLoggingSystem.java:57)
[2023-09-12 12:05:31] [info] #011at org.springframework.boot.logging.logback.LogbackLoggingSystem.initialize(LogbackLoggingSystem.java:132)
[2023-09-12 12:05:31] [info] #011at org.springframework.boot.context.logging.LoggingApplicationListener.initializeSystem(LoggingApplicationListener.java:332)
[2023-09-12 12:05:31] [info] #011at org.springframework.boot.context.logging.LoggingApplicationListener.initialize(LoggingApplicationListener.java:298)
[2023-09-12 12:05:31] [info] #011at org.springframework.boot.context.logging.LoggingApplicationListener.onApplicationEnvironmentPreparedEvent(LoggingApplicationListener.java:246)
[2023-09-12 12:05:31] [info] #011at org.springframework.boot.context.logging.LoggingApplicationListener.onApplicationEvent(LoggingApplicationListener.java:223)
[2023-09-12 12:05:31] [info] #011at org.springframework.context.event.SimpleApplicationEventMulticaster.doInvokeListener(SimpleApplicationEventMulticaster.java:176)
[2023-09-12 12:05:31] [info] #011at org.springframework.context.event.SimpleApplicationEventMulticaster.invokeListener(SimpleApplicationEventMulticaster.java:169)
[2023-09-12 12:05:31] [info] #011at org.springframework.context.event.SimpleApplicationEventMulticaster.multicastEvent(SimpleApplicationEventMulticaster.java:143)
[2023-09-12 12:05:31] [info] #011at org.springframework.context.event.SimpleApplicationEventMulticaster.multicastEvent(SimpleApplicationEventMulticaster.java:131)
[2023-09-12 12:05:31] [info] #011at org.springframework.boot.context.event.EventPublishingRunListener.environmentPrepared(EventPublishingRunListener.java:85)
[2023-09-12 12:05:31] [info] #011at org.springframework.boot.SpringApplicationRunListeners.lambda$environmentPrepared$2(SpringApplicationRunListeners.java:66)
[2023-09-12 12:05:31] [info] #011at java.base/java.util.ArrayList.forEach(ArrayList.java:1541)
[2023-09-12 12:05:31] [info] #011at org.springframework.boot.SpringApplicationRunListeners.doWithListeners(SpringApplicationRunListeners.java:120)
[2023-09-12 12:05:31] [info] #011at org.springframework.boot.SpringApplicationRunListeners.doWithListeners(SpringApplicationRunListeners.java:114)
[2023-09-12 12:05:31] [info] #011at org.springframework.boot.SpringApplicationRunListeners.environmentPrepared(SpringApplicationRunListeners.java:65)
[2023-09-12 12:05:31] [info] #011at org.springframework.boot.SpringApplication.prepareEnvironment(SpringApplication.java:343)
[2023-09-12 12:05:31] [info] #011at org.springframework.boot.SpringApplication.run(SpringApplication.java:301)
[2023-09-12 12:05:31] [info] #011at grails.boot.GrailsApp.run(GrailsApp.groovy:99)
[2023-09-12 12:05:31] [info] #011at org.springframework.boot.web.servlet.support.SpringBootServletInitializer.run(SpringBootServletInitializer.java:175)
[2023-09-12 12:05:31] [info] #011at org.springframework.boot.web.servlet.support.SpringBootServletInitializer.createRootApplicationContext(SpringBootServletInitializer.java:155)
[2023-09-12 12:05:31] [info] #011at org.springframework.boot.web.servlet.support.SpringBootServletInitializer.onStartup(SpringBootServletInitializer.java:97)
[2023-09-12 12:05:31] [info] #011at org.springframework.web.SpringServletContainerInitializer.onStartup(SpringServletContainerInitializer.java:174)
[2023-09-12 12:05:31] [info] #011at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5166)
[2023-09-12 12:05:31] [info] #011at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
[2023-09-12 12:05:31] [info] #011at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:717)
[2023-09-12 12:05:31] [info] #011at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:690)
[2023-09-12 12:05:31] [info] #011at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:706)
[2023-09-12 12:05:31] [info] #011at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:946)
[2023-09-12 12:05:31] [info] #011at org.apache.catalina.startup.HostConfig$DeployWar.run(HostConfig.java:1792)
[2023-09-12 12:05:31] [info] #011at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
[2023-09-12 12:05:31] [info] #011at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[2023-09-12 12:05:31] [info] #011at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75)
[2023-09-12 12:05:31] [info] #011at java.base/java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:118)
[2023-09-12 12:05:31] [info] #011at org.apache.catalina.startup.HostConfig.deployWARs(HostConfig.java:757)
[2023-09-12 12:05:31] [info] #011at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:426)
[2023-09-12 12:05:31] [info] #011at org.apache.catalina.startup.HostConfig.check(HostConfig.java:1565)
[2023-09-12 12:05:31] [info] #011at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:305)
[2023-09-12 12:05:31] [info] #011at org.apache.catalina.util.LifecycleBase.fireLifecycleEvent(LifecycleBase.java:123)
[2023-09-12 12:05:31] [info] #011at org.apache.catalina.core.ContainerBase.backgroundProcess(ContainerBase.java:1151)
[2023-09-12 12:05:31] [info] #011at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.processChildren(ContainerBase.java:1353)
[2023-09-12 12:05:31] [info] #011at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.processChildren(ContainerBase.java:1357)
[2023-09-12 12:05:31] [info] #011at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.run(ContainerBase.java:1335)
[2023-09-12 12:05:31] [info] #011at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
[2023-09-12 12:05:31] [info] #011at java.base/java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305)
[2023-09-12 12:05:31] [info] #011at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305)
[2023-09-12 12:05:31] [info] #011at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
[2023-09-12 12:05:31] [info] #011at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
[2023-09-12 12:05:31] [info] #011at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
[2023-09-12 12:05:31] [info] #011at java.base/java.lang.Thread.run(Thread.java:829)
[2023-09-12 12:05:31] [crit] Error deploying web application archive [/var/lib/tomcat9/webapps/ROOT.war]
[2023-09-12 12:05:31] [crit] java.lang.IllegalStateException: Error starting child
[2023-09-12 12:05:31] [crit]     at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:720)
[2023-09-12 12:05:31] [crit]     at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:690)
[2023-09-12 12:05:31] [crit]     at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:706)
[2023-09-12 12:05:31] [crit]     at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:946)
[2023-09-12 12:05:31] [crit]     at org.apache.catalina.startup.HostConfig$DeployWar.run(HostConfig.java:1792)
[2023-09-12 12:05:31] [crit]     at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
[2023-09-12 12:05:31] [crit]     at java.base/java.util.concurrent.FutureTask.run(FutureTask.java:264)
[2023-09-12 12:05:31] [crit]     at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75)
[2023-09-12 12:05:31] [crit]     at java.base/java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:118)
[2023-09-12 12:05:31] [crit]     at org.apache.catalina.startup.HostConfig.deployWARs(HostConfig.java:757)
[2023-09-12 12:05:31] [crit]     at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:426)
[2023-09-12 12:05:31] [crit]     at org.apache.catalina.startup.HostConfig.check(HostConfig.java:1565)
[2023-09-12 12:05:31] [crit]     at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:305)
[2023-09-12 12:05:31] [crit]     at org.apache.catalina.util.LifecycleBase.fireLifecycleEvent(LifecycleBase.java:123)
[2023-09-12 12:05:31] [crit]     at org.apache.catalina.core.ContainerBase.backgroundProcess(ContainerBase.java:1151)
[2023-09-12 12:05:31] [crit]     at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.processChildren(ContainerBase.java:1353)
[2023-09-12 12:05:31] [crit]     at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.processChildren(ContainerBase.java:1357)
[2023-09-12 12:05:31] [crit]     at org.apache.catalina.core.ContainerBase$ContainerBackgroundProcessor.run(ContainerBase.java:1335)
[2023-09-12 12:05:31] [crit]     at java.base/java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:515)
[2023-09-12 12:05:31] [crit]     at java.base/java.util.concurrent.FutureTask.runAndReset(FutureTask.java:305)
[2023-09-12 12:05:31] [crit]     at java.base/java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:305)
[2023-09-12 12:05:31] [crit]     at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1128)
[2023-09-12 12:05:31] [crit]     at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:628)
[2023-09-12 12:05:31] [crit]     at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
[2023-09-12 12:05:31] [crit]     at java.base/java.lang.Thread.run(Thread.java:829)
[2023-09-12 12:05:31] [crit] Caused by: org.apache.catalina.LifecycleException: Failed to start component [StandardEngine[Catalina].StandardHost[localhost].StandardContext[]]
[2023-09-12 12:05:31] [crit]     at org.apache.catalina.util.LifecycleBase.handleSubClassException(LifecycleBase.java:440)
[2023-09-12 12:05:31] [crit]     at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:198)
[2023-09-12 12:05:31] [crit]     at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:717)
[2023-09-12 12:05:31] [crit]     ... 24 more
[2023-09-12 12:05:31] [crit] Caused by: java.lang.IllegalStateException: java.lang.IllegalStateException: Could not initialize Logback logging from grails-app/conf/logback.groovy
[2023-09-12 12:05:31] [crit]     at org.springframework.boot.context.logging.LoggingApplicationListener.initializeSystem(LoggingApplicationListener.java:344)
[2023-09-12 12:05:31] [crit]     at org.springframework.boot.context.logging.LoggingApplicationListener.initialize(LoggingApplicationListener.java:298)
[2023-09-12 12:05:31] [crit]     at org.springframework.boot.context.logging.LoggingApplicationListener.onApplicationEnvironmentPreparedEvent(LoggingApplicationListener.java:246)
[2023-09-12 12:05:31] [crit]     at org.springframework.boot.context.logging.LoggingApplicationListener.onApplicationEvent(LoggingApplicationListener.java:223)
[2023-09-12 12:05:31] [crit]     at org.springframework.context.event.SimpleApplicationEventMulticaster.doInvokeListener(SimpleApplicationEventMulticaster.java:176)
[2023-09-12 12:05:31] [crit]     at org.springframework.context.event.SimpleApplicationEventMulticaster.invokeListener(SimpleApplicationEventMulticaster.java:169)
[2023-09-12 12:05:31] [crit]     at org.springframework.context.event.SimpleApplicationEventMulticaster.multicastEvent(SimpleApplicationEventMulticaster.java:143)
[2023-09-12 12:05:31] [crit]     at org.springframework.context.event.SimpleApplicationEventMulticaster.multicastEvent(SimpleApplicationEventMulticaster.java:131)
[2023-09-12 12:05:31] [crit]     at org.springframework.boot.context.event.EventPublishingRunListener.environmentPrepared(EventPublishingRunListener.java:85)
[2023-09-12 12:05:31] [crit]     at org.springframework.boot.SpringApplicationRunListeners.lambda$environmentPrepared$2(SpringApplicationRunListeners.java:66)
[2023-09-12 12:05:31] [crit]     at java.base/java.util.ArrayList.forEach(ArrayList.java:1541)
[2023-09-12 12:05:31] [crit]     at org.springframework.boot.SpringApplicationRunListeners.doWithListeners(SpringApplicationRunListeners.java:120)
[2023-09-12 12:05:31] [crit]     at org.springframework.boot.SpringApplicationRunListeners.doWithListeners(SpringApplicationRunListeners.java:114)
[2023-09-12 12:05:31] [crit]     at org.springframework.boot.SpringApplicationRunListeners.environmentPrepared(SpringApplicationRunListeners.java:65)
[2023-09-12 12:05:31] [crit]     at org.springframework.boot.SpringApplication.prepareEnvironment(SpringApplication.java:343)
[2023-09-12 12:05:31] [crit]     at org.springframework.boot.SpringApplication.run(SpringApplication.java:301)
[2023-09-12 12:05:31] [crit]     at grails.boot.GrailsApp.run(GrailsApp.groovy:99)
[2023-09-12 12:05:31] [crit]     at org.springframework.boot.web.servlet.support.SpringBootServletInitializer.run(SpringBootServletInitializer.java:175)
[2023-09-12 12:05:31] [crit]     at org.springframework.boot.web.servlet.support.SpringBootServletInitializer.createRootApplicationContext(SpringBootServletInitializer.java:155)
[2023-09-12 12:05:31] [crit]     at org.springframework.boot.web.servlet.support.SpringBootServletInitializer.onStartup(SpringBootServletInitializer.java:97)
[2023-09-12 12:05:31] [crit]     at org.springframework.web.SpringServletContainerInitializer.onStartup(SpringServletContainerInitializer.java:174)
[2023-09-12 12:05:31] [crit]     at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5166)
[2023-09-12 12:05:31] [crit]     at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
[2023-09-12 12:05:31] [crit]     ... 25 more
[2023-09-12 12:05:31] [crit] Caused by: java.lang.IllegalStateException: Could not initialize Logback logging from grails-app/conf/logback.groovy
[2023-09-12 12:05:31] [crit]     at org.springframework.boot.logging.logback.LogbackLoggingSystem.loadConfiguration(LogbackLoggingSystem.java:168)
[2023-09-12 12:05:31] [crit]     at org.springframework.boot.logging.AbstractLoggingSystem.initializeWithSpecificConfig(AbstractLoggingSystem.java:66)
[2023-09-12 12:05:31] [crit]     at org.springframework.boot.logging.AbstractLoggingSystem.initialize(AbstractLoggingSystem.java:57)
[2023-09-12 12:05:31] [crit]     at org.springframework.boot.logging.logback.LogbackLoggingSystem.initialize(LogbackLoggingSystem.java:132)
[2023-09-12 12:05:31] [crit]     at org.springframework.boot.context.logging.LoggingApplicationListener.initializeSystem(LoggingApplicationListener.java:332)
[2023-09-12 12:05:31] [crit]     ... 47 more
[2023-09-12 12:05:31] [crit] Caused by: java.io.FileNotFoundException: /var/lib/tomcat9/grails-app/conf/logback.groovy (Datei oder Verzeichnis nicht gefunden)
[2023-09-12 12:05:31] [crit]     at java.base/java.io.FileInputStream.open0(Native Method)
[2023-09-12 12:05:31] [crit]     at java.base/java.io.FileInputStream.open(FileInputStream.java:219)
[2023-09-12 12:05:31] [crit]     at java.base/java.io.FileInputStream.<init>(FileInputStream.java:157)
[2023-09-12 12:05:31] [crit]     at java.base/java.io.FileInputStream.<init>(FileInputStream.java:112)
[2023-09-12 12:05:31] [crit]     at java.base/sun.net.www.protocol.file.FileURLConnection.connect(FileURLConnection.java:86)
[2023-09-12 12:05:31] [crit]     at java.base/sun.net.www.protocol.file.FileURLConnection.getInputStream(FileURLConnection.java:184)
[2023-09-12 12:05:31] [crit]     at org.codehaus.groovy.runtime.ResourceGroovyMethods.configuredInputStream(ResourceGroovyMethods.java:2222)
[2023-09-12 12:05:31] [crit]     at org.codehaus.groovy.runtime.ResourceGroovyMethods.newReader(ResourceGroovyMethods.java:2308)
[2023-09-12 12:05:31] [crit]     at org.codehaus.groovy.runtime.ResourceGroovyMethods.getText(ResourceGroovyMethods.java:640)
[2023-09-12 12:05:31] [crit]     at org.codehaus.groovy.runtime.ResourceGroovyMethods.getText(ResourceGroovyMethods.java:603)
[2023-09-12 12:05:31] [crit]     at org.codehaus.groovy.runtime.dgm$1069.doMethodInvoke(Unknown Source)
[2023-09-12 12:05:31] [crit]     at org.codehaus.groovy.reflection.GeneratedMetaMethod$Proxy.doMethodInvoke(GeneratedMetaMethod.java:83)
[2023-09-12 12:05:31] [crit]     at org.codehaus.groovy.runtime.metaclass.MethodMetaProperty$GetBeanMethodMetaProperty.getProperty(MethodMetaProperty.java:76)
[2023-09-12 12:05:31] [crit]     at org.codehaus.groovy.runtime.callsite.GetEffectivePojoPropertySite.getProperty(GetEffectivePojoPropertySite.java:63)
[2023-09-12 12:05:31] [crit]     at org.codehaus.groovy.runtime.callsite.AbstractCallSite.callGetProperty(AbstractCallSite.java:329)
[2023-09-12 12:05:31] [crit]     at ch.qos.logback.classic.gaffer.GafferConfigurator.run(GafferConfigurator.groovy:44)
[2023-09-12 12:05:31] [crit]     at ch.qos.logback.classic.gaffer.GafferUtil.runGafferConfiguratorOn(GafferUtil.java:43)
[2023-09-12 12:05:31] [crit]     at ch.qos.logback.classic.util.ContextInitializer.configureByResource(ContextInitializer.java:67)
[2023-09-12 12:05:31] [crit]     at org.springframework.boot.logging.logback.LogbackLoggingSystem.configureByResourceUrl(LogbackLoggingSystem.java:191)
[2023-09-12 12:05:31] [crit]     at org.springframework.boot.logging.logback.LogbackLoggingSystem.loadConfiguration(LogbackLoggingSystem.java:165)
[2023-09-12 12:05:31] [crit]     ... 51 more
[2023-09-12 12:05:31] [info] Deployment of web application archive [/var/lib/tomcat9/webapps/ROOT.war] has finished in [3,151] ms

Steps To Reproduce

  1. Unpack the Grails project attached [logback-tomcat.zip] (https://github.com/grails/grails-core/files/12585370/logback-tomcat.zip) by unzipping it

  2. Change into the project directory

  3. Build the war file with

grails dev war 
OR
grails war
  1. Copy/deploy the war to the local tomcat installation for example with
cp /home/user/projects/logback-tomcat/build/libs/logback-tomcat-0.1.war /var/lib/tomcat9/webapps/ROOT.war
OR
cp /home/user/projects/logback-tomcat/build/libs/logback-tomcat-0.1-plain.war /var/lib/tomcat9/webapps/ROOT.war
  1. Open the log for example at /var/lib/tomcat9/logs/catalina.out
  2. Locate the error message "Caused by: java.lang.IllegalStateException: java.lang.IllegalStateException: Could not initialize Logback logging from grails-app/conf/logback.groovy"

Environment Information

  • Debian: 11.7
  • Tomcat: 9.0.43-2~deb11u6
  • Tomcat directory: /var/lib/tomcat9
  • Grails 5.3.3
  • openjdk version "11.0.20" 2023-07-18 OpenJDK Runtime Environment (build 11.0.20+8-post-Debian-1deb11u1) OpenJDK 64-Bit Server VM (build 11.0.20+8-post-Debian-1deb11u1, mixed mode, sharing)

Example Application

https://github.com/grails/grails-core/files/12585370/logback-tomcat.zip

Version

5.3.3
@fernando88to
Copy link

fernando88to commented Sep 14, 2023 •
edited by puneetbehl

I made a small change to the application.yml configuration file

logging:
    config: grails-app/conf/logback.groovy

environments:
    development:
        dataSource:
            dbCreate: create-drop
            url: jdbc:h2:mem:devDb;LOCK_TIMEOUT=10000;DB_CLOSE_ON_EXIT=FALSE
    test:
        dataSource:
            dbCreate: update
            url: jdbc:h2:mem:testDb;LOCK_TIMEOUT=10000;DB_CLOSE_ON_EXIT=FALSE
    production:
        logging:
            config: /usr/local/tomcat/webapps/ROOT/WEB-INF/classes/logback.groovy
        dataSource:
            dbCreate: none
            url: jdbc:h2:./prodDb;LOCK_TIMEOUT=10000;DB_CLOSE_ON_EXIT=FALSE
            properties:
                jmxEnabled: true
                initialSize: 5
                maxActive: 50
                minIdle: 5
                maxIdle: 25
                maxWait: 10000
                maxAge: 600000
                timeBetweenEvictionRunsMillis: 5000
                minEvictableIdleTimeMillis: 60000
                validationQuery: SELECT 1
                validationQueryTimeout: 3
                validationInterval: 15000
                testOnBorrow: true
                testWhileIdle: true
                testOnReturn: false
                jdbcInterceptors: ConnectionState
                defaultTransactionIsolation: 2 # TRANSACTION_READ_COMMITTED

It worked normally, if you want to see the project with Docker, follow the project link

https://github.com/fernando88to/logback-tomcat

The readme.md file contains instructions for running the project

@1442acb42ee0e3a727d7330e9755eb94
Copy link
Author

Thank you! I actually found this workaround myself, before publishing this bug report. But I think it's only a workaround, because in Grails 3 & 4 a reference to a local directories with logback 1.2.7 was not necessary.

@puneetbehl puneetbehl self-assigned this Oct 25, 2023
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees

@guillermocalvo guillermocalvo

Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
4 participants
@fernando88to @guillermocalvo @puneetbehl @1442acb42ee0e3a727d7330e9755eb94