powered by Jive Software

User Connections from Connection Manager are getting dropped

Hi OF Team,

It’s been couple of days that, my OF server is up and running in my LAN. FYI, we have connection Manager placed in DMZ which will handle requests comming from Internet on port 5222 & will establish a session with my OPENFIRE Server in Lan on port 5262. Today all of a sudden, i heard from some internet users complaining that, spark is getting frequently disconnected.

I tried to check the logs of connection manager, OF server but no clue. Can you please suggest the direction in which i should do troubleshooting.

I am pasting the logs FYR.

CONNECTION MANAGER ERROR LOG

2013.06.18 17:38:12 [org.jivesoftware.multiplexer.net.ConnectionHandler.messageReceived(ConnectionH andler.java:131)] Closing

connection due to error while processing message: ^P^@^@�^@�_K��V��a^O���^Vm����^C@�^NF�^Z�&,pT@w �etտ�VA^K<U+0773>�+;^P�M����

��^?^@^@��=.^FH��p>#��q��^��n�ԼQ��Mï¿½ï¿½ï¿½ï¿½ï¿ ½ï¿½*G���A^C��Q��{^P�^\je�YU<U+0764>mB�d���X^T^C^A^@^A ^A^V^C^A^@ =#^P�\

ØY^]Þ��!�cR4n�ک^Y"��@^B���mm0015097</

iq><c xmlns=‘http://jabber.org/protocol/caps’ hash=‘sha-1’ node='http://www.ignit

erealtime.org/projects/smack/’ ver=‘xYEd+1ZdePfGl3AaJ23FB7rizRg=’/>

org.xmlpull.v1.XmlPullParserException: only whitespace content allowed before start tag and not \u10 (position: START_DOCUMEN

T seen \u10… @1:1)

at org.xmlpull.mxp1.MXParser.parseProlog(MXParser.java:1519)

at org.jivesoftware.multiplexer.net.MXParser.nextImpl(MXParser.java:333)

at org.xmlpull.mxp1.MXParser.next(MXParser.java:1093)

at org.jivesoftware.multiplexer.net.StanzaHandler.process(StanzaHandler.java:132)

at org.jivesoftware.multiplexer.net.ConnectionHandler.messageReceived(ConnectionHa ndler.java:129)

at org.apache.mina.common.support.AbstractIoFilterChain$TailFilter.messageReceived (AbstractIoFilterChain.java:570)

at org.apache.mina.common.support.AbstractIoFilterChain.callNextMessageReceived(Ab stractIoFilterChain.java:299)

at org.apache.mina.common.support.AbstractIoFilterChain.access$1100(AbstractIoFilt erChain.java:53)

at org.apache.mina.common.support.AbstractIoFilterChain$EntryImpl$1.messageReceive d(AbstractIoFilterChain.java:648)

at org.apache.mina.filter.codec.support.SimpleProtocolDecoderOutput.flush(SimplePr otocolDecoderOutput.java:58)

at org.apache.mina.filter.codec.ProtocolCodecFilter.messageReceived(ProtocolCodecF ilter.java:185)

at org.apache.mina.common.support.AbstractIoFilterChain.callNextMessageReceived(Ab stractIoFilterChain.java:299)

at org.apache.mina.common.support.AbstractIoFilterChain.access$1100(AbstractIoFilt erChain.java:53)

at org.apache.mina.common.support.AbstractIoFilterChain$EntryImpl$1.messageReceive d(AbstractIoFilterChain.java:648)

at org.apache.mina.filter.support.SSLHandler.flushScheduledEvents(SSLHandler.java: 275)

at org.apache.mina.filter.SSLFilter.messageReceived(SSLFilter.java:427)

at org.apache.mina.common.support.AbstractIoFilterChain.callNextMessageReceived(Ab stractIoFilterChain.java:299)

at org.apache.mina.common.support.AbstractIoFilterChain.access$1100(AbstractIoFilt erChain.java:53)

at org.apache.mina.common.support.AbstractIoFilterChain$EntryImpl$1.messageReceive d(AbstractIoFilterChain.java:648)

at org.apache.mina.filter.executor.ExecutorFilter.processEvent(ExecutorFilter.java :239)

at org.apache.mina.filter.executor.ExecutorFilter$ProcessEventsRunnable.run(Execut orFilter.java:283)

at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)

at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)

at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:51)

at java.lang.Thread.run(Unknown Source)

Connection Manager warn.log

2013.06.21 19:27:44 Closing session due to exception: (SOCKET, R: 53574f95.cm-6-8b.dynamic.ziggo.nl/83.87.79.149:60479, L: /1

92.168.60.5:5222, S: 0.0.0.0/0.0.0.0:5222)

org.apache.mina.filter.codec.ProtocolDecoderException: java.lang.Exception: Disallowed character (Hexdump: 16 03 01 00 86 10

00 00 82 00 80 1E 4F F5 D0 63 21 1D B4 B1 AC AF 78 87 CD 74 B1 8A DF 1F AD C7 20 EA 99 FB 87 8D F1 83 85 F8 D4 AA 3F 48 60 DE

3C C3 EC 2C 3B 30 E6 AE 22 39 32 B2 55 A1 43 38 64 FE 65 E6 52 E6 93 40 C8 C2 EC 9A 8E 60 F3 48 36 C0 49 94 30 7D 57 43 13 5

D 3F D1 59 7F 04 33 D0 9F FF 22 41 C4 9F F2 43 54 75 F6 F7 BC E3 93 5A 43 CD F8 8F 34 5A 3E B9 FF 0F F5 E4 C1 8E A7 9A 01 12

B6 31 A8 81 75 88 CA 3C 14 03 01 00 01 01 16 03 01 00 20 7B DD E5 B0 5E 06 91 2A 47 6D 5D 38 50 DF 9F BC C7 68 95 B3 0F 0B EC

85 D8 D1 91 F6 10 C4 84 A2)

at org.apache.mina.filter.codec.ProtocolCodecFilter.messageReceived(ProtocolCodecF ilter.java:170)

at org.apache.mina.common.support.AbstractIoFilterChain.callNextMessageReceived(Ab stractIoFilterChain.java:299)

at org.apache.mina.common.support.AbstractIoFilterChain.access$1100(AbstractIoFilt erChain.java:53)

at org.apache.mina.common.support.AbstractIoFilterChain$EntryImpl$1.messageReceive d(AbstractIoFilterChain.java:648)

at org.apache.mina.filter.support.SSLHandler.flushScheduledEvents(SSLHandler.java: 275)

at org.apache.mina.filter.SSLFilter.messageReceived(SSLFilter.java:427)

at org.apache.mina.common.support.AbstractIoFilterChain.callNextMessageReceived(Ab stractIoFilterChain.java:299)

at org.apache.mina.common.support.AbstractIoFilterChain.access$1100(AbstractIoFilt erChain.java:53)

at org.apache.mina.common.support.AbstractIoFilterChain$EntryImpl$1.messageReceive d(AbstractIoFilterChain.java:648)

at org.apache.mina.filter.executor.ExecutorFilter.processEvent(ExecutorFilter.java :239)

at org.apache.mina.filter.executor.ExecutorFilter$ProcessEventsRunnable.run(Execut orFilter.java:283)

at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)

at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)

at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:51)

at java.lang.Thread.run(Unknown Source)

Caused by: java.lang.Exception: Disallowed character

at org.jivesoftware.multiplexer.net.XMLLightweightParser.read(XMLLightweightParser .java:210)

at org.jivesoftware.multiplexer.net.XMPPDecoder.doDecode(XMPPDecoder.java:32)

at org.apache.mina.filter.codec.CumulativeProtocolDecoder.decode(CumulativeProtoco lDecoder.java:133)

at org.apache.mina.filter.codec.ProtocolCodecFilter.messageReceived(ProtocolCodecF ilter.java:163)

… 14 more

Kindly share your Observations and how to troubleshoot ahead.

Thanks & regards,

Adhiraju Mouli.

havish.mouli@gmail.com
warn.log.zip (72023 Bytes)
error.log.zip (14773 Bytes)

Hi OF Team,

Now, i have enabled debug logs in Connection Manager. And below are the result. Can you help me out to fix this issue.

2013.06.25 20:45:47 IQ stanza of type RESULT was discarded: <iq type=“result” id=“358-6564” from=“10.10.1.106” to="mwq4w/Conn

ection Worker - 2"><host name="14.97

.26.85" address=“14.97.26.85”/>

Most commands optionally preceded by integer argument k. Defaults in brackets.

Star (*) indicates argument becomes new default.


Display next k lines of text [current screen size]

z Display next k lines of text [current screen size]*

Display next k lines of text [1]*

d or ctrl-D Scroll k lines [current scroll size, initially 11]*

q or Q or Exit from more

s Skip forward k lines of text [1]

f Skip forward k screenfuls of text [1]

b or ctrl-B Skip backwards k screenfuls of text [1]

’ Go to place where previous search started

= Display current line number

/ Search for kth occurrence of regular expression [1]

n Search for kth occurrence of last r.e [1]

! or :! Execute in a subshell

v Start up /usr/bin/vi at current line

ctrl-L Redraw screen

:n Go to kth next file [1]

Go to kth previous file [1]

:f Display current file name and line number

. Repeat previous command


2013.06.25 20:45:49 IQ stanza of type ERRROR was discarded: <iq type=“error” id=“390-6566” from=“10.10.1.106” to="mwq4w/Conne

ction Worker - 10"><error

code=“404” type=“cancel”>

2013.06.25 20:45:52 IQ stanza of type RESULT was discarded: <iq type=“result” id=“703-6568” from=“10.10.1.106” to="mwq4w/Conn

ection Worker - 6"><host name="1.38.

25.76" address=“1.38.25.76”/>

2013.06.25 20:46:38 IQ stanza of type RESULT was discarded: <iq type=“result” id=“437-6589” from=“10.10.1.106” to="mwq4w/Conn

ection Worker - 3"><host name="117.1

92.238.209" address=“117.192.238.209”/>

2013.06.25 20:46:38 IQ stanza of type RESULT was discarded: <iq type=“result” id=“567-6588” from=“10.10.1.106” to="mwq4w/Conn

ection Worker - 10"><host name="117.

192.238.209" address=“117.192.238.209”/>

2013.06.25 20:46:39

java.io.IOException: Connection timed out

at sun.nio.ch.FileDispatcher.read0(Native Method)

at sun.nio.ch.SocketDispatcher.read(Unknown Source)

at sun.nio.ch.IOUtil.readIntoNativeBuffer(Unknown Source)

at sun.nio.ch.IOUtil.read(Unknown Source)

at sun.nio.ch.SocketChannelImpl.read(Unknown Source)

at org.apache.mina.transport.socket.nio.SocketIoProcessor.read(SocketIoProcessor.j ava:218)

at org.apache.mina.transport.socket.nio.SocketIoProcessor.process(SocketIoProcesso r.java:198)

at org.apache.mina.transport.socket.nio.SocketIoProcessor.access$400(SocketIoProce ssor.java:45)

at org.apache.mina.transport.socket.nio.SocketIoProcessor$Worker.run(SocketIoProce ssor.java:485)

at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:51)

at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(Unknown Source)

at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source)

at java.lang.Thread.run(Unknown Source)

2013.06.25 20:46:39 IQ stanza of type RESULT was discarded: <iq type=“result” id=“577-6590” from=“10.10.1.106” to="mwq4w/Conn

ection Worker - 7">

I would be highly obliged if you could show some light on this problem. Waiting for your tweaks eagerly.

Thanking you in Advance,

With best Regards,

Adhiraju mouli.

Bump

I’m seeing this as well. Not using connection manager. Single instance dev box. Since we have both cases (with or without connection manager), my bet is that it has nothing to do with connection manager. It’s to do with server side socket implementation that does not handle a particular case from the client side very well.

2013.09.13 19:12:14 org.jivesoftware.openfire.nio.ConnectionHandler - ConnectionHandler reports IOException for session: (SOCKET, R: /[REDACTED_IP]:60010, L: /[REDACTED_IP]:5222, S: 0.0.0.0/0.0.0.0:5222)

java.io.IOException: Connection reset by peer

at sun.nio.ch.FileDispatcherImpl.read0(Native Method)

at sun.nio.ch.SocketDispatcher.read(SocketDispatcher.java:39)

at sun.nio.ch.IOUtil.readIntoNativeBuffer(IOUtil.java:225)

at sun.nio.ch.IOUtil.read(IOUtil.java:198)

at sun.nio.ch.SocketChannelImpl.read(SocketChannelImpl.java:359)

at org.apache.mina.transport.socket.nio.SocketIoProcessor.read(SocketIoProcessor.j ava:218)

at org.apache.mina.transport.socket.nio.SocketIoProcessor.process(SocketIoProcesso r.java:198)

at org.apache.mina.transport.socket.nio.SocketIoProcessor.access$400(SocketIoProce ssor.java:45)

at org.apache.mina.transport.socket.nio.SocketIoProcessor$Worker.run(SocketIoProce ssor.java:485)

at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:51)

at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)

at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)

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

Quick question, what sort of clients are you using? Desktop app or mobile?

-Jack

same problem here.

I’m using desktop app with smack library.

Any help ?

Thanks