Client disconnecting after some time

Zvonimir · March 31, 2006, 12:24pm

We use miranda (latest build), and everything worked just fine before 2.5.1 upgrade, now internet clients disconnects sometimes.

Internet connection is stable, I have IRC server also and it’'s running just fine, but wildfire is disconnecting users, why?

We have local adress 192.168.0.6 for server, and xmmp.domain is 192.168.0.6.

For external acces we use dyndns.org. Shuld I switch xmmp.domain to pojatno.dyndns.org? any help would be great!

good work btw!

2006.03.31 14:01:47 [org.jivesoftware.wildfire.net.SocketReader.run(SocketReader.java:161)

] Connection closed before session established

Socket[addr=/83.131.51.211,port=1984,localport=5222]

2006.03.31 14:02:48 [org.jivesoftware.wildfire.net.SocketReader.run(SocketReader.java:161)

] Connection closed before session established

Socket[addr=/83.131.51.211,port=1988,localport=5222]

2006.03.31 14:04:07 [org.jivesoftware.wildfire.net.SocketReader.run(SocketReader.java:161)

] Connection closed before session established

Socket[addr=/83.131.51.211,port=1993,localport=5222]

LG1 · March 31, 2006, 9:12pm

Bok Zvonimir,

does the log file matches such a case? The “Connection closed before session established” is usually displayed if the handshake did fail, and as we see here it did fail three time within three minutes. So it seems that the client can’'t connect.

Is it possible for you to enable the debug log, maybe it displays some more useful information.

LG

Zvonimir · April 1, 2006, 8:53am

So here it is:

2006.03.31 15:34:26 Connect Socket[addr=/192.168.0.4,port=1032,localport=5222]

2006.03.31 15:37:52 Connect Socket[addr=/194.152.247.95,port=1044,localport=5222]

2006.03.31 15:41:09 Connect Socket[addr=/192.168.0.12,port=4714,localport=5222]

2006.03.31 15:44:20 Connect Socket[addr=/194.152.247.95,port=1070,localport=5222]

2006.03.31 15:44:20 Error creating session

java.net.SocketException: Connection reset

at java.net.SocketInputStream.read(Unknown Source)

at sun.nio.cs.StreamDecoder$CharsetSD.readBytes(Unknown Source)

at sun.nio.cs.StreamDecoder$CharsetSD.implRead(Unknown Source)

at sun.nio.cs.StreamDecoder.read(Unknown Source)

at java.io.InputStreamReader.read(Unknown Source)

at org.xmlpull.mxp1.MXParser.fillBuf(MXParser.java:2971)

at org.xmlpull.mxp1.MXParser.more(MXParser.java:3025)

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

at org.jivesoftware.wildfire.net.MXParser.nextImpl(MXParser.java:331)

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

at org.jivesoftware.wildfire.net.SocketReader.createSession(SocketReader.java:573)

at org.jivesoftware.wildfire.net.SocketReader.run(SocketReader.java:110)

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

2006.03.31 15:44:59 Connect Socket[addr=/194.152.247.95,port=1080,localport=5222]

2006.03.31 15:45:00 Logging off vlado@192.168.0.6/Miranda on org.jivesoftware.wildfire.net.SocketConnection@1a99836 socket: Socket[addr=/194.152.247.95,port=1044,localport=5222] session: org.jivesoftware.wildfire.ClientSession@1c0cd80 status: 3 address: vlado@192.168.0.6/Miranda id: d26cfd4 presence:

Zvonimir · April 3, 2006, 7:20am

This is from miranda:

PS_SETSTATUS( 40072 )

Thread started: type=0

SetAwayMsg called, wParam=40072 lParam=(null)

xmpp-client.tcp.pojatno.dyndns.org not resolved

Thread type=0 server=’‘pojatno.dyndns.org’’ port=’‘5222’’

(288) Connected to pojatno.dyndns.org:5222

Local IP = 192.168.0.6

(0038DF48:288) Data sent

<?xml version=''1.0'' encoding=''UTF-8''?>

Entering main recv loop

(0038DF48:288) Data received

<?xml version=''1.0'' encoding=''UTF-8''?>

recvResult = 170

XML: skip <?xml> tag

IqAdd id=0, proc=0, func=0x32509dd8

(0038DF48:288) Data sent

bytesParsed = 170

(0038DF48:288) Data received

recvResult = 132

Handling iq request for id=0

iqIdGetAuth

IqAdd id=1, proc=0, func=0x3250a006

(0038DF48:288) Data sent

bytesParsed = 132

(0038DF48:288) Data received

recvResult = 62

Handling iq request for id=1

iqIdSetAuth

IqAdd id=2, proc=0, func=0x3250a203

(0038DF48:288) Data sent

bytesParsed = 62

(0038DF48:288) Data received

</i

recvResult = 2048

bytesParsed = 2045

(0038DF48:288) Data received

tem>

recvResult = 203

Handling iq request for id=2

iqIdGetRoster

Status changed via THREADSTART

(0038DF48:288) Data sent

bytesParsed = 206

(0038DF48:288) Data received

recvResult = 166

(0038DF48:288) Data sent

tibor ( tibor@192.168.0.6/Miranda ) online, set contact status to 40075

bytesParsed = 166

(0038DF48:288) Data received

recvResult = 577

(0038DF48:288) Data sent

marijan ( marijan@192.168.0.6/Miranda ) online, set contact status to 40073

(0038DF48:288) Data sent

bytesParsed = 577

(0038DF48:288) Data received

recvResult = 246

bytesParsed = 246

(0038DF48:288) Data received

recvResult = 256

bytesParsed = 256

(0038DF48:288) Data sent

Netlib_Recv() failed, error=10054

recvResult = 0

(0038DF48:4294967295) Connection closed

Exiting KeepAliveThread

Thread ended: type=0 server=’‘pojatno.dyndns.org’’

Exiting ServerThread

(2040) KeepStatus: connection lost! (JABBER)

(2040) CommonStatus sets status for JABBER to 40072

PS_SETSTATUS( 40072 )

Thread started: type=0

(2040) CommonStatus sets status message for JABBER to Yep, I’'m here.

SetAwayMsg called, wParam=40072 lParam=Yep, I’'m here.

xmpp-client.tcp.pojatno.dyndns.org not resolved

Thread type=0 server=’‘pojatno.dyndns.org’’ port=’‘5222’’

.\modules\netlib\netlibopenconn.c 495: connect() failed (10060)

Connection failed ( 10060 )

Thread ended, connection failed

(2040) CommonStatus sets status for JABBER to 40072

PS_SETSTATUS( 40072 )

Thread started: type=0

(2040) CommonStatus sets status message for JABBER to Yep, I’'m here.

SetAwayMsg called, wParam=40072 lParam=Yep, I’'m here.

xmpp-client.tcp.pojatno.dyndns.org not resolved

Thread type=0 server=’‘pojatno.dyndns.org’’ port=’‘5222’’

.\modules\netlib\netlibopenconn.c 495: connect() failed (10060)

Connection failed ( 10060 )

Thread ended, connection failed

(2040) CommonStatus sets status for JABBER to 40072

PS_SETSTATUS( 40072 )

Thread started: type=0

(2040) CommonStatus sets status message for JABBER to Yep, I’'m here.

SetAwayMsg called, wParam=40072 lParam=Yep, I’'m here.

xmpp-client.tcp.pojatno.dyndns.org not resolved

Thread type=0 server=’‘pojatno.dyndns.org’’ port=’‘5222’’

.\modules\netlib\netlibopenconn.c 495: connect() failed (10060)

Connection failed ( 10060 )

Thread ended, connection failed

(2040) CommonStatus sets status for JABBER to 40072

PS_SETSTATUS( 40072 )

Thread started: type=0

(2040) CommonStatus sets status message for JABBER to Yep, I’'m here.

SetAwayMsg called, wParam=40072 lParam=Yep, I’'m here.

xmpp-client.tcp.pojatno.dyndns.org not resolved

Thread type=0 server=’‘pojatno.dyndns.org’’ port=’‘5222’’

(536) Connected to pojatno.dyndns.org:5222

Local IP = 192.168.0.6

(0038DF48:536) Data sent

<?xml version=''1.0'' encoding=''UTF-8''?>

Entering main recv loop

(0038DF48:536) Data received

<?xml version=''1.0'' encoding=''UTF-8''?>

recvResult = 170

XML: skip <?xml> tag

IqAdd id=3, proc=0, func=0x32509dd8

(0038DF48:536) Data sent

bytesParsed = 170

(0038DF48:536) Data received

recvResult = 132

Handling iq request for id=3

iqIdGetAuth

IqAdd id=4, proc=0, func=0x3250a006

(0038DF48:536) Data sent

bytesParsed = 132

(0038DF48:536) Data received

recvResult = 62

Handling iq request for id=4

iqIdSetAuth

IqAdd id=5, proc=0, func=0x3250a203

(0038DF48:536) Data sent

bytesParsed = 62

(0038DF48:536) Data received

</i

recvResult = 2048

bytesParsed = 2045

(0038DF48:536) Data received

tem>

recvResult = 203

Handling iq request for id=5

iqIdGetRoster

Status changed via THREADSTART

(0038DF48:536) Data sent

bytesParsed = 206

(0038DF48:536) Data received

recvResult = 108

(0038DF48:536) Data sent

tibor ( tibor@192.168.0.6/Miranda ) online, set contact status to 40072

bytesParsed = 108

(0038DF48:536) Data received

recvResult = 577

(0038DF48:536) Data sent

marijan ( marijan@192.168.0.6/Miranda ) online, set contact status to 40073

(0038DF48:536) Data sent

bytesParsed = 577

(0038DF48:536) Data received

recvResult = 246

bytesParsed = 246

(0038DF48:536) Data received

recvResult = 256

bytesParsed = 256

(0038DF48:536) Data sent

Netlib_Recv() failed, error=10054

recvResult = 0

(0038DF48:4294967295) Connection closed

Exiting KeepAliveThread

Thread ended: type=0 server=’‘pojatno.dyndns.org’’

Exiting ServerThread

(2040) KeepStatus: connection lost! (JABBER)

(2040) CommonStatus sets status for JABBER to 40072

PS_SETSTATUS( 40072 )

Thread started: type=0

(2040) CommonStatus sets status message for JABBER to Yep, I’'m here.

SetAwayMsg called, wParam=40072 lParam=Yep, I’'m here.

xmpp-client.tcp.pojatno.dyndns.org not resolved

Thread type=0 server=’‘pojatno.dyndns.org’’ port=’‘5222’’

PS_SETSTATUS( 40071 )

.\modules\netlib\netlibopenconn.c 495: connect() failed (1460)

Connection failed ( 1460 )

Thread ended, connection failed

Free SSL library

matt · April 4, 2006, 12:06am

Could you try a different client than Miranda? That will help to isolate if this is a client or server issue.

Regards,

Matt

Zvonimir · April 4, 2006, 6:27am

JAJC, GAIM, all the same. But, maybe is my router disconnecting but I don’‘t see how becouse the line is up 24 hours/day and all other comunication is working just fine, irc, ventrilo, icq (local server). I’‘ll send you gaim’'s log later. I tried to reconfigure router (ipcop) yesterday and will report you if something is better.

Here it is:

Conversation with zvonimir@pojatno.dyndns.org at 2006-04-04 08:27:03 on zvonimir@pojatno.dyndns.org/Gaim (jabber)

+++ zvonimir@pojatno.dyndns.org/Gaim signed on @ 2006-04-04 08:27:03 -

Tibor signed on @ 2006-04-04 08:27:04 -

Marijan signed on @ 2006-04-04 08:27:04 -

+++ zvonimir@pojatno.dyndns.org/Gaim signed off @ 2006-04-04 08:30:47 -

I was hoping I fixed something…

Message was edited by: zbuzanic

Jay · April 4, 2006, 12:59pm

Im suspious that your router is doing something to drop the connections. Do you know Linux well? ipcop just uses iptables, you should be able to have it log all connections it drops; does anything interesting show up there?

Zvonimir · April 4, 2006, 1:25pm

Ok, I will check log for ipcop also

EDITED

Nope nothing, IPCOP is clean, everything is just fine, I’'ll reinstall IPCOP this weekend, what else …

Message was edited by: zbuzanic

Zvonimir · April 10, 2006, 6:30am

Well, this weekend I installed a brand new PC for IPCOP, and configured it from scratch. Also, I have downloaded wildfire 2.6.0, and still got the same problem. I really don’‘t know what to check next. It’‘s so annoying when you can’'t find the solution. Well, thanx for all the help, any other suggestion is welcome.

I also tried smack, here’'s log:

XMPPError connecting to pojatno.dyndns.org:5222.: (502)

– caused by: java.net.ConnectException: Connection timed out: connect

at org.jivesoftware.smack.XMPPConnection.connectUsingConfiguration(XMPPConnection. java:281)

at org.jivesoftware.smack.XMPPConnection.(XMPPConnection.java:171)

at com.jivesoftware.LoginDialog$LoginPanel.login(LoginDialog.java:575)

at com.jivesoftware.LoginDialog$LoginPanel.access$400(LoginDialog.java:179)

at com.jivesoftware.LoginDialog$3.construct(LoginDialog.java:492)

at com.jivesoftware.spark.util.SwingWorker$2.run(SwingWorker.java:121)

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

java.net.SocketException: Connection reset

at java.net.SocketInputStream.read(Unknown Source)

at com.sun.net.ssl.internal.ssl.InputRecord.readFully(Unknown Source)

at com.sun.net.ssl.internal.ssl.InputRecord.read(Unknown Source)

at com.sun.net.ssl.internal.ssl.SSLSocketImpl.readRecord(Unknown Source)

at com.sun.net.ssl.internal.ssl.SSLSocketImpl.readDataRecord(Unknown Source)

at com.sun.net.ssl.internal.ssl.AppInputStream.read(Unknown Source)

at sun.nio.cs.StreamDecoder$CharsetSD.readBytes(Unknown Source)

at sun.nio.cs.StreamDecoder$CharsetSD.implRead(Unknown Source)

at sun.nio.cs.StreamDecoder.read(Unknown Source)

at java.io.InputStreamReader.read(Unknown Source)

at java.io.BufferedReader.fill(Unknown Source)

at java.io.BufferedReader.read1(Unknown Source)

at java.io.BufferedReader.read(Unknown Source)

at org.jivesoftware.smack.util.ObservableReader.read(ObservableReader.java:42)

at org.xmlpull.mxp1.MXParser.fillBuf(MXParser.java:2947)

at org.xmlpull.mxp1.MXParser.more(MXParser.java:2996)

at org.xmlpull.mxp1.MXParser.nextImpl(MXParser.java:1143)

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

at org.jivesoftware.smack.PacketReader.parsePackets(PacketReader.java:377)

at org.jivesoftware.smack.PacketReader.access$000(PacketReader.java:43)

at org.jivesoftware.smack.PacketReader$1.run(PacketReader.java:63)

2006.04.10 11:34:58 com.jivesoftware.spark.util.log.Logger logError

SEVERE: Connection closed on error.

java.net.SocketException: Connection reset

at java.net.SocketInputStream.read(Unknown Source)

at com.sun.net.ssl.internal.ssl.InputRecord.readFully(Unknown Source)

at com.sun.net.ssl.internal.ssl.InputRecord.read(Unknown Source)

at com.sun.net.ssl.internal.ssl.SSLSocketImpl.readRecord(Unknown Source)

at com.sun.net.ssl.internal.ssl.SSLSocketImpl.readDataRecord(Unknown Source)

at com.sun.net.ssl.internal.ssl.AppInputStream.read(Unknown Source)

at sun.nio.cs.StreamDecoder$CharsetSD.readBytes(Unknown Source)

at sun.nio.cs.StreamDecoder$CharsetSD.implRead(Unknown Source)

at sun.nio.cs.StreamDecoder.read(Unknown Source)

at java.io.InputStreamReader.read(Unknown Source)

at java.io.BufferedReader.fill(Unknown Source)

at java.io.BufferedReader.read1(Unknown Source)

at java.io.BufferedReader.read(Unknown Source)

at org.jivesoftware.smack.util.ObservableReader.read(ObservableReader.java:42)

at org.xmlpull.mxp1.MXParser.fillBuf(MXParser.java:2947)

at org.xmlpull.mxp1.MXParser.more(MXParser.java:2996)

at org.xmlpull.mxp1.MXParser.nextImpl(MXParser.java:1143)

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

at org.jivesoftware.smack.PacketReader.parsePackets(PacketReader.java:377)

at org.jivesoftware.smack.PacketReader.access$000(PacketReader.java:43)

at org.jivesoftware.smack.PacketReader$1.run(PacketReader.java:63)

Zvonimir · April 11, 2006, 6:31am

Yesterday I downloaded latest nightly and after that everything is just fine.

And erhm, when I was starting 2.6.0 each time it reported that it has found old database and converted it. Even when I restarted server it said that there is old database.

With latest build it reported it only first time, and now it all seems to be in order.

Zvonimir · May 9, 2006, 7:58am

Ok, I tought I fixed the problem but it arised again after a day or two. Well, I just tought it’'s impossible to fix it. The best part comes here:

SERVER1 (jabber) > WIRELESS > SERVER2 > LAN > IPCOP > INTERNET > CLIENTS

This is how I acces jabber server from internet, so the problem was in wireless connection between servers. I have REPLACED SERVER2 with other WIFI solution and finally the problem is gone for real. So I think the problem was becouse WIRELESS had some problems or was disconnecting here and there. Is it possible to make time out for messages higer, you know what I mean ? To try more times to send the message?

I hope this will help somebody else with the same problem!