Can't login to sac or webmail

[ScalixSupport]

I made the change to the scalix-jk-common.conf file and restarted the processes (I even restarted the box) but when I run the lsof -i:8009 the result is:
[root@ctrfed04 ~]# lsof -i:8009
COMMAND PID USER FD TYPE DEVICE SIZE NODE NAME
java 2481 root 22u IPv4 9330 TCP localhost.localdomain:8009 (LISTEN)

I re-checked the file and the line is:
kWorkerProperty worker.worker1.type=ajp13
JkWorkerProperty worker.worker1.host=ctrfed04.caribshopper.com
JkWorkerProperty worker.worker1.port=8009
JkWorkerProperty worker.worker1.lbfactor=50
JkWorkerProperty worker.worker1.cachesize=10

What am I doing wrong?????

In the post where I told you that you needed to edit scalix-jk-common.conf, I said it was only necessary to do that if you had tomcat listening on something other than localhost. This doesn't look to be the case so you need to set JkWorkerProperty worker.worker1.host back to localhost and restart Apache.

You also need to check /etc/hosts to make sure that you have nothing other than localhost.localdomain and localhost on the 127.0.0.1 line.

Cheers

Dave

[maracas]

I re-installed scalix include scalix-tomcat. Changed the host fie back to:
127.0.0.1 localhost.localdomain localhost

This is the current scalix-jk-common.conf file:

Code: Select all

JkWorkerProperty worker.worker1.type=ajp13
JkWorkerProperty worker.worker1.host=localhost
JkWorkerProperty worker.worker1.port=8009
JkWorkerProperty worker.worker1.lbfactor=50
JkWorkerProperty worker.worker1.cachesize=10
JkWorkerProperty worker.worker1.cache_timeout=600
JkWorkerProperty worker.worker1.socket_keepalive=1
JkWorkerProperty worker.worker1.reclycle_timeout=300



This is the caa.log
t

Code: Select all

2006-03-11 19:14:26,848  INFO [RESMonitor.run:115] Server ctrfed04.caribshopper.com up 41 mins, 38 secs
2006-03-11 19:14:53,819  INFO [NotificationEventListener$WorkerThread.run:50] Event Notification: heartbeat|http://ctrfed0$
2006-03-11 19:15:26,852  INFO [RESMonitor.run:115] Server ctrfed04.caribshopper.com up 42 mins, 38 secs
2006-03-11 19:15:53,824  INFO [NotificationEventListener$WorkerThread.run:50] Event Notification: heartbeat|http://ctrfed0$
2006-03-11 19:16:26,857  INFO [RESMonitor.run:115] Server ctrfed04.caribshopper.com up 43 mins, 38 secs
2006-03-11 19:16:53,828  INFO [NotificationEventListener$WorkerThread.run:50] Event Notification: heartbeat|http://ctrfed0$
2006-03-11 19:17:26,861  INFO [RESMonitor.run:115] Server ctrfed04.caribshopper.com up 44 mins, 38 secs
2006-03-11 19:17:53,832  INFO [NotificationEventListener$WorkerThread.run:50] Event Notification: heartbeat|http://ctrfed0$
2006-03-11 19:18:26,865  INFO [RESMonitor.run:115] Server ctrfed04.caribshopper.com up 45 mins, 38 secs
2006-03-11 19:18:53,837  INFO [NotificationEventListener$WorkerThread.run:50] Event Notification: heartbeat|http://ctrfed0$
2006-03-11 19:19:26,870  INFO [RESMonitor.run:115] Server ctrfed04.caribshopper.com up 46 mins, 38 secs
2006-03-11 19:19:53,841  INFO [NotificationEventListener$WorkerThread.run:50] Event Notification: heartbeat|http://ctrfed0$
2006-03-11 19:20:26,874  INFO [RESMonitor.run:115] Server ctrfed04.caribshopper.com up 47 mins, 38 secs
$trfed04.caribshopper.com/res/RESDispatcher|LISTEN|300|ctrfed04|ctrfed04.caribshopper.com|10.0.0 from host:ctrfed04.caribs$


This is the catalina.out file:

Code: Select all

Mar 11, 2006 6:32:04 PM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 7861 ms
Mar 11, 2006 6:32:06 PM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
Mar 11, 2006 6:32:06 PM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/5.0.28
Mar 11, 2006 6:32:06 PM org.apache.catalina.core.StandardHost start
INFO: XML validation disabled
Mar 11, 2006 6:32:06 PM org.apache.catalina.core.StandardHost getDeployer
INFO: Create Host deployer for direct deployment ( non-jmx )
Mar 11, 2006 6:32:06 PM org.apache.catalina.core.StandardHostDeployer install
INFO: Processing Context configuration file URL file:/opt/scalix-tomcat/conf/Catalina/localhost/scalix-admin.xml
Mar 11, 2006 6:32:14 PM org.apache.catalina.core.StandardHostDeployer install
INFO: Processing Context configuration file URL file:/opt/scalix-tomcat/conf/Catalina/localhost/scalix-caa.xml
Mar 11, 2006 6:32:20 PM org.apache.catalina.loader.WebappClassLoader validateJarFile
INFO: validateJarFile(/opt/scalix-tomcat/webapps/caa/WEB-INF/lib/j2ee.jar) - jar not loaded. See Servlet Spec 2.3, section$
CAA: caa-root-directory is /etc/opt/scalix/caa/
CAA: Loaded web.xml init params
CAA: caa-config-directory is /etc/opt/scalix/caa/config
CAA: log4j-init-file is /etc/opt/scalix/caa/config/log4j.properties
CAA: caa-framework-init-file is /etc/opt/scalix/caa/config/framework-config.xml
Mar 11, 2006 6:32:26 PM org.apache.catalina.core.StandardHostDeployer install
INFO: Processing Context configuration file URL file:/opt/scalix-tomcat/conf/Catalina/localhost/manager.xml
Mar 11, 2006 6:32:29 PM org.apache.catalina.core.StandardHostDeployer install
INFO: Processing Context configuration file URL file:/opt/scalix-tomcat/conf/Catalina/localhost/scalix-webcal.xml
reading configuration @ /etc/opt/scalix/webmail/swa.properties
Mar 11, 2006 6:32:36 PM org.apache.catalina.core.StandardHostDeployer install
INFO: Processing Context configuration file URL file:/opt/scalix-tomcat/conf/Catalina/localhost/scalix-res.xml
RES: Initializing log4j using file /etc/opt/scalix/res/config/log4j.properties
Mar 11, 2006 6:32:43 PM org.apache.catalina.core.StandardHostDeployer install
INFO: Processing Context configuration file URL file:/opt/scalix-tomcat/conf/Catalina/localhost/admin.xml
Mar 11, 2006 6:32:48 PM org.apache.struts.util.PropertyMessageResources <init>
INFO: Initializing, config='org.apache.struts.util.LocalStrings', returnNull=true
Mar 11, 2006 6:32:48 PM org.apache.struts.util.PropertyMessageResources <init>
INFO: Initializing, config='org.apache.struts.action.ActionResources', returnNull=true
Mar 11, 2006 6:32:51 PM org.apache.struts.util.PropertyMessageResources <init>
INFO: Initializing, config='org.apache.webapp.admin.ApplicationResources', returnNull=true
Mar 11, 2006 6:33:03 PM org.apache.catalina.core.StandardHostDeployer install
INFO: Processing Context configuration file URL file:/opt/scalix-tomcat/conf/Catalina/localhost/scalix-swa.xml
reading configuration @ /etc/opt/scalix/webmail/swa.properties
Mar 11, 2006 6:33:09 PM org.apache.catalina.core.StandardHostDeployer install
INFO: Processing Context configuration file URL file:/opt/scalix-tomcat/conf/Catalina/localhost/balancer.xml
Mar 11, 2006 6:33:11 PM org.apache.catalina.core.StandardHostDeployer install
INFO: Installing web application at context path /jsp-examples from URL file:/opt/scalix-tomcat/webapps/jsp-examples
Mar 11, 2006 6:33:12 PM org.apache.catalina.core.StandardHostDeployer install
INFO: Installing web application at context path  from URL file:/opt/scalix-tomcat/webapps/ROOT
Mar 11, 2006 6:33:12 PM org.apache.catalina.core.StandardHostDeployer install
INFO: Installing web application at context path /tomcat-docs from URL file:/opt/scalix-tomcat/webapps/tomcat-docs
Mar 11, 2006 6:33:12 PM org.apache.catalina.core.StandardHostDeployer install


Getting the same 503 error when trying to access sac or webmail page.

I keep thinking I made a round turn somewhere but each time it works once I remove the JK connector.
Is there another log that might give me some hints?

apache log:

Code: Select all

10.86.225.60 - - [11/Mar/2006:18:33:26 -0500] "GET /sac?authid=sxadmin@caribshopper.com HTTP/1.1" 503 296 "-" "Mozilla/5.0$


[maracas]

On the tomcat site they make reference to an include statement in the httpd.conf file as follows:

example:
Include /usr/local/jakarta-tomcat/conf/auto/mod_jk.conf

When I checked my httpd.conf no such include exist. Does this have anything to do with the erros or am I going off on the wrong track?

http://tomcat.apache.org/tomcat-3.3-doc/mod_jk-howto.html

I check each time and the scalix-tomcat service is started. Why does it think that it is not?

[ScalixSupport]

Look at your mod_jk log file again and also check to see if you have selinux enabled.

As per the release notes, we need that it is disabled. You will need to reboot to effectively pick up the change.

Cheers

Dave

[maracas]

rebooted server and get the same error when accessing the sac page.

here is the selinux config file:
# This file controls the state of SELinux on the system.
# SELINUX= can take one of these three values:
# enforcing - SELinux security policy is enforced.
# permissive - SELinux prints warnings instead of enforcing.
# disabled - SELinux is fully disabled.
SELINUX=disabled
# SELINUXTYPE= type of policy in use. Possible values are:
# targeted - Only targeted network daemons are protected.
# strict - Full SELinux protection.
SELINUXTYPE=targeted

Here is the mod_jk.log file:

Code: Select all



[Sat Mar 11 21:05:22 2006] [info]  jk_open_socket::jk_connect.c (444): connect to 10.86.225.60:8009 failed with $
[Sat Mar 11 21:05:22 2006] [info]  ajp_connect_to_endpoint::jk_ajp_common.c (889): Failed opening socket to (10.$
[Sat Mar 11 21:05:22 2006] [info]  ajp_send_request::jk_ajp_common.c (1248): Error connecting to the Tomcat proc$
[Sat Mar 11 21:05:22 2006] [info]  ajp_service::jk_ajp_common.c (1749): Sending request to tomcat failed,  recov$
[Sat Mar 11 21:05:22 2006] [info]  jk_open_socket::jk_connect.c (444): connect to 10.86.225.60:8009 failed with $
[Sat Mar 11 21:05:22 2006] [info]  ajp_connect_to_endpoint::jk_ajp_common.c (889): Failed opening socket to (10.$
[Sat Mar 11 21:05:22 2006] [info]  ajp_send_request::jk_ajp_common.c (1248): Error connecting to the Tomcat proc$
[Sat Mar 11 21:05:22 2006] [info]  ajp_service::jk_ajp_common.c (1749): Sending request to tomcat failed,  recov$
[Sat Mar 11 21:05:22 2006] [info]  jk_open_socket::jk_connect.c (444): connect to 10.86.225.60:8009 failed with $
[Sat Mar 11 21:05:22 2006] [info]  ajp_connect_to_endpoint::jk_ajp_common.c (889): Failed opening socket to (10.$
[Sat Mar 11 21:05:22 2006] [info]  ajp_send_request::jk_ajp_common.c (1248): Error connecting to the Tomcat proc$
[Sat Mar 11 21:05:22 2006] [info]  ajp_service::jk_ajp_common.c (1749): Sending request to tomcat failed,  recov$
[Sat Mar 11 21:05:22 2006] [error] ajp_service::jk_ajp_common.c (1758): Error connecting to tomcat. Tomcat is pr$
[Sat Mar 11 21:05:22 2006] worker1 10.86.225.60 0.029604

[Sat Mar 11 21:05:22 2006] [info]  jk_handler::mod_jk.c (1971): Service error=0 for worker=worker1


It keeps giving the error connecting to Tomcat.

[ScalixSupport]

What is truncating the mod_jk.log output ?

Cheers

Dave

[maracas]

Thanks Dave for responding to my questions. Really appreciate the service.

Don't understand the question. I pasted the last part of the log file (it loks like it writes to the log on each access attempt). I would not know why or what could be truncating thee log.

maracas

[ScalixSupport]

Each line you posted ends in a "$" character indicating that there is more information.

Cheers

Dave

[maracas]

Sorry about that -- I was coping from nano which adds the $ at the end of screen.

here is the complete log:

Code: Select all

[Sat Mar 11 21:05:22 2006] [info]  jk_open_socket::jk_connect.c (444): connect to 10.86.225.60:8009 failed with errno=111
[Sat Mar 11 21:05:22 2006] [info]  ajp_connect_to_endpoint::jk_ajp_common.c (889): Failed opening socket to (10.86.225.60:8009) with (errno=111)
[Sat Mar 11 21:05:22 2006] [info]  ajp_send_request::jk_ajp_common.c (1248): Error connecting to the Tomcat process.
[Sat Mar 11 21:05:22 2006] [info]  ajp_service::jk_ajp_common.c (1749): Sending request to tomcat failed,  recoverable operation attempt=1
[Sat Mar 11 21:05:22 2006] [info]  jk_open_socket::jk_connect.c (444): connect to 10.86.225.60:8009 failed with errno=111
[Sat Mar 11 21:05:22 2006] [info]  ajp_connect_to_endpoint::jk_ajp_common.c (889): Failed opening socket to (10.86.225.60:8009) with (errno=111)
[Sat Mar 11 21:05:22 2006] [info]  ajp_send_request::jk_ajp_common.c (1248): Error connecting to the Tomcat process.
[Sat Mar 11 21:05:22 2006] [info]  ajp_service::jk_ajp_common.c (1749): Sending request to tomcat failed,  recoverable operation attempt=2
[Sat Mar 11 21:05:22 2006] [info]  jk_open_socket::jk_connect.c (444): connect to 10.86.225.60:8009 failed with errno=111
[Sat Mar 11 21:05:22 2006] [info]  ajp_connect_to_endpoint::jk_ajp_common.c (889): Failed opening socket to (10.86.225.60:8009) with (errno=111)
[Sat Mar 11 21:05:22 2006] [info]  ajp_send_request::jk_ajp_common.c (1248): Error connecting to the Tomcat process.
[Sat Mar 11 21:05:22 2006] [info]  ajp_service::jk_ajp_common.c (1749): Sending request to tomcat failed,  recoverable operation attempt=3
[Sat Mar 11 21:05:22 2006] [error] ajp_service::jk_ajp_common.c (1758): Error connecting to tomcat. Tomcat is probably not started or is listening on the wrong port. worker=worker1 failed
[Sat Mar 11 21:05:22 2006] worker1 10.86.225.60 0.029604

[Sat Mar 11 21:05:22 2006] [info]  jk_handler::mod_jk.c (1971): Service error=0 for worker=worker1


maracas

[ScalixSupport]

Code: Select all

[Sat Mar 11 21:05:22 2006] [info]  jk_open_socket::jk_connect.c (444): connect to 10.86.225.60:8009 failed with errno=111

The fact that this is showing an address other than 127.0.0.1 indicates that you didn't change the scalix-jk-common.conf file back to connect via localhost.

Please can you do that ?

Cheers

Dave.

[dmode]

[quote="init3"]Thanks for the info. I killed the process and restarted it with

Code: Select all

 /etc/init.d/scalix-tomcat restart

Same result. 503 error. I think I may have solved it as it may be DNS related, it seems SCALIX is very finicky about DNS.

[SeanMatula]

Hello,

I'm having the same problem as the original poster.


"lsof -i :8009" gives

Code: Select all

COMMAND   PID USER   FD   TYPE DEVICE SIZE NODE NAME
java    10219 root   10u  IPv4  58507       TCP localhost:8009 (LISTEN)

mod_jk.log:

Code: Select all

[Sun Mar 19 19:02:57 2006] [info]  jk_open_socket::jk_connect.c (444): connect to 192.168.1.2:8009 failed with errno=111
[Sun Mar 19 19:02:57 2006] [info]  ajp_connect_to_endpoint::jk_ajp_common.c (889): Failed opening socket to (192.168.1.2:8009) with (errno=111)
[Sun Mar 19 19:02:57 2006] [info]  ajp_send_request::jk_ajp_common.c (1248): Error connecting to the Tomcat process.
[Sun Mar 19 19:02:57 2006] [info]  ajp_service::jk_ajp_common.c (1749): Sending request to tomcat failed,  recoverable operation attempt=1
[Sun Mar 19 19:02:57 2006] [info]  jk_open_socket::jk_connect.c (444): connect to 192.168.1.2:8009 failed with errno=111
[Sun Mar 19 19:02:57 2006] [info]  ajp_connect_to_endpoint::jk_ajp_common.c (889): Failed opening socket to (192.168.1.2:8009) with (errno=111)
[Sun Mar 19 19:02:57 2006] [info]  ajp_send_request::jk_ajp_common.c (1248): Error connecting to the Tomcat process.
[Sun Mar 19 19:02:57 2006] [info]  ajp_service::jk_ajp_common.c (1749): Sending request to tomcat failed,  recoverable operation attempt=2
[Sun Mar 19 19:02:57 2006] [info]  jk_open_socket::jk_connect.c (444): connect to 192.168.1.2:8009 failed with errno=111
[Sun Mar 19 19:02:57 2006] [info]  ajp_connect_to_endpoint::jk_ajp_common.c (889): Failed opening socket to (192.168.1.2:8009) with (errno=111)
[Sun Mar 19 19:02:57 2006] [info]  ajp_send_request::jk_ajp_common.c (1248): Error connecting to the Tomcat process.
[Sun Mar 19 19:02:57 2006] [info]  ajp_service::jk_ajp_common.c (1749): Sending request to tomcat failed,  recoverable operation attempt=3
[Sun Mar 19 19:02:57 2006] [error] ajp_service::jk_ajp_common.c (1758): Error connecting to tomcat. Tomcat is probably not started or is listening on the wrong port. worker=worker1 failed
[Sun Mar 19 19:02:57 2006] worker1 localhost 0.014312

[Sun Mar 19 19:02:57 2006] [info]  jk_handler::mod_jk.c (1971): Service error=0 for worker=worker1

• selinux is disabled
• JkWorkerProperty worker.worker1.host=localhost
• Scalix installed on FC4

I'll post any log/conf files you think will help (as long as you can tell me where they are :))

Thanks,

Sean

[ScalixSupport]

Hi Sean,

Can you post your /etc/hosts file? It looks like your localhost entry may be incorrect.

Thanks,
Rachel

[SeanMatula]

127.0.0.1 localhost
192.168.1.2 tuxserver tuxserver.csclocal.net

[ScalixSupport]

Have you made any changes to either the /etc/hosts or the scalix-jk-common.conf file? According to the log files, jk_connect is attempting to connect to your servers IP not localhost. Can you type:

telnet locahost 8009

and see if it connects? If it does, please post your scalix-jk-common.conf file as well.

Thanks,
Rachel