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

Logging in FITS 1.0.7 (and maybe earlier appears to be broken) #246

Closed
nigelgbanks opened this issue Sep 29, 2022 · 4 comments
Closed

Logging in FITS 1.0.7 (and maybe earlier appears to be broken) #246

nigelgbanks opened this issue Sep 29, 2022 · 4 comments
Assignees
@nigelgbanks
Labels
bug Something isn't working

Comments

@nigelgbanks
Copy link
Contributor

Noticed this when FITS derivatives failed to be generated, might be related.

leaf-fits-1  | [s6-init] making user provided files available at /var/run/s6/etc...exited 0.
leaf-fits-1  | [s6-init] ensuring user provided files have correct perms...exited 0.
leaf-fits-1  | [fix-attrs.d] applying ownership & permissions fixes...
leaf-fits-1  | [fix-attrs.d] done.
leaf-fits-1  | [cont-init.d] executing container initialization scripts...
leaf-fits-1  | [cont-init.d] 00-container-environment-00-init.sh: executing... 
leaf-fits-1  | [cont-init.d] 00-container-environment-00-init.sh: exited 0.
leaf-fits-1  | [cont-init.d] 00-container-environment-01-override-tomcat.sh: executing... 
leaf-fits-1  | [cont-init.d] 00-container-environment-01-override-tomcat.sh: exited 0.
leaf-fits-1  | [cont-init.d] 00-container-environment-02-database-defaults.sh: executing... 
leaf-fits-1  | [cont-init.d] 00-container-environment-02-database-defaults.sh: exited 0.
leaf-fits-1  | [cont-init.d] 00-tty.sh: executing... 
leaf-fits-1  | [cont-init.d] 00-tty.sh: exited 0.
leaf-fits-1  | [cont-init.d] 01-confd-render-templates.sh: executing... 
leaf-fits-1  | [cont-init.d] 01-confd-render-templates.sh: exited 0.
leaf-fits-1  | [cont-init.d] 02-cacert-import.sh: executing... 
leaf-fits-1  | WARNING: ca-certificates.crt does not contain exactly one certificate or CRL: skipping
leaf-fits-1  | Certificate was added to keystore
leaf-fits-1  | [cont-init.d] 02-cacert-import.sh: exited 0.
leaf-fits-1  | [cont-init.d] done.
leaf-fits-1  | [services.d] starting services
leaf-fits-1  | [services.d] done.
leaf-fits-1  | 28-Sep-2022 23:31:10.399 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server version name:   Apache Tomcat/9.0.58
leaf-fits-1  | 28-Sep-2022 23:31:10.522 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server built:          Jan 15 2022 14:37:38 UTC
leaf-fits-1  | 28-Sep-2022 23:31:10.522 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server version number: 9.0.58.0
leaf-fits-1  | 28-Sep-2022 23:31:10.522 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log OS Name:               Linux
leaf-fits-1  | 28-Sep-2022 23:31:10.522 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log OS Version:            5.15.64
leaf-fits-1  | 28-Sep-2022 23:31:10.522 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Architecture:          amd64
leaf-fits-1  | 28-Sep-2022 23:31:10.522 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Java Home:             /usr/lib/jvm/java-1.8-openjdk/jre
leaf-fits-1  | 28-Sep-2022 23:31:10.522 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Version:           1.8.0_322-b06
leaf-fits-1  | 28-Sep-2022 23:31:10.522 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Vendor:            IcedTea
leaf-fits-1  | 28-Sep-2022 23:31:10.523 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_BASE:         /opt/tomcat
leaf-fits-1  | 28-Sep-2022 23:31:10.523 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_HOME:         /opt/tomcat
leaf-fits-1  | 28-Sep-2022 23:31:10.524 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.config.file=/opt/tomcat/conf/logging.properties
leaf-fits-1  | 28-Sep-2022 23:31:10.524 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager
leaf-fits-1  | 28-Sep-2022 23:31:10.524 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djdk.tls.ephemeralDHKeySize=2048
leaf-fits-1  | 28-Sep-2022 23:31:10.524 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.protocol.handler.pkgs=org.apache.catalina.webresources
leaf-fits-1  | 28-Sep-2022 23:31:10.524 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dorg.apache.catalina.security.SecurityListener.UMASK=0027
leaf-fits-1  | 28-Sep-2022 23:31:10.524 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djna.boot.library.path=/usr/lib
leaf-fits-1  | 28-Sep-2022 23:31:10.524 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djna.nosys=false
leaf-fits-1  | 28-Sep-2022 23:31:10.525 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dignore.endorsed.dirs=
leaf-fits-1  | 28-Sep-2022 23:31:10.525 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.base=/opt/tomcat
leaf-fits-1  | 28-Sep-2022 23:31:10.525 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.home=/opt/tomcat
leaf-fits-1  | 28-Sep-2022 23:31:10.525 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.io.tmpdir=/opt/tomcat/temp
leaf-fits-1  | 28-Sep-2022 23:31:10.526 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent The Apache Tomcat Native library which allows using OpenSSL was not found on the java.library.path: [/usr/lib/jvm/java-1.8-openjdk/jre/lib/amd64/server:/usr/lib/jvm/java-1.8-openjdk/jre/lib/amd64:/usr/lib/jvm/java-1.8-openjdk/jre/../lib/amd64:/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib]
leaf-fits-1  | 28-Sep-2022 23:31:10.890 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-8080"]
leaf-fits-1  | 28-Sep-2022 23:31:10.975 INFO [main] org.apache.catalina.startup.Catalina.load Server initialization in [739] milliseconds
leaf-fits-1  | 28-Sep-2022 23:31:11.045 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Catalina]
leaf-fits-1  | 28-Sep-2022 23:31:11.045 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet engine: [Apache Tomcat/9.0.58]
leaf-fits-1  | 28-Sep-2022 23:31:11.051 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [/opt/tomcat/webapps/host-manager]
leaf-fits-1  | 28-Sep-2022 23:31:11.293 INFO [main] org.apache.jasper.servlet.TldScanner.scanJars 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.
leaf-fits-1  | 28-Sep-2022 23:31:11.317 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/opt/tomcat/webapps/host-manager] has finished in [265] ms
leaf-fits-1  | 28-Sep-2022 23:31:11.317 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [/opt/tomcat/webapps/ROOT]
leaf-fits-1  | 28-Sep-2022 23:31:15.796 INFO [main] org.apache.jasper.servlet.TldScanner.scanJars 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.
leaf-fits-1  | 28-Sep-2022 23:31:15.798 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/opt/tomcat/webapps/ROOT] has finished in [4,481] ms
leaf-fits-1  | 28-Sep-2022 23:31:15.798 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [/opt/tomcat/webapps/manager]
leaf-fits-1  | 28-Sep-2022 23:31:15.884 INFO [main] org.apache.jasper.servlet.TldScanner.scanJars 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.
leaf-fits-1  | 28-Sep-2022 23:31:15.885 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/opt/tomcat/webapps/manager] has finished in [87] ms
leaf-fits-1  | 28-Sep-2022 23:31:15.885 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [/opt/tomcat/webapps/fits]
leaf-fits-1  | 28-Sep-2022 23:31:20.163 INFO [main] org.apache.jasper.servlet.TldScanner.scanJars 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.
leaf-fits-1  | Attempting to set up log4j logging...
leaf-fits-1  | No system property for [log4j2.configurationFile] -- using default log4j2.xml within WAR file
leaf-fits-1  | Look for log4j2.xml properties file in WAR here: file:/opt/tomcat/webapps/fits/WEB-INF/classes/log4j2.xml
leaf-fits-1  | 2022-09-28 23:31:20,785 main ERROR FileManager (../logs/fits-service.log) java.io.IOException: Could not create directory /run/s6/services/logs java.io.IOException: Could not create directory /run/s6/services/logs
leaf-fits-1  | 	at org.apache.logging.log4j.core.util.FileUtils.mkdir(FileUtils.java:120)
leaf-fits-1  | 	at org.apache.logging.log4j.core.util.FileUtils.makeParentDirs(FileUtils.java:137)
leaf-fits-1  | 	at org.apache.logging.log4j.core.appender.FileManager$FileManagerFactory.createManager(FileManager.java:434)
leaf-fits-1  | 	at org.apache.logging.log4j.core.appender.FileManager$FileManagerFactory.createManager(FileManager.java:422)
leaf-fits-1  | 	at org.apache.logging.log4j.core.appender.AbstractManager.getManager(AbstractManager.java:114)
leaf-fits-1  | 	at org.apache.logging.log4j.core.appender.OutputStreamManager.getManager(OutputStreamManager.java:100)
leaf-fits-1  | 	at org.apache.logging.log4j.core.appender.FileManager.getFileManager(FileManager.java:182)
leaf-fits-1  | 	at org.apache.logging.log4j.core.appender.FileAppender$Builder.build(FileAppender.java:96)
leaf-fits-1  | 	at org.apache.logging.log4j.core.appender.FileAppender$Builder.build(FileAppender.java:52)
leaf-fits-1  | 	at org.apache.logging.log4j.core.config.plugins.util.PluginBuilder.build(PluginBuilder.java:122)
leaf-fits-1  | 	at org.apache.logging.log4j.core.config.AbstractConfiguration.createPluginObject(AbstractConfiguration.java:1120)
leaf-fits-1  | 	at org.apache.logging.log4j.core.config.AbstractConfiguration.createConfiguration(AbstractConfiguration.java:1045)
leaf-fits-1  | 	at org.apache.logging.log4j.core.config.AbstractConfiguration.createConfiguration(AbstractConfiguration.java:1037)
leaf-fits-1  | 	at org.apache.logging.log4j.core.config.AbstractConfiguration.doConfigure(AbstractConfiguration.java:651)
leaf-fits-1  | 	at org.apache.logging.log4j.core.config.AbstractConfiguration.initialize(AbstractConfiguration.java:247)
leaf-fits-1  | 	at org.apache.logging.log4j.core.config.AbstractConfiguration.start(AbstractConfiguration.java:293)
leaf-fits-1  | 	at org.apache.logging.log4j.core.LoggerContext.setConfiguration(LoggerContext.java:626)
leaf-fits-1  | 	at org.apache.logging.log4j.core.LoggerContext.reconfigure(LoggerContext.java:699)
leaf-fits-1  | 	at org.apache.logging.log4j.core.LoggerContext.reconfigure(LoggerContext.java:716)
leaf-fits-1  | 	at org.apache.logging.log4j.core.LoggerContext.start(LoggerContext.java:270)
leaf-fits-1  | 	at org.apache.logging.log4j.core.impl.Log4jContextFactory.getContext(Log4jContextFactory.java:155)
leaf-fits-1  | 	at org.apache.logging.log4j.core.impl.Log4jContextFactory.getContext(Log4jContextFactory.java:47)
leaf-fits-1  | 	at org.apache.logging.log4j.LogManager.getContext(LogManager.java:196)
leaf-fits-1  | 	at org.apache.logging.log4j.LogManager.getLogger(LogManager.java:599)
leaf-fits-1  | 	at edu.harvard.hul.ois.fits.service.servlets.FitsServlet.<clinit>(FitsServlet.java:78)
leaf-fits-1  | 	at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
leaf-fits-1  | 	at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
leaf-fits-1  | 	at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
leaf-fits-1  | 	at java.lang.reflect.Constructor.newInstance(Constructor.java:423)
leaf-fits-1  | 	at org.apache.catalina.core.DefaultInstanceManager.newInstance(DefaultInstanceManager.java:150)
leaf-fits-1  | 	at org.apache.catalina.core.StandardWrapper.loadServlet(StandardWrapper.java:1070)
leaf-fits-1  | 	at org.apache.catalina.core.StandardWrapper.load(StandardWrapper.java:1010)
leaf-fits-1  | 	at org.apache.catalina.core.StandardContext.loadOnStartup(StandardContext.java:4957)
leaf-fits-1  | 	at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5264)
leaf-fits-1  | 	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
leaf-fits-1  | 	at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:726)
leaf-fits-1  | 	at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:698)
leaf-fits-1  | 	at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:696)
leaf-fits-1  | 	at org.apache.catalina.startup.HostConfig.deployDirectory(HostConfig.java:1185)
leaf-fits-1  | 	at org.apache.catalina.startup.HostConfig$DeployDirectory.run(HostConfig.java:1933)
leaf-fits-1  | 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
leaf-fits-1  | 	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
leaf-fits-1  | 	at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75)
leaf-fits-1  | 	at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:112)
leaf-fits-1  | 	at org.apache.catalina.startup.HostConfig.deployDirectories(HostConfig.java:1095)
leaf-fits-1  | 	at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:477)
leaf-fits-1  | 	at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1618)
leaf-fits-1  | 	at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:319)
leaf-fits-1  | 	at org.apache.catalina.util.LifecycleBase.fireLifecycleEvent(LifecycleBase.java:123)
leaf-fits-1  | 	at org.apache.catalina.util.LifecycleBase.setStateInternal(LifecycleBase.java:423)
leaf-fits-1  | 	at org.apache.catalina.util.LifecycleBase.setState(LifecycleBase.java:366)
leaf-fits-1  | 	at org.apache.catalina.core.ContainerBase.startInternal(ContainerBase.java:946)
leaf-fits-1  | 	at org.apache.catalina.core.StandardHost.startInternal(StandardHost.java:835)
leaf-fits-1  | 	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
leaf-fits-1  | 	at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1396)
leaf-fits-1  | 	at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1386)
leaf-fits-1  | 	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
leaf-fits-1  | 	at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75)
leaf-fits-1  | 	at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:134)
leaf-fits-1  | 	at org.apache.catalina.core.ContainerBase.startInternal(ContainerBase.java:919)
leaf-fits-1  | 	at org.apache.catalina.core.StandardEngine.startInternal(StandardEngine.java:263)
leaf-fits-1  | 	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
leaf-fits-1  | 	at org.apache.catalina.core.StandardService.startInternal(StandardService.java:432)
leaf-fits-1  | 	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
leaf-fits-1  | 	at org.apache.catalina.core.StandardServer.startInternal(StandardServer.java:927)
leaf-fits-1  | 	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
leaf-fits-1  | 	at org.apache.catalina.startup.Catalina.start(Catalina.java:772)
leaf-fits-1  | 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
leaf-fits-1  | 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
leaf-fits-1  | 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
leaf-fits-1  | 	at java.lang.reflect.Method.invoke(Method.java:498)
leaf-fits-1  | 	at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:345)
leaf-fits-1  | 	at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:476)
leaf-fits-1  | 2022-09-28 23:31:20,849 main ERROR Could not create plugin of type class org.apache.logging.log4j.core.appender.FileAppender for element File: java.lang.IllegalStateException: ManagerFactory [org.apache.logging.log4j.core.appender.FileManager$FileManagerFactory@7b3489b7] unable to create manager for [../logs/fits-service.log] with data [org.apache.logging.log4j.core.appender.FileManager$FactoryData@4df3b838] java.lang.IllegalStateException: ManagerFactory [org.apache.logging.log4j.core.appender.FileManager$FileManagerFactory@7b3489b7] unable to create manager for [../logs/fits-service.log] with data [org.apache.logging.log4j.core.appender.FileManager$FactoryData@4df3b838]
leaf-fits-1  | 	at org.apache.logging.log4j.core.appender.AbstractManager.getManager(AbstractManager.java:116)
leaf-fits-1  | 	at org.apache.logging.log4j.core.appender.OutputStreamManager.getManager(OutputStreamManager.java:100)
leaf-fits-1  | 	at org.apache.logging.log4j.core.appender.FileManager.getFileManager(FileManager.java:182)
leaf-fits-1  | 	at org.apache.logging.log4j.core.appender.FileAppender$Builder.build(FileAppender.java:96)
leaf-fits-1  | 	at org.apache.logging.log4j.core.appender.FileAppender$Builder.build(FileAppender.java:52)
leaf-fits-1  | 	at org.apache.logging.log4j.core.config.plugins.util.PluginBuilder.build(PluginBuilder.java:122)
leaf-fits-1  | 	at org.apache.logging.log4j.core.config.AbstractConfiguration.createPluginObject(AbstractConfiguration.java:1120)
leaf-fits-1  | 	at org.apache.logging.log4j.core.config.AbstractConfiguration.createConfiguration(AbstractConfiguration.java:1045)
leaf-fits-1  | 	at org.apache.logging.log4j.core.config.AbstractConfiguration.createConfiguration(AbstractConfiguration.java:1037)
leaf-fits-1  | 	at org.apache.logging.log4j.core.config.AbstractConfiguration.doConfigure(AbstractConfiguration.java:651)
leaf-fits-1  | 	at org.apache.logging.log4j.core.config.AbstractConfiguration.initialize(AbstractConfiguration.java:247)
leaf-fits-1  | 	at org.apache.logging.log4j.core.config.AbstractConfiguration.start(AbstractConfiguration.java:293)
leaf-fits-1  | 	at org.apache.logging.log4j.core.LoggerContext.setConfiguration(LoggerContext.java:626)
leaf-fits-1  | 	at org.apache.logging.log4j.core.LoggerContext.reconfigure(LoggerContext.java:699)
leaf-fits-1  | 	at org.apache.logging.log4j.core.LoggerContext.reconfigure(LoggerContext.java:716)
leaf-fits-1  | 	at org.apache.logging.log4j.core.LoggerContext.start(LoggerContext.java:270)
leaf-fits-1  | 	at org.apache.logging.log4j.core.impl.Log4jContextFactory.getContext(Log4jContextFactory.java:155)
leaf-fits-1  | 	at org.apache.logging.log4j.core.impl.Log4jContextFactory.getContext(Log4jContextFactory.java:47)
leaf-fits-1  | 	at org.apache.logging.log4j.LogManager.getContext(LogManager.java:196)
leaf-fits-1  | 	at org.apache.logging.log4j.LogManager.getLogger(LogManager.java:599)
leaf-fits-1  | 	at edu.harvard.hul.ois.fits.service.servlets.FitsServlet.<clinit>(FitsServlet.java:78)
leaf-fits-1  | 	at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
leaf-fits-1  | 	at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
leaf-fits-1  | 	at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
leaf-fits-1  | 	at java.lang.reflect.Constructor.newInstance(Constructor.java:423)
leaf-fits-1  | 	at org.apache.catalina.core.DefaultInstanceManager.newInstance(DefaultInstanceManager.java:150)
leaf-fits-1  | 	at org.apache.catalina.core.StandardWrapper.loadServlet(StandardWrapper.java:1070)
leaf-fits-1  | 	at org.apache.catalina.core.StandardWrapper.load(StandardWrapper.java:1010)
leaf-fits-1  | 	at org.apache.catalina.core.StandardContext.loadOnStartup(StandardContext.java:4957)
leaf-fits-1  | 	at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5264)
leaf-fits-1  | 	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
leaf-fits-1  | 	at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:726)
leaf-fits-1  | 	at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:698)
leaf-fits-1  | 	at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:696)
leaf-fits-1  | 	at org.apache.catalina.startup.HostConfig.deployDirectory(HostConfig.java:1185)
leaf-fits-1  | 	at org.apache.catalina.startup.HostConfig$DeployDirectory.run(HostConfig.java:1933)
leaf-fits-1  | 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
leaf-fits-1  | 	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
leaf-fits-1  | 	at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75)
leaf-fits-1  | 	at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:112)
leaf-fits-1  | 	at org.apache.catalina.startup.HostConfig.deployDirectories(HostConfig.java:1095)
leaf-fits-1  | 	at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:477)
leaf-fits-1  | 	at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1618)
leaf-fits-1  | 	at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:319)
leaf-fits-1  | 	at org.apache.catalina.util.LifecycleBase.fireLifecycleEvent(LifecycleBase.java:123)
leaf-fits-1  | 	at org.apache.catalina.util.LifecycleBase.setStateInternal(LifecycleBase.java:423)
leaf-fits-1  | 	at org.apache.catalina.util.LifecycleBase.setState(LifecycleBase.java:366)
leaf-fits-1  | 	at org.apache.catalina.core.ContainerBase.startInternal(ContainerBase.java:946)
leaf-fits-1  | 	at org.apache.catalina.core.StandardHost.startInternal(StandardHost.java:835)
leaf-fits-1  | 	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
leaf-fits-1  | 	at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1396)
leaf-fits-1  | 	at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1386)
leaf-fits-1  | 	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
leaf-fits-1  | 	at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75)
leaf-fits-1  | 	at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:134)
leaf-fits-1  | 	at org.apache.catalina.core.ContainerBase.startInternal(ContainerBase.java:919)
leaf-fits-1  | 	at org.apache.catalina.core.StandardEngine.startInternal(StandardEngine.java:263)
leaf-fits-1  | 	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
leaf-fits-1  | 	at org.apache.catalina.core.StandardService.startInternal(StandardService.java:432)
leaf-fits-1  | 	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
leaf-fits-1  | 	at org.apache.catalina.core.StandardServer.startInternal(StandardServer.java:927)
leaf-fits-1  | 	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
leaf-fits-1  | 	at org.apache.catalina.startup.Catalina.start(Catalina.java:772)
leaf-fits-1  | 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
leaf-fits-1  | 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
leaf-fits-1  | 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
leaf-fits-1  | 	at java.lang.reflect.Method.invoke(Method.java:498)
leaf-fits-1  | 	at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:345)
leaf-fits-1  | 	at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:476)
leaf-fits-1  | 2022-09-28 23:31:20,850 main ERROR Unable to invoke factory method in class org.apache.logging.log4j.core.appender.FileAppender for element File: java.lang.IllegalStateException: No factory method found for class org.apache.logging.log4j.core.appender.FileAppender java.lang.IllegalStateException: No factory method found for class org.apache.logging.log4j.core.appender.FileAppender
leaf-fits-1  | 	at org.apache.logging.log4j.core.config.plugins.util.PluginBuilder.findFactoryMethod(PluginBuilder.java:236)
leaf-fits-1  | 	at org.apache.logging.log4j.core.config.plugins.util.PluginBuilder.build(PluginBuilder.java:134)
leaf-fits-1  | 	at org.apache.logging.log4j.core.config.AbstractConfiguration.createPluginObject(AbstractConfiguration.java:1120)
leaf-fits-1  | 	at org.apache.logging.log4j.core.config.AbstractConfiguration.createConfiguration(AbstractConfiguration.java:1045)
leaf-fits-1  | 	at org.apache.logging.log4j.core.config.AbstractConfiguration.createConfiguration(AbstractConfiguration.java:1037)
leaf-fits-1  | 	at org.apache.logging.log4j.core.config.AbstractConfiguration.doConfigure(AbstractConfiguration.java:651)
leaf-fits-1  | 	at org.apache.logging.log4j.core.config.AbstractConfiguration.initialize(AbstractConfiguration.java:247)
leaf-fits-1  | 	at org.apache.logging.log4j.core.config.AbstractConfiguration.start(AbstractConfiguration.java:293)
leaf-fits-1  | 	at org.apache.logging.log4j.core.LoggerContext.setConfiguration(LoggerContext.java:626)
leaf-fits-1  | 	at org.apache.logging.log4j.core.LoggerContext.reconfigure(LoggerContext.java:699)
leaf-fits-1  | 	at org.apache.logging.log4j.core.LoggerContext.reconfigure(LoggerContext.java:716)
leaf-fits-1  | 	at org.apache.logging.log4j.core.LoggerContext.start(LoggerContext.java:270)
leaf-fits-1  | 	at org.apache.logging.log4j.core.impl.Log4jContextFactory.getContext(Log4jContextFactory.java:155)
leaf-fits-1  | 	at org.apache.logging.log4j.core.impl.Log4jContextFactory.getContext(Log4jContextFactory.java:47)
leaf-fits-1  | 	at org.apache.logging.log4j.LogManager.getContext(LogManager.java:196)
leaf-fits-1  | 	at org.apache.logging.log4j.LogManager.getLogger(LogManager.java:599)
leaf-fits-1  | 	at edu.harvard.hul.ois.fits.service.servlets.FitsServlet.<clinit>(FitsServlet.java:78)
leaf-fits-1  | 	at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
leaf-fits-1  | 	at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
leaf-fits-1  | 	at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
leaf-fits-1  | 	at java.lang.reflect.Constructor.newInstance(Constructor.java:423)
leaf-fits-1  | 	at org.apache.catalina.core.DefaultInstanceManager.newInstance(DefaultInstanceManager.java:150)
leaf-fits-1  | 	at org.apache.catalina.core.StandardWrapper.loadServlet(StandardWrapper.java:1070)
leaf-fits-1  | 	at org.apache.catalina.core.StandardWrapper.load(StandardWrapper.java:1010)
leaf-fits-1  | 	at org.apache.catalina.core.StandardContext.loadOnStartup(StandardContext.java:4957)
leaf-fits-1  | 	at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5264)
leaf-fits-1  | 	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
leaf-fits-1  | 	at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:726)
leaf-fits-1  | 	at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:698)
leaf-fits-1  | 	at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:696)
leaf-fits-1  | 	at org.apache.catalina.startup.HostConfig.deployDirectory(HostConfig.java:1185)
leaf-fits-1  | 	at org.apache.catalina.startup.HostConfig$DeployDirectory.run(HostConfig.java:1933)
leaf-fits-1  | 	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
leaf-fits-1  | 	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
leaf-fits-1  | 	at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75)
leaf-fits-1  | 	at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:112)
leaf-fits-1  | 	at org.apache.catalina.startup.HostConfig.deployDirectories(HostConfig.java:1095)
leaf-fits-1  | 	at org.apache.catalina.startup.HostConfig.deployApps(HostConfig.java:477)
leaf-fits-1  | 	at org.apache.catalina.startup.HostConfig.start(HostConfig.java:1618)
leaf-fits-1  | 	at org.apache.catalina.startup.HostConfig.lifecycleEvent(HostConfig.java:319)
leaf-fits-1  | 	at org.apache.catalina.util.LifecycleBase.fireLifecycleEvent(LifecycleBase.java:123)
leaf-fits-1  | 	at org.apache.catalina.util.LifecycleBase.setStateInternal(LifecycleBase.java:423)
leaf-fits-1  | 	at org.apache.catalina.util.LifecycleBase.setState(LifecycleBase.java:366)
leaf-fits-1  | 	at org.apache.catalina.core.ContainerBase.startInternal(ContainerBase.java:946)
leaf-fits-1  | 	at org.apache.catalina.core.StandardHost.startInternal(StandardHost.java:835)
leaf-fits-1  | 	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
leaf-fits-1  | 	at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1396)
leaf-fits-1  | 	at org.apache.catalina.core.ContainerBase$StartChild.call(ContainerBase.java:1386)
leaf-fits-1  | 	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
leaf-fits-1  | 	at org.apache.tomcat.util.threads.InlineExecutorService.execute(InlineExecutorService.java:75)
leaf-fits-1  | 	at java.util.concurrent.AbstractExecutorService.submit(AbstractExecutorService.java:134)
leaf-fits-1  | 	at org.apache.catalina.core.ContainerBase.startInternal(ContainerBase.java:919)
leaf-fits-1  | 	at org.apache.catalina.core.StandardEngine.startInternal(StandardEngine.java:263)
leaf-fits-1  | 	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
leaf-fits-1  | 	at org.apache.catalina.core.StandardService.startInternal(StandardService.java:432)
leaf-fits-1  | 	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
leaf-fits-1  | 	at org.apache.catalina.core.StandardServer.startInternal(StandardServer.java:927)
leaf-fits-1  | 	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:183)
leaf-fits-1  | 	at org.apache.catalina.startup.Catalina.start(Catalina.java:772)
leaf-fits-1  | 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
leaf-fits-1  | 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
leaf-fits-1  | 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
leaf-fits-1  | 	at java.lang.reflect.Method.invoke(Method.java:498)
leaf-fits-1  | 	at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:345)
leaf-fits-1  | 	at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:476)
leaf-fits-1  | 2022-09-28 23:31:20,852 main ERROR Null object returned for File in Appenders.
leaf-fits-1  | 2022-09-28 23:31:20,859 main ERROR Unable to locate appender "FILEOUT" for logger config "root"
leaf-fits-1  | 2022-09-28 23:31:20,872 INFO  - (FitsServlet:93) - fits.home: /opt/fits
leaf-fits-1  | 2022-09-28 23:31:20,879 INFO  - (FitsServlet:105) - Value of environment property: [FITS_SERVICE_PROPS] for finding external properties file in location: [/opt/tomcat/conf/fits-service.properties]
leaf-fits-1  | 2022-09-28 23:31:20,879 INFO  - (FitsServlet:107) - Will look for properties file from environment in location: [/opt/tomcat/conf/fits-service.properties]
leaf-fits-1  | 2022-09-28 23:31:20,879 INFO  - (FitsServlet:144) - Max objects in object pool: 5 -- Max file upload size: 2000MB -- Max request object size: 2000MB -- Max in-memory file size: 4MB
leaf-fits-1  | 2022-09-28 23:31:20,887 INFO  - (FitsServlet:163) - Created upload base directory: /opt/tomcat/webapps/fits/upload
leaf-fits-1  | 28-Sep-2022 23:31:20.889 INFO [main] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/opt/tomcat/webapps/fits] has finished in [5,004] ms
leaf-fits-1  | 28-Sep-2022 23:31:20.891 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-8080"]
leaf-fits-1  | 28-Sep-2022 23:31:20.898 INFO [main] org.apache.catalina.startup.Catalina.start Server startup in [9923] milliseconds
leaf-fits-1  | 2022-09-28 23:40:50,263 INFO  - (FitsWrapper:37) - FITS HOME: /opt/fits
leaf-fits-1  | 2022-09-28 23:40:50,263 INFO  - (FitsWrapper:37) - FITS HOME: /opt/fits
leaf-fits-1  | log4j:ERROR setFile(null,true) call failed.
leaf-fits-1  | java.io.FileNotFoundException: ./fits.log (Permission denied)
leaf-fits-1  | 	at java.io.FileOutputStream.open0(Native Method)
leaf-fits-1  | 	at java.io.FileOutputStream.open(FileOutputStream.java:270)
leaf-fits-1  | 	at java.io.FileOutputStream.<init>(FileOutputStream.java:213)
leaf-fits-1  | 	at java.io.FileOutputStream.<init>(FileOutputStream.java:133)
leaf-fits-1  | 	at org.apache.log4j.FileAppender.setFile(FileAppender.java:282)
leaf-fits-1  | 	at org.apache.log4j.RollingFileAppender.setFile(RollingFileAppender.java:200)
leaf-fits-1  | 	at org.apache.log4j.FileAppender.activateOptions(FileAppender.java:166)
leaf-fits-1  | 	at org.apache.log4j.config.PropertySetter.activate(PropertySetter.java:284)
leaf-fits-1  | 	at org.apache.log4j.config.PropertySetter.setProperties(PropertySetter.java:160)
leaf-fits-1  | 	at org.apache.log4j.config.PropertySetter.setProperties(PropertySetter.java:100)
leaf-fits-1  | 	at org.apache.log4j.PropertyConfigurator.parseAppender(PropertyConfigurator.java:802)
leaf-fits-1  | 	at org.apache.log4j.PropertyConfigurator.parseCategory(PropertyConfigurator.java:738)
leaf-fits-1  | 	at org.apache.log4j.PropertyConfigurator.configureRootCategory(PropertyConfigurator.java:630)
leaf-fits-1  | 	at org.apache.log4j.PropertyConfigurator.doConfigure(PropertyConfigurator.java:516)
leaf-fits-1  | 	at org.apache.log4j.PropertyConfigurator.doConfigure(PropertyConfigurator.java:577)
leaf-fits-1  | 	at org.apache.log4j.helpers.OptionConverter.selectAndConfigure(OptionConverter.java:504)
leaf-fits-1  | 	at org.apache.log4j.LogManager.<clinit>(LogManager.java:119)
leaf-fits-1  | 	at org.apache.log4j.Logger.getLogger(Logger.java:112)
leaf-fits-1  | 	at edu.harvard.hul.ois.fits.Fits.<init>(Fits.java:193)
leaf-fits-1  | 	at edu.harvard.hul.ois.fits.Fits.<init>(Fits.java:124)
leaf-fits-1  | 	at edu.harvard.hul.ois.fits.service.pool.FitsWrapper.<init>(FitsWrapper.java:46)
leaf-fits-1  | 	at edu.harvard.hul.ois.fits.service.pool.FitsWrapperFactory.create(FitsWrapperFactory.java:26)
leaf-fits-1  | 	at edu.harvard.hul.ois.fits.service.pool.FitsWrapperFactory.create(FitsWrapperFactory.java:19)
leaf-fits-1  | 	at org.apache.commons.pool2.BasePooledObjectFactory.makeObject(BasePooledObjectFactory.java:60)
leaf-fits-1  | 	at org.apache.commons.pool2.impl.GenericObjectPool.create(GenericObjectPool.java:868)
leaf-fits-1  | 	at org.apache.commons.pool2.impl.GenericObjectPool.borrowObject(GenericObjectPool.java:435)
leaf-fits-1  | 	at org.apache.commons.pool2.impl.GenericObjectPool.borrowObject(GenericObjectPool.java:363)
leaf-fits-1  | 	at edu.harvard.hul.ois.fits.service.servlets.FitsServlet.sendFitsExamineResponse(FitsServlet.java:366)
leaf-fits-1  | 	at edu.harvard.hul.ois.fits.service.servlets.FitsServlet.doPost(FitsServlet.java:300)
leaf-fits-1  | 	at javax.servlet.http.HttpServlet.service(HttpServlet.java:681)
leaf-fits-1  | 	at javax.servlet.http.HttpServlet.service(HttpServlet.java:764)
leaf-fits-1  | 	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:227)
leaf-fits-1  | 	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:162)
leaf-fits-1  | 	at org.apache.tomcat.websocket.server.WsFilter.doFilter(WsFilter.java:53)
leaf-fits-1  | 	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:189)
leaf-fits-1  | 	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:162)
leaf-fits-1  | 	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:197)
leaf-fits-1  | 	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:97)
leaf-fits-1  | 	at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:540)
leaf-fits-1  | 	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:135)
leaf-fits-1  | 	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:92)
leaf-fits-1  | 	at org.apache.catalina.valves.RemoteIpValve.invoke(RemoteIpValve.java:769)
leaf-fits-1  | 	at org.apache.catalina.valves.AbstractAccessLogValve.invoke(AbstractAccessLogValve.java:687)
leaf-fits-1  | 	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:78)
leaf-fits-1  | 	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:359)
leaf-fits-1  | 	at org.apache.coyote.http11.Http11Processor.service(Http11Processor.java:399)
leaf-fits-1  | 	at org.apache.coyote.AbstractProcessorLight.process(AbstractProcessorLight.java:65)
leaf-fits-1  | 	at org.apache.coyote.AbstractProtocol$ConnectionHandler.process(AbstractProtocol.java:889)
leaf-fits-1  | 	at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.doRun(NioEndpoint.java:1735)
leaf-fits-1  | 	at org.apache.tomcat.util.net.SocketProcessorBase.run(SocketProcessorBase.java:49)
leaf-fits-1  | 	at org.apache.tomcat.util.threads.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1191)
leaf-fits-1  | 	at org.apache.tomcat.util.threads.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:659)
leaf-fits-1  | 	at org.apache.tomcat.util.threads.TaskThread$WrappingRunnable.run(TaskThread.java:61)
leaf-fits-1  | 	at java.lang.Thread.run(Thread.java:750)
leaf-fits-1  | 2022-09-28 23:40:54,942 WARN  - (FitsServlet:314) - file_example_MP3_700KB.mp3 could not be deleted!
leaf-fits-1  | 2022-09-28 23:40:54,942 WARN  - (FitsServlet:319) - 1664408450260 could not be deleted!
leaf-fits-1  | 172.28.0.5 - - [28/Sep/2022:23:40:54 +0000] "POST /fits/examine HTTP/1.1" 200 6556
leaf-fits-1  | 172.28.0.5 - - [28/Sep/2022:23:40:54 +0000] "POST /fits/examine HTTP/1.1" 200 6556
leaf-fits-1  | 172.28.0.5 - - [28/Sep/2022:23:52:35 +0000] "POST /fits/examine HTTP/1.1" 200 6559
leaf-fits-1  | 172.28.0.5 - - [28/Sep/2022:23:52:35 +0000] "POST /fits/examine HTTP/1.1" 200 6558
@nigelgbanks nigelgbanks added the bug Something isn't working label Sep 29, 2022
@nigelgbanks nigelgbanks self-assigned this Sep 29, 2022
@nigelgbanks
Copy link
Contributor Author

Should note that fits is working still, just the logs are full of stack traces at the beginning.

@seth-shaw-asu
Copy link
Contributor

@wgilling brought this up in the Tech Call today. I mentioned my fix for the issue (asulibraries@686cfcc).

@nigelgbanks
Copy link
Contributor Author

@seth-shaw-asu cheers, I'll cherry pick your change back into this repo.

@nigelgbanks
Copy link
Contributor Author

Fixed by #251

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees

@nigelgbanks nigelgbanks

Labels
bug Something isn't working
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
2 participants
@nigelgbanks @seth-shaw-asu