Calendar pop-up reminder and weird error

Hi,

The IM client connects to the multiplexor on one host. The multiplexor is connected to the IM server on another host.

I have double checked. The enpd process is started on the calendar server. I have setup calendar frontend/backend. Only the backend has the enpd running. IM server points directly to the backend.

Here are a few logs that may make things clearer.

xmppd.log @ IM Server

--

[24 Jul 2007 11:52:01,958] ERROR xmppd [Thread-34] MultiplexManager#20890333 read error java.io.IOException: Connection reset by peer

[24 Jul 2007 11:52:01,958] ERROR xmppd [Thread-35] MultiplexManager#23814595 read error java.io.IOException: Connection reset by peer

[24 Jul 2007 11:52:01,959] ERROR xmppd [Thread-34] MultiplexManager#20890333 error sending BYE

[24 Jul 2007 11:52:01,959] ERROR xmppd [Thread-35] MultiplexManager#23814595 error sending BYE

[24 Jul 2007 11:52:01,960] INFO xmppd [Thread-35] MultiplexManager#23814595 exiting

[24 Jul 2007 11:52:02,006] INFO xmppd [Thread-34] [IS-SSO] Session Expired: joseph@esuria.com.bn reason=3

[24 Jul 2007 11:52:02,012] INFO xmppd [Thread-34] MultiplexManager#20890333 exiting

[24 Jul 2007 11:52:06,480] INFO xmppd [Thread-25] [NioSelectAcceptor][0.0.0.0:5269] accepted /127.0.0.1

[24 Jul 2007 11:52:16,131] INFO xmppd [Thread-26] New Multiplexor Connection started from /172.16.3.130

[24 Jul 2007 11:52:16,131] ERROR xmppd [Thread-36] MultiplexManager#12659064 read error java.io.IOException: Connection reset by peer

[24 Jul 2007 11:52:16,132] ERROR xmppd [Thread-36] MultiplexManager#12659064 error sending BYE

[24 Jul 2007 11:52:16,140] INFO xmppd [Thread-36] MultiplexManager#12659064 exiting

[24 Jul 2007 11:52:16,159] INFO xmppd [Thread-26] New Multiplexor Connection started from /172.16.3.130

[24 Jul 2007 11:52:16,159] INFO xmppd [Thread-37] MultiplexManager#25805098 HELLO received version=4.1 maxchannels=1002

[24 Jul 2007 11:52:16,161] INFO xmppd [Thread-26] New Multiplexor Connection started from /172.16.3.130

[24 Jul 2007 11:52:16,161] INFO xmppd [Thread-38] MultiplexManager#15255515 HELLO received version=4.1 maxchannels=1002

[24 Jul 2007 11:52:25,161] INFO xmppd [org.netbeans.lib.collab.util.Worker 2] Authenticating using Identity Server SSO

[Fatal Error] :1:1: Premature end of file.

[24 Jul 2007 11:52:26,703] INFO xmppd [org.netbeans.lib.collab.util.Worker 2] [LDAPPool] saved uid=joseph,ou=People,o=esuria.com.bn,dc=esuria,dc=com,dc=bn

[24 Jul 2007 11:52:27,171] INFO xmppd [org.netbeans.lib.collab.util.Worker 3] could not parse input

java.lang.RuntimeException: could not parse input

at net.outer_planes.jso.io.XMLReader.read(XMLReader.java:243)

at com.iplanet.im.server.StreamEndPoint.importElement(StreamEndPoint.java:566)

at com.iplanet.im.server.UserSettings.getPrivateSettings(UserSettings.java:100)

at com.iplanet.im.server.PrivateHandler.process(PrivateHandler.java:191)

at com.iplanet.im.server.ClientPacketDispatcher.handle(ClientPacketDispatcher.java:302)

at com.iplanet.im.server.ClientSession.packetTransferred(ClientSession.java:434)

at net.outer_planes.jso.AbstractStream.firePacketTransferredEvent(AbstractStream.java:674)

at net.outer_planes.jso.AbstractStream$1.addExtendedData(AbstractStream.java:115)

at net.outer_planes.jso.AbstractStream$Input.process(AbstractStream.java:198)

at net.outer_planes.jso.AbstractStream.process(AbstractStream.java:1160)

at com.iplanet.im.server.ClientSession.process(ClientSession.java:650)

at com.iplanet.im.server.ClientSession.run(ClientSession.java:631)

at org.netbeans.lib.collab.util.Worker.run(Worker.java:208)

at java.lang.Thread.run(Thread.java:595)

Caused by: org.xml.sax.SAXParseException: Premature end of file.

at org.apache.xerces.parsers.AbstractSAXParser.parse(Unknown Source)

at javax.xml.parsers.SAXParser.parse(SAXParser.java:375)

at net.outer_planes.jso.io.XMLReader.read(XMLReader.java:236)

... 13 more

mux.log @ IM multiplexor

-

[10:45:43 +0800] int-im-access mux[3186]: General Error: server[1]: received 0 bytes. Scheduling multiplexor restart.

[10:45:43 +0800] int-im-access mux[3727]: General Error: server[1]: received 0 bytes. Scheduling multiplexor restart.

[10:51:06 +0800] int-im-access mux[5115]: General Error: server[1]: received 0 bytes. Scheduling multiplexor restart.

[10:51:06 +0800] int-im-access mux[5119]: General Error: server[1]: received 0 bytes. Scheduling multiplexor restart.

[11:16:21 +0800] int-im-access mux[9894]: General Error: server[1]: received 0 bytes. Scheduling multiplexor restart.

[11:16:21 +0800] int-im-access mux[9898]: General Error: server[1]: received 0 bytes. Scheduling multiplexor restart.

It looks to me there is a communication problem between the multiplexor and IM server. If I telnet from the multiplexor host to IM server, I get disconnected after a short while.

Thanks.

JoeChris@Suna at 2007-7-29 15:52:56 >

Hi,

The log output related to the Exception seem to be related to some other problem. Looks like some users dont have private settings or have incorrect data stored in it. This is what is causing the xml related exception.

I dont think this is not related to the calendar agent problem you mentioned earlier.

The issue is a potential communication problem from agent-calendar to backend calendar server (mux is not really involved here). You could try to see if calendar agent (on im server) can connect to the backend calendar server it is configured to talk to.

About the disconnection - any socket connection if kept alive without establishing an xmpp session will get closed after some time.

Regards,

Mridul

MridulMa at 2007-7-29 15:52:56 >