powered by Jive Software

XMLNotWellFormedException - Character is invalid and ConnectionHandler reports IOException errors

I’m noticing the following which has me a little concerned. I’m getting messages of invalid characters and I think they’re causing issues with my clients connecting.

Not sure if this is a re-currence of OF-91 - http://issues.igniterealtime.org/browse/OF-91

2013.07.31 16:38:49 org.jivesoftware.openfire.nio.ConnectionHandler - Closing session due to exception: (SOCKET, R: /192.168.0.222:53874, L: /192.168.1.210:5222, S: 0.0.0.0/0.0.0.0:5222) org.apache.mina.filter.codec.ProtocolDecoderException: org.jivesoftware.openfire.nio.XMLNotWellFormedException: Character is invalid in:   (Hexdump: 7D 6C F3 B0 5F 2A 71 00 EA 78 1E 14 03 01 00 01 01 16 03 01 00 20 B6 00 D5 8D 16 DD D4 84 4B 77 F2 F3 58 70 2C F9 D5 3C D6 69 BC 6C D1 7A F5 DB C5 87 C7 E1 2E 75 3C 69 71 20 69 64 3D 22 35 69 4E 36 6F 2D 31 39 30 22 20 74 79 70 65 3D 22 67 65 74 22 3E 3C 71 75 65 72 79 20 78 6D 6C 6E 73 3D 22 6A 61 62 62 65 72 3A 69 71 3A 61 75 74 68 22 3E 3C 75 73 65 72 6E 61 6D 65 3E 73 61 6C 65 6D 61 6E 3C 2F 75 73 65 72 6E 61 6D 65 3E 3C 2F 71 75 65 72 79 3E 3C 2F 69 71 3E) at org.apache.mina.filter.codec.ProtocolCodecFilter.messageReceived(ProtocolCodecFilter.java:170) at org.apache.mina.common.support.AbstractIoFilterChain.callNextMessageReceived(AbstractIoFilterChain.java:299) at org.apache.mina.common.support.AbstractIoFilterChain.access$1100(AbstractIoFilterChain.java:53) at org.apache.mina.common.support.AbstractIoFilterChain$EntryImpl$1.messageReceived(AbstractIoFilterChain.java:648) at org.apache.mina.filter.executor.ExecutorFilter.processEvent(ExecutorFilter.java:239) at org.apache.mina.filter.executor.ExecutorFilter$ProcessEventsRunnable.run(ExecutorFilter.java:283) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1146) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:51) at java.lang.Thread.run(Thread.java:679) Caused by: org.jivesoftware.openfire.nio.XMLNotWellFormedException: Character is invalid in:   at org.jivesoftware.openfire.nio.XMLLightweightParser.read(XMLLightweightParser.java:208) at org.jivesoftware.openfire.nio.XMPPDecoder.doDecode(XMPPDecoder.java:41) at org.apache.mina.filter.codec.CumulativeProtocolDecoder.decode(CumulativeProtocolDecoder.java:133) at org.apache.mina.filter.codec.ProtocolCodecFilter.messageReceived(ProtocolCodecFilter.java:163) ... 9 more 2013.07.31 23:43:42 org.jivesoftware.openfire.nio.ConnectionHandler - Closing session due to exception: (SOCKET, R: /192.168.0.222:55785, L: /192.168.1.210:5222, S: 0.0.0.0/0.0.0.0:5222) org.apache.mina.filter.codec.ProtocolDecoderException: org.jivesoftware.openfire.nio.XMLNotWellFormedException: Character is invalid in:   (Hexdump: 16 03 01 00 4B 01 00 00 47 03 01 51 FA 03 9B 5F 28 7B 35 41 6A 77 AB B7 F4 48 BD 11 47 B4 EA 6E 4B 5B CA 58 91 87 AC B9 C5 50 DB 00 00 20 00 04 00 05 00 2F 00 33 00 32 00 0A 00 16 00 13 00 09 00 15 00 12 00 03 00 08 00 14 00 11 00 FF 01 00) at org.apache.mina.filter.codec.ProtocolCodecFilter.messageReceived(ProtocolCodecFilter.java:170) at org.apache.mina.common.support.AbstractIoFilterChain.callNextMessageReceived(AbstractIoFilterChain.java:299) at org.apache.mina.common.support.AbstractIoFilterChain.access$1100(AbstractIoFilterChain.java:53) at org.apache.mina.common.support.AbstractIoFilterChain$EntryImpl$1.messageReceived(AbstractIoFilterChain.java:648) at org.apache.mina.filter.executor.ExecutorFilter.processEvent(ExecutorFilter.java:239) at org.apache.mina.filter.executor.ExecutorFilter$ProcessEventsRunnable.run(ExecutorFilter.java:283) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1146) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:51) at java.lang.Thread.run(Thread.java:679) Caused by: org.jivesoftware.openfire.nio.XMLNotWellFormedException: Character is invalid in:   at org.jivesoftware.openfire.nio.XMLLightweightParser.read(XMLLightweightParser.java:208) at org.jivesoftware.openfire.nio.XMPPDecoder.doDecode(XMPPDecoder.java:41) at org.apache.mina.filter.codec.CumulativeProtocolDecoder.decode(CumulativeProtocolDecoder.java:133) at org.apache.mina.filter.codec.ProtocolCodecFilter.messageReceived(ProtocolCodecFilter.java:163) ... 9 more 2013.08.01 09:25:18 org.jivesoftware.openfire.net.StanzaHandler - Ignoring available presence packet of closed session: <presence to="devops@conference.servepath.com/Brian Menges"> <priority>1</priority> <c xmlns="http://jabber.org/protocol/caps" node="http://pidgin.im/" hash="sha-1" ver="AcN1/PEN8nq7AHD+9jpxMV4U6YM=" ext="voice-v1 camera-v1 video-v1"/> <x xmlns="http://jabber.org/protocol/muc"/> </presence>

I too am also seeing some other reported issues with SSL:

2013.08.01 09:25:18 org.jivesoftware.openfire.nio.ConnectionHandler - ConnectionHandler reports IOException for session: (SOCKET, R: /192.168.0.222:41576, L: /192.168.1.210:5222, S: 0.0.0.0/0.0.0.0:5222) javax.net.ssl.SSLException: Received fatal alert: bad_record_mac at sun.security.ssl.Alerts.getSSLException(Alerts.java:208) at sun.security.ssl.SSLEngineImpl.fatal(SSLEngineImpl.java:1446) at sun.security.ssl.SSLEngineImpl.fatal(SSLEngineImpl.java:1414) at sun.security.ssl.SSLEngineImpl.recvAlert(SSLEngineImpl.java:1576) at sun.security.ssl.SSLEngineImpl.readRecord(SSLEngineImpl.java:1018) at sun.security.ssl.SSLEngineImpl.readNetRecord(SSLEngineImpl.java:853) at sun.security.ssl.SSLEngineImpl.unwrap(SSLEngineImpl.java:727) at javax.net.ssl.SSLEngine.unwrap(SSLEngine.java:624) at org.apache.mina.filter.support.SSLHandler.unwrap0(SSLHandler.java:658) at org.apache.mina.filter.support.SSLHandler.unwrap(SSLHandler.java:596) at org.apache.mina.filter.support.SSLHandler.decrypt(SSLHandler.java:423) at org.apache.mina.filter.support.SSLHandler.messageReceived(SSLHandler.java:308) at org.apache.mina.filter.SSLFilter.messageReceived(SSLFilter.java:392) at org.apache.mina.common.support.AbstractIoFilterChain.callNextMessageReceived(AbstractIoFilterChain.java:299) at org.apache.mina.common.support.AbstractIoFilterChain.access$1100(AbstractIoFilterChain.java:53) at org.apache.mina.common.support.AbstractIoFilterChain$EntryImpl$1.messageReceived(AbstractIoFilterChain.java:648) at org.apache.mina.common.support.AbstractIoFilterChain$HeadFilter.messageReceived(AbstractIoFilterChain.java:499) at org.apache.mina.common.support.AbstractIoFilterChain.callNextMessageReceived(AbstractIoFilterChain.java:299) at org.apache.mina.common.support.AbstractIoFilterChain.fireMessageReceived(AbstractIoFilterChain.java:293) at org.apache.mina.transport.socket.nio.SocketIoProcessor.read(SocketIoProcessor.java:228) at org.apache.mina.transport.socket.nio.SocketIoProcessor.process(SocketIoProcessor.java:198) at org.apache.mina.transport.socket.nio.SocketIoProcessor.access$400(SocketIoProcessor.java:45) at org.apache.mina.transport.socket.nio.SocketIoProcessor$Worker.run(SocketIoProcessor.java:485) at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:51) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1146) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:679) 2013.08.01 09:25:18 org.jivesoftware.openfire.nio.ConnectionHandler - ConnectionHandler reports IOException for session: (SOCKET, R: /192.168.0.222:41576, L: /192.168.1.210:5222, S: 0.0.0.0/0.0.0.0:5222) java.io.IOException: Connection reset by peer at sun.nio.ch.FileDispatcher.read0(Native Method) at sun.nio.ch.SocketDispatcher.read(SocketDispatcher.java:39) at sun.nio.ch.IOUtil.readIntoNativeBuffer(IOUtil.java:251) at sun.nio.ch.IOUtil.read(IOUtil.java:224) at sun.nio.ch.SocketChannelImpl.read(SocketChannelImpl.java:254) at org.apache.mina.transport.socket.nio.SocketIoProcessor.read(SocketIoProcessor.java:218) at org.apache.mina.transport.socket.nio.SocketIoProcessor.process(SocketIoProcessor.java:198) at org.apache.mina.transport.socket.nio.SocketIoProcessor.access$400(SocketIoProcessor.java:45) at org.apache.mina.transport.socket.nio.SocketIoProcessor$Worker.run(SocketIoProcessor.java:485) at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:51) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1146) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:679)

Can anyone provide some insight?