"Could not retrieve the information for the selected us

[Marc]

Hi there,

after an upgrade from 9.4.2 to 10.0.1 I have a problem with the admin
console: when I click on a user in the left column, I get a popup with
an error message "Could not retrieve the information for the selected user".

In the SOAP debugger I see the entry:

Code: Select all

<SOAP-ENV:Envelope
xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/">
<SOAP-ENV:Body><SOAP-ENV:Fault><faultcode>SOAP-ENV:Server</faultcode>
<faultstring>CAA Service
error</faultstring><detail><scalix-caa:fault-details
xmlns:scalix-caa="http://www.scalix.com/caa">
<message>com.scalix.sac.ubermgr.res.RESServiceHandler:Fatal Exception
raised. Check the CAA logs
</message><errorcode>UM-1003</errorcode></scalix-caa:fault-details>
</detail></SOAP-ENV:Fault></SOAP-ENV:Body></SOAP-ENV:Envelope>

and in the caa.log:

Code: Select all

2006-05-15 10:51:05,837 FATAL
[RESServiceHandler.executeRESCommand:1844]
java.net.SocketException: Unexpected end of file from server
2006-05-15 10:51:05,837 FATAL RESServiceHandler.executeRESCommand:1853]
com.scalix.sac.ubermgr.res.RESServiceHandler:Fatal Exception raised.
Check the CAA logs

I also can't get a list of running services in the server info tab, in
this case I see:

Code: Select all

<SOAP-ENV:Envelope
xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/"
SOAP-ENV:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
<SOAP-ENV:Body><scalix-caa:CAAResponseMessage
xmlns:scalix-caa="http://www.scalix.com/caa"><ServiceType>scalix.res</ServiceType>
<FunctionName>GetServerInfo</FunctionName><ReturnGetServerInfoParameters/>
</scalix-caa:CAAResponseMessage></SOAP-ENV:Body></SOAP-ENV:Envelope>

and

Code: Select all

<SOAP-ENV:Envelope
xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/"
SOAP-ENV:encodingStyle="http://schemas.xmlsoap.org/soap/encoding/">
<SOAP-ENV:Body><scalix-caa:CAAResponseMessage xmlns:scalix-caa="http://www.scalix.com/caa">
<ServiceType>scalix.res</ServiceType><FunctionName>GetServicesList</FunctionName>
<ReturnGetServicesListParameters><server
name="fqdn_of_the_host"/></ReturnGetServicesListParameters>
</scalix-caa:CAAResponseMessage></SOAP-ENV:Body></SOAP-ENV:Envelope>

in the debugger and get

Code: Select all

2006-05-15 11:30:28,973 FATAL
[RESServiceHandler.executeRESCommand:1844] java.net.SocketException:
Unexpected end of file from server
2006-05-15 11:30:28,974 FATAL [RESServiceHandler.executeRESCommand:1853]
com.scalix.sac.ubermgr.res.RESServiceHandler:Fatal Exception raised.
Check the CAA logs
2006-05-15 11:30:28,976  WARN
[GetServicesList.createResponseMessage:118] Skipping server
fqdn_of_the_host no document received.

in the caa.log.

Since the log only tells me to check the logs, I don't really have an
idea where to look next...


Marc

[ScalixSupport]

Can you also check catalina.out and scalix-res logs ?

Cheers

Dave

[Marc]

Hi Dave,

I did, here's the output of both logs during a tomcat restart:

scalix-res.log

Code: Select all

2006-05-16 02:36:00,008  INFO [RESDispatcherServlet.destroy:51] Shuting down...all threads...
2006-05-16 02:36:00,009  INFO [RESNotifier.stopPollingThreads:101] Stopping HeartBeat-thread
2006-05-16 02:36:00,010  INFO [RESNotifier.stopNotificationThreads:113] Stopping Notifier-thread-0
2006-05-16 02:36:00,011  INFO [RESNotifier.stopNotificationThreads:113] Stopping Notifier-thread-1
2006-05-16 02:36:00,011  INFO [RESNotifier.stopRegistrationThreads:89] Registration-thread Stopping thread...
2006-05-16 02:36:00,017 DEBUG [Notifier.run:50] Notifier-thread-0 Thread interrupted...
2006-05-16 02:36:00,018 DEBUG [Notifier.run:50] Notifier-thread-1 Thread interrupted...
2006-05-16 02:36:09,294  INFO [RESNamedInstances.initNamedInstances:61] Loading Scalix named instances.
2006-05-16 02:36:09,437  INFO [RESNamedInstances.initNamedInstances:100] Finised reading Named Instances. Total Named instances in the global config file are = 1
2006-05-16 02:36:09,440  INFO [RESNamedInstances.initNamedInstances:101] Checking Named Instances state..
2006-05-16 02:36:09,449 DEBUG [CmdExecution.executeCmd:138] ENVIRONMENT: LANG=en_US.UTF-8 OM_CHAR=UTF-8
2006-05-16 02:36:09,453 DEBUG [CmdExecution.executeCmd:140] COMMAND: /opt/scalix/bin/sxchkinstances
2006-05-16 02:36:09,813  INFO [RESNamedInstances.initNamedInstances:116] sx1 - is an Active named instance
2006-05-16 02:36:09,824  INFO [RESNotifier.startRegistrationThread:80] Launching Registration-thread
2006-05-16 02:36:09,825 DEBUG [RESDispatcherServlet.initialize:373] : Almost finished initialization, now waiting for registration
2006-05-16 02:36:09,832  INFO [RegistrationEventPoller.run:113] Added Registration event to the Notification Event Queue queue:Event=[register|http://sx1.example.com:8443/res/RESDispatcher|LISTEN|300|sx1|sx1.example.com|10.0.1] (try 100 retries)
2006-05-16 02:36:09,833 DEBUG [RegistrationEventPoller.run:116] Notified monitor queue that is has a new event
2006-05-16 02:36:09,842 DEBUG [Notifier.sendNotification:130] Notifier-thread-0/ACK/Event=[register|http://sx1.example.com:8443/res/RESDispatcher|LISTEN|300|sx1|sx1.example.com|10.0.1]
2006-05-16 02:36:09,843 DEBUG [RegistrationEventPoller.run:121] Sleeping for 5000 ms before trying again
2006-05-16 02:36:14,845  INFO [RegistrationEventPoller.run:113] Added Registration event to the Notification Event Queue queue:Event=[register|http://sx1.example.com:8443/res/RESDispatcher|LISTEN|300|sx1|sx1.example.com|10.0.1] (try 100 retries)
2006-05-16 02:36:14,846 DEBUG [RegistrationEventPoller.run:116] Notified monitor queue that is has a new event
2006-05-16 02:36:14,846 DEBUG [RegistrationEventPoller.run:121] Sleeping for 5000 ms before trying again
2006-05-16 02:36:14,847 DEBUG [Notifier.sendNotification:130] Notifier-thread-0/ACK/Event=[register|http://sx1.example.com:8443/res/RESDispatcher|LISTEN|300|sx1|sx1.example.com|10.0.1]
2006-05-16 02:36:19,849  INFO [RegistrationEventPoller.run:87] We are registered, stopping registration thread
2006-05-16 02:36:19,867  INFO [RESNotifier.startPollingThreads:66] Launching HeartBeat-thread
2006-05-16 02:36:19,867  INFO [RegistrationEventPoller.run:92] : Finished initialization
2006-05-16 02:36:19,867 DEBUG [HeartBeatEventPoller.run:67] Added heartbeatEventEvent=[heartbeat|http://sx1.example.com:8443/res/RESDispatcher|LISTEN|300|sx1|sx1.example.com|10.0.1] to the Notification Event Queue
2006-05-16 02:36:19,869 DEBUG [Notifier.sendNotification:130] Notifier-thread-0/ACK/Event=[heartbeat|http://sx1.example.com:8443/res/RESDispatcher|LISTEN|300|sx1|sx1.example.com|10.0.1]



catalina.out

Code: Select all

May 16, 2006 3:20:43 AM org.apache.coyote.http11.Http11Protocol pause
INFO: Pausing Coyote HTTP/1.1 on http-8443
May 16, 2006 3:20:44 AM org.apache.catalina.core.StandardService stop
INFO: Stopping service Catalina
May 16, 2006 3:20:44 AM org.apache.catalina.core.StandardHostDeployer remove
INFO: Removing web application at context path /webdav
May 16, 2006 3:20:44 AM org.apache.catalina.core.StandardHostDeployer remove
INFO: Removing web application at context path /jsp-examples
May 16, 2006 3:20:44 AM org.apache.catalina.core.StandardHostDeployer remove
INFO: Removing web application at context path /sac
May 16, 2006 3:20:44 AM org.apache.catalina.logger.LoggerBase stop
INFO: unregistering logger Catalina:type=Logger,path=/sac,host=localhost
May 16, 2006 3:20:44 AM org.apache.catalina.core.StandardHostDeployer remove
INFO: Removing web application at context path /manager
May 16, 2006 3:20:44 AM org.apache.catalina.core.StandardHostDeployer remove
INFO: Removing web application at context path /res
May 16, 2006 3:20:44 AM org.apache.catalina.logger.LoggerBase stop
INFO: unregistering logger Catalina:type=Logger,path=/res,host=localhost
May 16, 2006 3:20:44 AM org.apache.catalina.core.StandardHostDeployer remove
INFO: Removing web application at context path /admin
May 16, 2006 3:20:44 AM org.apache.catalina.loader.WebappClassLoader loadClass
INFO: Illegal access: this web application instance has been stopped already (the eventual following stack trace is caused by an error thrown for debuggin
g purposes as well as to attempt to terminate the thread which caused the illegal access, and has no functional impact)
May 16, 2006 3:20:44 AM org.apache.catalina.logger.LoggerBase stop
INFO: unregistering logger Catalina:type=Logger,path=/admin,host=localhost
May 16, 2006 3:20:44 AM org.apache.catalina.core.StandardHostDeployer remove
INFO: Removing web application at context path /webmail
May 16, 2006 3:20:44 AM org.apache.catalina.logger.LoggerBase stop
INFO: unregistering logger Catalina:type=Logger,path=/webmail,host=localhost
May 16, 2006 3:20:44 AM org.apache.catalina.core.StandardHostDeployer remove
INFO: Removing web application at context path /servlets-examples
May 16, 2006 3:20:44 AM org.apache.catalina.core.StandardHostDeployer remove
INFO: Removing web application at context path /balancer
May 16, 2006 3:20:44 AM org.apache.catalina.core.StandardHostDeployer remove
INFO: Removing web application at context path /webcal
May 16, 2006 3:20:45 AM org.apache.catalina.logger.LoggerBase stop
INFO: unregistering logger Catalina:type=Logger,path=/webcal,host=localhost
May 16, 2006 3:20:45 AM org.apache.catalina.core.StandardHostDeployer remove
INFO: Removing web application at context path /tomcat-docs
May 16, 2006 3:20:45 AM org.apache.catalina.core.StandardHostDeployer remove
INFO: Removing web application at context path /caa
May 16, 2006 3:20:45 AM org.apache.catalina.logger.LoggerBase stop
INFO: unregistering logger Catalina:type=Logger,path=/caa,host=localhost
May 16, 2006 3:20:45 AM org.apache.catalina.core.StandardHostDeployer remove
INFO: Removing web application at context path
May 16, 2006 3:20:45 AM org.apache.catalina.logger.LoggerBase stop
INFO: unregistering logger Catalina:type=Logger,host=localhost
May 16, 2006 3:20:45 AM org.apache.catalina.logger.LoggerBase stop
INFO: unregistering logger Catalina:type=Logger
May 16, 2006 3:20:45 AM org.apache.coyote.http11.Http11Protocol destroy
INFO: Stopping Coyote HTTP/1.1 on http-8443
May 16, 2006 3:20:46 AM org.apache.coyote.http11.Http11Protocol init
INFO: Initializing Coyote HTTP/1.1 on http-8443
May 16, 2006 3:20:47 AM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 2789 ms
May 16, 2006 3:20:47 AM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
May 16, 2006 3:20:47 AM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/5.0.28
May 16, 2006 3:20:47 AM org.apache.catalina.core.StandardHost start
INFO: XML validation disabled
May 16, 2006 3:20:47 AM org.apache.catalina.core.StandardHost getDeployer
INFO: Create Host deployer for direct deployment ( non-jmx )
May 16, 2006 3:20:47 AM org.apache.catalina.core.StandardHostDeployer install
INFO: Processing Context configuration file URL file:/opt/scalix-tomcat/conf/Catalina/localhost/admin.xml
May 16, 2006 3:20:48 AM org.apache.struts.util.PropertyMessageResources <init>
INFO: Initializing, config='org.apache.struts.util.LocalStrings', returnNull=true
May 16, 2006 3:20:48 AM org.apache.struts.util.PropertyMessageResources <init>
INFO: Initializing, config='org.apache.struts.action.ActionResources', returnNull=true
May 16, 2006 3:20:49 AM org.apache.struts.util.PropertyMessageResources <init>
INFO: Initializing, config='org.apache.webapp.admin.ApplicationResources', returnNull=true
May 16, 2006 3:20:51 AM org.apache.catalina.core.StandardHostDeployer install
INFO: Processing Context configuration file URL file:/opt/scalix-tomcat/conf/Catalina/localhost/balancer.xml
May 16, 2006 3:20:51 AM org.apache.catalina.core.StandardHostDeployer install
INFO: Processing Context configuration file URL file:/opt/scalix-tomcat/conf/Catalina/localhost/manager.xml
May 16, 2006 3:20:51 AM 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
May 16, 2006 3:20:52 AM 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
May 16, 2006 3:20:53 AM org.apache.catalina.core.StandardHostDeployer install
INFO: Processing Context configuration file URL file:/opt/scalix-tomcat/conf/Catalina/localhost/scalix-admin.xml
May 16, 2006 3:20:53 AM org.apache.catalina.core.StandardHostDeployer install
INFO: Processing Context configuration file URL file:/opt/scalix-tomcat/conf/Catalina/localhost/scalix-caa.xml
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
May 16, 2006 3:20:54 AM 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
May 16, 2006 3:20:54 AM org.apache.catalina.core.StandardHostDeployer install
INFO: Installing web application at context path  from URL file:/opt/scalix-tomcat/webapps/ROOT
May 16, 2006 3:20:54 AM org.apache.catalina.core.StandardHostDeployer install
INFO: Installing web application at context path /jsp-examples from URL file:/opt/scalix-tomcat/webapps/jsp-examples
May 16, 2006 3:20:55 AM org.apache.catalina.core.StandardHostDeployer install
INFO: Installing web application at context path /servlets-examples from URL file:/opt/scalix-tomcat/webapps/servlets-examples
May 16, 2006 3:20:55 AM org.apache.catalina.core.StandardHostDeployer install
INFO: Installing web application at context path /tomcat-docs from URL file:/opt/scalix-tomcat/webapps/tomcat-docs
May 16, 2006 3:20:55 AM org.apache.catalina.core.StandardHostDeployer install
INFO: Installing web application at context path /webdav from URL file:/opt/scalix-tomcat/webapps/webdav
May 16, 2006 3:20:55 AM org.apache.coyote.http11.Http11Protocol start
INFO: Starting Coyote HTTP/1.1 on http-8443
May 16, 2006 3:20:56 AM org.apache.jk.common.ChannelSocket init
INFO: JK2: ajp13 listening on /127.0.0.1:8009
May 16, 2006 3:20:56 AM org.apache.jk.server.JkMain start
INFO: Jk running ID=0 time=0/22  config=/opt/scalix-tomcat/conf/jk2.properties
May 16, 2006 3:20:56 AM org.apache.catalina.startup.Catalina start
INFO: Server startup in 9252 ms


I just noticed this "Illegal access" while stopping tomcat... btw. I can see and change all user info from users that are located on our second scalix host in the admin console, and I can also see the listing of running services there.


Marc

[Marc]

Uh, ok. I just saw it:

Code: Select all

http://sx1.example.com:8443/res/RESDispatcher

That was wrong, of course. In my enthusiasm to move SWA and SAC to port 8443 I also put a "res.tomcat.tcp.port=8443" in res.properties.

It's working ok now.

Thanks,

Marc

[mackkaif]

thanks for postings