Page 1 of 1

Startup errors and unreliable behavior

PostPosted:Tue Aug 22, 2017 4:11 pm
by dferguson
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"]

Re: Startup errors and unreliable behavior

PostPosted:Tue Aug 22, 2017 4:12 pm
by dferguson
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.

Re: Startup errors and unreliable behavior

PostPosted:Tue Aug 22, 2017 4:13 pm
by dferguson
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/>


Re: Startup errors and unreliable behavior

PostPosted:Tue Aug 22, 2017 4:16 pm
by dferguson
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]

Re: Startup errors and unreliable behavior

PostPosted:Tue Aug 22, 2017 6:45 pm
by dferguson
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: 

Re: Startup errors and unreliable behavior

PostPosted:Wed Aug 23, 2017 11:05 am
by jllort
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 )

Re: Startup errors and unreliable behavior

PostPosted:Wed Aug 23, 2017 1:02 pm
by dferguson
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.

Re: Startup errors and unreliable behavior

PostPosted:Thu Nov 30, 2017 3:14 pm
by dferguson
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.

Re: Startup errors and unreliable behavior

PostPosted:Mon Jan 08, 2018 4:16 pm
by dferguson
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'

Re: Startup errors and unreliable behavior

PostPosted:Mon Jan 08, 2018 4:30 pm
by dferguson
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

Re: Startup errors and unreliable behavior

PostPosted:Mon Jan 08, 2018 4:49 pm
by dferguson
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.

Re: Startup errors and unreliable behavior

PostPosted:Wed Jan 10, 2018 8:09 am
by jllort
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.

Re: Startup errors and unreliable behavior

PostPosted:Wed Jan 10, 2018 3:16 pm
by dferguson
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.

Re: Startup errors and unreliable behavior

PostPosted:Fri Jan 12, 2018 9:23 am
by jllort
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.