ERROR : Bad or malformed request. Inbox sorting (?) error

Torin · Postby **Torin** » Mon Dec 19, 2005 6:33 pm

Hi!
I've succesfully installed Scalix community edition on our fedora core 4 system.
Everything works perfectly until users recieve a message. Once the message is recieved and the user clicks on the inbox the following error comes up:

ERROR : Bad or malformed request.
Query: SORT (ARRIVAL) ISO-8859-1 ALL
Server responded: syntax error

If you search for the mail that arrived, it's viewable as normal.

I haven't even got a clue as to where to start... :(

Postby **ScalixSupport** » Mon Dec 19, 2005 7:31 pm

Hi,

What version of Tomcat and Java do you have installed on the server? What browser (version) are you using?

Can you post the content of /TOMCAT_HOME/logs/scalix-swa_log.2005-12-<latest>.txt?

Thanks,

Matthew
Support

Torin · Postby **Torin** » Mon Dec 19, 2005 7:39 pm

Wow. Quick turnaround :~)

tomcat-5.0.28
j2sdk-1_4_2_10

... The log you've requested doesn't appear in tomcat's log directory, the logs that exists are as follows:
-rw-r--r-- 1 root root 48693 Dec 20 09:52 caa.log
-rw-r--r-- 1 root root 50626 Dec 20 08:22 catalina.out
-rw-r--r-- 1 root root 3032 Dec 20 08:22 localhost_log.2005-12-20.txt

Torin · Postby **Torin** » Mon Dec 19, 2005 7:42 pm

Sorry, and browsers - tried I.E and Firefox.

Postby **ScalixSupport** » Mon Dec 19, 2005 8:25 pm

So this happens to all users with any message, correct?

Post the output from:

ps -ef | grep tomcat

and:

The catalina.log file (It's probably quite large, so just events from the 20th wil be ok).

Regards,

Matthew
Support

Torin · Postby **Torin** » Mon Dec 19, 2005 11:23 pm

[root@mail logs]# ps -ef |grep tomcat
root 7565 1 0 08:21 ? 00:01:07 /usr/java/j2sdk1.4.2_10/bin/java -server -Xms256m -Xmx256m -Djava.endorsed.dirs=/var/tomcat/jakarta-tomcat-5.0.28/common/endorsed -classpath /usr/java/j2sdk1.4.2_10/lib/tools.jar:/var/tomcat/jakarta-tomcat-5.0.28/bin/bootstrap.jar:/var/tomcat/jakarta-tomcat-5.0.28/bin/commons-logging-api.jar -Dcatalina.base=/var/tomcat/jakarta-tomcat-5.0.28 -Dcatalina.home=/var/tomcat/jakarta-tomcat-5.0.28 -Djava.io.tmpdir=/var/tomcat/jakarta-tomcat-5.0.28/temp org.apache.catalina.startup.Bootstrap start
root 26885 22289 0 13:50 pts/1 00:00:00 grep tomcat

---------------

20/12/2005 08:19:41 org.apache.coyote.http11.Http11Protocol init
INFO: Initializing Coyote HTTP/1.1 on http-8080
20/12/2005 08:19:42 org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 7488 ms
20/12/2005 08:19:42 org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
20/12/2005 08:19:42 org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/5.0.28
20/12/2005 08:19:42 org.apache.catalina.core.StandardHost start
INFO: XML validation disabled
20/12/2005 08:19:42 org.apache.catalina.core.StandardHost getDeployer
INFO: Create Host deployer for direct deployment ( non-jmx )
20/12/2005 08:19:42 org.apache.catalina.core.StandardHostDeployer install
INFO: Processing Context configuration file URL file:/var/tomcat/jakarta-tomcat-5.0.28/conf/Catalina/localhost/scalix-admin.xml
20/12/2005 08:19:44 org.apache.catalina.startup.ContextConfig applicationConfig
INFO: Missing application web.xml, using defaults only StandardEngine[Catalina].StandardHost[localhost].StandardContext[/sac]
20/12/2005 08:19:45 org.apache.catalina.core.StandardHostDeployer install
INFO: Processing Context configuration file URL file:/var/tomcat/jakarta-tomcat-5.0.28/conf/Catalina/localhost/admin.xml
20/12/2005 08:19:46 org.apache.struts.util.PropertyMessageResources <init>
INFO: Initializing, config='org.apache.struts.util.LocalStrings', returnNull=true
20/12/2005 08:19:46 org.apache.struts.util.PropertyMessageResources <init>
INFO: Initializing, config='org.apache.struts.action.ActionResources', returnNull=true
20/12/2005 08:19:49 org.apache.struts.util.PropertyMessageResources <init>
INFO: Initializing, config='org.apache.webapp.admin.ApplicationResources', returnNull=true
20/12/2005 08:19:56 org.apache.catalina.core.StandardHostDeployer install
INFO: Processing Context configuration file URL file:/var/tomcat/jakarta-tomcat-5.0.28/conf/Catalina/localhost/scalix-caa.xml
20/12/2005 08:19:56 org.apache.catalina.loader.WebappClassLoader validateJarFile
INFO: validateJarFile(/var/tomcat/jakarta-tomcat-5.0.28/webapps/caa/WEB-INF/lib/j2ee.jar) - jar not loaded. See Servlet Spec 2.3, section 9.7.2. Offending class: javax/servlet/Servlet.class
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
20/12/2005 08:19:59 org.apache.catalina.core.StandardHostDeployer install
INFO: Processing Context configuration file URL file:/var/tomcat/jakarta-tomcat-5.0.28/conf/Catalina/localhost/scalix-swa.xml
reading partner.xml @ /etc/opt/scalix/webmail/partner.xml
Problem in partner.xml: missing or incorrect defaultLocale parameter. Setting defaultLocale to 'en_US'
20/12/2005 08:20:02 org.apache.catalina.core.StandardHostDeployer install
INFO: Processing Context configuration file URL file:/var/tomcat/jakarta-tomcat-5.0.28/conf/Catalina/localhost/balancer.xml
20/12/2005 08:20:04 org.apache.catalina.core.StandardHostDeployer install
INFO: Processing Context configuration file URL file:/var/tomcat/jakarta-tomcat-5.0.28/conf/Catalina/localhost/manager.xml
20/12/2005 08:20:06 org.apache.catalina.core.StandardHostDeployer install
INFO: Installing web application at context path /tomcat-docs from URL file:/var/tomcat/jakarta-tomcat-5.0.28/webapps/tomcat-docs
20/12/2005 08:20:07 org.apache.catalina.core.StandardHostDeployer install
INFO: Installing web application at context path from URL file:/var/tomcat/jakarta-tomcat-5.0.28/webapps/ROOT
20/12/2005 08:20:07 org.apache.catalina.core.StandardHostDeployer install
INFO: Installing web application at context path /jsp-examples from URL file:/var/tomcat/jakarta-tomcat-5.0.28/webapps/jsp-examples
20/12/2005 08:20:08 org.apache.catalina.core.StandardHostDeployer install
INFO: Installing web application at context path /webdav from URL file:/var/tomcat/jakarta-tomcat-5.0.28/webapps/webdav
20/12/2005 08:20:09 org.apache.catalina.core.StandardHostDeployer install
INFO: Installing web application at context path /servlets-examples from URL file:/var/tomcat/jakarta-tomcat-5.0.28/webapps/servlets-examples
20/12/2005 08:20:09 org.apache.coyote.http11.Http11Protocol start
INFO: Starting Coyote HTTP/1.1 on http-8080
20/12/2005 08:20:11 org.apache.jk.common.ChannelSocket init
INFO: JK2: ajp13 listening on /0.0.0.0:8009
20/12/2005 08:20:11 org.apache.jk.server.JkMain start
INFO: Jk running ID=0 time=19/191 config=/var/tomcat/jakarta-tomcat-5.0.28/conf/jk2.properties
20/12/2005 08:20:11 org.apache.catalina.startup.Catalina start
INFO: Server startup in 29929 ms
20/12/2005 08:21:17 org.apache.coyote.http11.Http11Protocol pause
INFO: Pausing Coyote HTTP/1.1 on http-8080
20/12/2005 08:21:18 org.apache.catalina.core.StandardService stop
INFO: Stopping service Catalina
20/12/2005 08:21:18 org.apache.catalina.core.StandardHostDeployer remove
INFO: Removing web application at context path /admin
20/12/2005 08:21:19 org.apache.catalina.logger.LoggerBase stop
INFO: unregistering logger Catalina:type=Logger,path=/admin,host=localhost
20/12/2005 08:21:19 org.apache.catalina.core.StandardHostDeployer remove
INFO: Removing web application at context path /servlets-examples
20/12/2005 08:21:19 org.apache.catalina.core.StandardHostDeployer remove
INFO: Removing web application at context path /webdav
20/12/2005 08:21:19 org.apache.catalina.core.StandardHostDeployer remove
INFO: Removing web application at context path /webmail
20/12/2005 08:21:19 org.apache.catalina.logger.LoggerBase stop
INFO: unregistering logger Catalina:type=Logger,path=/webmail,host=localhost
20/12/2005 08:21:19 org.apache.catalina.core.StandardHostDeployer remove
INFO: Removing web application at context path /jsp-examples
20/12/2005 08:21:19 org.apache.catalina.core.StandardHostDeployer remove
INFO: Removing web application at context path /balancer
20/12/2005 08:21:19 org.apache.catalina.core.StandardHostDeployer remove
INFO: Removing web application at context path /sac
20/12/2005 08:21:19 org.apache.catalina.logger.LoggerBase stop
INFO: unregistering logger Catalina:type=Logger,path=/sac,host=localhost
20/12/2005 08:21:19 org.apache.catalina.core.StandardHostDeployer remove
INFO: Removing web application at context path /tomcat-docs
20/12/2005 08:21:19 org.apache.catalina.core.StandardHostDeployer remove
INFO: Removing web application at context path /caa
20/12/2005 08:21:19 org.apache.catalina.logger.LoggerBase stop
INFO: unregistering logger Catalina:type=Logger,path=/caa,host=localhost
20/12/2005 08:21:19 org.apache.catalina.core.StandardHostDeployer remove
INFO: Removing web application at context path
20/12/2005 08:21:19 org.apache.catalina.core.StandardHostDeployer remove
INFO: Removing web application at context path /manager
20/12/2005 08:21:19 org.apache.catalina.logger.LoggerBase stop
INFO: unregistering logger Catalina:type=Logger,host=localhost
20/12/2005 08:21:19 org.apache.catalina.logger.LoggerBase stop
INFO: unregistering logger Catalina:type=Logger
20/12/2005 08:21:19 org.apache.coyote.http11.Http11Protocol destroy
INFO: Stopping Coyote HTTP/1.1 on http-8080
20/12/2005 08:21:55 org.apache.coyote.http11.Http11Protocol init
INFO: Initializing Coyote HTTP/1.1 on http-8080
20/12/2005 08:21:55 org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 5740 ms
20/12/2005 08:21:55 org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
20/12/2005 08:21:56 org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/5.0.28
20/12/2005 08:21:56 org.apache.catalina.core.StandardHost start
INFO: XML validation disabled
20/12/2005 08:21:56 org.apache.catalina.core.StandardHost getDeployer
INFO: Create Host deployer for direct deployment ( non-jmx )
20/12/2005 08:21:56 org.apache.catalina.core.StandardHostDeployer install
INFO: Processing Context configuration file URL file:/var/tomcat/jakarta-tomcat-5.0.28/conf/Catalina/localhost/scalix-admin.xml
20/12/2005 08:21:57 org.apache.catalina.startup.ContextConfig applicationConfig
INFO: Missing application web.xml, using defaults only StandardEngine[Catalina].StandardHost[localhost].StandardContext[/sac]
20/12/2005 08:21:58 org.apache.catalina.core.StandardHostDeployer install
INFO: Processing Context configuration file URL file:/var/tomcat/jakarta-tomcat-5.0.28/conf/Catalina/localhost/admin.xml
20/12/2005 08:21:59 org.apache.struts.util.PropertyMessageResources <init>
INFO: Initializing, config='org.apache.struts.util.LocalStrings', returnNull=true
20/12/2005 08:21:59 org.apache.struts.util.PropertyMessageResources <init>
INFO: Initializing, config='org.apache.struts.action.ActionResources', returnNull=true
20/12/2005 08:22:02 org.apache.struts.util.PropertyMessageResources <init>
INFO: Initializing, config='org.apache.webapp.admin.ApplicationResources', returnNull=true
20/12/2005 08:22:07 org.apache.catalina.core.StandardHostDeployer install
INFO: Processing Context configuration file URL file:/var/tomcat/jakarta-tomcat-5.0.28/conf/Catalina/localhost/scalix-caa.xml
20/12/2005 08:22:09 org.apache.catalina.loader.WebappClassLoader validateJarFile
INFO: validateJarFile(/var/tomcat/jakarta-tomcat-5.0.28/webapps/caa/WEB-INF/lib/j2ee.jar) - jar not loaded. See Servlet Spec 2.3, section 9.7.2. Offending class: javax/servlet/Servlet.class
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
20/12/2005 08:22:14 org.apache.catalina.core.StandardHostDeployer install
INFO: Processing Context configuration file URL file:/var/tomcat/jakarta-tomcat-5.0.28/conf/Catalina/localhost/scalix-swa.xml
reading partner.xml @ /etc/opt/scalix/webmail/partner.xml
Problem in partner.xml: missing or incorrect defaultLocale parameter. Setting defaultLocale to 'en_US'
20/12/2005 08:22:20 org.apache.catalina.core.StandardHostDeployer install
INFO: Processing Context configuration file URL file:/var/tomcat/jakarta-tomcat-5.0.28/conf/Catalina/localhost/balancer.xml
20/12/2005 08:22:21 org.apache.catalina.core.StandardHostDeployer install
INFO: Processing Context configuration file URL file:/var/tomcat/jakarta-tomcat-5.0.28/conf/Catalina/localhost/manager.xml
20/12/2005 08:22:21 org.apache.catalina.core.StandardHostDeployer install
INFO: Installing web application at context path /tomcat-docs from URL file:/var/tomcat/jakarta-tomcat-5.0.28/webapps/tomcat-docs
20/12/2005 08:22:21 org.apache.catalina.core.StandardHostDeployer install
INFO: Installing web application at context path from URL file:/var/tomcat/jakarta-tomcat-5.0.28/webapps/ROOT
20/12/2005 08:22:22 org.apache.catalina.core.StandardHostDeployer install
INFO: Installing web application at context path /jsp-examples from URL file:/var/tomcat/jakarta-tomcat-5.0.28/webapps/jsp-examples
20/12/2005 08:22:22 org.apache.catalina.core.StandardHostDeployer install
INFO: Installing web application at context path /webdav from URL file:/var/tomcat/jakarta-tomcat-5.0.28/webapps/webdav
20/12/2005 08:22:22 org.apache.catalina.core.StandardHostDeployer install
INFO: Installing web application at context path /servlets-examples from URL file:/var/tomcat/jakarta-tomcat-5.0.28/webapps/servlets-examples
20/12/2005 08:22:22 org.apache.coyote.http11.Http11Protocol start
INFO: Starting Coyote HTTP/1.1 on http-8080
20/12/2005 08:22:24 org.apache.jk.common.ChannelSocket init
INFO: JK2: ajp13 listening on /0.0.0.0:8009
20/12/2005 08:22:24 org.apache.jk.server.JkMain start
INFO: Jk running ID=0 time=1/82 config=/var/tomcat/jakarta-tomcat-5.0.28/conf/jk2.properties
20/12/2005 08:22:25 org.apache.catalina.startup.Catalina start
INFO: Server startup in 29674 ms

---

Torin · Postby **Torin** » Mon Dec 19, 2005 11:31 pm

And yes, it's for all users who have any mail in the mailbox....

Torin · Postby **Torin** » Mon Dec 19, 2005 11:45 pm

Also; this is probably predicatable, but if you use the search function to find the mail and move it to any other folder (like drafts for example) the empty folder is viewable, but the folder the message was moved to now displays the error.

Postby **ScalixSupport** » Tue Dec 20, 2005 6:31 am

Torin wrote:Hi!
I've succesfully installed Scalix community edition on our fedora core 4 system.
Everything works perfectly until users recieve a message. Once the message is recieved and the user clicks on the inbox the following error comes up:

ERROR : Bad or malformed request.
Query: SORT (ARRIVAL) ISO-8859-1 ALL
Server responded: syntax error

If you search for the mail that arrived, it's viewable as normal.

I haven't even got a clue as to where to start... :(

Hello,

can you please on that server telnet to port 143 and post the output?

To end that session, type ". logout" ( dot space logout)

Please post the output of telnet here.

Cheers,

Sascha.

Torin · Postby **Torin** » Tue Dec 20, 2005 8:08 am

Sure thing :)
----
Trying 192.168.2.2...
Connected to mail.malessa.homeip.net (192.168.2.2).
Escape character is '^]'.
* OK Scalix IMAP server 9.4.2.4 ready on mail.malessa.homeip.net

Postby **ScalixSupport** » Tue Dec 20, 2005 8:26 am

Torin wrote:Sure thing :)
----
Trying 192.168.2.2...
Connected to mail.malessa.homeip.net (192.168.2.2).
Escape character is '^]'.
* OK Scalix IMAP server 9.4.2.4 ready on mail.malessa.homeip.net

OK, good - you are talking to the right server ;-)

Does this occur on all workstations? Can you delete the cache, restart browser and try again? Are there any other utilities accessing the mailbox? Do you have any other mail clients open at the time?

Additionally, please enable verbose logging:

In /etc/opt/scalix/caa/config/log4j.properties set

# switch on or off level and log destinations here
log4j.rootLogger=DEBUG
log4j.logger.com.scalix.caa.util.CAALogger=DEBUG, file

and restart Tomcat.

In general.cfg, please set

IMAP_LOGFILE=~/tmp/imap.%u.%p.log
IMAP_LOGLEVEL=0x1a

Restart the IMAP server, omoff -d0 imap; omon imap

Then, access using SWA to recreate the error and post the content of the above files here.

Cheers,

Sascha.

Torin · Postby **Torin** » Tue Dec 20, 2005 9:56 am

Done as requested - though the only general.cfg I could find was in
/opt/scalix/template/release.sys
is that the right one? The templates directory makes me uneasy...
Well. that and that I'm not seeing any logs appear - it should just be imap.*.*.log in ~/tmp right?
Being that it's 1am over here now, I'll probably take a littel while before responding again ;~)
==========================
Caa.log:
2005-12-21 00:09:43,969 INFO [RESInitializer.terminate:237] Shutting down UberManager and all its threads...
2005-12-21 00:09:44,018 INFO [RESMonitor.halt:157] RESMonitor-thread Thread shutting down
2005-12-21 00:09:44,020 INFO [NotificationEventListener.cleanup:268] NotificationEventListener-Thread closing UDP socket...
2005-12-21 00:09:44,023 INFO [NotificationEventListener$WorkerThread.halt:120] com.scalix.sac.ubermgr.events.NotificationEventListener$WorkerThread-thread-0 Thread shutting down...
2005-12-21 00:09:44,025 INFO [NotificationEventListener$WorkerThread.halt:120] com.scalix.sac.ubermgr.events.NotificationEventListener$WorkerThread-thread-1 Thread shutting down...
2005-12-21 00:09:44,028 INFO [GuidToServerCache$CleanupThread.stopThread:54] com.scalix.sac.ubermgr.data.GuidToServerCache$CleanupThread-thread Shutting down...
2005-12-21 00:09:44,028 ERROR [NotificationEventListener.run:233] java.net.SocketException: Socket closed
2005-12-21 00:10:21,405 INFO [SOAPDispatcherServlet.init:76] ====> CAA is starting up, standby <====
2005-12-21 00:10:21,461 DEBUG [CAAConfigLoader.init:104] **** Start of Config Loader ****
2005-12-21 00:10:21,465 DEBUG [CAAConfigLoader.init:109] Framework config file: framework-config.xml
2005-12-21 00:10:21,467 DEBUG [CAAConfigLoader.loadConfigFile:150] Loading config from /etc/opt/scalix/caa/config/framework-config.xml
2005-12-21 00:10:21,527 DEBUG [CAAConfigLoader.parseConfigDocument:200] Service type: system
2005-12-21 00:10:21,535 DEBUG [CAAConfigLoader.getParamNodeValue:373] The node disabled was not found in service system, component null
2005-12-21 00:10:21,538 DEBUG [CAAConfigLoader.parseComponentNode:236] ComponentName: sessionManager
2005-12-21 00:10:21,542 DEBUG [CAAConfigLoader.parseComponentNode:251] Storing config for system, key sessionManager.class, value com.scalix.caa.framework.CAASessionManager
2005-12-21 00:10:21,591 DEBUG [CAASessionManager.readConfig:261] Reading config
2005-12-21 00:10:21,635 DEBUG [CAASessionManager.readConfig:295] Setting sessionTimeout=6000000
2005-12-21 00:10:21,639 DEBUG [CAASessionManager.readConfig:299] Setting cleanupFrequency=50000
2005-12-21 00:10:21,642 DEBUG [CAAConfigLoader.parseComponentNode:236] ComponentName: securityProvider
2005-12-21 00:10:21,645 DEBUG [CAAConfigLoader.parseComponentNode:251] Storing config for system, key securityProvider.class, value com.scalix.caa.security.CAAKerberosProvider
2005-12-21 00:10:21,662 DEBUG [CAAConfigLoader.getValue:56] Looking for config for system, key config.dir found /etc/opt/scalix/caa/config
2005-12-21 00:10:21,867 DEBUG [CAAKerberosProvider.readConfig:420] ParamName: loginFile, value krblogin.conf
2005-12-21 00:10:21,880 DEBUG [CAAConfigLoader.init:129] Service config file: sample-service-config.xml
2005-12-21 00:10:21,884 DEBUG [CAAConfigLoader.loadConfigFile:150] Loading config from /etc/opt/scalix/caa/config/sample-service-config.xml
2005-12-21 00:10:21,925 WARN [CAAConfigLoader.parseConfigDocument:224] No services are configured in this config file
2005-12-21 00:10:21,929 DEBUG [CAAConfigLoader.init:129] Service config file: scalix.res-config.xml
2005-12-21 00:10:21,933 DEBUG [CAAConfigLoader.loadConfigFile:150] Loading config from /etc/opt/scalix/caa/config/scalix.res-config.xml
2005-12-21 00:10:21,947 DEBUG [CAAConfigLoader.parseConfigDocument:200] Service type: scalix.res
2005-12-21 00:10:21,951 DEBUG [CAAConfigLoader.parseComponentNode:236] ComponentName: disabled
2005-12-21 00:10:21,955 DEBUG [CAAConfigLoader.getParamNodeValue:373] The node class was not found in service scalix.res, component disabled
2005-12-21 00:10:21,959 ERROR [CAAConfigLoader.parseComponentNode:244] Service scalix.res, Component disabled has no class defined!
2005-12-21 00:10:21,963 DEBUG [CAAConfigLoader.parseComponentNode:236] ComponentName: initializer
2005-12-21 00:10:21,967 DEBUG [CAAConfigLoader.parseComponentNode:251] Storing config for scalix.res, key initializer.class, value com.scalix.sac.ubermgr.caa.RESInitializer
2005-12-21 00:10:21,981 DEBUG [CAAConfigLoader.parseComponentParam:346] ParamName: class
2005-12-21 00:10:21,985 DEBUG [CAAConfigLoader.parseComponentParam:350] Storing config for scalix.res, key initializer.class, value
com.scalix.sac.ubermgr.caa.RESInitializer

2005-12-21 00:10:21,989 DEBUG [CAAConfigLoader.parseComponentNode:236] ComponentName: transformer
2005-12-21 00:10:21,997 DEBUG [CAAConfigLoader.parseComponentNode:251] Storing config for scalix.res, key transformer.class, value com.scalix.sac.ubermgr.caa.RESTransformer
2005-12-21 00:10:22,047 DEBUG [CAAConfigLoader.parseComponentParam:346] ParamName: class
2005-12-21 00:10:22,052 DEBUG [CAAConfigLoader.parseComponentParam:350] Storing config for scalix.res, key transformer.class, value
com.scalix.sac.ubermgr.caa.RESTransformer

2005-12-21 00:10:22,056 DEBUG [CAAConfigLoader.parseComponentNode:236] ComponentName: connection
2005-12-21 00:10:22,066 DEBUG [CAAConfigLoader.parseComponentNode:251] Storing config for scalix.res, key connection.class, value com.scalix.sac.ubermgr.caa.RESConnection
2005-12-21 00:10:22,087 DEBUG [CAAConfigLoader.parseComponentParam:346] ParamName: class
2005-12-21 00:10:22,096 DEBUG [CAAConfigLoader.parseComponentParam:350] Storing config for scalix.res, key connection.class, value
com.scalix.sac.ubermgr.caa.RESConnection

2005-12-21 00:10:22,105 DEBUG [CAAConfigLoader.parseComponentParam:346] ParamName: mode
2005-12-21 00:10:22,109 DEBUG [CAAConfigLoader.parseComponentParam:350] Storing config for scalix.res, key connection.mode, value 1
2005-12-21 00:10:22,124 DEBUG [CAAConfigLoader.parseComponentNode:295] Storing config for scalix.res, key connection.configurator.class, value com.scalix.caa.framework.CAAConnectionPoolManager
2005-12-21 00:10:22,133 DEBUG [CAAConnectionPoolManager.readConfig:350] ParamName: minConnections
2005-12-21 00:10:22,138 DEBUG [CAAConnectionPoolManager.readConfig:357] Value: 2
2005-12-21 00:10:22,142 DEBUG [CAAConnectionPoolManager.readConfig:350] ParamName: maxConnections
2005-12-21 00:10:22,146 DEBUG [CAAConnectionPoolManager.readConfig:357] Value: 8
2005-12-21 00:10:22,150 DEBUG [CAAConnectionPoolManager.readConfig:350] ParamName: maxLifeTime
2005-12-21 00:10:22,154 DEBUG [CAAConnectionPoolManager.readConfig:357] Value: 86400000
2005-12-21 00:10:22,158 DEBUG [CAAConnectionPoolManager.readConfig:350] ParamName: maxIdleTime
2005-12-21 00:10:22,162 DEBUG [CAAConnectionPoolManager.readConfig:357] Value: 300000
2005-12-21 00:10:22,166 DEBUG [CAAConnectionPoolManager.initializePool:249] Initializing pool for scalix.res with initial size of 2
2005-12-21 00:10:22,175 DEBUG [CAAConfigLoader.getValue:56] Looking for config for scalix.res, key connection.class found com.scalix.sac.ubermgr.caa.RESConnection
2005-12-21 00:10:22,180 DEBUG [CAAConnectionFactory.createConnection:49] Class name is com.scalix.sac.ubermgr.caa.RESConnection
2005-12-21 00:10:22,216 DEBUG [CAAConnectionPoolManager.createNewAndAddToPool:285] Adding new connection to pool for scalix.res and endpoint null
2005-12-21 00:10:22,220 DEBUG [CAAConnectionPoolManager.logConnectionPool:315] Connection pool scalix.res status: min=2,max=10,size=1,used=0
2005-12-21 00:10:22,224 DEBUG [CAAConfigLoader.getValue:56] Looking for config for scalix.res, key connection.class found com.scalix.sac.ubermgr.caa.RESConnection
2005-12-21 00:10:22,230 DEBUG [CAAConnectionFactory.createConnection:49] Class name is com.scalix.sac.ubermgr.caa.RESConnection
2005-12-21 00:10:22,234 DEBUG [CAAConnectionPoolManager.createNewAndAddToPool:285] Adding new connection to pool for scalix.res and endpoint null
2005-12-21 00:10:22,238 DEBUG [CAAConnectionPoolManager.logConnectionPool:315] Connection pool scalix.res status: min=2,max=10,size=2,used=0
2005-12-21 00:10:22,242 DEBUG [CAAConfigLoader.parseComponentNode:236] ComponentName: service
2005-12-21 00:10:22,247 DEBUG [CAAConfigLoader.parseComponentNode:251] Storing config for scalix.res, key service.class, value com.scalix.sac.ubermgr.caa.RESService
2005-12-21 00:10:22,262 DEBUG [CAAConfigLoader.parseComponentParam:346] ParamName: class
2005-12-21 00:10:22,266 DEBUG [CAAConfigLoader.parseComponentParam:350] Storing config for scalix.res, key service.class, value
com.scalix.sac.ubermgr.caa.RESService

2005-12-21 00:10:22,272 DEBUG [CAAConfigLoader.init:145] **** End of Config Loader ****
2005-12-21 00:10:22,418 DEBUG [CAAInitializerManager.loadAllInitializers:27] **** Start of Load Initializers ****
2005-12-21 00:10:22,427 DEBUG [CAAConfigLoader.getValue:56] Looking for config for system, key initializer.class found null
2005-12-21 00:10:22,430 DEBUG [CAAConfigLoader.getValue:56] Looking for config for scalix.res, key initializer.class found com.scalix.sac.ubermgr.caa.RESInitializer
2005-12-21 00:10:22,445 DEBUG [CAAInitializerFactory.getInitializer:42] Class name for scalix.res is com.scalix.sac.ubermgr.caa.RESInitializer
2005-12-21 00:10:22,449 DEBUG [CAAInitializerFactory.getInitializer:63] Initializer for scalix.res is com.scalix.sac.ubermgr.caa.RESInitializer@671f95
2005-12-21 00:10:22,452 DEBUG [CAAInitializerManager.loadAllInitializers:49] Initializing service scalix.res
2005-12-21 00:10:22,470 DEBUG [UberMgrConfig.loadFile:171] Loading Ubermanager properties...
2005-12-21 00:10:22,485 DEBUG [UberMgrConfig.loadFile:175] Finished Loading Ubermanager properties
2005-12-21 00:10:22,488 DEBUG [UberMgrConfig.loadLdapAttribs:187] Loading Ldap Attribute mappings...
2005-12-21 00:10:22,494 DEBUG [UberMgrConfig.loadLdapAttribs:191] Finished Loading Ldap Attribute mappings...entries read=33
2005-12-21 00:10:22,499 DEBUG [UberMgrConfig.loadDirectoryAttribs:203] Loading X.400 Attributes for USERLIST
2005-12-21 00:10:22,583 DEBUG [UberMgrConfig.loadDirectoryAttribs:221] Finised Loading X.400 Attributes for USERLIST
2005-12-21 00:10:22,587 DEBUG [UberMgrConfig.loadDirectoryAttribs:203] Loading X.400 Attributes for SYSTEM
2005-12-21 00:10:22,661 DEBUG [UberMgrConfig.loadDirectoryAttribs:221] Finised Loading X.400 Attributes for SYSTEM
2005-12-21 00:10:22,669 DEBUG [RESInitializer.initNotificationTables:145] Reading Event Notifications Table:events.data
2005-12-21 00:10:22,729 DEBUG [EventTable.readTable:37] com.scalix.sac.ubermgr.events.EventTable:Reading persistent data from /etc/opt/scalix/caa/scalix.res/config/store/events.data
2005-12-21 00:10:22,737 DEBUG [EventTable.readTable:51] com.scalix.sac.ubermgr.events.EventTable:No events persitence data found
2005-12-21 00:10:22,758 DEBUG [RESInitializer.initNotificationTables:147] Finished Event Notifications Table
2005-12-21 00:10:22,856 INFO [GuidToServerCache$CleanupThread.run:39] Guid2Server cache is empty. Skipping cleanup
2005-12-21 00:10:22,865 INFO [NotificationEventListener.launch:243] Main Notification Event Listener thread launched...
2005-12-21 00:10:22,872 INFO [NotificationEventListener.launch:246] Notification Worker thread com.scalix.sac.ubermgr.events.NotificationEventListener$WorkerThread-thread-0 launched...
2005-12-21 00:10:22,878 INFO [NotificationEventListener.launch:246] Notification Worker thread com.scalix.sac.ubermgr.events.NotificationEventListener$WorkerThread-thread-1 launched...
2005-12-21 00:10:22,891 DEBUG [RESMonitor.<init>:43] Now monitoring RES servers. We will check every 60 seconds and tolerate no heartbeat for 720 seconds
2005-12-21 00:10:22,897 INFO [RESInitializer.initNotificationListener:201] RESMonitor-thread Thread launched...
2005-12-21 00:10:22,902 DEBUG [RESInitializer.initLocalDeployment:89] Registering server mail.malessa.homeip.net in local mode
2005-12-21 00:10:22,912 DEBUG [UberMgrConfig.loadServiceDaemonMappings:71] Loading Service/Daemon Mappings
2005-12-21 00:10:22,926 DEBUG [UberMgrConfig.loadServiceDaemonMappings:74] Finished Loading Service/Daemon Mappings
2005-12-21 00:10:22,931 DEBUG [UberMgrConfig.loadServiceDaemonMappings:75] Service/Daemon Mappings=36
2005-12-21 00:10:22,936 DEBUG [UberMgrConfig.loadScalixQueuesNames:58] Loading Service Scalix Queue Names
2005-12-21 00:10:22,942 DEBUG [UberMgrConfig.loadScalixQueuesNames:61] Finished Loading Scalix Queue Names
2005-12-21 00:10:22,947 DEBUG [UberMgrConfig.loadScalixCompsRpmNames:50] Loading Scalix Component RPM prefix names
2005-12-21 00:10:22,952 DEBUG [UberMgrConfig.loadScalixCompsRpmNames:53] Finished Loading Scalix Component RPM prefix names
2005-12-21 00:10:22,956 DEBUG [CAAInitializerManager.loadAllInitializers:67] **** End of Load Initializers ****
2005-12-21 00:10:22,959 INFO [SOAPDispatcherServlet.init:118] ====> CAA started, you may fire when ready <====
2005-12-21 00:10:22,997 DEBUG [RESMonitor.run:64] There are no remote servers and 1 local server registered
2005-12-21 00:10:23,009 DEBUG [RESMonitor.run:84] Skipping local server mail.malessa.homeip.net
2005-12-21 00:11:23,005 DEBUG [RESMonitor.run:64] There are no remote servers and 1 local server registered
2005-12-21 00:11:23,008 DEBUG [RESMonitor.run:84] Skipping local server mail.malessa.homeip.net
2005-12-21 00:12:23,004 DEBUG [RESMonitor.run:64] There are no remote servers and 1 local server registered
2005-12-21 00:12:23,007 DEBUG [RESMonitor.run:84] Skipping local server mail.malessa.homeip.net
2005-12-21 00:13:23,006 DEBUG [RESMonitor.run:64] There are no remote servers and 1 local server registered
2005-12-21 00:13:23,008 DEBUG [RESMonitor.run:84] Skipping local server mail.malessa.homeip.net
2005-12-21 00:14:23,008 DEBUG [RESMonitor.run:64] There are no remote servers and 1 local server registered
2005-12-21 00:14:23,010 DEBUG [RESMonitor.run:84] Skipping local server mail.malessa.homeip.net

Postby **ScalixSupport** » Tue Dec 20, 2005 8:36 pm

Hi,

Actually, the general.cfg file you want is in /var/opt/scalix/sys

Matthew
Support

Torin · Postby **Torin** » Tue Dec 20, 2005 9:07 pm

Okay...
In /var/opt/scalix/sys/general.cfg added
IMAP_LOGFILE=~/tmp/imap.%u.%p.log
IMAP_LOGLEVEL=0x1a
Stopped IMAP (omoff -d0 imap)
Re-stared IMAP (omon imap)
Re-created the error
Checked tmp directory - no log.
Saw the ~ in front of the /tmp, created directory ~/tmp, restarted IMAP, recreated error, checked directory... No log.
I am meant to find imap.*.*.log right?

Postby **ScalixSupport** » Tue Dec 20, 2005 10:15 pm

I have to ask, are you using SquirrelMail ?

I checked through our source code and the word ARRIVAL doesn't feature in the IMAP server, Sun javamail source or SWA source code.

However, google reveals a number of links that all contain reference to the error you quoted but in relation to SquirrelMail.

Cheers

Dave

Scalix Forums

ERROR : Bad or malformed request. Inbox sorting (?) error

ERROR : Bad or malformed request. Inbox sorting (?) error

Re: ERROR : Bad or malformed request. Inbox sorting (?) err

Who is online