Startup errors and unreliable behavior

We tried to make OpenKM as intuitive as possible, but an advice is always welcome.
Forum rules
Please, before asking something see the documentation wiki or use the search feature of the forum. And remember we don't have a crystal ball or mental readers, so if you post about an issue tell us which OpenKM are you using and also the browser and operating system version. For more info read How to Report Bugs Effectively.
Post Reply
dferguson
Expert Boarder
Expert Boarder
Posts: 86
Joined: Thu May 18, 2017 12:48 pm

Startup errors and unreliable behavior

Post by dferguson » Tue Aug 22, 2017 4:11 pm

I deployed a version of 6.3 ce on a VM of Ubuntu 16.04 and everything went fine. I deployed the final version on a Lenovo desktop running Ubuntu 16.04 following the same directions and have been getting unreliable behavior. Notably, some documents will preview fine, but other won't. Lately, I am not able to Update and edited document when viewed in Metadata view. I can only update when trying to update from the Taxonomy view.

I have cleared the logs and rebooted the machine and I am seeing what looks like lots of error in the startup.

Catalina.log

Code: Select all

Aug 22, 2017 11:44:12 AM org.apache.catalina.core.AprLifecycleListener lifecycleEvent
INFO: The APR based Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: :/home/openkm/tomcat-7.0.61/lib/sigar:/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib
Aug 22, 2017 11:44:12 AM org.apache.catalina.core.AprLifecycleListener lifecycleEvent
INFO: The APR based Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: :/home/openkm/tomcat-7.0.61/lib/sigar:/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib
Aug 22, 2017 11:44:12 AM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-bio-0.0.0.0-8080"]
Aug 22, 2017 11:44:13 AM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["ajp-bio-127.0.0.1-8009"]
Aug 22, 2017 11:44:13 AM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 525 ms
DEBUG: JavaMail version 1.5.2
DEBUG: successfully loaded resource: /META-INF/javamail.default.providers
DEBUG: Tables of loaded providers
DEBUG: Providers Listed By Class Name: {com.sun.mail.smtp.SMTPSSLTransport=javax.mail.Provider[TRANSPORT,smtps,com.sun.mail.smtp.SMTPSSLTransport,Oracle], com.sun.mail.smtp.SMTPTransport=javax.mail.Provider[TRANSPORT,smtp,com.sun.mail.smtp.SMTPTransport,Oracle], com.sun.mail.imap.IMAPSSLStore=javax.mail.Provider[STORE,imaps,com.sun.mail.imap.IMAPSSLStore,Oracle], com.sun.mail.pop3.POP3SSLStore=javax.mail.Provider[STORE,pop3s,com.sun.mail.pop3.POP3SSLStore,Oracle], com.sun.mail.imap.IMAPStore=javax.mail.Provider[STORE,imap,com.sun.mail.imap.IMAPStore,Oracle], com.sun.mail.pop3.POP3Store=javax.mail.Provider[STORE,pop3,com.sun.mail.pop3.POP3Store,Oracle]}
DEBUG: Providers Listed By Protocol: {imaps=javax.mail.Provider[STORE,imaps,com.sun.mail.imap.IMAPSSLStore,Oracle], imap=javax.mail.Provider[STORE,imap,com.sun.mail.imap.IMAPStore,Oracle], smtps=javax.mail.Provider[TRANSPORT,smtps,com.sun.mail.smtp.SMTPSSLTransport,Oracle], pop3=javax.mail.Provider[STORE,pop3,com.sun.mail.pop3.POP3Store,Oracle], pop3s=javax.mail.Provider[STORE,pop3s,com.sun.mail.pop3.POP3SSLStore,Oracle], smtp=javax.mail.Provider[TRANSPORT,smtp,com.sun.mail.smtp.SMTPTransport,Oracle]}
DEBUG: successfully loaded resource: /META-INF/javamail.default.address.map
Aug 22, 2017 11:44:13 AM org.apache.catalina.core.StandardService startInternal
INFO: Starting service Catalina
Aug 22, 2017 11:44:13 AM org.apache.catalina.core.StandardEngine startInternal
INFO: Starting Servlet Engine: Apache Tomcat/7.0.61
Aug 22, 2017 11:44:13 AM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /home/openkm/tomcat-7.0.61/webapps/OpenKM.war
Aug 22, 2017 11:44:13 AM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["http-bio-0.0.0.0-8080"]
Aug 22, 2017 11:44:13 AM org.apache.coyote.AbstractProtocol init
SEVERE: Failed to initialize end point associated with ProtocolHandler ["http-bio-0.0.0.0-8080"]
java.net.BindException: Address already in use (Bind failed) /0.0.0.0:8080
	at org.apache.tomcat.util.net.JIoEndpoint.bind(JIoEndpoint.java:413)
	at org.apache.tomcat.util.net.AbstractEndpoint.init(AbstractEndpoint.java:649)
	at org.apache.coyote.AbstractProtocol.init(AbstractProtocol.java:434)
	at org.apache.coyote.http11.AbstractHttp11JsseProtocol.init(AbstractHttp11JsseProtocol.java:119)
	at org.apache.catalina.connector.Connector.initInternal(Connector.java:978)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:102)
	at org.apache.catalina.core.StandardService.initInternal(StandardService.java:559)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:102)
	at org.apache.catalina.core.StandardServer.initInternal(StandardServer.java:821)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:102)
	at org.apache.catalina.startup.Catalina.load(Catalina.java:638)
	at org.apache.catalina.startup.Catalina.load(Catalina.java:663)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at org.apache.catalina.startup.Bootstrap.load(Bootstrap.java:280)
	at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:454)
Caused by: java.net.BindException: Address already in use (Bind failed)
	at java.net.PlainSocketImpl.socketBind(Native Method)
	at java.net.AbstractPlainSocketImpl.bind(AbstractPlainSocketImpl.java:387)
	at java.net.ServerSocket.bind(ServerSocket.java:375)
	at java.net.ServerSocket.<init>(ServerSocket.java:237)
	at org.apache.tomcat.util.net.DefaultServerSocketFactory.createSocket(DefaultServerSocketFactory.java:55)
	at org.apache.tomcat.util.net.JIoEndpoint.bind(JIoEndpoint.java:403)
	... 17 more

Aug 22, 2017 11:44:13 AM org.apache.catalina.core.StandardService initInternal
SEVERE: Failed to initialize connector [Connector[HTTP/1.1-8080]]
org.apache.catalina.LifecycleException: Failed to initialize component [Connector[HTTP/1.1-8080]]
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:106)
	at org.apache.catalina.core.StandardService.initInternal(StandardService.java:559)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:102)
	at org.apache.catalina.core.StandardServer.initInternal(StandardServer.java:821)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:102)
	at org.apache.catalina.startup.Catalina.load(Catalina.java:638)
	at org.apache.catalina.startup.Catalina.load(Catalina.java:663)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at org.apache.catalina.startup.Bootstrap.load(Bootstrap.java:280)
	at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:454)
Caused by: org.apache.catalina.LifecycleException: Protocol handler initialization failed
	at org.apache.catalina.connector.Connector.initInternal(Connector.java:980)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:102)
	... 12 more
Caused by: java.net.BindException: Address already in use (Bind failed) /0.0.0.0:8080
	at org.apache.tomcat.util.net.JIoEndpoint.bind(JIoEndpoint.java:413)
	at org.apache.tomcat.util.net.AbstractEndpoint.init(AbstractEndpoint.java:649)
	at org.apache.coyote.AbstractProtocol.init(AbstractProtocol.java:434)
	at org.apache.coyote.http11.AbstractHttp11JsseProtocol.init(AbstractHttp11JsseProtocol.java:119)
	at org.apache.catalina.connector.Connector.initInternal(Connector.java:978)
	... 13 more
Caused by: java.net.BindException: Address already in use (Bind failed)
	at java.net.PlainSocketImpl.socketBind(Native Method)
	at java.net.AbstractPlainSocketImpl.bind(AbstractPlainSocketImpl.java:387)
	at java.net.ServerSocket.bind(ServerSocket.java:375)
	at java.net.ServerSocket.<init>(ServerSocket.java:237)
	at org.apache.tomcat.util.net.DefaultServerSocketFactory.createSocket(DefaultServerSocketFactory.java:55)
	at org.apache.tomcat.util.net.JIoEndpoint.bind(JIoEndpoint.java:403)
	... 17 more

Aug 22, 2017 11:44:13 AM org.apache.coyote.AbstractProtocol init
INFO: Initializing ProtocolHandler ["ajp-bio-127.0.0.1-8009"]
Aug 22, 2017 11:44:13 AM org.apache.coyote.AbstractProtocol init
SEVERE: Failed to initialize end point associated with ProtocolHandler ["ajp-bio-127.0.0.1-8009"]
java.net.BindException: Address already in use (Bind failed) /127.0.0.1:8009
	at org.apache.tomcat.util.net.JIoEndpoint.bind(JIoEndpoint.java:413)
	at org.apache.tomcat.util.net.AbstractEndpoint.init(AbstractEndpoint.java:649)
	at org.apache.coyote.AbstractProtocol.init(AbstractProtocol.java:434)
	at org.apache.catalina.connector.Connector.initInternal(Connector.java:978)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:102)
	at org.apache.catalina.core.StandardService.initInternal(StandardService.java:559)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:102)
	at org.apache.catalina.core.StandardServer.initInternal(StandardServer.java:821)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:102)
	at org.apache.catalina.startup.Catalina.load(Catalina.java:638)
	at org.apache.catalina.startup.Catalina.load(Catalina.java:663)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at org.apache.catalina.startup.Bootstrap.load(Bootstrap.java:280)
	at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:454)
Caused by: java.net.BindException: Address already in use (Bind failed)
	at java.net.PlainSocketImpl.socketBind(Native Method)
	at java.net.AbstractPlainSocketImpl.bind(AbstractPlainSocketImpl.java:387)
	at java.net.ServerSocket.bind(ServerSocket.java:375)
	at java.net.ServerSocket.<init>(ServerSocket.java:237)
	at org.apache.tomcat.util.net.DefaultServerSocketFactory.createSocket(DefaultServerSocketFactory.java:55)
	at org.apache.tomcat.util.net.JIoEndpoint.bind(JIoEndpoint.java:403)
	... 16 more

Aug 22, 2017 11:44:13 AM org.apache.catalina.core.StandardService initInternal
SEVERE: Failed to initialize connector [Connector[AJP/1.3-8009]]
org.apache.catalina.LifecycleException: Failed to initialize component [Connector[AJP/1.3-8009]]
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:106)
	at org.apache.catalina.core.StandardService.initInternal(StandardService.java:559)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:102)
	at org.apache.catalina.core.StandardServer.initInternal(StandardServer.java:821)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:102)
	at org.apache.catalina.startup.Catalina.load(Catalina.java:638)
	at org.apache.catalina.startup.Catalina.load(Catalina.java:663)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at org.apache.catalina.startup.Bootstrap.load(Bootstrap.java:280)
	at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:454)
Caused by: org.apache.catalina.LifecycleException: Protocol handler initialization failed
	at org.apache.catalina.connector.Connector.initInternal(Connector.java:980)
	at org.apache.catalina.util.LifecycleBase.init(LifecycleBase.java:102)
	... 12 more
Caused by: java.net.BindException: Address already in use (Bind failed) /127.0.0.1:8009
	at org.apache.tomcat.util.net.JIoEndpoint.bind(JIoEndpoint.java:413)
	at org.apache.tomcat.util.net.AbstractEndpoint.init(AbstractEndpoint.java:649)
	at org.apache.coyote.AbstractProtocol.init(AbstractProtocol.java:434)
	at org.apache.catalina.connector.Connector.initInternal(Connector.java:978)
	... 13 more
Caused by: java.net.BindException: Address already in use (Bind failed)
	at java.net.PlainSocketImpl.socketBind(Native Method)
	at java.net.AbstractPlainSocketImpl.bind(AbstractPlainSocketImpl.java:387)
	at java.net.ServerSocket.bind(ServerSocket.java:375)
	at java.net.ServerSocket.<init>(ServerSocket.java:237)
	at org.apache.tomcat.util.net.DefaultServerSocketFactory.createSocket(DefaultServerSocketFactory.java:55)
	at org.apache.tomcat.util.net.JIoEndpoint.bind(JIoEndpoint.java:403)
	... 16 more

Aug 22, 2017 11:44:13 AM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 640 ms
DEBUG: JavaMail version 1.5.2
DEBUG: successfully loaded resource: /META-INF/javamail.default.providers
DEBUG: Tables of loaded providers
DEBUG: Providers Listed By Class Name: {com.sun.mail.smtp.SMTPSSLTransport=javax.mail.Provider[TRANSPORT,smtps,com.sun.mail.smtp.SMTPSSLTransport,Oracle], com.sun.mail.smtp.SMTPTransport=javax.mail.Provider[TRANSPORT,smtp,com.sun.mail.smtp.SMTPTransport,Oracle], com.sun.mail.imap.IMAPSSLStore=javax.mail.Provider[STORE,imaps,com.sun.mail.imap.IMAPSSLStore,Oracle], com.sun.mail.pop3.POP3SSLStore=javax.mail.Provider[STORE,pop3s,com.sun.mail.pop3.POP3SSLStore,Oracle], com.sun.mail.imap.IMAPStore=javax.mail.Provider[STORE,imap,com.sun.mail.imap.IMAPStore,Oracle], com.sun.mail.pop3.POP3Store=javax.mail.Provider[STORE,pop3,com.sun.mail.pop3.POP3Store,Oracle]}
DEBUG: Providers Listed By Protocol: {imaps=javax.mail.Provider[STORE,imaps,com.sun.mail.imap.IMAPSSLStore,Oracle], imap=javax.mail.Provider[STORE,imap,com.sun.mail.imap.IMAPStore,Oracle], smtps=javax.mail.Provider[TRANSPORT,smtps,com.sun.mail.smtp.SMTPSSLTransport,Oracle], pop3=javax.mail.Provider[STORE,pop3,com.sun.mail.pop3.POP3Store,Oracle], pop3s=javax.mail.Provider[STORE,pop3s,com.sun.mail.pop3.POP3SSLStore,Oracle], smtp=javax.mail.Provider[TRANSPORT,smtp,com.sun.mail.smtp.SMTPTransport,Oracle]}
DEBUG: successfully loaded resource: /META-INF/javamail.default.address.map
Aug 22, 2017 11:44:13 AM org.apache.catalina.core.StandardService startInternal
INFO: Starting service Catalina
Aug 22, 2017 11:44:13 AM org.apache.catalina.core.StandardEngine startInternal
INFO: Starting Servlet Engine: Apache Tomcat/7.0.61
Aug 22, 2017 11:44:13 AM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deploying web application archive /home/openkm/tomcat-7.0.61/webapps/OpenKM.war
Aug 22, 2017 11:44:13 AM org.apache.catalina.loader.WebappClassLoader validateJarFile
INFO: validateJarFile(/home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/lib/servlet-api-2.5-20081211.jar) - jar not loaded. See Servlet Spec 3.0, section 10.7.2. Offending class: javax/servlet/Servlet.class
Aug 22, 2017 11:44:13 AM org.apache.catalina.loader.WebappClassLoader validateJarFile
INFO: validateJarFile(/home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/lib/servlet-api-6.0.36.jar) - jar not loaded. See Servlet Spec 3.0, section 10.7.2. Offending class: javax/servlet/Servlet.class
Aug 22, 2017 11:44:13 AM org.apache.catalina.loader.WebappClassLoader validateJarFile
INFO: validateJarFile(/home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/lib/servlet-api-2.5-20081211.jar) - jar not loaded. See Servlet Spec 3.0, section 10.7.2. Offending class: javax/servlet/Servlet.class
Aug 22, 2017 11:44:13 AM org.apache.catalina.loader.WebappClassLoader validateJarFile
INFO: validateJarFile(/home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/lib/servlet-api-6.0.36.jar) - jar not loaded. See Servlet Spec 3.0, section 10.7.2. Offending class: javax/servlet/Servlet.class
SLF4J: Class path contains multiple SLF4J bindings.
SLF4J: Found binding in [jar:file:/home/openkm/tomcat-7.0.61/lib/activemq-all-5.13.2.jar!/org/slf4j/impl/StaticLoggerBinder.class]
SLF4J: Found binding in [jar:file:/home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/lib/logback-classic-1.1.3.jar!/org/slf4j/impl/StaticLoggerBinder.class]SLF4J: Class path contains multiple SLF4J bindings.

SLF4J: Found binding in [jar:file:/home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/lib/slf4j-log4j12-1.7.5.jar!/org/slf4j/impl/StaticLoggerBinder.class]
SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
SLF4J: Found binding in [jar:file:/home/openkm/tomcat-7.0.61/lib/activemq-all-5.13.2.jar!/org/slf4j/impl/StaticLoggerBinder.class]
SLF4J: Found binding in [jar:file:/home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/lib/logback-classic-1.1.3.jar!/org/slf4j/impl/StaticLoggerBinder.class]
SLF4J: Found binding in [jar:file:/home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/lib/slf4j-log4j12-1.7.5.jar!/org/slf4j/impl/StaticLoggerBinder.class]
SLF4J: See http://www.slf4j.org/codes.html#multiple_bindings for an explanation.
11:44:44,140 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy]
11:44:44,140 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
11:44:44,140 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [file:/home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/logback.xml]
11:44:44,308 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set
11:44:44,312 |-INFO in ReconfigureOnChangeFilter{invocationCounter=0} - Will scan for changes in [[/home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/logback.xml]] every 60 seconds. 
11:44:44,312 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Adding ReconfigureOnChangeFilter as a turbo filter
11:44:44,315 |-INFO in ch.qos.logback.classic.joran.action.JMXConfiguratorAction - begin
11:44:44,320 |-INFO in ch.qos.logback.core.joran.action.StatusListenerAction - Added status listener of type [ch.qos.logback.core.status.OnConsoleStatusListener]
11:44:44,140 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy]
11:44:44,140 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
11:44:44,140 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [file:/home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/logback.xml]
11:44:44,312 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set
11:44:44,317 |-INFO in ReconfigureOnChangeFilter{invocationCounter=0} - Will scan for changes in [[/home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/logback.xml]] every 60 seconds. 
11:44:44,317 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Adding ReconfigureOnChangeFilter as a turbo filter
11:44:44,320 |-INFO in ch.qos.logback.classic.joran.action.JMXConfiguratorAction - begin
11:44:44,327 |-INFO in ch.qos.logback.core.joran.action.StatusListenerAction - Added status listener of type [ch.qos.logback.core.status.OnConsoleStatusListener]
11:44:44,332 |-INFO in ch.qos.logback.core.joran.action.TimestampAction - Using current interpretation time, i.e. now, as time reference.
11:44:44,333 |-INFO in ch.qos.logback.core.joran.action.TimestampAction - Using current interpretation time, i.e. now, as time reference.
11:44:44,333 |-INFO in ch.qos.logback.core.joran.action.TimestampAction - Adding property to the context with key="bySecond" and value="2017-08-22" to the LOCAL scope
11:44:44,333 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
11:44:44,333 |-INFO in ch.qos.logback.core.joran.action.TimestampAction - Adding property to the context with key="bySecond" and value="2017-08-22" to the LOCAL scope
11:44:44,333 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
11:44:44,336 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [STDOUT]
11:44:44,339 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [STDOUT]
11:44:44,514 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
11:44:44,514 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
11:44:44,565 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
11:44:44,569 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
11:44:44,571 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE]
11:44:44,581 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE]
11:44:44,605 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy - No compression will be used
11:44:44,607 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy - Will use the pattern /home/openkm/tomcat-7.0.61/logs/openkm_%d{yyyy-MM-dd}.%i.log for the active file
11:44:44,611 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@542f421 - The date pattern is 'yyyy-MM-dd' from file name pattern '/home/openkm/tomcat-7.0.61/logs/openkm_%d{yyyy-MM-dd}.%i.log'.
11:44:44,611 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@542f421 - Roll-over at midnight.
11:44:44,611 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@542f421 - Setting initial period to Tue Aug 22 11:44:44 EDT 2017
11:44:44,633 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy - No compression will be used
11:44:44,635 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy - Will use the pattern /home/openkm/tomcat-7.0.61/logs/openkm_%d{yyyy-MM-dd}.%i.log for the active file
11:44:44,646 |-WARN in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - This appender no longer admits a layout as a sub-component, set an encoder instead.
11:44:44,646 |-WARN in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - To ensure compatibility, wrapping your layout in LayoutWrappingEncoder.
11:44:44,646 |-WARN in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - See also http://logback.qos.ch/codes.html#layoutInsteadOfEncoder for details
11:44:44,648 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@653536e0 - The date pattern is 'yyyy-MM-dd' from file name pattern '/home/openkm/tomcat-7.0.61/logs/openkm_%d{yyyy-MM-dd}.%i.log'.
11:44:44,648 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@653536e0 - Roll-over at midnight.
11:44:44,648 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@653536e0 - Setting initial period to Tue Aug 22 11:44:44 EDT 2017
11:44:44,651 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: /home/openkm/tomcat-7.0.61/logs/openkm.log
11:44:44,651 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [/home/openkm/tomcat-7.0.61/logs/openkm.log]
11:44:44,652 |-INFO in ch.qos.logback.core.joran.util.ConfigurationWatchListUtil@536ebc3d - Adding [file:/home/openkm/tomcat-7.0.61/logback.xml] to configuration watch list.
11:44:44,662 |-WARN in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - This appender no longer admits a layout as a sub-component, set an encoder instead.
11:44:44,662 |-WARN in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - To ensure compatibility, wrapping your layout in LayoutWrappingEncoder.
11:44:44,662 |-WARN in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - See also http://logback.qos.ch/codes.html#layoutInsteadOfEncoder for details
11:44:44,664 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: /home/openkm/tomcat-7.0.61/logs/openkm.log
11:44:44,664 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [/home/openkm/tomcat-7.0.61/logs/openkm.log]
11:44:44,665 |-INFO in ch.qos.logback.core.joran.util.ConfigurationWatchListUtil@389f398f - Adding [file:/home/openkm/tomcat-7.0.61/logback.xml] to configuration watch list.
11:44:44,677 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework] to WARN
11:44:44,677 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework] to WARN
11:44:44,677 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate] to WARN
11:44:44,677 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate] to WARN
11:44:44,677 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [ch.qos.logback] to WARN
11:44:44,677 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [ch.qos.logback] to WARN
11:44:44,677 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.jbpm] to WARN
11:44:44,677 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.jbpm] to WARN
11:44:44,677 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.openkm] to INFO
11:44:44,677 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.openkm] to INFO
11:44:44,677 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to WARN
11:44:44,677 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to WARN
11:44:44,678 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [FILE] to Logger[ROOT]
11:44:44,678 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [FILE] to Logger[ROOT]
11:44:44,678 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
11:44:44,678 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
11:44:44,679 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@6a4f4875 - Registering current configuration as safe fallback point
11:44:44,679 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@606efc35 - Registering current configuration as safe fallback point
SLF4J: Actual binding is of type [ch.qos.logback.classic.util.ContextSelectorStaticBinder]SLF4J: Actual binding is of type [ch.qos.logback.classic.util.ContextSelectorStaticBinder]

Aug 22, 2017 11:45:05 AM org.artofsolving.jodconverter.office.ProcessPoolOfficeManager <init>
INFO: ProcessManager implementation is SigarProcessManager
Aug 22, 2017 11:45:05 AM org.artofsolving.jodconverter.office.ProcessPoolOfficeManager <init>
INFO: ProcessManager implementation is SigarProcessManager
Aug 22, 2017 11:45:05 AM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: starting process with acceptString 'socket,host=127.0.0.1,port=2002,tcpNoDelay=1' and profileDir '/home/openkm/tomcat-7.0.61/temp/.jodconverter_socket_host-127.0.0.1_port-2002'
Aug 22, 2017 11:45:05 AM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: starting process with acceptString 'socket,host=127.0.0.1,port=2002,tcpNoDelay=1' and profileDir '/home/openkm/tomcat-7.0.61/temp/.jodconverter_socket_host-127.0.0.1_port-2002'
Aug 22, 2017 11:45:05 AM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: started process; pid = 2264
Aug 22, 2017 11:45:05 AM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: started process; pid = 2264
Aug 22, 2017 11:45:10 AM org.artofsolving.jodconverter.office.ManagedOfficeProcess$6 attempt
WARNING: office process died with exit code 81; restarting it
Aug 22, 2017 11:45:10 AM net.xeoh.plugins.base.impl.classpath.loader.FileLoader loadFrom
WARNING: Supplied path does not exist. Unable to add plugins from there.
Aug 22, 2017 11:45:10 AM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /home/openkm/tomcat-7.0.61/webapps/OpenKM.war has finished in 57,738 ms
Aug 22, 2017 11:45:10 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /home/openkm/tomcat-7.0.61/webapps/ROOT
Aug 22, 2017 11:45:11 AM org.artofsolving.jodconverter.office.ManagedOfficeProcess$6 attempt
WARNING: office process died with exit code 81; restarting it
Aug 22, 2017 11:45:11 AM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: starting process with acceptString 'socket,host=127.0.0.1,port=2002,tcpNoDelay=1' and profileDir '/home/openkm/tomcat-7.0.61/temp/.jodconverter_socket_host-127.0.0.1_port-2002'
Aug 22, 2017 11:45:11 AM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: started process; pid = 2291
Aug 22, 2017 11:45:11 AM org.artofsolving.jodconverter.office.OfficeConnection connect
INFO: connected: 'socket,host=127.0.0.1,port=2002,tcpNoDelay=1'
Aug 22, 2017 11:45:11 AM net.xeoh.plugins.base.impl.classpath.loader.FileLoader loadFrom
WARNING: Supplied path does not exist. Unable to add plugins from there.
Aug 22, 2017 11:45:11 AM org.apache.catalina.startup.HostConfig deployWAR
INFO: Deployment of web application archive /home/openkm/tomcat-7.0.61/webapps/OpenKM.war has finished in 58,686 ms
Aug 22, 2017 11:45:11 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory /home/openkm/tomcat-7.0.61/webapps/ROOT
Aug 22, 2017 11:45:12 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /home/openkm/tomcat-7.0.61/webapps/ROOT has finished in 1,339 ms
Aug 22, 2017 11:45:12 AM org.apache.catalina.startup.Catalina start
INFO: Server startup in 58639 ms
Aug 22, 2017 11:45:13 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /home/openkm/tomcat-7.0.61/webapps/ROOT has finished in 1,426 ms
Aug 22, 2017 11:45:13 AM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-0.0.0.0-8080"]
Aug 22, 2017 11:45:13 AM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["ajp-bio-127.0.0.1-8009"]
Aug 22, 2017 11:45:13 AM org.apache.catalina.startup.Catalina start
INFO: Server startup in 59724 ms
Aug 22, 2017 11:45:13 AM org.apache.catalina.core.StandardServer await
SEVERE: StandardServer.await: create[localhost:8005]: 
java.net.BindException: Address already in use (Bind failed)
	at java.net.PlainSocketImpl.socketBind(Native Method)
	at java.net.AbstractPlainSocketImpl.bind(AbstractPlainSocketImpl.java:387)
	at java.net.ServerSocket.bind(ServerSocket.java:375)
	at java.net.ServerSocket.<init>(ServerSocket.java:237)
	at org.apache.catalina.core.StandardServer.await(StandardServer.java:427)
	at org.apache.catalina.startup.Catalina.await(Catalina.java:777)
	at org.apache.catalina.startup.Catalina.start(Catalina.java:723)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:321)
	at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:455)

Aug 22, 2017 11:45:13 AM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["http-bio-0.0.0.0-8080"]
Aug 22, 2017 11:45:13 AM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["ajp-bio-127.0.0.1-8009"]
Aug 22, 2017 11:45:13 AM org.apache.catalina.core.StandardService stopInternal
INFO: Stopping service Catalina
Aug 22, 2017 11:45:13 AM org.artofsolving.jodconverter.office.ProcessPoolOfficeManager stop
INFO: stopping
Aug 22, 2017 11:45:13 AM org.artofsolving.jodconverter.office.OfficeConnection$1 disposing
INFO: disconnected: 'socket,host=127.0.0.1,port=2002,tcpNoDelay=1'
Aug 22, 2017 11:45:13 AM org.artofsolving.jodconverter.office.ManagedOfficeProcess doEnsureProcessExited
INFO: process exited with code 0
Aug 22, 2017 11:45:13 AM org.artofsolving.jodconverter.office.ProcessPoolOfficeManager stop
INFO: stopped
Aug 22, 2017 11:45:13 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/OpenKM] appears to have started a thread named [Abandoned connection cleanup thread] but has failed to stop it. This is very likely to create a memory leak.
Aug 22, 2017 11:45:13 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/OpenKM] appears to have started a thread named [OfficeProcessThread-0] but has failed to stop it. This is very likely to create a memory leak.
Aug 22, 2017 11:45:13 AM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/OpenKM] created a ThreadLocal with key of type [java.lang.ThreadLocal] (value [java.lang.ThreadLocal@4af868f1]) and a value of type [org.springframework.security.core.context.SecurityContextImpl] (value [org.springframework.security.core.context.SecurityContextImpl@ffffffff: Null authentication]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
Aug 22, 2017 11:45:13 AM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/OpenKM] created a ThreadLocal with key of type [com.sun.xml.bind.v2.ClassFactory$1] (value [com.sun.xml.bind.v2.ClassFactory$1@7682d62e]) and a value of type [java.util.WeakHashMap] (value [{class javax.xml.bind.annotation.W3CDomHandler=java.lang.ref.WeakReference@61c140c}]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
Aug 22, 2017 11:45:13 AM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["http-bio-0.0.0.0-8080"]
Aug 22, 2017 11:45:13 AM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["ajp-bio-127.0.0.1-8009"]
Aug 22, 2017 11:45:13 AM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["http-bio-0.0.0.0-8080"]
Aug 22, 2017 11:45:13 AM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["ajp-bio-127.0.0.1-8009"]

dferguson
Expert Boarder
Expert Boarder
Posts: 86
Joined: Thu May 18, 2017 12:48 pm

Re: Startup errors and unreliable behavior

Post by dferguson » Tue Aug 22, 2017 4:12 pm

openkm.log Part 1

Code: Select all

2017-08-22 11:44:56,822 [localhost-startStop-1] [] INFO  com.openkm.core.Config - ** Application OpenKM has DTDs at /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd **
2017-08-22 11:44:56,826 [localhost-startStop-1] [] INFO  com.openkm.core.Config - ** Language profiles at /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/lang-profiles **
2017-08-22 11:44:56,826 [localhost-startStop-1] [] INFO  com.openkm.core.Config - ** Reading config file /home/openkm/tomcat-7.0.61/OpenKM.cfg **
2017-08-22 11:44:56,816 [localhost-startStop-1] [] INFO  com.openkm.core.Config - ** Application OpenKM has DTDs at /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd **
2017-08-22 11:44:56,832 [localhost-startStop-1] [] INFO  com.openkm.core.Config - ** Language profiles at /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/lang-profiles **
2017-08-22 11:44:56,832 [localhost-startStop-1] [] INFO  com.openkm.core.Config - ** Reading config file /home/openkm/tomcat-7.0.61/OpenKM.cfg **
2017-08-22 11:44:56,834 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - cloud.max.repository.size=0
2017-08-22 11:44:56,834 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - cloud.max.users=0
2017-08-22 11:44:56,834 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - cloud.mode=false
2017-08-22 11:44:56,834 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - hibernate.create.autofix=true
2017-08-22 11:44:56,834 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - hibernate.datasource=java:/comp/env/jdbc/OpenKMDS
2017-08-22 11:44:56,834 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - hibernate.dialect=org.hibernate.dialect.MySQL5InnoDBDialect
2017-08-22 11:44:56,834 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - hibernate.hbm2ddl=none
2017-08-22 11:44:56,834 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - hibernate.search.analyzer=org.apache.lucene.analysis.standard.StandardAnalyzer
2017-08-22 11:44:56,834 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - hibernate.search.index.exclusive=true
2017-08-22 11:44:56,834 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - hibernate.search.index.home=/home/openkm/tomcat-7.0.61/repository/index
2017-08-22 11:44:56,834 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - hibernate.show_sql=false
2017-08-22 11:44:56,834 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - hibernate.statistics=false
2017-08-22 11:44:56,834 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - instance.chroot.path=
2017-08-22 11:44:56,834 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - instance.home=/home/openkm/tomcat-7.0.61
2017-08-22 11:44:56,834 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - jbpm.config=/home/openkm/tomcat-7.0.61/jbpm.xml
2017-08-22 11:44:56,834 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - property.groups.cnd=/home/openkm/tomcat-7.0.61/PropertyGroups.cnd
2017-08-22 11:44:56,834 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - property.groups.xml=/home/openkm/tomcat-7.0.61/PropertyGroups.xml
2017-08-22 11:44:56,834 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - repository.cache.dxf=/home/openkm/tomcat-7.0.61/repository/cache/dxf
2017-08-22 11:44:56,834 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - repository.cache.home=/home/openkm/tomcat-7.0.61/repository/cache
2017-08-22 11:44:56,834 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - repository.cache.pdf=/home/openkm/tomcat-7.0.61/repository/cache/pdf
2017-08-22 11:44:56,834 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - repository.cache.swf=/home/openkm/tomcat-7.0.61/repository/cache/swf
2017-08-22 11:44:56,834 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - repository.config=/dev/null
2017-08-22 11:44:56,834 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - repository.datastore.backend=fs
2017-08-22 11:44:56,834 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - repository.datastore.home=/home/openkm/tomcat-7.0.61/repository/datastore
2017-08-22 11:44:56,835 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - repository.home=/home/openkm/tomcat-7.0.61/repository
2017-08-22 11:44:56,835 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - repository.native=true
2017-08-22 11:44:56,835 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - system.multiple.instances=false
2017-08-22 11:44:56,839 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - cloud.max.repository.size=0
2017-08-22 11:44:56,839 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - cloud.max.users=0
2017-08-22 11:44:56,839 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - cloud.mode=false
2017-08-22 11:44:56,839 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - hibernate.create.autofix=true
2017-08-22 11:44:56,839 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - hibernate.datasource=java:/comp/env/jdbc/OpenKMDS
2017-08-22 11:44:56,839 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - hibernate.dialect=org.hibernate.dialect.MySQL5InnoDBDialect
2017-08-22 11:44:56,839 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - hibernate.hbm2ddl=none
2017-08-22 11:44:56,839 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - hibernate.search.analyzer=org.apache.lucene.analysis.standard.StandardAnalyzer
2017-08-22 11:44:56,839 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - hibernate.search.index.exclusive=true
2017-08-22 11:44:56,839 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - hibernate.search.index.home=/home/openkm/tomcat-7.0.61/repository/index
2017-08-22 11:44:56,839 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - hibernate.show_sql=false
2017-08-22 11:44:56,839 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - hibernate.statistics=false
2017-08-22 11:44:56,839 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - instance.chroot.path=
2017-08-22 11:44:56,839 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - instance.home=/home/openkm/tomcat-7.0.61
2017-08-22 11:44:56,839 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - jbpm.config=/home/openkm/tomcat-7.0.61/jbpm.xml
2017-08-22 11:44:56,839 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - property.groups.cnd=/home/openkm/tomcat-7.0.61/PropertyGroups.cnd
2017-08-22 11:44:56,839 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - property.groups.xml=/home/openkm/tomcat-7.0.61/PropertyGroups.xml
2017-08-22 11:44:56,839 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - repository.cache.dxf=/home/openkm/tomcat-7.0.61/repository/cache/dxf
2017-08-22 11:44:56,839 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - repository.cache.home=/home/openkm/tomcat-7.0.61/repository/cache
2017-08-22 11:44:56,839 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - repository.cache.pdf=/home/openkm/tomcat-7.0.61/repository/cache/pdf
2017-08-22 11:44:56,839 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - repository.cache.swf=/home/openkm/tomcat-7.0.61/repository/cache/swf
2017-08-22 11:44:56,839 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - repository.config=/dev/null
2017-08-22 11:44:56,839 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - repository.datastore.backend=fs
2017-08-22 11:44:56,839 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - repository.datastore.home=/home/openkm/tomcat-7.0.61/repository/datastore
2017-08-22 11:44:56,839 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - repository.home=/home/openkm/tomcat-7.0.61/repository
2017-08-22 11:44:56,839 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - repository.native=true
2017-08-22 11:44:56,840 [localhost-startStop-1] [] INFO  com.openkm.core.Config - LOAD - system.multiple.instances=false
2017-08-22 11:44:56,844 [localhost-startStop-1] [] INFO  com.openkm.util.WarUtils - okm.version: 6.3.4
2017-08-22 11:44:56,844 [localhost-startStop-1] [] INFO  com.openkm.util.WarUtils - okm.build: 47204ef
2017-08-22 11:44:56,847 [localhost-startStop-1] [] INFO  com.openkm.util.WarUtils - okm.version: 6.3.4
2017-08-22 11:44:56,847 [localhost-startStop-1] [] INFO  com.openkm.util.WarUtils - okm.build: 47204ef
2017-08-22 11:44:56,850 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Application version: 6.3.4 (build: 47204ef) ***
2017-08-22 11:44:56,850 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Initialize cache manager... ***
2017-08-22 11:44:56,852 [localhost-startStop-1] [] WARN  com.openkm.cache.CacheProvider - Using Ehcache config from ClassPath: /ehcache.xml
2017-08-22 11:44:56,856 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Application version: 6.3.4 (build: 47204ef) ***
2017-08-22 11:44:56,856 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Initialize cache manager... ***
2017-08-22 11:44:56,857 [localhost-startStop-1] [] WARN  com.openkm.cache.CacheProvider - Using Ehcache config from ClassPath: /ehcache.xml
2017-08-22 11:44:57,147 [localhost-startStop-1] [] INFO  com.openkm.cache.CacheProvider - Cache disk store path: /home/openkm/tomcat-7.0.61/temp/okm
2017-08-22 11:44:57,148 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Hibernate initialize ***
2017-08-22 11:44:57,179 [localhost-startStop-1] [] INFO  com.openkm.cache.CacheProvider - Cache disk store path: /home/openkm/tomcat-7.0.61/temp/okm
2017-08-22 11:44:57,180 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Hibernate initialize ***
2017-08-22 11:44:59,082 [localhost-startStop-1] [] INFO  com.openkm.dao.HibernateUtil - Hibernate 'hibernate.dialect' = org.hibernate.dialect.MySQL5InnoDBDialect
2017-08-22 11:44:59,089 [localhost-startStop-1] [] INFO  com.openkm.dao.HibernateUtil - Hibernate 'hibernate.connection.datasource' = java:/comp/env/jdbc/OpenKMDS
2017-08-22 11:44:59,089 [localhost-startStop-1] [] INFO  com.openkm.dao.HibernateUtil - Hibernate 'hibernate.hbm2ddl.auto' = none
2017-08-22 11:44:59,089 [localhost-startStop-1] [] INFO  com.openkm.dao.HibernateUtil - Hibernate 'hibernate.show_sql' = false
2017-08-22 11:44:59,089 [localhost-startStop-1] [] INFO  com.openkm.dao.HibernateUtil - Hibernate 'hibernate.generate_statistics' = false
2017-08-22 11:44:59,089 [localhost-startStop-1] [] INFO  com.openkm.dao.HibernateUtil - Hibernate 'hibernate.search.default.directory_provider' = org.hibernate.search.store.FSDirectoryProvider
2017-08-22 11:44:59,089 [localhost-startStop-1] [] INFO  com.openkm.dao.HibernateUtil - Hibernate 'hibernate.search.default.indexBase' = /home/openkm/tomcat-7.0.61/repository/index
2017-08-22 11:44:59,174 [localhost-startStop-1] [] INFO  com.openkm.dao.HibernateUtil - Hibernate 'hibernate.dialect' = org.hibernate.dialect.MySQL5InnoDBDialect
2017-08-22 11:44:59,175 [localhost-startStop-1] [] INFO  com.openkm.dao.HibernateUtil - Hibernate 'hibernate.connection.datasource' = java:/comp/env/jdbc/OpenKMDS
2017-08-22 11:44:59,175 [localhost-startStop-1] [] INFO  com.openkm.dao.HibernateUtil - Hibernate 'hibernate.hbm2ddl.auto' = none
2017-08-22 11:44:59,175 [localhost-startStop-1] [] INFO  com.openkm.dao.HibernateUtil - Hibernate 'hibernate.show_sql' = false
2017-08-22 11:44:59,175 [localhost-startStop-1] [] INFO  com.openkm.dao.HibernateUtil - Hibernate 'hibernate.generate_statistics' = false
2017-08-22 11:44:59,175 [localhost-startStop-1] [] INFO  com.openkm.dao.HibernateUtil - Hibernate 'hibernate.search.default.directory_provider' = org.hibernate.search.store.FSDirectoryProvider
2017-08-22 11:44:59,175 [localhost-startStop-1] [] INFO  com.openkm.dao.HibernateUtil - Hibernate 'hibernate.search.default.indexBase' = /home/openkm/tomcat-7.0.61/repository/index
2017-08-22 11:45:01,640 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.DatabaseMetadataSequence]; using defaults.
2017-08-22 11:45:01,688 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.DatabaseMetadataSequence]; using defaults.
2017-08-22 11:45:01,688 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.Action]; using defaults.
2017-08-22 11:45:01,708 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.Node]; using defaults.
2017-08-22 11:45:01,737 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.DatabaseMetadataType]; using defaults.
2017-08-22 11:45:01,741 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.Action]; using defaults.
2017-08-22 11:45:01,776 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.Node]; using defaults.
2017-08-22 11:45:01,782 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.ExceptionHandler]; using defaults.
2017-08-22 11:45:01,803 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.DatabaseMetadataType]; using defaults.
2017-08-22 11:45:01,819 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.module.def.ModuleDefinition]; using defaults.
2017-08-22 11:45:01,835 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.ExceptionHandler]; using defaults.
2017-08-22 11:45:01,854 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.ProcessDefinition]; using defaults.
2017-08-22 11:45:01,874 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.context.def.VariableAccess]; using defaults.
2017-08-22 11:45:01,889 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.module.def.ModuleDefinition]; using defaults.
2017-08-22 11:45:01,902 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.instantiation.Delegation]; using defaults.
2017-08-22 11:45:01,917 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.ProcessDefinition]; using defaults.
2017-08-22 11:45:01,922 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.Bookmark]; using defaults.
2017-08-22 11:45:01,928 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.Event]; using defaults.
2017-08-22 11:45:01,935 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.context.def.VariableAccess]; using defaults.
2017-08-22 11:45:01,965 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.instantiation.Delegation]; using defaults.
2017-08-22 11:45:01,982 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.Bookmark]; using defaults.
2017-08-22 11:45:01,995 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.Event]; using defaults.
2017-08-22 11:45:02,022 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.Css]; using defaults.
2017-08-22 11:45:02,073 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.Css]; using defaults.
2017-08-22 11:45:02,074 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.Role]; using defaults.
2017-08-22 11:45:02,104 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.Role]; using defaults.
2017-08-22 11:45:02,188 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.NodeProperty]; using defaults.
2017-08-22 11:45:02,189 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.NodeProperty]; using defaults.
2017-08-22 11:45:02,202 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.NodeNote]; using defaults.
2017-08-22 11:45:02,205 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.NodeNote]; using defaults.
2017-08-22 11:45:02,222 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.Transition]; using defaults.
2017-08-22 11:45:02,246 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.Transition]; using defaults.
2017-08-22 11:45:02,254 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.User]; using defaults.
2017-08-22 11:45:02,271 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.Language]; using defaults.
2017-08-22 11:45:02,275 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.taskmgmt.def.TaskController]; using defaults.
2017-08-22 11:45:02,296 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.User]; using defaults.
2017-08-22 11:45:02,306 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.Language]; using defaults.
2017-08-22 11:45:02,307 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.Translation]; using defaults.
2017-08-22 11:45:02,327 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.UserConfig]; using defaults.
2017-08-22 11:45:02,331 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.taskmgmt.def.TaskController]; using defaults.
2017-08-22 11:45:02,336 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.NodeDocumentVersion]; using defaults.
2017-08-22 11:45:02,362 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.Translation]; using defaults.
2017-08-22 11:45:02,383 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.UserConfig]; using defaults.
2017-08-22 11:45:02,383 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.taskmgmt.def.Task]; using defaults.
2017-08-22 11:45:02,391 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.DatabaseMetadataValue]; using defaults.
2017-08-22 11:45:02,392 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.NodeDocumentVersion]; using defaults.
2017-08-22 11:45:02,405 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.MimeType]; using defaults.
2017-08-22 11:45:02,410 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.RegisteredPropertyGroup]; using defaults.
2017-08-22 11:45:02,425 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.node.Decision.decisionConditions]; using defaults.
2017-08-22 11:45:02,428 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.taskmgmt.def.Task]; using defaults.
2017-08-22 11:45:02,436 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.Transition.exceptionHandlers]; using defaults.
2017-08-22 11:45:02,438 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.ExceptionHandler.actions]; using defaults.
2017-08-22 11:45:02,438 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.DatabaseMetadataValue]; using defaults.
2017-08-22 11:45:02,439 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.node.TaskNode.tasks]; using defaults.
2017-08-22 11:45:02,445 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.MimeType]; using defaults.
2017-08-22 11:45:02,451 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.RegisteredPropertyGroup]; using defaults.
2017-08-22 11:45:02,464 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.node.Decision.decisionConditions]; using defaults.
2017-08-22 11:45:02,474 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.Transition.exceptionHandlers]; using defaults.
2017-08-22 11:45:02,476 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.ExceptionHandler.actions]; using defaults.
2017-08-22 11:45:02,476 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.node.TaskNode.tasks]; using defaults.
2017-08-22 11:45:02,513 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.taskmgmt.def.TaskMgmtDefinition.tasks]; using defaults.
2017-08-22 11:45:02,514 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.ProcessDefinition.definitions]; using defaults.
2017-08-22 11:45:02,515 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.Transition.events]; using defaults.
2017-08-22 11:45:02,518 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.taskmgmt.def.TaskMgmtDefinition.tasks]; using defaults.
2017-08-22 11:45:02,519 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.ProcessDefinition.definitions]; using defaults.
2017-08-22 11:45:02,519 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.Transition.events]; using defaults.
2017-08-22 11:45:02,520 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.taskmgmt.def.TaskMgmtDefinition.swimlanes]; using defaults.
2017-08-22 11:45:02,521 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.ProcessDefinition.actions]; using defaults.
2017-08-22 11:45:02,522 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.Node.arrivingTransitions]; using defaults.
2017-08-22 11:45:02,522 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.taskmgmt.def.TaskMgmtDefinition.swimlanes]; using defaults.
2017-08-22 11:45:02,523 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.ProcessDefinition.actions]; using defaults.
2017-08-22 11:45:02,524 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.Node.arrivingTransitions]; using defaults.
2017-08-22 11:45:02,524 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.taskmgmt.def.Swimlane.tasks]; using defaults.
2017-08-22 11:45:02,525 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.taskmgmt.def.Task.exceptionHandlers]; using defaults.
2017-08-22 11:45:02,526 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.taskmgmt.def.Swimlane.tasks]; using defaults.
2017-08-22 11:45:02,526 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.taskmgmt.def.Task.exceptionHandlers]; using defaults.
2017-08-22 11:45:02,527 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.action.Script.variableAccesses]; using defaults.
2017-08-22 11:45:02,527 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.taskmgmt.def.Task.events]; using defaults.
2017-08-22 11:45:02,527 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.action.Script.variableAccesses]; using defaults.
2017-08-22 11:45:02,528 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.Language.translations]; using defaults.
2017-08-22 11:45:02,529 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.Node.exceptionHandlers]; using defaults.
2017-08-22 11:45:02,532 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.ProcessDefinition.nodes]; using defaults.
2017-08-22 11:45:02,533 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.taskmgmt.def.Task.events]; using defaults.
2017-08-22 11:45:02,533 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.Language.translations]; using defaults.
2017-08-22 11:45:02,534 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.Event.actions]; using defaults.
2017-08-22 11:45:02,535 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.ProcessDefinition.events]; using defaults.
2017-08-22 11:45:02,539 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.node.ProcessState.variableAccesses]; using defaults.
2017-08-22 11:45:02,540 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.Node.exceptionHandlers]; using defaults.
2017-08-22 11:45:02,541 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.ProcessDefinition.nodes]; using defaults.
2017-08-22 11:45:02,542 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.Event.actions]; using defaults.
2017-08-22 11:45:02,547 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.ProcessDefinition.events]; using defaults.
2017-08-22 11:45:02,550 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.Node.events]; using defaults.
2017-08-22 11:45:02,551 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.Node.leavingTransitions]; using defaults.
2017-08-22 11:45:02,552 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.node.ProcessState.variableAccesses]; using defaults.
2017-08-22 11:45:02,553 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.Node.events]; using defaults.
2017-08-22 11:45:02,553 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.Node.leavingTransitions]; using defaults.
2017-08-22 11:45:02,554 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.taskmgmt.def.TaskController.variableAccesses]; using defaults.
2017-08-22 11:45:02,555 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.SuperState.nodes]; using defaults.
2017-08-22 11:45:02,556 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.ProcessDefinition.exceptionHandlers]; using defaults.
2017-08-22 11:45:02,556 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.taskmgmt.def.TaskController.variableAccesses]; using defaults.
2017-08-22 11:45:02,557 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.file.def.FileDefinition.processFiles]; using defaults.
2017-08-22 11:45:02,557 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.User.roles]; using defaults.
2017-08-22 11:45:02,557 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.SuperState.nodes]; using defaults.
2017-08-22 11:45:02,558 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.graph.def.ProcessDefinition.exceptionHandlers]; using defaults.
2017-08-22 11:45:02,558 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.jbpm.file.def.FileDefinition.processFiles]; using defaults.
2017-08-22 11:45:02,558 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [com.openkm.dao.bean.User.roles]; using defaults.
2017-08-22 11:45:02,780 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.hibernate.cache.UpdateTimestampsCache]; using defaults.
2017-08-22 11:45:02,783 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.hibernate.cache.StandardQueryCache]; using defaults.
2017-08-22 11:45:02,831 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.hibernate.cache.UpdateTimestampsCache]; using defaults.
2017-08-22 11:45:02,833 [localhost-startStop-1] [] WARN  n.s.e.h.AbstractEhcacheRegionFactory - Couldn't find a specific ehcache configuration for cache named [org.hibernate.cache.StandardQueryCache]; using defaults.

dferguson
Expert Boarder
Expert Boarder
Posts: 86
Joined: Thu May 18, 2017 12:48 pm

Re: Startup errors and unreliable behavior

Post by dferguson » Tue Aug 22, 2017 4:13 pm

openkm.log part 2

Code: Select all

2017-08-22 11:45:03,115 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Initialize property groups... ***
2017-08-22 11:45:03,118 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - new LocalResolver(/home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd)
2017-08-22 11:45:03,119 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Workflow Forms 2.1//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/workflow-forms-2.1.dtd)
2017-08-22 11:45:03,119 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Workflow Forms 2.4//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/workflow-forms-2.4.dtd)
2017-08-22 11:45:03,119 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Workflow Forms 2.0//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/workflow-forms-2.0.dtd)
2017-08-22 11:45:03,120 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Property Groups 2.1//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/property-groups-2.1.dtd)
2017-08-22 11:45:03,120 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Property Groups 1.1//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/property-groups-1.1.dtd)
2017-08-22 11:45:03,120 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Workflow Forms 1.0//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/workflow-forms-1.0.dtd)
2017-08-22 11:45:03,120 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Property Groups 2.0//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/property-groups-2.0.dtd)
2017-08-22 11:45:03,121 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Property Groups 1.0//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/property-groups-1.0.dtd)
2017-08-22 11:45:03,121 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Property Groups 2.2//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/property-groups-2.2.dtd)
2017-08-22 11:45:03,122 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Report Parameters 2.0//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/report-parameters-2.0.dtd)
2017-08-22 11:45:03,122 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Workflow Forms 2.3//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/workflow-forms-2.3.dtd)
2017-08-22 11:45:03,122 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Workflow Forms 1.1//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/workflow-forms-1.1.dtd)
2017-08-22 11:45:03,122 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Property Groups 2.3//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/property-groups-2.3.dtd)
2017-08-22 11:45:03,122 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Workflow Forms 2.2//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/workflow-forms-2.2.dtd)
2017-08-22 11:45:03,130 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Initialize property groups... ***
2017-08-22 11:45:03,133 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - new LocalResolver(/home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd)
2017-08-22 11:45:03,133 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - resolveEntity(publicId=-//OpenKM//DTD Property Groups 2.0//EN, systemId=http://www.openkm.com/dtd/property-groups-2.0.dtd) => /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/property-groups-2.0.dtd
2017-08-22 11:45:03,133 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Workflow Forms 2.1//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/workflow-forms-2.1.dtd)
2017-08-22 11:45:03,133 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Workflow Forms 2.4//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/workflow-forms-2.4.dtd)
2017-08-22 11:45:03,133 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Workflow Forms 2.0//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/workflow-forms-2.0.dtd)
2017-08-22 11:45:03,133 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Property Groups 2.1//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/property-groups-2.1.dtd)
2017-08-22 11:45:03,133 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Property Groups 1.1//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/property-groups-1.1.dtd)
2017-08-22 11:45:03,134 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Workflow Forms 1.0//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/workflow-forms-1.0.dtd)
2017-08-22 11:45:03,134 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Property Groups 2.0//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/property-groups-2.0.dtd)
2017-08-22 11:45:03,134 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Property Groups 1.0//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/property-groups-1.0.dtd)
2017-08-22 11:45:03,134 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Property Groups 2.2//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/property-groups-2.2.dtd)
2017-08-22 11:45:03,134 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Report Parameters 2.0//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/report-parameters-2.0.dtd)
2017-08-22 11:45:03,134 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Workflow Forms 2.3//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/workflow-forms-2.3.dtd)
2017-08-22 11:45:03,134 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Workflow Forms 1.1//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/workflow-forms-1.1.dtd)
2017-08-22 11:45:03,134 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Property Groups 2.3//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/property-groups-2.3.dtd)
2017-08-22 11:45:03,134 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - registerDTD(-//OpenKM//DTD Workflow Forms 2.2//EN, /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/workflow-forms-2.2.dtd)
2017-08-22 11:45:03,136 [localhost-startStop-1] [] INFO  c.o.util.FormUtils$LocalResolver - resolveEntity(publicId=-//OpenKM//DTD Property Groups 2.0//EN, systemId=http://www.openkm.com/dtd/property-groups-2.0.dtd) => /home/openkm/tomcat-7.0.61/webapps/OpenKM/WEB-INF/classes/dtd/property-groups-2.0.dtd
2017-08-22 11:45:03,179 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Initialize language detection engine... ***
2017-08-22 11:45:03,192 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Initialize language detection engine... ***
2017-08-22 11:45:03,976 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Initializing text extractors
2017-08-22 11:45:03,976 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Initializing text extractors
2017-08-22 11:45:03,977 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.PlainTextExtractor for 'text/plain'
2017-08-22 11:45:03,977 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.PlainTextExtractor for 'text/plain'
2017-08-22 11:45:03,979 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsWordTextExtractor for 'application/vnd.ms-word'
2017-08-22 11:45:03,979 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsWordTextExtractor for 'application/msword'
2017-08-22 11:45:03,979 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsWordTextExtractor for 'application/vnd.ms-word'
2017-08-22 11:45:03,979 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsWordTextExtractor for 'application/msword'
2017-08-22 11:45:03,980 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsExcelTextExtractor for 'application/vnd.ms-excel'
2017-08-22 11:45:03,980 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsExcelTextExtractor for 'application/msexcel'
2017-08-22 11:45:03,980 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsExcelTextExtractor for 'application/excel'
2017-08-22 11:45:03,981 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsExcelTextExtractor for 'application/vnd.ms-excel'
2017-08-22 11:45:03,981 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsExcelTextExtractor for 'application/msexcel'
2017-08-22 11:45:03,981 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsExcelTextExtractor for 'application/excel'
2017-08-22 11:45:03,981 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsPowerPointTextExtractor for 'application/vnd.ms-powerpoint'
2017-08-22 11:45:03,982 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsPowerPointTextExtractor for 'application/mspowerpoint'
2017-08-22 11:45:03,982 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsPowerPointTextExtractor for 'application/powerpoint'
2017-08-22 11:45:03,982 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsPowerPointTextExtractor for 'application/vnd.ms-powerpoint'
2017-08-22 11:45:03,982 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsPowerPointTextExtractor for 'application/mspowerpoint'
2017-08-22 11:45:03,982 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsPowerPointTextExtractor for 'application/powerpoint'
2017-08-22 11:45:03,982 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.OpenOfficeTextExtractor for 'application/vnd.oasis.opendocument.database'
2017-08-22 11:45:03,982 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.OpenOfficeTextExtractor for 'application/vnd.oasis.opendocument.formula'
2017-08-22 11:45:03,982 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.OpenOfficeTextExtractor for 'application/vnd.oasis.opendocument.graphics'
2017-08-22 11:45:03,982 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.OpenOfficeTextExtractor for 'application/vnd.oasis.opendocument.presentation'
2017-08-22 11:45:03,983 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.OpenOfficeTextExtractor for 'application/vnd.oasis.opendocument.spreadsheet'
2017-08-22 11:45:03,983 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.OpenOfficeTextExtractor for 'application/vnd.oasis.opendocument.text'
2017-08-22 11:45:03,983 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.OpenOfficeTextExtractor for 'application/vnd.sun.xml.calc'
2017-08-22 11:45:03,983 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.OpenOfficeTextExtractor for 'application/vnd.sun.xml.draw'
2017-08-22 11:45:03,983 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.OpenOfficeTextExtractor for 'application/vnd.sun.xml.impress'
2017-08-22 11:45:03,983 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.OpenOfficeTextExtractor for 'application/vnd.sun.xml.writer'
2017-08-22 11:45:03,983 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.RTFTextExtractor for 'application/rtf'
2017-08-22 11:45:03,983 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.RTFTextExtractor for 'text/rtf'
2017-08-22 11:45:03,984 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.HTMLTextExtractor for 'text/html'
2017-08-22 11:45:03,984 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.XMLTextExtractor for 'text/xml'
2017-08-22 11:45:03,984 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.XMLTextExtractor for 'application/xml'
2017-08-22 11:45:03,984 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.XMLTextExtractor for 'application/vnd.scribus'
2017-08-22 11:45:03,986 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsOutlookTextExtractor for 'application/vnd.ms-outlook'
2017-08-22 11:45:03,987 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.OpenOfficeTextExtractor for 'application/vnd.oasis.opendocument.database'
2017-08-22 11:45:03,987 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.OpenOfficeTextExtractor for 'application/vnd.oasis.opendocument.formula'
2017-08-22 11:45:03,987 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.OpenOfficeTextExtractor for 'application/vnd.oasis.opendocument.graphics'
2017-08-22 11:45:03,987 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.OpenOfficeTextExtractor for 'application/vnd.oasis.opendocument.presentation'
2017-08-22 11:45:03,987 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.OpenOfficeTextExtractor for 'application/vnd.oasis.opendocument.spreadsheet'
2017-08-22 11:45:03,987 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.OpenOfficeTextExtractor for 'application/vnd.oasis.opendocument.text'
2017-08-22 11:45:03,987 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.OpenOfficeTextExtractor for 'application/vnd.sun.xml.calc'
2017-08-22 11:45:03,987 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.OpenOfficeTextExtractor for 'application/vnd.sun.xml.draw'
2017-08-22 11:45:03,987 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.OpenOfficeTextExtractor for 'application/vnd.sun.xml.impress'
2017-08-22 11:45:03,987 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.OpenOfficeTextExtractor for 'application/vnd.sun.xml.writer'
2017-08-22 11:45:03,988 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.RTFTextExtractor for 'application/rtf'
2017-08-22 11:45:03,988 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.RTFTextExtractor for 'text/rtf'
2017-08-22 11:45:03,988 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.HTMLTextExtractor for 'text/html'
2017-08-22 11:45:03,989 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.PdfTextExtractor for 'application/pdf'
2017-08-22 11:45:03,989 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.XMLTextExtractor for 'text/xml'
2017-08-22 11:45:03,989 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.XMLTextExtractor for 'application/xml'
2017-08-22 11:45:03,989 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.XMLTextExtractor for 'application/vnd.scribus'
2017-08-22 11:45:03,990 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.AudioTextExtractor for 'audio/mpeg'
2017-08-22 11:45:03,990 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.AudioTextExtractor for 'audio/x-ogg'
2017-08-22 11:45:03,991 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsOutlookTextExtractor for 'application/vnd.ms-outlook'
2017-08-22 11:45:03,991 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.ExifTextExtractor for 'image/jpeg'
2017-08-22 11:45:03,992 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.Tesseract3TextExtractor for 'image/tiff'
2017-08-22 11:45:03,992 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.Tesseract3TextExtractor for 'image/gif'
2017-08-22 11:45:03,992 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.Tesseract3TextExtractor for 'image/jpg'
2017-08-22 11:45:03,992 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.Tesseract3TextExtractor for 'image/jpeg'
2017-08-22 11:45:03,992 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.Tesseract3TextExtractor for 'image/png'
2017-08-22 11:45:03,993 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.SourceCodeTextExtractor for 'text/x-java'
2017-08-22 11:45:03,993 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.SourceCodeTextExtractor for 'text/css'
2017-08-22 11:45:03,993 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.SourceCodeTextExtractor for 'text/x-csrc'
2017-08-22 11:45:03,994 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.PdfTextExtractor for 'application/pdf'
2017-08-22 11:45:03,993 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.SourceCodeTextExtractor for 'text/x-sql'
2017-08-22 11:45:03,995 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.SourceCodeTextExtractor for 'application/x-php'
2017-08-22 11:45:03,995 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.SourceCodeTextExtractor for 'application/javascript'
2017-08-22 11:45:03,995 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.AudioTextExtractor for 'audio/mpeg'
2017-08-22 11:45:03,995 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.AudioTextExtractor for 'audio/x-ogg'
2017-08-22 11:45:03,996 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsOffice2007TextExtractor for 'application/vnd.openxmlformats-officedocument.wordprocessingml.document'
2017-08-22 11:45:03,997 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsOffice2007TextExtractor for 'application/vnd.openxmlformats-officedocument.wordprocessingml.template'
2017-08-22 11:45:03,997 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsOffice2007TextExtractor for 'application/vnd.openxmlformats-officedocument.presentationml.template'
2017-08-22 11:45:03,997 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.ExifTextExtractor for 'image/jpeg'
2017-08-22 11:45:03,997 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsOffice2007TextExtractor for 'application/vnd.openxmlformats-officedocument.presentationml.slideshow'
2017-08-22 11:45:03,997 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsOffice2007TextExtractor for 'application/vnd.openxmlformats-officedocument.presentationml.presentation'
2017-08-22 11:45:03,997 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsOffice2007TextExtractor for 'application/vnd.openxmlformats-officedocument.spreadsheetml.sheet'
2017-08-22 11:45:03,997 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsOffice2007TextExtractor for 'application/vnd.openxmlformats-officedocument.spreadsheetml.template'
2017-08-22 11:45:03,998 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.Tesseract3TextExtractor for 'image/tiff'
2017-08-22 11:45:03,998 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.Tesseract3TextExtractor for 'image/gif'
2017-08-22 11:45:03,998 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.Tesseract3TextExtractor for 'image/jpg'
2017-08-22 11:45:03,998 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.Tesseract3TextExtractor for 'image/jpeg'
2017-08-22 11:45:03,998 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.Tesseract3TextExtractor for 'image/png'
2017-08-22 11:45:03,999 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.SourceCodeTextExtractor for 'text/x-java'
2017-08-22 11:45:03,999 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.SourceCodeTextExtractor for 'text/css'
2017-08-22 11:45:03,999 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.SourceCodeTextExtractor for 'text/x-csrc'
2017-08-22 11:45:03,999 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.SourceCodeTextExtractor for 'text/x-sql'
2017-08-22 11:45:03,999 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.SourceCodeTextExtractor for 'application/x-php'
2017-08-22 11:45:03,999 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.SourceCodeTextExtractor for 'application/javascript'
2017-08-22 11:45:04,001 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsOffice2007TextExtractor for 'application/vnd.openxmlformats-officedocument.wordprocessingml.document'
2017-08-22 11:45:04,001 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsOffice2007TextExtractor for 'application/vnd.openxmlformats-officedocument.wordprocessingml.template'
2017-08-22 11:45:04,001 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsOffice2007TextExtractor for 'application/vnd.openxmlformats-officedocument.presentationml.template'
2017-08-22 11:45:04,001 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsOffice2007TextExtractor for 'application/vnd.openxmlformats-officedocument.presentationml.slideshow'
2017-08-22 11:45:04,002 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsOffice2007TextExtractor for 'application/vnd.openxmlformats-officedocument.presentationml.presentation'
2017-08-22 11:45:04,002 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsOffice2007TextExtractor for 'application/vnd.openxmlformats-officedocument.spreadsheetml.sheet'
2017-08-22 11:45:04,002 [localhost-startStop-1] [] INFO  c.o.extractor.RegisteredExtractors - Registering com.openkm.extractor.MsOffice2007TextExtractor for 'application/vnd.openxmlformats-officedocument.spreadsheetml.template'
2017-08-22 11:45:04,063 [localhost-startStop-1] [] INFO  com.openkm.util.EnvironmentDetector - Using LibreOffice from: /usr/lib/libreoffice
2017-08-22 11:45:04,073 [localhost-startStop-1] [] INFO  com.openkm.util.EnvironmentDetector - Using LibreOffice from: /usr/lib/libreoffice
2017-08-22 11:45:04,300 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - activity.log.actions=[LOGIN, LOGOUT, CREATE_.*, DELETE_.*, PURGE_.*, MOVE_.*, COPY_.*, CHECKOUT_DOCUMENT, CHECKIN_DOCUMENT, GET_DOCUMENT_CONTENT.*]
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - amazon.access.key=
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - amazon.secret.key=
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - application.url=http://10.30.40.185:8080/OpenKM/index.jsp
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - cloud.max.repository.size=0
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - activity.log.actions=[LOGIN, LOGOUT, CREATE_.*, DELETE_.*, PURGE_.*, MOVE_.*, COPY_.*, CHECKOUT_DOCUMENT, CHECKIN_DOCUMENT, GET_DOCUMENT_CONTENT.*]
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - cloud.max.users=0
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - amazon.access.key=
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - cloud.mode=false
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - amazon.secret.key=
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - application.url=http://10.30.40.185:8080/OpenKM/index.jsp
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - default.admin.role=ROLE_ADMIN
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - cloud.max.repository.size=0
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - default.lang=
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - cloud.max.users=0
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - default.user.role=ROLE_USER
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - cloud.mode=false
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.create.autofix=true
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.datasource=java:/comp/env/jdbc/OpenKMDS
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - default.admin.role=ROLE_ADMIN
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.dialect=org.hibernate.dialect.MySQL5InnoDBDialect
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - default.lang=
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.hbm2ddl=none
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - default.user.role=ROLE_USER
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.indexer.batch.size.load.objects=30
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.create.autofix=true
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.indexer.mass.indexer=false
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.datasource=java:/comp/env/jdbc/OpenKMDS
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.indexer.threads.index.writer=3
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.dialect=org.hibernate.dialect.MySQL5InnoDBDialect
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.indexer.threads.load.objects=4
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.hbm2ddl=none
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.indexer.threads.subsequent.fetching=8
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.indexer.batch.size.load.objects=30
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.search.analyzer=org.apache.lucene.analysis.standard.StandardAnalyzer
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.indexer.mass.indexer=false
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.search.index.exclusive=true
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.indexer.threads.index.writer=3
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.search.index.home=/home/openkm/tomcat-7.0.61/repository/index
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.indexer.threads.load.objects=4
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.indexer.threads.subsequent.fetching=8
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.show_sql=false
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.search.analyzer=org.apache.lucene.analysis.standard.StandardAnalyzer
2017-08-22 11:45:04,301 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.statistics=false
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.search.index.exclusive=true
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - instance.chroot.path=
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.search.index.home=/home/openkm/tomcat-7.0.61/repository/index
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - instance.home=/home/openkm/tomcat-7.0.61
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.show_sql=false
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - jbpm.config=/home/openkm/tomcat-7.0.61/jbpm.xml
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - hibernate.statistics=false
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - kea.thesaurus.base.url=
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - kea.thesaurus.owl.file=
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - instance.chroot.path=
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - kea.thesaurus.skos.file=
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - instance.home=/home/openkm/tomcat-7.0.61
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - kea.thesaurus.tree.childs=
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - jbpm.config=/home/openkm/tomcat-7.0.61/jbpm.xml
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - kea.thesaurus.tree.root=
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - kea.thesaurus.base.url=
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - kea.thesaurus.vocabulary.serql=
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - kea.thesaurus.owl.file=
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - keep.session.alive.interval=5
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - kea.thesaurus.skos.file=
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - logo.favicon=ABT logo.png
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - kea.thesaurus.tree.childs=
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - logo.login=dose on demand logo.jpg
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - kea.thesaurus.tree.root=
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - logo.mobile=dose on demand logo.jpg
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - kea.thesaurus.vocabulary.serql=
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - logo.report=dose on demand logo.jpg
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - keep.session.alive.interval=5
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - logo.tiny=dose on demand logo.jpg
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - logo.favicon=ABT logo.png
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - managed.text.extraction=true
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - logo.login=dose on demand logo.jpg
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - managed.text.extraction.batch=10
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - logo.mobile=dose on demand logo.jpg
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - managed.text.extraction.concurrent=false
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - logo.report=dose on demand logo.jpg
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - managed.text.extraction.pool.size=5
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - logo.tiny=dose on demand logo.jpg
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - managed.text.extraction.pool.threads=5
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - managed.text.extraction=true
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - managed.text.extraction.pool.timeout=1
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - managed.text.extraction.batch=10
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - max.file.size=0
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - managed.text.extraction.concurrent=false
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - max.search.results=500
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - managed.text.extraction.pool.size=5
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - min.search.characters=3
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - managed.text.extraction.pool.threads=5
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - mobile.theme=b
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - managed.text.extraction.pool.timeout=1
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - native.sql.optimizations=false
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - max.file.size=0
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - notification.message.body=<b>Message: </b>${notificationMessage}<br/><b>User: </b>${userId}<br/><#list documentList as doc><b>Document: </b><a href="${doc.url}">${doc.path}</a><br/></#list>
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - max.search.results=500
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - notification.message.subject=ABT Docs - NOTIFICATION
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - min.search.characters=3
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - notify.twitter.password=
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - mobile.theme=b
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - notify.twitter.status=OpenKM - ${documentUrl} - ${documentPath} - ${userId} - ${eventType}
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - native.sql.optimizations=false
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - notify.twitter.user=
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - notification.message.body=<b>Message: </b>${notificationMessage}<br/><b>User: </b>${userId}<br/><#list documentList as doc><b>Document: </b><a href="${doc.url}">${doc.path}</a><br/></#list>
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - notification.message.subject=ABT Docs - NOTIFICATION
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - openmeetings.credentials=
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - notify.twitter.password=
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - openmeetings.port=
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - notify.twitter.status=OpenKM - ${documentUrl} - ${documentPath} - ${userId} - ${eventType}
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - openmeetings.url=
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - notify.twitter.user=
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - openmeetings.user=
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - openmeetings.credentials=
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - plugin.debug=false
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.adapter=com.openkm.principal.DatabasePrincipalAdapter
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - openmeetings.port=
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.database.filter.inactive.users=true
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - openmeetings.url=
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.hide.connection.roles=false
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - openmeetings.user=
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.identifier.validation=^[a-zA-Z0-9_]+$
2017-08-22 11:45:04,302 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - plugin.debug=false
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.mail.attribute=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.adapter=com.openkm.principal.DatabasePrincipalAdapter
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.mail.search.base=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.database.filter.inactive.users=true
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.mail.search.filter=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.hide.connection.roles=false
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.referral=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.identifier.validation=^[a-zA-Z0-9_]+$
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.role.attribute=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.mail.attribute=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.role.search.base=[]
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.mail.search.base=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.role.search.filter=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.mail.search.filter=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.roles.by.user.attribute=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.referral=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.roles.by.user.search.base=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.role.attribute=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.roles.by.user.search.filter=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.role.search.base=[]
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.security.credentials=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.role.search.filter=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.security.principal=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.server=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.user.attribute=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.user.search.base=[]
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.user.search.filter=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.roles.by.user.attribute=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.username.attribute=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.roles.by.user.search.base=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.username.search.base=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.roles.by.user.search.filter=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.username.search.filter=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.security.credentials=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.users.by.role.attribute=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.users.by.role.search.base=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.security.principal=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.users.by.role.search.filter=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.server=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.users.from.roles=false
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.user.attribute=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - property.groups.cnd=/home/openkm/tomcat-7.0.61/PropertyGroups.cnd
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.user.search.base=[]
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.user.search.filter=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - property.groups.xml=/home/openkm/tomcat-7.0.61/PropertyGroups.xml
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.username.attribute=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - proposed.subscription.message.body=<b>Comment: </b>${proposedSubscriptionComment}<br/><b>User: </b>${userId}<br/><#list documentList as doc><b>Document: </b><a href="${doc.url}">${doc.path}</a><br/></#list>
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.username.search.base=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - proposed.subscription.message.subject=ABT Docs - PROPOSED SUBSCRIPTION
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.username.search.filter=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - registered.text.extractors=[com.openkm.extractor.PlainTextExtractor, com.openkm.extractor.MsWordTextExtractor, com.openkm.extractor.MsExcelTextExtractor, com.openkm.extractor.MsPowerPointTextExtractor, com.openkm.extractor.OpenOfficeTextExtractor, com.openkm.extractor.RTFTextExtractor, com.openkm.extractor.HTMLTextExtractor, com.openkm.extractor.XMLTextExtractor, com.openkm.extractor.MsOutlookTextExtractor, com.openkm.extractor.PdfTextExtractor, com.openkm.extractor.AudioTextExtractor, com.openkm.extractor.ExifTextExtractor, com.openkm.extractor.Tesseract3TextExtractor, com.openkm.extractor.SourceCodeTextExtractor, com.openkm.extractor.MsOffice2007TextExtractor]
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.users.by.role.attribute=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - remote.conversion.server=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.users.by.role.search.base=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - repository.cache.dxf=/home/openkm/tomcat-7.0.61/repository/cache/dxf
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.users.by.role.search.filter=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - repository.cache.home=/home/openkm/tomcat-7.0.61/repository/cache
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - principal.ldap.users.from.roles=false
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - repository.cache.pdf=/home/openkm/tomcat-7.0.61/repository/cache/pdf
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - property.groups.cnd=/home/openkm/tomcat-7.0.61/PropertyGroups.cnd
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - repository.cache.swf=/home/openkm/tomcat-7.0.61/repository/cache/swf
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - repository.config=/dev/null
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - repository.content.checksum=true
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - property.groups.xml=/home/openkm/tomcat-7.0.61/PropertyGroups.xml
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - repository.datastore.backend=fs
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - proposed.subscription.message.body=<b>Comment: </b>${proposedSubscriptionComment}<br/><b>User: </b>${userId}<br/><#list documentList as doc><b>Document: </b><a href="${doc.url}">${doc.path}</a><br/></#list>
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - repository.datastore.home=/home/openkm/tomcat-7.0.61/repository/datastore
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - proposed.subscription.message.subject=ABT Docs - PROPOSED SUBSCRIPTION
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - repository.home=/home/openkm/tomcat-7.0.61/repository
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - registered.text.extractors=[com.openkm.extractor.PlainTextExtractor, com.openkm.extractor.MsWordTextExtractor, com.openkm.extractor.MsExcelTextExtractor, com.openkm.extractor.MsPowerPointTextExtractor, com.openkm.extractor.OpenOfficeTextExtractor, com.openkm.extractor.RTFTextExtractor, com.openkm.extractor.HTMLTextExtractor, com.openkm.extractor.XMLTextExtractor, com.openkm.extractor.MsOutlookTextExtractor, com.openkm.extractor.PdfTextExtractor, com.openkm.extractor.AudioTextExtractor, com.openkm.extractor.ExifTextExtractor, com.openkm.extractor.Tesseract3TextExtractor, com.openkm.extractor.SourceCodeTextExtractor, com.openkm.extractor.MsOffice2007TextExtractor]
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - repository.native=true
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - remote.conversion.server=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - repository.purgatory.home=
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - repository.cache.dxf=/home/openkm/tomcat-7.0.61/repository/cache/dxf
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - repository.stats.optimization=true
2017-08-22 11:45:04,303 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - repository.cache.home=/home/openkm/tomcat-7.0.61/repository/cache
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - restrict.file.mime=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - repository.cache.pdf=/home/openkm/tomcat-7.0.61/repository/cache/pdf
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - restrict.file.name=*~;*.bak
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - schedule.dashboard.refresh=30
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - repository.cache.swf=/home/openkm/tomcat-7.0.61/repository/cache/swf
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - schedule.session.keepalive=15
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - repository.config=/dev/null
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - schedule.ui.notification=1
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - repository.content.checksum=true
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - security.access.manager=simple
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - repository.datastore.backend=fs
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - security.extended.mask=0
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - repository.datastore.home=/home/openkm/tomcat-7.0.61/repository/datastore
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - security.live.change.node.limit=100
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - repository.home=/home/openkm/tomcat-7.0.61/repository
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - security.mode.multiple=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - repository.native=true
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - security.search.evaluation=lucene
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - repository.purgatory.home=
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - send.mail.from.user=true
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - repository.stats.optimization=true
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - store.node.path=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - restrict.file.mime=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - subscription.message.body=<b>Document: </b><a href="${documentUrl}">${documentPath}</a><br/><b>User: </b>${userId}<br/><b>Event: </b>${eventType}<br/><b>Comment: </b>${subscriptionComment}<br/>


dferguson
Expert Boarder
Expert Boarder
Posts: 86
Joined: Thu May 18, 2017 12:48 pm

Re: Startup errors and unreliable behavior

Post by dferguson » Tue Aug 22, 2017 4:16 pm

openkm.log part 3

Code: Select all

2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.login.lowercase=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - subscription.message.subject=ABT Docs - ${eventType} - ${documentPath}
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.maintenance=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.antivir=/usr/bin/clamscan
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.multiple.instances=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.apache.request.header.fix=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.ocr=
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.catdoc.xls2csv=
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.ocr.rotate=
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.demo=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.openoffice.dictionary=
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.document.name.mismatch.check=true
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.openoffice.path=/usr/lib/libreoffice
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.dwg2dxf=
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.openoffice.port=2002
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.execution.timeout=5
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.openoffice.server=
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.ghostscript=/usr/bin/gs
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.openoffice.tasks=200
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.imagemagick.convert=/usr/bin/convert
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.pdf.force.ocr=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.keyword.lowercase=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.pdfimages=/usr/bin/pdfimages -j -f ${firstPage} -l ${lastPage} ${fileIn} ${imageRoot}
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.login.lowercase=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.previewer=flexpaper
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.maintenance=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.profiling=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.multiple.instances=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.readonly=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.ocr=
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.swftools.pdf2swf=/home/openkm/tomcat-7.0.61/bin/pdf2swf -f -T 9 -t -s storeallcharacters ${fileIn} -o ${fileOut}
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.ocr.rotate=
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.webdav.fix=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.openoffice.dictionary=
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.webdav.server=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - text.banner=&nbsp;
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.openoffice.path=/usr/lib/libreoffice
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - text.title=ABT Documents
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.openoffice.port=2002
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - text.welcome=<p>Welcome to ABT Documents !</p><p>Use a valid username and password to access to ABT Docs user Desktop.</p>
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.openoffice.server=
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - tomcat.connector.uri.encoding=ISO-8859-1
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.openoffice.tasks=200
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - upload.throttle.filter=false
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.pdf.force.ocr=false
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - user.admin=okmAdmin
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.pdfimages=/usr/bin/pdfimages -j -f ${firstPage} -l ${lastPage} ${fileIn} ${imageRoot}
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - user.assign.document.creation=true
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.previewer=flexpaper
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - user.item.cache=true
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.profiling=false
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - user.keywords.cache=false
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.readonly=false
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - user.password.reset=false
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.swftools.pdf2swf=/home/openkm/tomcat-7.0.61/bin/pdf2swf -f -T 9 -t -s storeallcharacters ${fileIn} -o ${fileOut}
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - user.system=system
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.webdav.fix=false
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.webdav.server=false
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - text.banner=&nbsp;
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password=com.openkm.validator.password.NoPasswordValidator
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password.max.length=0
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - text.title=ABT Documents
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password.min.digits=0
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - text.welcome=<p>Welcome to ABT Documents !</p><p>Use a valid username and password to access to ABT Docs user Desktop.</p>
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password.min.length=0
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - tomcat.connector.uri.encoding=ISO-8859-1
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password.min.lowercase=0
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password.min.uppercase=0
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - upload.throttle.filter=false
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password.mini.special=0
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - user.admin=okmAdmin
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - version.append.download=false
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - user.assign.document.creation=true
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - version.numeration.adapter=com.openkm.vernum.MajorMinorVersionNumerationAdapter
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - user.item.cache=true
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - version.numeration.format=%d
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - user.keywords.cache=false
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - workflow.run.config.form=run_config
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - user.password.reset=false
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - workflow.start.task.auto.run=true
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - user.system=system
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - zoho.api.key=
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - zoho.password=
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - zoho.secret.key=
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password=com.openkm.validator.password.NoPasswordValidator
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - zoho.user=
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password.max.length=0
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password.min.digits=0
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password.min.length=0
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password.min.lowercase=0
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password.min.uppercase=0
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password.mini.special=0
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - version.append.download=false
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - version.numeration.adapter=com.openkm.vernum.MajorMinorVersionNumerationAdapter
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - version.numeration.format=%d
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - workflow.run.config.form=run_config
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - workflow.start.task.auto.run=true
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - zoho.api.key=
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - zoho.password=
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - zoho.secret.key=
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - zoho.user=
2017-08-22 11:45:04,306 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Repository initializing... ***
2017-08-22 11:45:04,306 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Repository initializing... ***
2017-08-22 11:45:04,392 [localhost-startStop-1] [] INFO  c.o.module.db.stuff.SecurityHelper - Configuring AccessManager with com.openkm.module.db.stuff.DbSimpleAccessManager
2017-08-22 11:45:04,395 [localhost-startStop-1] [] INFO  c.o.module.db.stuff.SecurityHelper - Configuring AccessManager with com.openkm.module.db.stuff.DbSimpleAccessManager
2017-08-22 11:45:04,402 [localhost-startStop-1] [] INFO  c.o.module.db.DbRepositoryModule - Repository already created
2017-08-22 11:45:04,411 [localhost-startStop-1] [] INFO  c.o.module.db.DbRepositoryModule - Repository already created
2017-08-22 11:45:04,440 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Repository initialized ***
2017-08-22 11:45:04,440 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Repository initialized ***
2017-08-22 11:45:04,440 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Cache deserialization ***
2017-08-22 11:45:04,440 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Cache deserialization ***
2017-08-22 11:45:04,473 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** User database initialized ***
2017-08-22 11:45:04,481 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** User database initialized ***
2017-08-22 11:45:04,482 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Initializing workflow engine... ***
2017-08-22 11:45:04,482 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Initializing workflow engine... ***
2017-08-22 11:45:04,483 [localhost-startStop-1] [] INFO  com.openkm.util.JBPMUtils - Creating JBPM default configuration
2017-08-22 11:45:04,483 [localhost-startStop-1] [] INFO  com.openkm.util.JBPMUtils - Creating JBPM default configuration
2017-08-22 11:45:04,534 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Initializing MIME types... ***
2017-08-22 11:45:04,587 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Initializing MIME types... ***
2017-08-22 11:45:04,693 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Activating update info ***
2017-08-22 11:45:04,693 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Activating cron ***
2017-08-22 11:45:04,693 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Activating UI Notification ***
2017-08-22 11:45:04,693 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Activating update info ***
2017-08-22 11:45:04,705 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Activating cron ***
2017-08-22 11:45:04,705 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Activating UI Notification ***
2017-08-22 11:45:05,565 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Activating thesaurus repository ***
2017-08-22 11:45:05,568 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Start OpenOffice manager ***
2017-08-22 11:45:05,616 [localhost-startStop-1] [] INFO  com.openkm.util.DocConverter - *** Build Office Manager ***
2017-08-22 11:45:05,617 [localhost-startStop-1] [] INFO  com.openkm.util.DocConverter - system.openoffice.path=/usr/lib/libreoffice
2017-08-22 11:45:05,617 [localhost-startStop-1] [] INFO  com.openkm.util.DocConverter - system.openoffice.tasks=200
2017-08-22 11:45:05,617 [localhost-startStop-1] [] INFO  com.openkm.util.DocConverter - system.openoffice.port=2002
2017-08-22 11:45:05,632 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Activating thesaurus repository ***
2017-08-22 11:45:05,635 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Start OpenOffice manager ***
2017-08-22 11:45:05,638 [localhost-startStop-1] [] INFO  com.openkm.util.DocConverter - *** Build Office Manager ***
2017-08-22 11:45:05,638 [localhost-startStop-1] [] INFO  com.openkm.util.DocConverter - system.openoffice.path=/usr/lib/libreoffice
2017-08-22 11:45:05,638 [localhost-startStop-1] [] INFO  com.openkm.util.DocConverter - system.openoffice.tasks=200
2017-08-22 11:45:05,638 [localhost-startStop-1] [] INFO  com.openkm.util.DocConverter - system.openoffice.port=2002
2017-08-22 11:45:10,821 [OfficeProcessThread-0] [] WARN  o.a.j.office.ManagedOfficeProcess - office process died with exit code 81; restarting it
2017-08-22 11:45:10,829 [localhost-startStop-1] [] WARN  c.o.servlet.RepositoryStartupServlet - failed to start and connect
org.artofsolving.jodconverter.office.OfficeException: failed to start and connect
	at org.artofsolving.jodconverter.office.ManagedOfficeProcess.startAndWait(ManagedOfficeProcess.java:64) ~[jodconverter-core-3.0-beta-4.jar:na]
	at org.artofsolving.jodconverter.office.PooledOfficeManager.start(PooledOfficeManager.java:101) ~[jodconverter-core-3.0-beta-4.jar:na]
	at org.artofsolving.jodconverter.office.ProcessPoolOfficeManager.start(ProcessPoolOfficeManager.java:62) ~[jodconverter-core-3.0-beta-4.jar:na]
	at com.openkm.util.DocConverter.start(DocConverter.java:153) ~[DocConverter.class:na]
	at com.openkm.servlet.RepositoryStartupServlet.start(RepositoryStartupServlet.java:283) [RepositoryStartupServlet.class:na]
	at com.openkm.servlet.RepositoryStartupServlet.init(RepositoryStartupServlet.java:121) [RepositoryStartupServlet.class:na]
	at javax.servlet.GenericServlet.init(GenericServlet.java:158) [servlet-api.jar:3.0.FR]
	at org.apache.catalina.core.StandardWrapper.initServlet(StandardWrapper.java:1284) [catalina.jar:7.0.61]
	at org.apache.catalina.core.StandardWrapper.loadServlet(StandardWrapper.java:1197) [catalina.jar:7.0.61]
	at org.apache.catalina.core.StandardWrapper.load(StandardWrapper.java:1087) [catalina.jar:7.0.61]
	at org.apache.catalina.core.StandardContext.loadOnStartup(StandardContext.java:5266) [catalina.jar:7.0.61]
	at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5554) [catalina.jar:7.0.61]
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) [catalina.jar:7.0.61]
	at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:901) [catalina.jar:7.0.61]
	at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:877) [catalina.jar:7.0.61]
	at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:652) [catalina.jar:7.0.61]
	at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:1090) [catalina.jar:7.0.61]
	at org.apache.catalina.startup.HostConfig$DeployWar.run(HostConfig.java:1900) [catalina.jar:7.0.61]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [na:1.8.0_144]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [na:1.8.0_144]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [na:1.8.0_144]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [na:1.8.0_144]
	at java.lang.Thread.run(Thread.java:748) [na:1.8.0_144]
Caused by: java.util.concurrent.ExecutionException: org.artofsolving.jodconverter.office.OfficeException: could not establish connection
	at java.util.concurrent.FutureTask.report(FutureTask.java:122) [na:1.8.0_144]
	at java.util.concurrent.FutureTask.get(FutureTask.java:192) [na:1.8.0_144]
	at org.artofsolving.jodconverter.office.ManagedOfficeProcess.startAndWait(ManagedOfficeProcess.java:62) ~[jodconverter-core-3.0-beta-4.jar:na]
	... 22 common frames omitted
Caused by: org.artofsolving.jodconverter.office.OfficeException: could not establish connection
	at org.artofsolving.jodconverter.office.ManagedOfficeProcess.doStartProcessAndConnect(ManagedOfficeProcess.java:142) ~[jodconverter-core-3.0-beta-4.jar:na]
	at org.artofsolving.jodconverter.office.ManagedOfficeProcess.access$000(ManagedOfficeProcess.java:31) ~[jodconverter-core-3.0-beta-4.jar:na]
	at org.artofsolving.jodconverter.office.ManagedOfficeProcess$1.run(ManagedOfficeProcess.java:58) ~[jodconverter-core-3.0-beta-4.jar:na]
	... 5 common frames omitted
Caused by: java.lang.IllegalStateException: a process with acceptString 'socket,host=127.0.0.1,port=2002' is already running; pid 2264
	at org.artofsolving.jodconverter.office.OfficeProcess.start(OfficeProcess.java:67) ~[jodconverter-core-3.0-beta-4.jar:na]
	at org.artofsolving.jodconverter.office.ManagedOfficeProcess$6.attempt(ManagedOfficeProcess.java:133) ~[jodconverter-core-3.0-beta-4.jar:na]
	at org.artofsolving.jodconverter.office.Retryable.execute(Retryable.java:40) ~[jodconverter-core-3.0-beta-4.jar:na]
	at org.artofsolving.jodconverter.office.Retryable.execute(Retryable.java:30) ~[jodconverter-core-3.0-beta-4.jar:na]
	at org.artofsolving.jodconverter.office.ManagedOfficeProcess.doStartProcessAndConnect(ManagedOfficeProcess.java:120) ~[jodconverter-core-3.0-beta-4.jar:na]
	... 7 common frames omitted
2017-08-22 11:45:10,830 [localhost-startStop-1] [] INFO  c.o.extension.core.ExtensionManager - Initialize and load plugins...
2017-08-22 11:45:10,867 [localhost-startStop-1] [] WARN  n.x.p.b.i.c.loader.FileLoader - Supplied path does not exist. Unable to add plugins from there.
2017-08-22 11:45:10,868 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Execute start script ***
2017-08-22 11:45:10,869 [localhost-startStop-1] [] WARN  com.openkm.util.ExecutionUtils - Unable to read script: /home/openkm/tomcat-7.0.61/start.bsh
2017-08-22 11:45:10,869 [localhost-startStop-1] [] WARN  com.openkm.util.ExecutionUtils - Unable to read jar: /home/openkm/tomcat-7.0.61/start.jar
2017-08-22 11:45:10,869 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Execute start SQL ***
2017-08-22 11:45:10,869 [localhost-startStop-1] [] WARN  c.o.servlet.RepositoryStartupServlet - Unable to read sql: /home/openkm/tomcat-7.0.61/start.sql
2017-08-22 11:45:10,872 [localhost-startStop-1] [] WARN  o.a.c.o.s.i.a.CmisAtomPubServlet - CMIS version is not defined! Setting it to CMIS 1.0.
2017-08-22 11:45:11,339 [OfficeProcessThread-0] [] WARN  o.a.j.office.ManagedOfficeProcess - office process died with exit code 81; restarting it
2017-08-22 11:45:11,693 [localhost-startStop-1] [] INFO  c.o.extension.core.ExtensionManager - Initialize and load plugins...
2017-08-22 11:45:11,735 [localhost-startStop-1] [] WARN  n.x.p.b.i.c.loader.FileLoader - Supplied path does not exist. Unable to add plugins from there.
2017-08-22 11:45:11,735 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Execute start script ***
2017-08-22 11:45:11,737 [localhost-startStop-1] [] WARN  com.openkm.util.ExecutionUtils - Unable to read script: /home/openkm/tomcat-7.0.61/start.bsh
2017-08-22 11:45:11,737 [localhost-startStop-1] [] WARN  com.openkm.util.ExecutionUtils - Unable to read jar: /home/openkm/tomcat-7.0.61/start.jar
2017-08-22 11:45:11,737 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Execute start SQL ***
2017-08-22 11:45:11,737 [localhost-startStop-1] [] WARN  c.o.servlet.RepositoryStartupServlet - Unable to read sql: /home/openkm/tomcat-7.0.61/start.sql
2017-08-22 11:45:11,740 [localhost-startStop-1] [] WARN  o.a.c.o.s.i.a.CmisAtomPubServlet - CMIS version is not defined! Setting it to CMIS 1.0.
2017-08-22 11:45:13,396 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Shutting down OpenOffice manager ***
2017-08-22 11:45:13,687 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Shutting down UI Notification... ***
2017-08-22 11:45:13,687 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Shutting down cron... ***
2017-08-22 11:45:13,687 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Shutting down update info... ***
2017-08-22 11:45:13,687 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Shutting pending task executor... ***
2017-08-22 11:45:13,689 [localhost-startStop-1] [] INFO  c.o.u.pendtask.PendingTaskExecutor - ### All threads shutdown requested ###
2017-08-22 11:45:13,689 [localhost-startStop-1] [] INFO  c.o.u.pendtask.PendingTaskExecutor - ### All threads have finished ###
2017-08-22 11:45:13,689 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Shutting down repository... ***
2017-08-22 11:45:13,689 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Cache serialization ***
2017-08-22 11:45:13,709 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Repository shutted down ***
2017-08-22 11:45:13,709 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Execute stop script ***
2017-08-22 11:45:13,709 [localhost-startStop-1] [] WARN  com.openkm.util.ExecutionUtils - Unable to read script: /home/openkm/tomcat-7.0.61/stop.bsh
2017-08-22 11:45:13,709 [localhost-startStop-1] [] WARN  com.openkm.util.ExecutionUtils - Unable to read jar: /home/openkm/tomcat-7.0.61/stop.jar
2017-08-22 11:45:13,709 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Execute stop SQL ***
2017-08-22 11:45:13,709 [localhost-startStop-1] [] WARN  c.o.servlet.RepositoryStartupServlet - Unable to read sql: /home/openkm/tomcat-7.0.61/stop.sql
2017-08-22 11:45:13,709 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Shutting down workflow engine... ***
2017-08-22 11:45:13,731 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Shutting down cache manager... ***
2017-08-22 11:45:13,753 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Hibernate shutdown ***
2017-08-22 11:50:06,472 [Update Info] [] INFO  com.openkm.core.UpdateInfo - *** UpdateInfo activated ***
2017-08-22 11:50:06,474 [Update Info] [] INFO  c.o.module.common.CommonAuthModule - PrincipalAdapter: com.openkm.principal.DatabasePrincipalAdapter
2017-08-22 11:50:06,897 [Update Info] [] INFO  com.openkm.util.Update - checkVersion: 
2017-08-22 12:00:00,195 [Thread-18] [] INFO  com.openkm.core.UserMailImporter - *** User mail importer activated ***2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.login.lowercase=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - subscription.message.subject=ABT Docs - ${eventType} - ${documentPath}
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.maintenance=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.antivir=/usr/bin/clamscan
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.multiple.instances=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.apache.request.header.fix=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.ocr=
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.catdoc.xls2csv=
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.ocr.rotate=
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.demo=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.openoffice.dictionary=
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.document.name.mismatch.check=true
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.openoffice.path=/usr/lib/libreoffice
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.dwg2dxf=
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.openoffice.port=2002
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.execution.timeout=5
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.openoffice.server=
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.ghostscript=/usr/bin/gs
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.openoffice.tasks=200
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.imagemagick.convert=/usr/bin/convert
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.pdf.force.ocr=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.keyword.lowercase=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.pdfimages=/usr/bin/pdfimages -j -f ${firstPage} -l ${lastPage} ${fileIn} ${imageRoot}
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.login.lowercase=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.previewer=flexpaper
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.maintenance=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.profiling=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.multiple.instances=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.readonly=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.ocr=
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.swftools.pdf2swf=/home/openkm/tomcat-7.0.61/bin/pdf2swf -f -T 9 -t -s storeallcharacters ${fileIn} -o ${fileOut}
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.ocr.rotate=
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.webdav.fix=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.openoffice.dictionary=
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.webdav.server=false
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - text.banner=&nbsp;
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.openoffice.path=/usr/lib/libreoffice
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - text.title=ABT Documents
2017-08-22 11:45:04,304 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.openoffice.port=2002
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - text.welcome=<p>Welcome to ABT Documents !</p><p>Use a valid username and password to access to ABT Docs user Desktop.</p>
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.openoffice.server=
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - tomcat.connector.uri.encoding=ISO-8859-1
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.openoffice.tasks=200
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - upload.throttle.filter=false
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.pdf.force.ocr=false
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - user.admin=okmAdmin
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.pdfimages=/usr/bin/pdfimages -j -f ${firstPage} -l ${lastPage} ${fileIn} ${imageRoot}
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - user.assign.document.creation=true
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.previewer=flexpaper
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - user.item.cache=true
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.profiling=false
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - user.keywords.cache=false
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.readonly=false
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - user.password.reset=false
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.swftools.pdf2swf=/home/openkm/tomcat-7.0.61/bin/pdf2swf -f -T 9 -t -s storeallcharacters ${fileIn} -o ${fileOut}
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - user.system=system
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.webdav.fix=false
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - system.webdav.server=false
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - text.banner=&nbsp;
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password=com.openkm.validator.password.NoPasswordValidator
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password.max.length=0
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - text.title=ABT Documents
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password.min.digits=0
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - text.welcome=<p>Welcome to ABT Documents !</p><p>Use a valid username and password to access to ABT Docs user Desktop.</p>
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password.min.length=0
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - tomcat.connector.uri.encoding=ISO-8859-1
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password.min.lowercase=0
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password.min.uppercase=0
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - upload.throttle.filter=false
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password.mini.special=0
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - user.admin=okmAdmin
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - version.append.download=false
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - user.assign.document.creation=true
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - version.numeration.adapter=com.openkm.vernum.MajorMinorVersionNumerationAdapter
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - user.item.cache=true
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - version.numeration.format=%d
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - user.keywords.cache=false
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - workflow.run.config.form=run_config
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - user.password.reset=false
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - workflow.start.task.auto.run=true
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - user.system=system
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - zoho.api.key=
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - zoho.password=
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - zoho.secret.key=
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password=com.openkm.validator.password.NoPasswordValidator
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - zoho.user=
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password.max.length=0
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password.min.digits=0
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password.min.length=0
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password.min.lowercase=0
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password.min.uppercase=0
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - validator.password.mini.special=0
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - version.append.download=false
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - version.numeration.adapter=com.openkm.vernum.MajorMinorVersionNumerationAdapter
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - version.numeration.format=%d
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - workflow.run.config.form=run_config
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - workflow.start.task.auto.run=true
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - zoho.api.key=
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - zoho.password=
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - zoho.secret.key=
2017-08-22 11:45:04,305 [localhost-startStop-1] [] INFO  com.openkm.core.Config - RELOAD - zoho.user=
2017-08-22 11:45:04,306 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Repository initializing... ***
2017-08-22 11:45:04,306 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Repository initializing... ***
2017-08-22 11:45:04,392 [localhost-startStop-1] [] INFO  c.o.module.db.stuff.SecurityHelper - Configuring AccessManager with com.openkm.module.db.stuff.DbSimpleAccessManager
2017-08-22 11:45:04,395 [localhost-startStop-1] [] INFO  c.o.module.db.stuff.SecurityHelper - Configuring AccessManager with com.openkm.module.db.stuff.DbSimpleAccessManager
2017-08-22 11:45:04,402 [localhost-startStop-1] [] INFO  c.o.module.db.DbRepositoryModule - Repository already created
2017-08-22 11:45:04,411 [localhost-startStop-1] [] INFO  c.o.module.db.DbRepositoryModule - Repository already created
2017-08-22 11:45:04,440 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Repository initialized ***
2017-08-22 11:45:04,440 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Repository initialized ***
2017-08-22 11:45:04,440 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Cache deserialization ***
2017-08-22 11:45:04,440 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Cache deserialization ***
2017-08-22 11:45:04,473 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** User database initialized ***
2017-08-22 11:45:04,481 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** User database initialized ***
2017-08-22 11:45:04,482 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Initializing workflow engine... ***
2017-08-22 11:45:04,482 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Initializing workflow engine... ***
2017-08-22 11:45:04,483 [localhost-startStop-1] [] INFO  com.openkm.util.JBPMUtils - Creating JBPM default configuration
2017-08-22 11:45:04,483 [localhost-startStop-1] [] INFO  com.openkm.util.JBPMUtils - Creating JBPM default configuration
2017-08-22 11:45:04,534 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Initializing MIME types... ***
2017-08-22 11:45:04,587 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Initializing MIME types... ***
2017-08-22 11:45:04,693 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Activating update info ***
2017-08-22 11:45:04,693 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Activating cron ***
2017-08-22 11:45:04,693 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Activating UI Notification ***
2017-08-22 11:45:04,693 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Activating update info ***
2017-08-22 11:45:04,705 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Activating cron ***
2017-08-22 11:45:04,705 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Activating UI Notification ***
2017-08-22 11:45:05,565 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Activating thesaurus repository ***
2017-08-22 11:45:05,568 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Start OpenOffice manager ***
2017-08-22 11:45:05,616 [localhost-startStop-1] [] INFO  com.openkm.util.DocConverter - *** Build Office Manager ***
2017-08-22 11:45:05,617 [localhost-startStop-1] [] INFO  com.openkm.util.DocConverter - system.openoffice.path=/usr/lib/libreoffice
2017-08-22 11:45:05,617 [localhost-startStop-1] [] INFO  com.openkm.util.DocConverter - system.openoffice.tasks=200
2017-08-22 11:45:05,617 [localhost-startStop-1] [] INFO  com.openkm.util.DocConverter - system.openoffice.port=2002
2017-08-22 11:45:05,632 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Activating thesaurus repository ***
2017-08-22 11:45:05,635 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Start OpenOffice manager ***
2017-08-22 11:45:05,638 [localhost-startStop-1] [] INFO  com.openkm.util.DocConverter - *** Build Office Manager ***
2017-08-22 11:45:05,638 [localhost-startStop-1] [] INFO  com.openkm.util.DocConverter - system.openoffice.path=/usr/lib/libreoffice
2017-08-22 11:45:05,638 [localhost-startStop-1] [] INFO  com.openkm.util.DocConverter - system.openoffice.tasks=200
2017-08-22 11:45:05,638 [localhost-startStop-1] [] INFO  com.openkm.util.DocConverter - system.openoffice.port=2002
2017-08-22 11:45:10,821 [OfficeProcessThread-0] [] WARN  o.a.j.office.ManagedOfficeProcess - office process died with exit code 81; restarting it
2017-08-22 11:45:10,829 [localhost-startStop-1] [] WARN  c.o.servlet.RepositoryStartupServlet - failed to start and connect
org.artofsolving.jodconverter.office.OfficeException: failed to start and connect
	at org.artofsolving.jodconverter.office.ManagedOfficeProcess.startAndWait(ManagedOfficeProcess.java:64) ~[jodconverter-core-3.0-beta-4.jar:na]
	at org.artofsolving.jodconverter.office.PooledOfficeManager.start(PooledOfficeManager.java:101) ~[jodconverter-core-3.0-beta-4.jar:na]
	at org.artofsolving.jodconverter.office.ProcessPoolOfficeManager.start(ProcessPoolOfficeManager.java:62) ~[jodconverter-core-3.0-beta-4.jar:na]
	at com.openkm.util.DocConverter.start(DocConverter.java:153) ~[DocConverter.class:na]
	at com.openkm.servlet.RepositoryStartupServlet.start(RepositoryStartupServlet.java:283) [RepositoryStartupServlet.class:na]
	at com.openkm.servlet.RepositoryStartupServlet.init(RepositoryStartupServlet.java:121) [RepositoryStartupServlet.class:na]
	at javax.servlet.GenericServlet.init(GenericServlet.java:158) [servlet-api.jar:3.0.FR]
	at org.apache.catalina.core.StandardWrapper.initServlet(StandardWrapper.java:1284) [catalina.jar:7.0.61]
	at org.apache.catalina.core.StandardWrapper.loadServlet(StandardWrapper.java:1197) [catalina.jar:7.0.61]
	at org.apache.catalina.core.StandardWrapper.load(StandardWrapper.java:1087) [catalina.jar:7.0.61]
	at org.apache.catalina.core.StandardContext.loadOnStartup(StandardContext.java:5266) [catalina.jar:7.0.61]
	at org.apache.catalina.core.StandardContext.startInternal(StandardContext.java:5554) [catalina.jar:7.0.61]
	at org.apache.catalina.util.LifecycleBase.start(LifecycleBase.java:150) [catalina.jar:7.0.61]
	at org.apache.catalina.core.ContainerBase.addChildInternal(ContainerBase.java:901) [catalina.jar:7.0.61]
	at org.apache.catalina.core.ContainerBase.addChild(ContainerBase.java:877) [catalina.jar:7.0.61]
	at org.apache.catalina.core.StandardHost.addChild(StandardHost.java:652) [catalina.jar:7.0.61]
	at org.apache.catalina.startup.HostConfig.deployWAR(HostConfig.java:1090) [catalina.jar:7.0.61]
	at org.apache.catalina.startup.HostConfig$DeployWar.run(HostConfig.java:1900) [catalina.jar:7.0.61]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [na:1.8.0_144]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [na:1.8.0_144]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [na:1.8.0_144]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [na:1.8.0_144]
	at java.lang.Thread.run(Thread.java:748) [na:1.8.0_144]
Caused by: java.util.concurrent.ExecutionException: org.artofsolving.jodconverter.office.OfficeException: could not establish connection
	at java.util.concurrent.FutureTask.report(FutureTask.java:122) [na:1.8.0_144]
	at java.util.concurrent.FutureTask.get(FutureTask.java:192) [na:1.8.0_144]
	at org.artofsolving.jodconverter.office.ManagedOfficeProcess.startAndWait(ManagedOfficeProcess.java:62) ~[jodconverter-core-3.0-beta-4.jar:na]
	... 22 common frames omitted
Caused by: org.artofsolving.jodconverter.office.OfficeException: could not establish connection
	at org.artofsolving.jodconverter.office.ManagedOfficeProcess.doStartProcessAndConnect(ManagedOfficeProcess.java:142) ~[jodconverter-core-3.0-beta-4.jar:na]
	at org.artofsolving.jodconverter.office.ManagedOfficeProcess.access$000(ManagedOfficeProcess.java:31) ~[jodconverter-core-3.0-beta-4.jar:na]
	at org.artofsolving.jodconverter.office.ManagedOfficeProcess$1.run(ManagedOfficeProcess.java:58) ~[jodconverter-core-3.0-beta-4.jar:na]
	... 5 common frames omitted
Caused by: java.lang.IllegalStateException: a process with acceptString 'socket,host=127.0.0.1,port=2002' is already running; pid 2264
	at org.artofsolving.jodconverter.office.OfficeProcess.start(OfficeProcess.java:67) ~[jodconverter-core-3.0-beta-4.jar:na]
	at org.artofsolving.jodconverter.office.ManagedOfficeProcess$6.attempt(ManagedOfficeProcess.java:133) ~[jodconverter-core-3.0-beta-4.jar:na]
	at org.artofsolving.jodconverter.office.Retryable.execute(Retryable.java:40) ~[jodconverter-core-3.0-beta-4.jar:na]
	at org.artofsolving.jodconverter.office.Retryable.execute(Retryable.java:30) ~[jodconverter-core-3.0-beta-4.jar:na]
	at org.artofsolving.jodconverter.office.ManagedOfficeProcess.doStartProcessAndConnect(ManagedOfficeProcess.java:120) ~[jodconverter-core-3.0-beta-4.jar:na]

dferguson
Expert Boarder
Expert Boarder
Posts: 86
Joined: Thu May 18, 2017 12:48 pm

Re: Startup errors and unreliable behavior

Post by dferguson » Tue Aug 22, 2017 6:45 pm

Now I can't even get OpenKM to run. I keep seeing this in the openkm.log

Code: Select all

017-08-22 14:36:05,552 [localhost-startStop-1] [] INFO  c.o.extension.core.ExtensionManager - Initialize and load plugins...
2017-08-22 14:36:05,552 [localhost-startStop-1] [] INFO  c.o.extension.core.ExtensionManager - Initialize and load plugins...
2017-08-22 14:36:05,594 [localhost-startStop-1] [] WARN  n.x.p.b.i.c.loader.FileLoader - Supplied path does not exist. Unable to add plugins from there.
2017-08-22 14:36:05,595 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Execute start script ***
2017-08-22 14:36:05,596 [localhost-startStop-1] [] WARN  com.openkm.util.ExecutionUtils - Unable to read script: /home/openkm/tomcat-7.0.61/start.bsh
2017-08-22 14:36:05,596 [localhost-startStop-1] [] WARN  com.openkm.util.ExecutionUtils - Unable to read jar: /home/openkm/tomcat-7.0.61/start.jar
2017-08-22 14:36:05,596 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Execute start SQL ***
2017-08-22 14:36:05,596 [localhost-startStop-1] [] WARN  c.o.servlet.RepositoryStartupServlet - Unable to read sql: /home/openkm/tomcat-7.0.61/start.sql
2017-08-22 14:36:05,599 [localhost-startStop-1] [] WARN  o.a.c.o.s.i.a.CmisAtomPubServlet - CMIS version is not defined! Setting it to CMIS 1.0.
2017-08-22 14:36:05,614 [localhost-startStop-1] [] WARN  n.x.p.b.i.c.loader.FileLoader - Supplied path does not exist. Unable to add plugins from there.
2017-08-22 14:36:05,615 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Execute start script ***
2017-08-22 14:36:05,616 [localhost-startStop-1] [] WARN  com.openkm.util.ExecutionUtils - Unable to read script: /home/openkm/tomcat-7.0.61/start.bsh
2017-08-22 14:36:05,616 [localhost-startStop-1] [] WARN  com.openkm.util.ExecutionUtils - Unable to read jar: /home/openkm/tomcat-7.0.61/start.jar
2017-08-22 14:36:05,616 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Execute start SQL ***
2017-08-22 14:36:05,616 [localhost-startStop-1] [] WARN  c.o.servlet.RepositoryStartupServlet - Unable to read sql: /home/openkm/tomcat-7.0.61/start.sql
2017-08-22 14:36:05,619 [localhost-startStop-1] [] WARN  o.a.c.o.s.i.a.CmisAtomPubServlet - CMIS version is not defined! Setting it to CMIS 1.0.
2017-08-22 14:36:07,553 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Shutting down OpenOffice manager ***
2017-08-22 14:36:07,553 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Shutting down UI Notification... ***
2017-08-22 14:36:07,553 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Shutting down cron... ***
2017-08-22 14:36:07,553 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Shutting down update info... ***
2017-08-22 14:36:07,553 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Shutting pending task executor... ***
2017-08-22 14:36:07,555 [localhost-startStop-1] [] INFO  c.o.u.pendtask.PendingTaskExecutor - ### All threads shutdown requested ###
2017-08-22 14:36:07,555 [localhost-startStop-1] [] INFO  c.o.u.pendtask.PendingTaskExecutor - ### All threads have finished ###
2017-08-22 14:36:07,555 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Shutting down repository... ***
2017-08-22 14:36:07,555 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Cache serialization ***
2017-08-22 14:36:07,570 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Repository shutted down ***
2017-08-22 14:36:07,570 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Execute stop script ***
2017-08-22 14:36:07,570 [localhost-startStop-1] [] WARN  com.openkm.util.ExecutionUtils - Unable to read script: /home/openkm/tomcat-7.0.61/stop.bsh
2017-08-22 14:36:07,570 [localhost-startStop-1] [] WARN  com.openkm.util.ExecutionUtils - Unable to read jar: /home/openkm/tomcat-7.0.61/stop.jar
2017-08-22 14:36:07,570 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Execute stop SQL ***
2017-08-22 14:36:07,570 [localhost-startStop-1] [] WARN  c.o.servlet.RepositoryStartupServlet - Unable to read sql: /home/openkm/tomcat-7.0.61/stop.sql
2017-08-22 14:36:07,570 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Shutting down workflow engine... ***
2017-08-22 14:36:07,590 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Shutting down cache manager... ***
2017-08-22 14:36:07,606 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Hibernate shutdown ***
2017-08-22 14:41:08,326 [Update Info] [] INFO  com.openkm.core.UpdateInfo - *** UpdateInfo activated ***
2017-08-22 14:41:08,330 [Update Info] [] INFO  c.o.module.common.CommonAuthModule - PrincipalAdapter: com.openkm.principal.DatabasePrincipalAdapter
2017-08-22 14:41:08,852 [Update Info] [] INFO  com.openkm.util.Update - checkVersion: 

jllort
Moderator
Moderator
Posts: 9892
Joined: Fri Dec 21, 2007 11:23 am
Location: Sineu - ( Illes Balears ) - Spain
Contact:

Re: Startup errors and unreliable behavior

Post by jllort » Wed Aug 23, 2017 11:05 am

For future log sharing. Better if you stop application, remove all logs, try starting application and share with us the catalina.log file into zip ( you can attach as part of the post )

About the error:

Code: Select all

java.net.BindException: Address already in use (Bind failed) /0.0.0.0:8080
The tomcat can not use port 8080 because there's another application what is using it ( or tomcat previously has not been correctly stopped and is still running, but I suppose you have the port used by another application ). here you have two options, stop the other application or change tomcat default ports ( https://docs.openkm.com/kcenter/view/ok ... -port.html )

dferguson
Expert Boarder
Expert Boarder
Posts: 86
Joined: Thu May 18, 2017 12:48 pm

Re: Startup errors and unreliable behavior

Post by dferguson » Wed Aug 23, 2017 1:02 pm

When I looked into who was using the port, I couldn't get father than /java.

Found some literature referencing JBoss Dev using that port as well. Even though I have JBoss and OpenKM on a VM running just fine, I removed JBoss and the launch seems better now.

dferguson
Expert Boarder
Expert Boarder
Posts: 86
Joined: Thu May 18, 2017 12:48 pm

Re: Startup errors and unreliable behavior

Post by dferguson » Thu Nov 30, 2017 3:14 pm

I had to resinstall JBoss and started having the same issues. Followed instructions to swap ports and everything works great and start up is super fast now. Thanks.

dferguson
Expert Boarder
Expert Boarder
Posts: 86
Joined: Thu May 18, 2017 12:48 pm

Re: Startup errors and unreliable behavior

Post by dferguson » Mon Jan 08, 2018 4:16 pm

This behavior has returned...

Code: Select all

Jan 08, 2018 11:00:34 AM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deployment of web application directory /home/openkm/tomcat-7.0.61/webapps/ROOT has finished in 2,112 ms
Jan 08, 2018 11:00:34 AM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["http-bio-10.30.40.185-8180"]
Jan 08, 2018 11:00:34 AM org.apache.coyote.AbstractProtocol start
INFO: Starting ProtocolHandler ["ajp-bio-127.0.0.1-8109"]
Jan 08, 2018 11:00:34 AM org.apache.catalina.startup.Catalina start
INFO: Server startup in 55644 ms
Jan 08, 2018 11:00:34 AM org.apache.catalina.core.StandardServer await
SEVERE: StandardServer.await: create[localhost:8015]: 
java.net.BindException: Address already in use (Bind failed)
	at java.net.PlainSocketImpl.socketBind(Native Method)
	at java.net.AbstractPlainSocketImpl.bind(AbstractPlainSocketImpl.java:387)
	at java.net.ServerSocket.bind(ServerSocket.java:375)
	at java.net.ServerSocket.<init>(ServerSocket.java:237)
	at org.apache.catalina.core.StandardServer.await(StandardServer.java:427)
	at org.apache.catalina.startup.Catalina.await(Catalina.java:777)
	at org.apache.catalina.startup.Catalina.start(Catalina.java:723)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:498)
	at org.apache.catalina.startup.Bootstrap.start(Bootstrap.java:321)
	at org.apache.catalina.startup.Bootstrap.main(Bootstrap.java:455)

Jan 08, 2018 11:00:34 AM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["http-bio-10.30.40.185-8180"]
Jan 08, 2018 11:00:34 AM org.apache.coyote.AbstractProtocol pause
INFO: Pausing ProtocolHandler ["ajp-bio-127.0.0.1-8109"]
Jan 08, 2018 11:00:34 AM org.apache.catalina.core.StandardService stopInternal
INFO: Stopping service Catalina
Jan 08, 2018 11:00:34 AM org.artofsolving.jodconverter.office.ProcessPoolOfficeManager stop
INFO: stopping
Jan 08, 2018 11:00:34 AM org.artofsolving.jodconverter.office.OfficeConnection$1 disposing
INFO: disconnected: 'socket,host=127.0.0.1,port=2002,tcpNoDelay=1'
Jan 08, 2018 11:00:34 AM org.artofsolving.jodconverter.office.OfficeConnection$1 disposing
INFO: disconnected: 'socket,host=127.0.0.1,port=2002,tcpNoDelay=1'
Jan 08, 2018 11:00:34 AM org.artofsolving.jodconverter.office.PooledOfficeManager$1 disconnected
WARNING: connection lost unexpectedly; attempting restart
Jan 08, 2018 11:00:34 AM org.artofsolving.jodconverter.office.ManagedOfficeProcess doEnsureProcessExited
INFO: process exited with code 0
Jan 08, 2018 11:00:35 AM org.artofsolving.jodconverter.office.ManagedOfficeProcess doEnsureProcessExited
INFO: process exited with code 0
Jan 08, 2018 11:00:35 AM org.artofsolving.jodconverter.office.ProcessPoolOfficeManager stop
INFO: stopped
Jan 08, 2018 11:00:35 AM org.artofsolving.jodconverter.office.OfficeProcess deleteProfileDir
SEVERE: could not delete profileDir: File does not exist: /home/openkm/tomcat-7.0.61/temp/.jodconverter_socket_host-127.0.0.1_port-2002/user/backup
Jan 08, 2018 11:00:35 AM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: starting process with acceptString 'socket,host=127.0.0.1,port=2002,tcpNoDelay=1' and profileDir '/home/openkm/tomcat-7.0.61/temp/.jodconverter_socket_host-127.0.0.1_port-2002'
Jan 08, 2018 11:00:35 AM org.artofsolving.jodconverter.office.OfficeProcess start
INFO: started process; pid = 2230
Jan 08, 2018 11:00:35 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/OpenKM] appears to have started a thread named [Abandoned connection cleanup thread] but has failed to stop it. This is very likely to create a memory leak.
Jan 08, 2018 11:00:35 AM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/OpenKM] appears to have started a thread named [OfficeProcessThread-0] but has failed to stop it. This is very likely to create a memory leak.
Jan 08, 2018 11:00:35 AM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/OpenKM] created a ThreadLocal with key of type [java.lang.ThreadLocal] (value [java.lang.ThreadLocal@4ed16ca0]) and a value of type [org.springframework.security.core.context.SecurityContextImpl] (value [org.springframework.security.core.context.SecurityContextImpl@ffffffff: Null authentication]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
Jan 08, 2018 11:00:35 AM org.apache.catalina.loader.WebappClassLoader checkThreadLocalMapForLeaks
SEVERE: The web application [/OpenKM] created a ThreadLocal with key of type [com.sun.xml.bind.v2.ClassFactory$1] (value [com.sun.xml.bind.v2.ClassFactory$1@264b648a]) and a value of type [java.util.WeakHashMap] (value [{class javax.xml.bind.annotation.W3CDomHandler=java.lang.ref.WeakReference@66964bfa}]) but failed to remove it when the web application was stopped. Threads are going to be renewed over time to try and avoid a probable memory leak.
Jan 08, 2018 11:00:35 AM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["http-bio-10.30.40.185-8180"]
Jan 08, 2018 11:00:35 AM org.apache.coyote.AbstractProtocol stop
INFO: Stopping ProtocolHandler ["ajp-bio-127.0.0.1-8109"]
Jan 08, 2018 11:00:35 AM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["http-bio-10.30.40.185-8180"]
Jan 08, 2018 11:00:35 AM org.apache.coyote.AbstractProtocol destroy
INFO: Destroying ProtocolHandler ["ajp-bio-127.0.0.1-8109"]
Jan 08, 2018 11:00:36 AM org.artofsolving.jodconverter.office.OfficeConnection connect
INFO: connected: 'socket,host=127.0.0.1,port=2002,tcpNoDelay=1'
Attachments
logs.tar.gz
(12.94 KiB) Downloaded 5 times

dferguson
Expert Boarder
Expert Boarder
Posts: 86
Joined: Thu May 18, 2017 12:48 pm

Re: Startup errors and unreliable behavior

Post by dferguson » Mon Jan 08, 2018 4:30 pm

Code: Select all

openkm@RDDocMan:~$ netstat -l
Active Internet connections (only servers)
Proto Recv-Q Send-Q Local Address           Foreign Address         State      
tcp        0      0 localhost:mysql         *:*                     LISTEN     
tcp        0      0 localhost:2002          *:*                     LISTEN     
tcp        0      0 RDDocMan:domain         *:*                     LISTEN     
tcp6       0      0 localhost:8015          [::]:*                  LISTEN     
udp        0      0 RDDocMan:domain         *:*                                
udp        0      0 *:ipp                   *:*                                
udp        0      0 *:58145                 *:*                                
udp        0      0 *:mdns                  *:*                                
udp6       0      0 [::]:35450              [::]:*                             
udp6       0      0 [::]:mdns               [::]:*                             
raw6       0      0 [::]:ipv6-icmp          [::]:*                  7          
Active UNIX domain sockets (only servers)
Proto RefCnt Flags       Type       State         I-Node   Path
unix  2      [ ACC ]     STREAM     LISTENING     23914    @/tmp/ibus/dbus-6lnj1fr0
unix  2      [ ACC ]     STREAM     LISTENING     22790    /tmp/.X11-unix/X0
unix  2      [ ACC ]     STREAM     LISTENING     23388    @/tmp/.ICE-unix/1681
unix  2      [ ACC ]     STREAM     LISTENING     23297    /home/openkm/.gnupg/S.gpg-agent
unix  2      [ ACC ]     STREAM     LISTENING     23389    /tmp/.ICE-unix/1681
unix  2      [ ACC ]     STREAM     LISTENING     22789    @/tmp/.X11-unix/X0
unix  2      [ ACC ]     STREAM     LISTENING     28103    /tmp/OSL_PIPE_1001_SingleOfficeIPC_b7eb9c79ecabe5714da9cdb2e5aa050
unix  2      [ ACC ]     STREAM     LISTENING     19802    /var/run/mysqld/mysqld.sock
unix  2      [ ACC ]     STREAM     LISTENING     22621    /run/user/1001/systemd/private
unix  2      [ ACC ]     STREAM     LISTENING     14962    /var/run/dbus/system_bus_socket
unix  2      [ ACC ]     STREAM     LISTENING     14965    /var/run/cups/cups.sock
unix  2      [ ACC ]     STREAM     LISTENING     14968    /var/run/avahi-daemon/socket
unix  2      [ ACC ]     STREAM     LISTENING     14971    /run/snapd.socket
unix  2      [ ACC ]     STREAM     LISTENING     24957    /run/user/1001/keyring/ssh
unix  2      [ ACC ]     STREAM     LISTENING     14973    /run/snapd-snap.socket
unix  2      [ ACC ]     STREAM     LISTENING     14975    /run/uuidd/request
unix  2      [ ACC ]     STREAM     LISTENING     14978    /run/acpid.socket
unix  2      [ ACC ]     STREAM     LISTENING     23864    @/tmp/dbus-Zrzj3iPOGS
unix  2      [ ACC ]     STREAM     LISTENING     22349    @/tmp/dbus-BbTR2cq4CM
unix  2      [ ACC ]     STREAM     LISTENING     23227    @/com/ubuntu/upstart-session/1001/1456
unix  2      [ ACC ]     STREAM     LISTENING     22195    /run/user/1001/keyring/control
unix  2      [ ACC ]     STREAM     LISTENING     22473    /run/user/1001/pulse/native
unix  2      [ ACC ]     STREAM     LISTENING     726      /run/systemd/private
unix  2      [ ACC ]     STREAM     LISTENING     22232    /run/user/1001/keyring/pkcs11
unix  2      [ ACC ]     STREAM     LISTENING     732      /run/systemd/fsck.progress
unix  2      [ ACC ]     SEQPACKET  LISTENING     734      /run/udev/control
unix  2      [ ACC ]     STREAM     LISTENING     739      /run/systemd/journal/stdout
openkm@RDDocMan:~$ fuser 8015/tcp
8015/tcp:             1209
openkm@RDDocMan:~$ ls -l /proc/1209/exe
lrwxrwxrwx 1 openkm openkm 0 Jan  8 10:59 /proc/1209/exe -> /usr/lib/jvm/java-8-oracle/bin/java
Will try to use port 8105 in server.xml instead of 8015

dferguson
Expert Boarder
Expert Boarder
Posts: 86
Joined: Thu May 18, 2017 12:48 pm

Re: Startup errors and unreliable behavior

Post by dferguson » Mon Jan 08, 2018 4:49 pm

Ok, found that there was another instance of tomcat running. I killed the PID and restarted via command line restart of tomcat.

Noticed these in my openkm.log

Code: Select all

2018-01-08 11:41:51,928 [localhost-startStop-1] [] WARN  com.openkm.util.ExecutionUtils - Unable to read script: /home/openkm/tomcat-7.0.61/start.bsh
2018-01-08 11:41:51,929 [localhost-startStop-1] [] WARN  com.openkm.util.ExecutionUtils - Unable to read jar: /home/openkm/tomcat-7.0.61/start.jar
2018-01-08 11:41:51,929 [localhost-startStop-1] [] INFO  c.o.servlet.RepositoryStartupServlet - *** Execute start SQL ***
2018-01-08 11:41:51,929 [localhost-startStop-1] [] WARN  c.o.servlet.RepositoryStartupServlet - Unable to read sql: /home/openkm/tomcat-7.0.61/start.sql
I have confirmed that they are not located in my /home/openkm/tomcat-7.0.61/ location. OpenKM is up and running now. Why are multiple instances get started or why are not getting closed properly.

jllort
Moderator
Moderator
Posts: 9892
Joined: Fri Dec 21, 2007 11:23 am
Location: Sineu - ( Illes Balears ) - Spain
Contact:

Re: Startup errors and unreliable behavior

Post by jllort » Wed Jan 10, 2018 8:09 am

I suggest to change default tomcat ports by your own as is explained in this documentation section https://docs.openkm.com/kcenter/view/ok ... -port.html and then the issue should not come again. Anyway consider having uncontrolled tomcat - applications - running out of control in the server is not a good scenario.

dferguson
Expert Boarder
Expert Boarder
Posts: 86
Joined: Thu May 18, 2017 12:48 pm

Re: Startup errors and unreliable behavior

Post by dferguson » Wed Jan 10, 2018 3:16 pm

I have changed the tomcat ports previously. The conficlt with Jboss ports was the first reason for doing so.

However with this instance I found that it was tomcat conflicting with tomcat. For some reason tomcat was not shutting down properly and it took a PID kill to accomplish that. Simply rebooting and/or stopping and starting the tomcat service didn't kill the "stuck" instance of tomcat. The shutdown port was persisting. Resulting in a no-start when trying to restart tomcat.

How can I ensure that tomcat properly shuts down in the event of a server reboot, glitch, etc. I need this to come back up smoothly following a condition which might restart the server.

jllort
Moderator
Moderator
Posts: 9892
Joined: Fri Dec 21, 2007 11:23 am
Location: Sineu - ( Illes Balears ) - Spain
Contact:

Re: Startup errors and unreliable behavior

Post by jllort » Fri Jan 12, 2018 9:23 am

You must change tomcat port with tomcat stopped, then will start and shutdown correctly. If you change the server.xml configuration while application is started you will have strange behavious.

Post Reply

Who is online

Users browsing this forum: No registered users and 4 guests