• OpenKM URL not acccessable, it was working earlier

  • Problems with installing OpenKM? No problemo, the solution is closer than you think.
Problems with installing OpenKM? No problemo, the solution is closer than you think.
Forum rules: Please, before asking something see the documentation wiki or use the search feature of the forum. And remember we don't have a crystal ball or mental readers, so if you post about an issue tell us which OpenKM are you using and also the browser and operating system version. For more info read How to Report Bugs Effectively.
 #47775  by samgabrielbb
 
OpenKM service on linux machine , it was working earlier more then 6 month , today i try to access the url , its says ERR_CONNECTION_TIMED_OUT
i did clear the caches and deleted the all logs under /home/openkmadmin/tomcat-8.5.24/logs
restarted the serves but so for no luck

i am trying to connect via program, that's getting Connection timed out: connect
Code: Select all
com.openkm.sdk4j.exception.WebserviceException: com.sun.jersey.api.client.ClientHandlerException: java.net.ConnectException: Connection timed out: connect
	at com.openkm.sdk4j.impl.RepositoryImpl.getRootFolder(RepositoryImpl.java:121)
	at com.openkm.sdk4j.OKMWebservice20.getRootFolder(OKMWebservice20.java:929)
	at com.openkm.TestOpenKM.main(TestOpenKM.java:13)
Caused by: com.sun.jersey.api.client.ClientHandlerException: java.net.ConnectException: Connection timed out: connect
	at com.sun.jersey.client.urlconnection.URLConnectionClientHandler.handle(URLConnectionClientHandler.java:149)
	at com.sun.jersey.api.client.filter.HTTPBasicAuthFilter.handle(HTTPBasicAuthFilter.java:81)
	at com.sun.jersey.api.client.Client.handle(Client.java:648)
	at com.sun.jersey.api.client.WebResource.handle(WebResource.java:670)
	at com.sun.jersey.api.client.WebResource.access$200(WebResource.java:74)
	at com.sun.jersey.api.client.WebResource$Builder.get(WebResource.java:503)
	at com.openkm.sdk4j.impl.RepositoryImpl.getRootFolder(RepositoryImpl.java:94)
	... 2 more
Caused by: java.net.ConnectException: Connection timed out: connect
	at java.net.DualStackPlainSocketImpl.connect0(Native Method)
	at java.net.DualStackPlainSocketImpl.socketConnect(DualStackPlainSocketImpl.java:79)
	at java.net.AbstractPlainSocketImpl.doConnect(AbstractPlainSocketImpl.java:350)
	at java.net.AbstractPlainSocketImpl.connectToAddress(AbstractPlainSocketImpl.java:206)
	at java.net.AbstractPlainSocketImpl.connect(AbstractPlainSocketImpl.java:188)
	at java.net.PlainSocketImpl.connect(PlainSocketImpl.java:172)
	at java.net.SocksSocketImpl.connect(SocksSocketImpl.java:392)
	at java.net.Socket.connect(Socket.java:589)
	at java.net.Socket.connect(Socket.java:538)
	at sun.net.NetworkClient.doConnect(NetworkClient.java:180)
	at sun.net.www.http.HttpClient.openServer(HttpClient.java:432)
	at sun.net.www.http.HttpClient.openServer(HttpClient.java:527)
	at sun.net.www.http.HttpClient.<init>(HttpClient.java:211)
	at sun.net.www.http.HttpClient.New(HttpClient.java:308)
	at sun.net.www.http.HttpClient.New(HttpClient.java:326)
	at sun.net.www.protocol.http.HttpURLConnection.getNewHttpClient(HttpURLConnection.java:1169)
	at sun.net.www.protocol.http.HttpURLConnection.plainConnect0(HttpURLConnection.java:1105)
	at sun.net.www.protocol.http.HttpURLConnection.plainConnect(HttpURLConnection.java:999)
	at sun.net.www.protocol.http.HttpURLConnection.connect(HttpURLConnection.java:933)
	at sun.net.www.protocol.http.HttpURLConnection.getInputStream0(HttpURLConnection.java:1513)
	at sun.net.www.protocol.http.HttpURLConnection.getInputStream(HttpURLConnection.java:1441)
	at java.net.HttpURLConnection.getResponseCode(HttpURLConnection.java:480)
	at com.sun.jersey.client.urlconnection.URLConnectionClientHandler._invoke(URLConnectionClientHandler.java:240)
	at com.sun.jersey.client.urlconnection.URLConnectionClientHandler.handle(URLConnectionClientHandler.java:147)
	... 8 more
Code: Select all
$ netstat -an
Active Internet connections (servers and established)
Proto Recv-Q Send-Q Local Address           Foreign Address         State
tcp        0      0 127.0.0.1:8009          0.0.0.0:*               LISTEN
tcp        0      0 0.0.0.0:111             0.0.0.0:*               LISTEN
tcp        0      0 0.0.0.0:8080            0.0.0.0:*               LISTEN
tcp        0      0 0.0.0.0:6000            0.0.0.0:*               LISTEN
tcp        0      0 192.168.122.1:53        0.0.0.0:*               LISTEN
tcp        0      0 0.0.0.0:22              0.0.0.0:*               LISTEN
tcp        0      0 127.0.0.1:631           0.0.0.0:*               LISTEN
tcp        0      0 127.0.0.1:8005          0.0.0.0:*               LISTEN
tcp        0     64 10.30.2.7:22            10.30.3.102:60631       ESTABLISHED
tcp        0      0 10.30.2.7:22            10.30.3.102:59076       ESTABLISHED
tcp        0      0 127.0.0.1:48528         127.0.0.1:3306          ESTABLISHED
tcp        0      0 127.0.0.1:48526         127.0.0.1:3306          ESTABLISHED
Please advise
Last edited by samgabrielbb on Wed Apr 17, 2019 12:23 am, edited 1 time in total.
 #47776  by samgabrielbb
 
Log
Code: Select all
OpenJDK 64-Bit Server VM warning: ignoring option MaxPermSize=256m; support was removed in 8.0
16-Apr-2019 17:23:58.926 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server version:        Apache Tomcat/8.5.24
16-Apr-2019 17:23:58.929 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server built:          Nov 27 2017 13:05:30 UTC
16-Apr-2019 17:23:58.929 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Server number:         8.5.24.0
16-Apr-2019 17:23:58.929 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log OS Name:               Linux
16-Apr-2019 17:23:58.929 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log OS Version:            3.10.0-957.1.3.el7.x86_64
16-Apr-2019 17:23:58.929 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Architecture:          amd64
16-Apr-2019 17:23:58.930 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Java Home:             /usr/lib/jvm/java-1.8.0-openjdk-1.8.0.191.b12-1.el7_6.x86_64/jre
16-Apr-2019 17:23:58.930 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Version:           1.8.0_191-b12
16-Apr-2019 17:23:58.930 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log JVM Vendor:            Oracle Corporation
16-Apr-2019 17:23:58.930 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_BASE:         /home/openkmadmin/tomcat-8.5.24
16-Apr-2019 17:23:58.930 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log CATALINA_HOME:         /home/openkmadmin/tomcat-8.5.24
16-Apr-2019 17:23:58.931 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.config.file=/home/openkmadmin/tomcat-8.5.24/conf/logging.properties
16-Apr-2019 17:23:58.931 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager
16-Apr-2019 17:23:58.931 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Xms1025m
16-Apr-2019 17:23:58.931 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Xmx2048m
16-Apr-2019 17:23:58.931 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -XX:+UseG1GC
16-Apr-2019 17:23:58.933 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -XX:MaxPermSize=256m
16-Apr-2019 17:23:58.933 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.net.preferIPv4Stack=true
16-Apr-2019 17:23:58.933 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dfile.encoding=UTF-8
16-Apr-2019 17:23:58.933 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dsun.jnu.encoding=UTF-8
16-Apr-2019 17:23:58.934 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dmail.mime.ignoreunknownencoding=true
16-Apr-2019 17:23:58.934 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.awt.headless=true
16-Apr-2019 17:23:58.934 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djdk.tls.ephemeralDHKeySize=2048
16-Apr-2019 17:23:58.934 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.protocol.handler.pkgs=org.apache.catalina.webresources
16-Apr-2019 17:23:58.934 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dignore.endorsed.dirs=
16-Apr-2019 17:23:58.934 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.base=/home/openkmadmin/tomcat-8.5.24
16-Apr-2019 17:23:58.935 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Dcatalina.home=/home/openkmadmin/tomcat-8.5.24
16-Apr-2019 17:23:58.935 INFO [main] org.apache.catalina.startup.VersionLoggerListener.log Command line argument: -Djava.io.tmpdir=/home/openkmadmin/tomcat-8.5.24/temp
16-Apr-2019 17:23:58.935 INFO [main] org.apache.catalina.core.AprLifecycleListener.lifecycleEvent The APR based Apache Tomcat Native library which allows optimal performance in production environments was not found on the java.library.path: [:/home/openkmadmin/tomcat-8.5.24/lib/sigar:/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib]
16-Apr-2019 17:23:59.113 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["http-nio-0.0.0.0-8080"]
16-Apr-2019 17:23:59.143 INFO [main] org.apache.tomcat.util.net.NioSelectorPool.getSharedSelector Using a shared selector for servlet write/read
16-Apr-2019 17:23:59.155 INFO [main] org.apache.coyote.AbstractProtocol.init Initializing ProtocolHandler ["ajp-nio-127.0.0.1-8009"]
16-Apr-2019 17:23:59.161 INFO [main] org.apache.tomcat.util.net.NioSelectorPool.getSharedSelector Using a shared selector for servlet write/read
16-Apr-2019 17:23:59.161 INFO [main] org.apache.catalina.startup.Catalina.load Initialization processed in 846 ms
16-Apr-2019 17:23:59.176 WARNING [main] org.apache.tomcat.dbcp.dbcp2.BasicDataSourceFactory.getObjectInstance Name = OpenKMDS Property maxActive is not used in DBCP2, use maxTotal instead. maxTotal default value is 8. You have set value of "100" for "maxActive" property, which is being ignored.
16-Apr-2019 17:23:59.176 WARNING [main] org.apache.tomcat.dbcp.dbcp2.BasicDataSourceFactory.getObjectInstance Name = OpenKMDS Property maxWait is not used in DBCP2 , use maxWaitMillis instead. maxWaitMillis default value is -1. You have set value of "10000" for "maxWait" property, which is being ignored.
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
16-Apr-2019 17:23:59.255 INFO [main] org.apache.catalina.core.StandardService.startInternal Starting service [Catalina]
16-Apr-2019 17:23:59.255 INFO [main] org.apache.catalina.core.StandardEngine.startInternal Starting Servlet Engine: Apache Tomcat/8.5.24
16-Apr-2019 17:23:59.313 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployWAR Deploying web application archive [/home/openkmadmin/tomcat-8.5.24/webapps/OpenKM.war]
16-Apr-2019 17:23:59.338 WARNING [localhost-startStop-1] org.apache.catalina.startup.SetContextPropertiesRule.begin [SetContextPropertiesRule]{Context} Setting property 'antiJARLocking' to 'true' did not find a matching property.
16-Apr-2019 17:23:59.343 WARNING [localhost-startStop-1] org.apache.catalina.startup.SetContextPropertiesRule.begin [SetContextPropertiesRule]{Context} Setting property 'allowLinking' to 'true' did not find a matching property.
16-Apr-2019 17:24:15.222 INFO [localhost-startStop-1] org.apache.jasper.servlet.TldScanner.scanJars At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
17:24:15,333 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback.groovy]
17:24:15,336 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Could NOT find resource [logback-test.xml]
17:24:15,336 |-INFO in ch.qos.logback.classic.LoggerContext[default] - Found resource [logback.xml] at [file:/home/openkmadmin/tomcat-8.5.24/webapps/OpenKM/WEB-INF/classes/logback.xml]
17:24:15,456 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - debug attribute not set
17:24:15,465 |-INFO in ReconfigureOnChangeFilter{invocationCounter=0} - Will scan for changes in [[/home/openkmadmin/tomcat-8.5.24/webapps/OpenKM/WEB-INF/classes/logback.xml]] every 60 seconds. 
17:24:15,465 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - Adding ReconfigureOnChangeFilter as a turbo filter
17:24:15,470 |-INFO in ch.qos.logback.classic.joran.action.JMXConfiguratorAction - begin
17:24:15,514 |-INFO in ch.qos.logback.core.joran.action.StatusListenerAction - Added status listener of type [ch.qos.logback.core.status.OnConsoleStatusListener]
17:24:15,575 |-INFO in ch.qos.logback.core.joran.action.TimestampAction - Using current interpretation time, i.e. now, as time reference.
17:24:15,576 |-INFO in ch.qos.logback.core.joran.action.TimestampAction - Adding property to the context with key="bySecond" and value="2019-04-16" to the LOCAL scope
17:24:15,576 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.ConsoleAppender]
17:24:15,581 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [STDOUT]
17:24:15,675 |-INFO in ch.qos.logback.core.joran.action.NestedComplexPropertyIA - Assuming default type [ch.qos.logback.classic.encoder.PatternLayoutEncoder] for [encoder] property
17:24:15,757 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - About to instantiate appender of type [ch.qos.logback.core.rolling.RollingFileAppender]
17:24:15,761 |-INFO in ch.qos.logback.core.joran.action.AppenderAction - Naming appender as [FILE]
17:24:15,836 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy - No compression will be used
17:24:15,839 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy - Will use the pattern /home/openkmadmin/tomcat-8.5.24/logs/openkm_%d{yyyy-MM-dd}.%i.log for the active file
17:24:15,844 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@7647db7c - The date pattern is 'yyyy-MM-dd' from file name pattern '/home/openkmadmin/tomcat-8.5.24/logs/openkm_%d{yyyy-MM-dd}.%i.log'.
17:24:15,844 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@7647db7c - Roll-over at midnight.
17:24:15,844 |-INFO in ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP@7647db7c - Setting initial period to Tue Apr 16 17:24:15 CDT 2019
17:24:15,870 |-WARN in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - This appender no longer admits a layout as a sub-component, set an encoder instead.
17:24:15,870 |-WARN in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - To ensure compatibility, wrapping your layout in LayoutWrappingEncoder.
17:24:15,870 |-WARN in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - See also http://logback.qos.ch/codes.html#layoutInsteadOfEncoder for details
17:24:15,873 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - Active log file name: /home/openkmadmin/tomcat-8.5.24/logs/openkm.log
17:24:15,873 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[FILE] - File property is set to [/home/openkmadmin/tomcat-8.5.24/logs/openkm.log]
17:24:15,875 |-INFO in ch.qos.logback.core.joran.util.ConfigurationWatchListUtil@45e8657 - Adding [file:/home/openkmadmin/tomcat-8.5.24/logback.xml] to configuration watch list.
17:24:15,878 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.springframework] to WARN
17:24:15,878 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.hibernate] to WARN
17:24:15,878 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [ch.qos.logback] to WARN
17:24:15,878 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [org.jbpm] to WARN
17:24:15,879 |-INFO in ch.qos.logback.classic.joran.action.LoggerAction - Setting level of logger [com.openkm] to INFO
17:24:15,879 |-INFO in ch.qos.logback.classic.joran.action.RootLoggerAction - Setting level of ROOT logger to WARN
17:24:15,879 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [STDOUT] to Logger[ROOT]
17:24:15,880 |-INFO in ch.qos.logback.core.joran.action.AppenderRefAction - Attaching appender named [FILE] to Logger[ROOT]
17:24:15,880 |-INFO in ch.qos.logback.classic.joran.action.ConfigurationAction - End of configuration.
17:24:15,880 |-INFO in ch.qos.logback.classic.joran.JoranConfigurator@310eb611 - Registering current configuration as safe fallback point
16-Apr-2019 17:24:15.925 INFO [localhost-startStop-1] org.springframework.web.context.ContextLoader.initWebApplicationContext Root WebApplicationContext: initialization started
16-Apr-2019 17:24:16.066 INFO [localhost-startStop-1] org.springframework.context.support.AbstractApplicationContext.prepareRefresh Refreshing Root WebApplicationContext: startup date [Tue Apr 16 17:24:16 CDT 2019]; root of context hierarchy
16-Apr-2019 17:24:16.154 INFO [localhost-startStop-1] org.springframework.beans.factory.xml.XmlBeanDefinitionReader.loadBeanDefinitions Loading XML bean definitions from ServletContext resource [/WEB-INF/appContext.xml]
16-Apr-2019 17:24:17.594 INFO [localhost-startStop-1] org.springframework.beans.factory.xml.XmlBeanDefinitionReader.loadBeanDefinitions Loading XML bean definitions from ServletContext resource [/WEB-INF/soap.xml]
16-Apr-2019 17:24:17.716 INFO [localhost-startStop-1] org.springframework.beans.factory.xml.XmlBeanDefinitionReader.loadBeanDefinitions Loading XML bean definitions from ServletContext resource [/WEB-INF/rest.xml]
16-Apr-2019 17:24:17.776 INFO [localhost-startStop-1] org.springframework.beans.factory.xml.XmlBeanDefinitionReader.loadBeanDefinitions Loading XML bean definitions from ServletContext resource [/WEB-INF/cmis.xml]
16-Apr-2019 17:24:17.829 INFO [localhost-startStop-1] org.springframework.security.core.SpringSecurityCoreVersion.performVersionChecks You are running with Spring Security Core 3.2.3.RELEASE
16-Apr-2019 17:24:17.830 INFO [localhost-startStop-1] org.springframework.security.config.SecurityNamespaceHandler.<init> Spring Security 'config' module version is 3.2.3.RELEASE
16-Apr-2019 17:24:17.968 INFO [localhost-startStop-1] 
16-Apr-2019 17:24:26.339 INFO [localhost-startStop-1] org.springframework.security.web.DefaultSecurityFilterChain.<init> Creating filter chain: Ant [pattern='/status'], [org.springframework.security.web.context.SecurityContextPersistenceFilter@60d966de, 
16-Apr-2019 17:24:26.808 INFO [localhost-startStop-1] org.springframework.security.config.http.DefaultFilterChainValidator.checkLoginPageIsntProtected Checking whether login URL '/login.jsp' is accessible with your configuration
16-Apr-2019 17:24:26.849 INFO [localhost-startStop-1] org.springframework.web.context.ContextLoader.initWebApplicationContext Root WebApplicationContext: initialization completed in 10924 ms
2019-04-16 17:24:26,871 [localhost-startStop-1] INFO  com.openkm.core.Config - ** Application OpenKM has DTDs at /home/openkmadmin/tomcat-8.5.24/webapps/OpenKM/WEB-INF/classes/dtd **
2019-04-16 17:24:26,878 [localhost-startStop-1] INFO  com.openkm.core.Config - ** Language profiles at /home/openkmadmin/tomcat-8.5.24/webapps/OpenKM/WEB-INF/classes/lang-profiles **
2019-04-16 17:24:26,879 [localhost-startStop-1] INFO  com.openkm.core.Config - ** Reading config file /home/openkmadmin/tomcat-8.5.24/OpenKM.cfg **
2019-04-16 17:24:26,881 [localhost-startStop-1] INFO  com.openkm.core.Config - LOAD - cloud.max.repository.size=0
2019-04-16 17:24:26,881 [localhost-startStop-1] INFO  com.openkm.core.Config - LOAD - cloud.max.users=0
2019-04-16 17:24:26,881 [localhost-startStop-1] INFO  com.openkm.core.Config - LOAD - cloud.mode=false
2019-04-16 17:24:26,881 [localhost-startStop-1] INFO  com.openkm.core.Config - LOAD - hibernate.create.autofix=true
2019-04-16 17:24:26,881 [localhost-startStop-1] INFO  com.openkm.core.Config - LOAD - hibernate.datasource=java:/comp/env/jdbc/OpenKMDS
2019-04-16 17:24:26,881 [localhost-startStop-1] INFO  com.openkm.core.Config - LOAD - hibernate.dialect=org.hibernate.dialect.MySQL5InnoDBDialect
2019-04-16 17:24:26,882 [localhost-startStop-1] INFO  com.openkm.core.Config - LOAD - hibernate.hbm2ddl=none
2019-04-16 17:24:26,882 [localhost-startStop-1] INFO  com.openkm.core.Config - LOAD - hibernate.search.analyzer=org.apache.lucene.analysis.standard.StandardAnalyzer
2019-04-16 17:24:26,882 [localhost-startStop-1] INFO  com.openkm.core.Config - LOAD - hibernate.search.index.exclusive=true
2019-04-16 17:24:26,882 [localhost-startStop-1] INFO  com.openkm.core.Config - LOAD - hibernate.search.index.home=/home/openkmadmin/tomcat-8.5.24/repository/index
2019-04-16 17:24:26,882 [localhost-startStop-1] INFO  com.openkm.core.Config - LOAD - hibernate.show_sql=false
2019-04-16 17:24:26,882 [localhost-startStop-1] INFO  com.openkm.core.Config - LOAD - hibernate.statistics=false
2019-04-16 17:24:26,882 [localhost-startStop-1] INFO  com.openkm.core.Config - LOAD - instance.chroot.path=
2019-04-16 17:24:26,882 [localhost-startStop-1] INFO  com.openkm.core.Config - LOAD - instance.home=/home/openkmadmin/tomcat-8.5.24
2019-04-16 17:24:26,883 [localhost-startStop-1] INFO  com.openkm.core.Config - LOAD - jbpm.config=/home/openkmadmin/tomcat-8.5.24/jbpm.xml
2019-04-16 17:24:26,883 [localhost-startStop-1] INFO  com.openkm.core.Config - LOAD - property.groups.cnd=/home/openkmadmin/tomcat-8.5.24/PropertyGroups.cnd
2019-04-16 17:24:26,883 [localhost-startStop-1] INFO  com.openkm.core.Config - LOAD - property.groups.xml=/home/openkmadmin/tomcat-8.5.24/PropertyGroups.xml
2019-04-16 17:24:26,883 [localhost-startStop-1] INFO  com.openkm.core.Config - LOAD - repository.cache.dxf=/home/openkmadmin/tomcat-8.5.24/repository/cache/dxf
2019-04-16 17:24:26,883 [localhost-startStop-1] INFO  com.openkm.core.Config - LOAD - repository.cache.home=/home/openkmadmin/tomcat-8.5.24/repository/cache
2019-04-16 17:24:26,883 [localhost-startStop-1] INFO  com.openkm.core.Config - LOAD - repository.cache.pdf=/home/openkmadmin/tomcat-8.5.24/repository/cache/pdf
2019-04-16 17:24:26,883 [localhost-startStop-1] INFO  com.openkm.core.Config - LOAD - repository.cache.swf=/home/openkmadmin/tomcat-8.5.24/repository/cache/swf
2019-04-16 17:24:26,883 [localhost-startStop-1] INFO  com.openkm.core.Config - LOAD - repository.config=/dev/null
2019-04-16 17:24:26,883 [localhost-startStop-1] INFO  com.openkm.core.Config - LOAD - repository.datastore.backend=fs
2019-04-16 17:24:26,884 [localhost-startStop-1] INFO  com.openkm.core.Config - LOAD - repository.datastore.home=/home/openkmadmin/tomcat-8.5.24/repository/datastore
2019-04-16 17:24:26,884 [localhost-startStop-1] INFO  com.openkm.core.Config - LOAD - repository.home=/home/openkmadmin/tomcat-8.5.24/repository
2019-04-16 17:24:26,884 [localhost-startStop-1] INFO  com.openkm.core.Config - LOAD - repository.native=true
2019-04-16 17:24:26,884 [localhost-startStop-1] INFO  com.openkm.core.Config - LOAD - system.multiple.instances=false
2019-04-16 17:24:26,885 [localhost-startStop-1] INFO  com.openkm.util.WarUtils - okm.version: 6.3.6
2019-04-16 17:24:26,885 [localhost-startStop-1] INFO  com.openkm.util.WarUtils - okm.build: 87d181f
2019-04-16 17:24:26,893 [localhost-startStop-1] INFO  c.o.servlet.RepositoryStartupServlet - *** Application version: 6.3.6 (build: 87d181f) ***
2019-04-16 17:24:26,894 [localhost-startStop-1] INFO  c.o.servlet.RepositoryStartupServlet - *** Initialize cache manager... ***
2019-04-16 17:24:26,895 [localhost-startStop-1] WARN  com.openkm.cache.CacheProvider - Using Ehcache config from ClassPath: /ehcache.xml
2019-04-16 17:24:27,059 [localhost-startStop-1] INFO  com.openkm.cache.CacheProvider - Cache disk store path: /home/openkmadmin/tomcat-8.5.24/temp/okm
2019-04-16 17:24:27,059 [localhost-startStop-1] INFO  c.o.servlet.RepositoryStartupServlet - *** Hibernate initialize ***
2019-04-16 17:24:28,355 [localhost-startStop-1] INFO  com.openkm.dao.HibernateUtil - Hibernate 'hibernate.dialect' = org.hibernate.dialect.MySQL5InnoDBDialect
2019-04-16 17:24:28,355 [localhost-startStop-1] INFO  com.openkm.dao.HibernateUtil - Hibernate 'hibernate.connection.datasource' = java:/comp/env/jdbc/OpenKMDS
2019-04-16 17:24:28,355 [localhost-startStop-1] INFO  com.openkm.dao.HibernateUtil - Hibernate 'hibernate.hbm2ddl.auto' = none
2019-04-16 17:24:28,355 [localhost-startStop-1] INFO  com.openkm.dao.HibernateUtil - Hibernate 'hibernate.show_sql' = false
2019-04-16 17:24:28,355 [localhost-startStop-1] INFO  com.openkm.dao.HibernateUtil - Hibernate 'hibernate.generate_statistics' = false
2019-04-16 17:24:28,355 [localhost-startStop-1] INFO  com.openkm.dao.HibernateUtil - Hibernate 'hibernate.search.default.directory_provider' = org.hibernate.search.store.FSDirectoryProvider



2019-04-16 17:24:33,516 [localhost-startStop-1] INFO  com.openkm.core.Config - RELOAD - validator.password.max.length=0
2019-04-16 17:24:33,516 [localhost-startStop-1] INFO  com.openkm.core.Config - RELOAD - validator.password.min.digits=0
2019-04-16 17:24:33,516 [localhost-startStop-1] INFO  com.openkm.core.Config - RELOAD - validator.password.min.length=0
2019-04-16 17:24:33,516 [localhost-startStop-1] INFO  com.openkm.core.Config - RELOAD - validator.password.min.lowercase=0
2019-04-16 17:24:33,516 [localhost-startStop-1] INFO  com.openkm.core.Config - RELOAD - validator.password.min.uppercase=0
2019-04-16 17:24:33,516 [localhost-startStop-1] INFO  com.openkm.core.Config - RELOAD - validator.password.mini.special=0
2019-04-16 17:24:33,516 [localhost-startStop-1] INFO  com.openkm.core.Config - RELOAD - version.append.download=false
2019-04-16 17:24:33,516 [localhost-startStop-1] INFO  com.openkm.core.Config - RELOAD - version.numeration.adapter=com.openkm.vernum.MajorMinorVersionNumerationAdapter
2019-04-16 17:24:33,516 [localhost-startStop-1] INFO  com.openkm.core.Config - RELOAD - version.numeration.format=%d
2019-04-16 17:24:33,516 [localhost-startStop-1] INFO  com.openkm.core.Config - RELOAD - workflow.run.config.form=run_config
2019-04-16 17:24:33,517 [localhost-startStop-1] INFO  com.openkm.core.Config - RELOAD - workflow.start.task.auto.run=true
2019-04-16 17:24:33,517 [localhost-startStop-1] INFO  com.openkm.core.Config - RELOAD - zoho.api.key=
2019-04-16 17:24:33,517 [localhost-startStop-1] INFO  com.openkm.core.Config - RELOAD - zoho.password=
2019-04-16 17:24:33,517 [localhost-startStop-1] INFO  com.openkm.core.Config - RELOAD - zoho.secret.key=
2019-04-16 17:24:33,517 [localhost-startStop-1] INFO  com.openkm.core.Config - RELOAD - zoho.user=
2019-04-16 17:24:33,518 [localhost-startStop-1] INFO  c.o.servlet.RepositoryStartupServlet - *** Repository initializing... ***
2019-04-16 17:24:33,592 [localhost-startStop-1] INFO  c.o.module.db.stuff.SecurityHelper - Configuring AccessManager with com.openkm.module.db.stuff.DbSimpleAccessManager
2019-04-16 17:24:33,604 [localhost-startStop-1] INFO  c.o.module.db.DbRepositoryModule - Repository already created
2019-04-16 17:24:33,620 [localhost-startStop-1] INFO  c.o.servlet.RepositoryStartupServlet - *** Repository initialized ***
2019-04-16 17:24:33,620 [localhost-startStop-1] INFO  c.o.servlet.RepositoryStartupServlet - *** Cache deserialization ***
2019-04-16 17:24:33,634 [localhost-startStop-1] INFO  c.o.servlet.RepositoryStartupServlet - *** User database initialized ***
2019-04-16 17:24:33,635 [localhost-startStop-1] INFO  c.o.servlet.RepositoryStartupServlet - *** Initializing workflow engine... ***
2019-04-16 17:24:33,636 [localhost-startStop-1] INFO  com.openkm.util.JBPMUtils - Creating JBPM default configuration
16-Apr-2019 17:24:33.640 INFO [localhost-startStop-1] org.jbpm.JbpmConfiguration.getInstance using jbpm configuration resource 'jbpm.cfg.xml'
16-Apr-2019 17:24:33.668 INFO [localhost-startStop-1] org.jbpm.persistence.db.StaleObjectLogConfigurer.hideStaleObjectExceptions stale object exceptions will be hidden from logging
2019-04-16 17:24:33,695 [localhost-startStop-1] INFO  c.o.servlet.RepositoryStartupServlet - *** Initializing MIME types... ***
2019-04-16 17:24:33,816 [localhost-startStop-1] INFO  c.o.servlet.RepositoryStartupServlet - *** Activating update info ***
2019-04-16 17:24:33,816 [localhost-startStop-1] INFO  c.o.servlet.RepositoryStartupServlet - *** Activating cron ***
2019-04-16 17:24:33,816 [localhost-startStop-1] INFO  c.o.servlet.RepositoryStartupServlet - *** Activating UI Notification ***
2019-04-16 17:24:33,901 [localhost-startStop-1] INFO  c.o.servlet.RepositoryStartupServlet - *** Activating thesaurus repository ***
2019-04-16 17:24:33,907 [localhost-startStop-1] WARN  c.o.servlet.RepositoryStartupServlet - *** No OpenOffice manager nor server configured ***
2019-04-16 17:24:33,908 [localhost-startStop-1] INFO  c.o.extension.core.ExtensionManager - Initialize and load plugins...
16-Apr-2019 17:24:33.983 WARNING [localhost-startStop-1] net.xeoh.plugins.base.impl.classpath.loader.FileLoader.loadFrom Supplied path does not exist. Unable to add plugins from there.
2019-04-16 17:24:33,984 [localhost-startStop-1] INFO  c.o.servlet.RepositoryStartupServlet - *** Execute start script ***
2019-04-16 17:24:33,986 [localhost-startStop-1] WARN  com.openkm.util.ExecutionUtils - Unable to read script: /home/openkmadmin/tomcat-8.5.24/start.bsh
2019-04-16 17:24:33,986 [localhost-startStop-1] WARN  com.openkm.util.ExecutionUtils - Unable to read jar: /home/openkmadmin/tomcat-8.5.24/start.jar
2019-04-16 17:24:33,986 [localhost-startStop-1] INFO  c.o.servlet.RepositoryStartupServlet - *** Execute start SQL ***
2019-04-16 17:24:33,986 [localhost-startStop-1] WARN  c.o.servlet.RepositoryStartupServlet - Unable to read sql: /home/openkmadmin/tomcat-8.5.24/start.sql
2019-04-16 17:24:33,991 [localhost-startStop-1] WARN  o.a.c.o.s.i.a.CmisAtomPubServlet - CMIS version is not defined! Setting it to CMIS 1.0.
16-Apr-2019 17:24:34.093 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployWAR Deployment of web application archive [/home/openkmadmin/tomcat-8.5.24/webapps/OpenKM.war] has finished in [34,779] ms
16-Apr-2019 17:24:34.094 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deploying web application directory [/home/openkmadmin/tomcat-8.5.24/webapps/ROOT]
16-Apr-2019 17:24:34.097 WARNING [localhost-startStop-1] org.apache.catalina.startup.SetContextPropertiesRule.begin [SetContextPropertiesRule]{Context} Setting property 'antiJARLocking' to 'true' did not find a matching property.
16-Apr-2019 17:24:34.672 INFO [localhost-startStop-1] org.apache.jasper.servlet.TldScanner.scanJars At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.
16-Apr-2019 17:24:34.674 INFO [localhost-startStop-1] org.apache.catalina.startup.HostConfig.deployDirectory Deployment of web application directory [/home/openkmadmin/tomcat-8.5.24/webapps/ROOT] has finished in [579] ms
16-Apr-2019 17:24:34.682 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-0.0.0.0-8080"]
16-Apr-2019 17:24:34.692 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["ajp-nio-127.0.0.1-8009"]
16-Apr-2019 17:24:34.696 INFO [main] org.apache.catalina.startup.Catalina.start Server startup in 35534 ms
Exception in thread "Crontab Manager" com.kenai.crontabparser.impl.TokenMgrError: Lexical error at line 1, column 2.  Encountered: "." (46), after : ""
	at com.kenai.crontabparser.impl.CronTabParserTokenManager.getNextToken(CronTabParserTokenManager.java:1030)
	at com.kenai.crontabparser.impl.CronTabParser.jj_scan_token(CronTabParser.java:1101)
	at com.kenai.crontabparser.impl.CronTabParser.jj_3R_5(CronTabParser.java:823)
	at com.kenai.crontabparser.impl.CronTabParser.jj_3_8(CronTabParser.java:845)
	at com.kenai.crontabparser.impl.CronTabParser.jj_3R_4(CronTabParser.java:852)
	at com.kenai.crontabparser.impl.CronTabParser.jj_3_1(CronTabParser.java:943)
	at com.kenai.crontabparser.impl.CronTabParser.jj_2_1(CronTabParser.java:749)
	at com.kenai.crontabparser.impl.CronTabParser.Entry(CronTabParser.java:287)
	at com.kenai.crontabparser.impl.CronTabParser.MinuteField(CronTabParser.java:123)
	at com.kenai.crontabparser.impl.CronTabParser.StandardExpression(CronTabParser.java:91)
	at com.kenai.crontabparser.impl.CronTabParser.CronTabExpression(CronTabParser.java:34)
	at com.kenai.crontabparser.impl.CronTabParser.parse(CronTabParser.java:16)
	at com.kenai.crontabparser.impl.CronTabParserBridge.parse(CronTabParserBridge.java:28)
	at com.kenai.crontabparser.CronTabExpression.parse(CronTabExpression.java:231)
	at com.openkm.core.Cron.run(Cron.java:49)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
2019-04-16 17:25:00,361 [Thread-15] WARN  com.openkm.core.Cron - Crontab task mail address is empty: Return: null
<hr/>
StdOut: 
<hr/>
StdErr: 
2019-04-16 17:25:00,431 [Thread-16] WARN  com.openkm.core.Cron - Crontab task mail address is empty: Return: null
<hr/>
StdOut: 
<hr/>
StdErr: 
2019-04-16 17:25:00,564 [Thread-14] INFO  c.o.extractor.TextExtractorWorker - processSerial.Working on {docUuid=122a22a2-00ca-4686-b4c4-8fa60a317df5, docPath=/okm:root/globeunion/SupplyChain/InboundContainer_Photos/container loading PO#132057pic2.JPG, docVerUuid=5710e37e-4814-46e1-ba02-a1b04da85475, date=Tue Feb 12 19:02:40 CST 2019}
2019-04-16 17:25:00,708 [Thread-14] WARN  c.o.e.Tesseract3TextExtractor - Undefined OCR application
2019-04-16 17:25:00,716 [Thread-14] WARN  c.o.extractor.RegisteredExtractors - Text extraction failure: Undefined OCR application
2019-04-16 17:25:00,718 [Thread-14] WARN  com.openkm.dao.NodeDocumentDAO - There was a problem extracting text from '/okm:root/globeunion/SupplyChain/InboundContainer_Photos/container loading PO#132057pic2.JPG': Undefined OCR application
2019-04-16 17:25:02,135 [Hibernate Search: Directory writer-1] ERROR o.h.s.exception.impl.LogErrorHandler - Exception occurred org.apache.lucene.store.LockObtainFailedException: Lock obtain timed out: SimpleFSLock@/home/openkmadmin/tomcat-8.5.24/repository/index/com.openkm.dao.bean.NodeBase/write.lock
Primary Failure:
	Entity com.openkm.dao.bean.NodeDocument  Id 122a22a2-00ca-4686-b4c4-8fa60a317df5  Work Type  org.hibernate.search.backend.DeleteLuceneWork
Subsequent failures:
	Entity com.openkm.dao.bean.NodeDocument  Id 122a22a2-00ca-4686-b4c4-8fa60a317df5  Work Type  org.hibernate.search.backend.AddLuceneWork

org.apache.lucene.store.LockObtainFailedException: Lock obtain timed out: SimpleFSLock@/home/openkmadmin/tomcat-8.5.24/repository/index/com.openkm.dao.bean.NodeBase/write.lock
	at org.apache.lucene.store.Lock.obtain(Lock.java:84) ~[lucene-core-3.1.0.jar:3.1.0 1085809 - 2011-03-26 17:59:57]
	at org.apache.lucene.index.IndexWriter.<init>(IndexWriter.java:1097) ~[lucene-core-3.1.0.jar:3.1.0 1085809 - 2011-03-26 17:59:57]
	at org.hibernate.search.backend.Workspace.createNewIndexWriter(Workspace.java:202) ~[hibernate-search-3.4.2.Final.jar:3.4.2.Final]
	at org.hibernate.search.backend.Workspace.getIndexWriter(Workspace.java:180) ~[hibernate-search-3.4.2.Final.jar:3.4.2.Final]
	at org.hibernate.search.backend.impl.lucene.PerDPQueueProcessor.run(PerDPQueueProcessor.java:103) [hibernate-search-3.4.2.Final.jar:3.4.2.Final]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [na:1.8.0_191]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [na:1.8.0_191]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [na:1.8.0_191]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [na:1.8.0_191]
	at java.lang.Thread.run(Thread.java:748) [na:1.8.0_191]
2019-04-16 17:25:02,146 [Hibernate Search: Directory writer-1] ERROR o.h.s.b.i.lucene.PerDPQueueProcessor - Unexpected error in Lucene Backend: 
org.hibernate.search.SearchException: Unable to remove class com.openkm.dao.bean.NodeDocument#122a22a2-00ca-4686-b4c4-8fa60a317df5 from index.
	at org.hibernate.search.backend.impl.lucene.works.DeleteWorkDelegate.performWork(DeleteWorkDelegate.java:91) ~[hibernate-search-3.4.2.Final.jar:3.4.2.Final]
	at org.hibernate.search.backend.impl.lucene.PerDPQueueProcessor.run(PerDPQueueProcessor.java:106) ~[hibernate-search-3.4.2.Final.jar:3.4.2.Final]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [na:1.8.0_191]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [na:1.8.0_191]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [na:1.8.0_191]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [na:1.8.0_191]
	at java.lang.Thread.run(Thread.java:748) [na:1.8.0_191]
Caused by: java.lang.NullPointerException: null
	at org.hibernate.search.backend.impl.lucene.works.DeleteWorkDelegate.performWork(DeleteWorkDelegate.java:87) ~[hibernate-search-3.4.2.Final.jar:3.4.2.Final]
	... 6 common frames omitted
2019-04-16 17:25:02,149 [Hibernate Search: Directory writer-1] ERROR o.h.s.exception.impl.LogErrorHandler - Exception occurred org.hibernate.search.SearchException: Unable to remove class com.openkm.dao.bean.NodeDocument#122a22a2-00ca-4686-b4c4-8fa60a317df5 from index.
Primary Failure:
	Entity com.openkm.dao.bean.NodeDocument  Id 122a22a2-00ca-4686-b4c4-8fa60a317df5  Work Type  org.hibernate.search.backend.DeleteLuceneWork
Subsequent failures:
	Entity com.openkm.dao.bean.NodeDocument  Id 122a22a2-00ca-4686-b4c4-8fa60a317df5  Work Type  org.hibernate.search.backend.AddLuceneWork
	Entity com.openkm.dao.bean.NodeDocument  Id 122a22a2-00ca-4686-b4c4-8fa60a317df5  Work Type  org.hibernate.search.backend.AddLuceneWork

org.hibernate.search.SearchException: Unable to remove class com.openkm.dao.bean.NodeDocument#122a22a2-00ca-4686-b4c4-8fa60a317df5 from index.
	at org.hibernate.search.backend.impl.lucene.works.DeleteWorkDelegate.performWork(DeleteWorkDelegate.java:91) ~[hibernate-search-3.4.2.Final.jar:3.4.2.Final]
	at org.hibernate.search.backend.impl.lucene.PerDPQueueProcessor.run(PerDPQueueProcessor.java:106) ~[hibernate-search-3.4.2.Final.jar:3.4.2.Final]
	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [na:1.8.0_191]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [na:1.8.0_191]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [na:1.8.0_191]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [na:1.8.0_191]
	at java.lang.Thread.run(Thread.java:748) [na:1.8.0_191]
Caused by: java.lang.NullPointerException: null
	at org.hibernate.search.backend.impl.lucene.works.DeleteWorkDelegate.performWork(DeleteWorkDelegate.java:87) ~[hibernate-search-3.4.2.Final.jar:3.4.2.Final]
	... 6 common frames omitted
2019-04-16 17:25:02,150 [Hibernate Search: Directory writer-1] WARN  o.hibernate.search.backend.Workspace - going to force release of the IndexWriter lock
2019-04-16 17:25:02,151 [Thread-14] INFO  c.o.extractor.TextExtractorWorker - processSerial.Working on {docUuid=122fd00d-6877-471b-9b54-7a57f750e8a2, docPath=/okm:root/globeunion/SupplyChain/InboundContainer_Photos/container loading PO#19170pic2.JPG, docVerUuid=296b8316-b8e5-4722-8e9b-9cfd19c1efa4, date=Wed Feb 20 20:33:45 CST 2019}
2019-04-16 17:25:02,280 [Thread-14] WARN  c.o.e.Tesseract3TextExtractor - Undefined OCR application
2019-04-16 17:25:02,282 [Thread-14] WARN  c.o.extractor.RegisteredExtractors - Text extraction failure: Undefined OCR application
2019-04-16 17:25:02,284 [Thread-14] WARN  com.openkm.dao.NodeDocumentDAO - There was a problem extracting text from '/okm:root/globeunion/SupplyChain/InboundContainer_Photos/container loading PO#19170pic2.JPG': Undefined OCR application
2019-04-16 17:25:02,481 [Thread-14] INFO  c.o.extractor.TextExtractorWorker - processSerial.Working on {docUuid=12553e54-5586-412e-925e-7c3455a502fa, docPath=/okm:root/globeunion/SupplyChain/InboundContainer_Photos/container loading PO#132296pic1.JPG, docVerUuid=722a9169-f2ec-4de0-9d21-be5cd709b022, date=Thu Mar 28 01:31:50 CDT 2019}
2019-04-16 17:25:02,619 [Thread-14] WARN  c.o.e.Tesseract3TextExtractor - Undefined OCR application
2019-04-16 17:25:02,620 [Thread-14] WARN  c.o.extractor.RegisteredExtractors - Text extraction failure: Undefined OCR application
2019-04-16 17:25:02,622 [Thread-14] WARN  com.openkm.dao.NodeDocumentDAO - There was a problem extracting text from '/okm:root/globeunion/SupplyChain/InboundContainer_Photos/container loading PO#132296pic1.JPG': Undefined OCR application
2019-04-16 17:25:02,677 [Thread-14] INFO  c.o.extractor.TextExtractorWorker - processSerial.Working on {docUuid=12e2224c-5daa-4081-81de-fdb5e67f702a, docPath=/okm:root/globeunion/SupplyChain/InboundContainer_Photos/container loading PO#19224pic1.JPG, docVerUuid=385f5c42-8e03-4fef-b0c3-7a22df878af4, date=Mon Mar 25 22:12:56 CDT 2019}
2019-04-16 17:25:02,803 [Thread-14] WARN  c.o.e.Tesseract3TextExtractor - Undefined OCR application
2019-04-16 17:25:02,804 [Thread-14] WARN  c.o.extractor.RegisteredExtractors - Text extraction failure: Undefined OCR application
2019-04-16 17:25:02,806 [Thread-14] WARN  com.openkm.dao.NodeDocumentDAO - There was a problem extracting text from '/okm:root/globeunion/SupplyChain/InboundContainer_Photos/container loading PO#19224pic1.JPG': Undefined OCR application
2019-04-16 17:25:02,844 [Thread-14] INFO  c.o.extractor.TextExtractorWorker - processSerial.Working on {docUuid=1322e5b6-1ee2-411a-8b6b-04e0383e0493, docPath=/okm:root/globeunion/SupplyChain/InboundContainer_Photos/container loading PO#19259pic2.JPG, docVerUuid=419f8881-afcd-49a3-8a56-b75f0fbb8b3a, date=Thu Apr 04 02:44:36 CDT 2019}
2019-04-16 17:25:02,949 [Thread-14] WARN  c.o.e.Tesseract3TextExtractor - Undefined OCR application
2019-04-16 17:25:02,951 [Thread-14] WARN  c.o.extractor.RegisteredExtractors - Text extraction failure: Undefined OCR application
2019-04-16 17:25:02,952 [Thread-14] WARN  com.openkm.dao.NodeDocumentDAO - There was a problem extracting text from '/okm:root/globeunion/SupplyChain/InboundContainer_Photos/container loading PO#19259pic2.JPG': Undefined OCR application
2019-04-16 17:25:02,986 [Thread-14] INFO  c.o.extractor.TextExtractorWorker - processSerial.Working on {docUuid=13297b06-bede-4867-9254-3b697cd69348, docPath=/okm:root/globeunion/SupplyChain/InboundContainer_Photos/container loading PO#132116pic5.JPG, docVerUuid=bebb99be-689c-46c1-aae1-004f57e26035, date=Thu Mar 14 03:17:45 CDT 2019}
2019-04-16 17:25:03,101 [Thread-14] WARN  c.o.e.Tesseract3TextExtractor - Undefined OCR application
2019-04-16 17:25:03,102 [Thread-14] WARN  c.o.extractor.RegisteredExtractors - Text extraction failure: Undefined OCR application
2019-04-16 17:25:03,103 [Thread-14] WARN  com.openkm.dao.NodeDocumentDAO - There was a problem extracting text from '/okm:root/globeunion/SupplyChain/InboundContainer_Photos/container loading PO#132116pic5.JPG': Undefined OCR application
2019-04-16 17:25:03,130 [Thread-14] INFO  c.o.extractor.TextExtractorWorker - processSerial.Working on {docUuid=137c55ee-797d-4ae2-a433-9670e2e2fd40, docPath=/okm:root/globeunion/SupplyChain/InboundContainer_Photos/container loading PO#19237pic2.JPG, docVerUuid=49d6e6e7-dc7b-4388-8e48-5965a0d5ee6d, date=Wed Mar 06 20:38:44 CST 2019}
2019-04-16 17:25:03,290 [Thread-14] WARN  c.o.e.Tesseract3TextExtractor - Undefined OCR application
2019-04-16 17:25:03,292 [Thread-14] WARN  c.o.extractor.RegisteredExtractors - Text extraction failure: Undefined OCR application
2019-04-16 17:25:03,295 [Thread-14] WARN  com.openkm.dao.NodeDocumentDAO - There was a problem extracting text from '/okm:root/globeunion/SupplyChain/InboundContainer_Photos/container loading PO#19237pic2.JPG': Undefined OCR application
2019-04-16 17:25:03,323 [Thread-14] INFO  c.o.extractor.TextExtractorWorker - processSerial.Working on {docUuid=13f50c99-be51-4fae-9453-6825b1cf3113, docPath=/okm:root/globeunion/SupplyChain/InboundContainer_Photos/container loading PO#19221pic7.JPG, docVerUuid=0dc381c3-f2db-42ff-82a3-13f5bf1dae51, date=Wed Apr 03 04:16:13 CDT 2019}
2019-04-16 17:25:03,439 [Thread-14] WARN  c.o.e.Tesseract3TextExtractor - Undefined OCR application
2019-04-16 17:25:03,441 [Thread-14] WARN  c.o.extractor.RegisteredExtractors - Text extraction failure: Undefined OCR application
2019-04-16 17:25:03,443 [Thread-14] WARN  com.openkm.dao.NodeDocumentDAO - There was a problem extracting text from '/okm:root/globeunion/SupplyChain/InboundContainer_Photos/container loading PO#19221pic7.JPG': Undefined OCR application
2019-04-16 17:25:03,471 [Thread-14] INFO  c.o.extractor.TextExtractorWorker - processSerial.Working on {docUuid=14b7fdc3-e07f-406b-80b3-4bf7211251e6, docPath=/okm:root/globeunion/SupplyChain/InboundContainer_Photos/container loading PO#132268pic5.JPG, docVerUuid=ed9ed501-f91f-4ca5-9f82-9c6e05230386, date=Wed Mar 20 02:32:32 CDT 2019}
2019-04-16 17:25:03,573 [Thread-14] WARN  c.o.e.Tesseract3TextExtractor - Undefined OCR application
2019-04-16 17:25:03,575 [Thread-14] WARN  c.o.extractor.RegisteredExtractors - Text extraction failure: Undefined OCR application
2019-04-16 17:25:03,576 [Thread-14] WARN  com.openkm.dao.NodeDocumentDAO - There was a problem extracting text from '/okm:root/globeunion/SupplyChain/InboundContainer_Photos/container loading PO#132268pic5.JPG': Undefined OCR application
2019-04-16 17:25:03,604 [Thread-14] INFO  c.o.extractor.TextExtractorWorker - processSerial.Working on {docUuid=14c54cb4-51e9-44a7-9f77-412ce010c4bd, docPath=/okm:root/globeunion/SupplyChain/InboundContainer_Photos/container loading PO#132294pic3.JPG, docVerUuid=a619c0df-e372-4884-bb14-5924fd21821d, date=Wed Mar 20 02:37:11 CDT 2019}
2019-04-16 17:25:03,829 [Thread-14] WARN  c.o.e.Tesseract3TextExtractor - Undefined OCR application
2019-04-16 17:25:03,830 [Thread-14] WARN  c.o.extractor.RegisteredExtractors - Text extraction failure: Undefined OCR application
2019-04-16 17:25:03,832 [Thread-14] WARN  com.openkm.dao.NodeDocumentDAO - There was a problem extracting text from '/okm:root/globeunion/SupplyChain/InboundContainer_Photos/container loading PO#132294pic3.JPG': Undefined OCR application
2019-04-16 17:25:03,851 [Thread-14] WARN  com.openkm.core.Cron - Crontab task mail address is empty: Return: null
<hr/>
StdOut: 
<hr/>
StdErr: 
2019-04-16 17:29:34,065 [Update Info] INFO  com.openkm.core.UpdateInfo - *** UpdateInfo activated ***
2019-04-16 17:29:34,069 [Update Info] INFO  c.o.module.common.CommonAuthModule - PrincipalAdapter: com.openkm.principal.DatabasePrincipalAdapter
2019-04-16 17:29:34,471 [Update Info] INFO  com.openkm.util.Update - checkVersion:
 #47788  by jllort
 
Do you watch the OpenKM login screen? seems application start up.
Code: Select all
16-Apr-2019 17:24:34.682 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["http-nio-0.0.0.0-8080"]
16-Apr-2019 17:24:34.692 INFO [main] org.apache.coyote.AbstractProtocol.start Starting ProtocolHandler ["ajp-nio-127.0.0.1-8009"]
16-Apr-2019 17:24:34.696 INFO [main] org.apache.catalina.startup.Catalina.start Server startup in 35534 ms
At least you have a error with search engine, might be several reasons ( this is a bad error and might explain why you are not success in login ).
Code: Select all
2019-04-16 17:25:02,135 [Hibernate Search: Directory writer-1] ERROR o.h.s.exception.impl.LogErrorHandler - Exception occurred org.apache.lucene.store.LockObtainFailedException: Lock obtain timed out: SimpleFSLock@/home/openkmadmin/tomcat-8.5.24/repository/index/com.openkm.dao.bean.NodeBase/write.lock
Primary Failure:
Entity com.openkm.dao.bean.NodeDocument Id 122a22a2-00ca-4686-b4c4-8fa60a317df5 Work Type org.hibernate.search.backend.DeleteLuceneWork
Subsequent failures:
Entity com.openkm.dao.bean.NodeDocument Id 122a22a2-00ca-4686-b4c4-8fa60a317df5 Work Type org.hibernate.search.backend.AddLuceneWork

org.apache.lucene.store.LockObtainFailedException: Lock obtain timed out: SimpleFSLock@/home/openkmadmin/tomcat-8.5.24/repository/index/com.openkm.dao.bean.NodeBase/write.lock
at org.apache.lucene.store.Lock.obtain(Lock.java:84) ~[lucene-core-3.1.0.jar:3.1.0 1085809 - 2011-03-26 17:59:57]
at org.apache.lucene.index.IndexWriter.<init>(IndexWriter.java:1097) ~[lucene-core-3.1.0.jar:3.1.0 1085809 - 2011-03-26 17:59:57]
at org.hibernate.search.backend.Workspace.createNewIndexWriter(Workspace.java:202) ~[hibernate-search-3.4.2.Final.jar:3.4.2.Final]
at org.hibernate.search.backend.Workspace.getIndexWriter(Workspace.java:180) ~[hibernate-search-3.4.2.Final.jar:3.4.2.Final]
at org.hibernate.search.backend.impl.lucene.PerDPQueueProcessor.run(PerDPQueueProcessor.java:103) [hibernate-search-3.4.2.Final.jar:3.4.2.Final]
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511) [na:1.8.0_191]
at java.util.concurrent.FutureTask.run(FutureTask.java:266) [na:1.8.0_191]
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [na:1.8.0_191]
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [na:1.8.0_191]
at java.lang.Thread.run(Thread.java:748) [na:1.8.0_191]
2019-04-16 17:25:02,146 [Hibernate Search: Directory writer-1] ERROR o.h.s.b.i.lucene.PerDPQueueProcessor - Unexpected error in Lucene Backend:
org.hibernate.search.SearchException: Unable to remove class com.openkm.dao.bean.NodeDocument#122a22a2-00ca-4686-b4c4-8fa60a317df5 from index.
at org.hibernate.search.backend.impl.lucene.works.DeleteWorkDelegate.performWork(DeleteWorkDelegate.java:91) ~[hibernate-search-3.4.2.Final.jar:3.4.2.Final]
at org.hibernate.search.backend.impl.lucene.PerDPQueueProcessor.run(PerDPQueueProcessor.java:106) ~[hibernate-search-3.4.2.Final.jar:3.4.2.Final]
Are you starting application under user openkm or what user are you using for starting the application?

Also you have another error much is not so relevant in the crontab. Did you created crontab task?
Code: Select all
Exception in thread "Crontab Manager" com.kenai.crontabparser.impl.TokenMgrError: Lexical error at line 1, column 2. Encountered: "." (46), after : ""
at com.kenai.crontabparser.impl.CronTabParserTokenManager.getNextToken(CronTabParserTokenManager.java:1030)
at com.kenai.crontabparser.impl.CronTabParser.jj_scan_token(CronTabParser.java:1101)
at com.kenai.crontabparser.impl.CronTabParser.jj_3R_5(CronTabParser.java:823)
at com.kenai.crontabparser.impl.CronTabParser.jj_3_8(CronTabParser.java:845)
at com.kenai.crontabparser.impl.CronTabParser.jj_3R_4(CronTabParser.java:852)
at com.kenai.crontabparser.impl.CronTabParser.jj_3_1(CronTabParser.java:943)
at com.kenai.crontabparser.impl.CronTabParser.jj_2_1(CronTabParser.java:749)
at com.kenai.crontabparser.impl.CronTabParser.Entry(CronTabParser.java:287)
at com.kenai.crontabparser.impl.CronTabParser.MinuteField(CronTabParser.java:123)
at com.kenai.crontabparser.impl.CronTabParser.StandardExpression(CronTabParser.java:91)
at com.kenai.crontabparser.impl.CronTabParser.CronTabExpression(CronTabParser.java:34)
at com.kenai.crontabparser.impl.CronTabParser.parse(CronTabParser.java:16)
at com.kenai.crontabparser.impl.CronTabParserBridge.parse(CronTabParserBridge.java:28)
at com.kenai.crontabparser.CronTabExpression.parse(CronTabExpression.java:231)
at com.openkm.core.Cron.run(Cron.java:49)
at java.util.TimerThread.mainLoop(Timer.java:555)
at java.util.TimerThread.run(Timer.java:505)
2019-04-16 17:25:00,361 [Thread-15] WARN com.openkm.core.Cron - Crontab task mail address is empty: Return: null
<hr/>
StdOut:
<hr/>
StdErr:
2019-04-16 17:25:00,431 [Thread-16] WARN com.openkm.core.Cron - Crontab task mail address is empty: Return: null
<hr/>
StdOut:
<hr/>
StdErr: 
 #48653  by Miss Makebe
 
I am not able to run OpenKM on services. Each time i try, i am told to contact service vendor and refer to service specific error code 4.
also, when i try to connect to the server with IP:8080/OpenKM, ERR_CONNECTION_REFUSED
IP does not authorise the conncetion. Can you help me?
 #48683  by jllort
 
Better if you stop the application. Remove all the files into logs folder. Start openkm from command line with ( file is into bin folder ):
catalina.bat run

When the service started be shown in the log, try to login.
Stop the application and share the catalina.log file here

About Us

OpenKM is part of the management software. A management software is a program that facilitates the accomplishment of administrative tasks. OpenKM is a document management system that allows you to manage business content and workflow in a more efficient way. Document managers guarantee data protection by establishing information security for business content.