Severe Solr install problems

[sjordi]

Hi,
I'm trying to install Alfresco 4.01 Enterprise in my test VM under CentOS 6 64-bit.
Alfresco 3 went smoothly, but I can't find a way to have 4.x up and running.
The install went all by default except I'm using MySQL and passed it the MySQL jdbc and drivers instead of PostGr

When tomcat starts, it bugs on Solr and stops everything.
My iptables are opening the ports for it, so it's not the problem.

Here is an excerpt:
EVERE: Error listenerStart
May 4, 2012 3:10:33 PM org.apache.catalina.core.StandardContext start
SEVERE: Context [/alfresco] startup failed due to previous errors
May 4, 2012 3:10:33 PM org.apache.catalina.loader.WebappClassLoader clearReferencesThreads
SEVERE: The web application [/alfresco] appears to have started a thread named [net.sf.ehcache.CacheManager@591ce164] but has failed to stop it. This is very likely to create a memory leak.
May 4, 2012 3:10:33 PM org.apache.catalina.loader.WebappClassLoader clearThreadLocalMap
SEVERE: The web application [/alfresco] created a ThreadLocal with key of type [java.lang.ThreadLocal] (value [java.lang.ThreadLocal@704f66ab]) and a value of type [org.apache.cxf.bus.CXFBusImpl] (value [org.apache.cxf.bus.CXFBusImpl@6cdd9e27]) but failed to remove it when the web application was stopped. This is very likely to create a memory leak.
May 4, 2012 3:10:33 PM org.apache.catalina.loader.WebappClassLoader clearThreadLocalMap
SEVERE: The web application [/alfresco] created a ThreadLocal with key of type [com.sun.xml.bind.v2.ClassFactory$1] (value [com.sun.xml.bind.v2.ClassFactory$1@6562a121]) and a value of type [java.util.WeakHashMap] (value [{class javax.xml.bind.annotation.W3CDomHandler=java.lang.ref.WeakReference@100f35bd}]) but failed to remove it when the web application was stopped. This is very likely to create a memory leak.
May 4, 2012 3:10:35 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory ROOT
May 4, 2012 3:10:35 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory manager
May 4, 2012 3:10:35 PM org.apache.catalina.startup.HostConfig deployDirectory
INFO: Deploying web application directory host-manager
May 4, 2012 3:10:35 PM org.apache.coyote.http11.Http11Protocol start
INFO: Starting Coyote HTTP/1.1 on http-8080
May 4, 2012 3:10:35 PM org.apache.jk.common.ChannelSocket init
INFO: JK: ajp13 listening on /0.0.0.0:8009
May 4, 2012 3:10:35 PM org.apache.jk.server.JkMain start
INFO: Jk running ID=0 time=0/11  config=null
May 4, 2012 3:10:35 PM org.apache.coyote.http11.Http11Protocol start
INFO: Starting Coyote HTTP/1.1 on http-8443
May 4, 2012 3:10:35 PM org.apache.catalina.startup.Catalina start
INFO: Server startup in 28286 ms
May 4, 2012 3:10:36 PM org.quartz.core.JobRunShell run
SEVERE: Job Solr.CoreTracker-alfresco threw an unhandled Exception:
org.alfresco.error.AlfrescoRuntimeException: 04040001 GetModelsDiff return status is 404
   at org.alfresco.solr.client.SOLRAPIClient.getModelsDiff(SOLRAPIClient.java:1017)
   at org.alfresco.solr.tracker.CoreTracker.trackModels(CoreTracker.java:1686)
   at org.alfresco.solr.tracker.CoreTracker.trackRepository(CoreTracker.java:1158)
   at org.alfresco.solr.tracker.CoreTracker.updateIndex(CoreTracker.java:512)
   at org.alfresco.solr.tracker.CoreTrackerJob.execute(CoreTrackerJob.java:45)
   at org.quartz.core.JobRunShell.run(JobRunShell.java:216)
   at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:563)

Any idea? Am I missing something simple?  :shock:

Thanks for any help.
I enclose the full catalina.out file if you're really interested in my dropbox folder:
http://dl.dropbox.com/u/11649154/catalina.out

[mrogers]

You have various errors like java.net.BindException: Address already in use <null>:8080 so it looks like you already have something running.

[sjordi]

Ok,
I'll check this once back at work.
Should be the problem, but I don't know what would listen to 8080, it's a "naked" Linux machine.
A good "lsof" will tell me  :shock:

[bisana]

SEVERE: Error initializing endpoint
java.net.BindException: Address already in use <null>:8080
   at org.apache.tomcat.util.net.JIoEndpoint.init(JIoEndpoint.java:549)

You need to kill the other process

ps aux | grep tomcat
ps aux | grep alf

Kill the process, restart the alfresco, check the catalina.out using "tail -f " and give feedback
thanks

[sjordi]

both ps aux return

/opt/alfresco/current/java/bin/java -Djava.util.logging.config.file=/opt/alfresco/current/tomcat/conf/logging.properties -XX:MaxPermSize=512m -Xms128m -Xmx1024m -XX:-DisableExplicitGC -Djava.awt.headless=true -Dalfresco.home=/opt/alfresco/current -Dcom.sun.management.jmxremote -Dsun.security.ssl.allowUnsafeRenegotiation=true -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager -Djava.endorsed.dirs=/opt/alfresco/current/tomcat/endorsed -classpath /opt/alfresco/current/tomcat/bin/bootstrap.jar -Dcatalina.base=/opt/alfresco/current/tomcat -Dcatalina.home=/opt/alfresco/current/tomcat -Djava.io.tmpdir=/opt/alfresco/current/tomcat/temp org.apache.catalina.startup.Bootstrap start

netstat -vat returns

Active Internet connections (servers and established)Proto Recv-Q Send-Q Local Address               Foreign Address             Statetcp        0      0 *:8009                      *:*                         LISTENtcp        0      0 *:mysql                     *:*                         LISTENtcp        0      0 *:52429                     *:*                         LISTENtcp        0      0 *:webcache                  *:*                         LISTENtcp        0      0 *:ssh                       *:*                         LISTENtcp        0      0 localhost:smtp              *:*                         LISTENtcp        0      0 *:pcsync-https              *:*                         LISTENtcp        0      0 localhost:mxi               *:*                         LISTENtcp        1      0 localhost:40967             localhost:webcache          CLOSE_WAITtcp        1      0 localhost:35080             localhost:webcache          CLOSE_WAITtcp        0      0 localhost:pcsync-https      localhost:52796             ESTABLISHEDtcp        0      0 localhost:35982             localhost:pcsync-https      ESTABLISHEDtcp        1      0 localhost:51163             localhost:webcache          CLOSE_WAITtcp        0      0 localhost:52796             localhost:pcsync-https      ESTABLISHEDtcp        1      0 localhost:38433             localhost:webcache          CLOSE_WAITtcp        0    500 nar.ville-ge.ch:ssh         volcano.ville-ge.ch:50061   ESTABLISHEDtcp        1      0 localhost:46177             localhost:webcache          CLOSE_WAITtcp        1      0 localhost:38458             localhost:webcache          CLOSE_WAITtcp        0      0 localhost:pcsync-https      localhost:35982             ESTABLISHED‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍‍

So what should I actually kill?
Tomcat is launched automatically by the alfresco.sh file anyway…
Sorry, but I'm a bit at lost here.

[bisana]

First make sure you have shutdown the server

 /pathwhereyouhavelafrescofolder/alfresco.sh stop    ‍

most probable you may have java  process related alfresco running  you should kill the processor of the service
like

 ps aux | grep alf‍‍‍

which gives result similar to

   ps aux | grep alfalfresco  9172  0.0  2.5 1024400 100948 ?      Sl   May02   0:02 /opt/legaldm/openoffice/program/soffice.bin -accept=socket,host=127.0.0.1,port=8100;urp;StarOffice.ServiceManager -env:UserInstallation=file:///opt/legaldm/alf_data/oouser -nologo -headless -nofirststartwizard -nocrashrep -norestorealfresco  9666  1.7 29.4 3038284 1155664 ?     Sl   May02 124:15 /opt/legaldm/java/bin/java -Djava.util.logging.config.file=/opt/legaldm/tomcat/conf/logging.properties -XX:MaxPermSize=512m -Xms128m -Xmx1024m -XX:-DisableExplicitGC -Dalfresco.home=/opt/legaldm -Dcom.sun.management.jmxremote -Dsun.security.ssl.allowUnsafeRenegotiation=true -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager -Djava.endorsed.dirs=/opt/legaldm/tomcat/endorsed -classpath /opt/legaldm/tomcat/bin/bootstrap.jar -Dcatalina.base=/opt/legaldm/tomcat -Dcatalina.home=/opt/legaldm/tomcat -Djava.io.tmpdir=/opt/legaldm/tomcat/temp org.apache.catalina.startup.Bootstrap start‍‍‍‍‍

the digit in the secound row, in this case it is 9172 and 9666
you need to kill it by

kill -9 9172kill -9 9666‍‍‍‍

the restart the alfresco service
use tail -f to see the catalina.log for the status

[sjordi]

Well,
I owe you.

You were right, the Alfresco Java process was still running despite the shutdown.

root       813  0.1  4.9 2871376 397364 ?      Sl   May04   4:15 /opt/alfresco/current/java/bin/java -Djava.util.logging.config.file=/opt/alfresco/current/tomcat/conf/logging.properties -XX:MaxPermSize=512m -Xms128m -Xmx1024m -XX:-DisableExplicitGC -Djava.awt.headless=true -Dalfresco.home=/opt/alfresco/current -Dcom.sun.management.jmxremote -Dsun.security.ssl.allowUnsafeRenegotiation=true -Djava.util.logging.manager=org.apache.juli.ClassLoaderLogManager -Djava.endorsed.dirs=/opt/alfresco/current/tomcat/endorsed -classpath /opt/alfresco/current/tomcat/bin/bootstrap.jar -Dcatalina.base=/opt/alfresco/current/tomcat -Dcatalina.home=/opt/alfresco/current/tomcat -Djava.io.tmpdir=/opt/alfresco/current/tomcat/temp org.apache.catalina.startup.Bootstrap start‍‍

Killed -9 813 and restarted the alfresco service.
No erros in catalina.out despite something else probably linked to Open Office, which is not installed

ERROR [repo.content.JodConverterSharedInstance] [main] Unable to start JodConverter library. The following error is shown for informational purposes only. org.artofsolving.jodconverter.office.OfficeException‍‍

So what does this mean? What will happen during the next reboot? Where does this Java process come from?
Any way to remove it?
I'd like to make sure that during a next restart, the admin won't need to shtudown the Alfresco service, kill the Java process and restart Alfresco.

If you have any idea why the Java process interferes, that would be great.
Thanks for your help.

[bisana]

So what does this mean? What will happen during the next reboot? Where does this Java process come from?

I guess the answer lies in this link
http://docs.alfresco.com/4.0/index.jsp?topic=%2Fcom.alfresco.enterprise.doc%2Ftasks%2Ftroubleshoot-o...

Now , if catalina.out is not showing any other errors, are you able to login through the share web interface, I guess you should be able to login
Regarding killing the java process after stooping alfresco, I don't have any idea. At present I am not taking seriously since still we are evaluating Alfresco for our needs, I think the next release may be able to solve the issue 
Thanks

[sjordi]

Ok then,
good luck.
We have Alfresco 3 here without troubles. Since I was upgrading to 4.0.1, I first installed it in a VM for evaluation purpose, in order to not jeopardize the entire production line.
Anyway, next time I'll reboot it, I'll check for that stupid java error/problem.

Thanks for your help.
Steve