Mailing-List: contact user-help@zookeeper.apache.org; run by ezmlm
Precedence: bulk
Reply-To: user@zookeeper.apache.org
Received-SPF: pass (athena.apache.org: domain of calanchue@gmail.com
 designates 209.85.215.195 as permitted sender)
MIME-Version: 1.0
Date: Thu, 20 Jun 2013 10:21:10 +0900
Message-ID: 
 <CAMTA1zgR4wQXq9+2ZdMDU3KmWsJVS-2+CWGMnuQcuZA7MTrT+Q@mail.gmail.com>
Subject: session expired suddenly after just receive 'Unable to read data from
 server'
From: JinHwan Hwang <calanchue@gmail.com>
To: user@zookeeper.apache.org
Content-Type: multipart/alternative; boundary=001a1133d77ef46acf04df8bc211

--001a1133d77ef46acf04df8bc211
Content-Type: text/plain; charset=ISO-8859-1

The below is what i received at zookeeper client. Something i can't
understand is time between 'unable to read' and 'socket connection'. As you
see at below, that is just 1 second. That is too small for expiration.
negotiated time of the client is 6000ms and tick time is 2000ms. In my
opinion, it should be at least 4 or 6 seconds to get expiration from
getting 'unable to read'.

What i'm currently suspicious about is JVM setting. Before I set up
concurrent garbage collector on zookeeper server, there were really many
 connection loss. Can that weird connection loss come from not setting up
garbage collector on zk client JVM? There were no gc logging at the client,
and i can't reproduce this error.

thanks for reading.
______________________________________________________________________________________________
INFO ][01:00:00]-Unable to read additional data from server sessionid
0x13f3b16dcb70005, likely server has closed socket, closing socket
connection and attempting reconnect
[INFO ][01:00:01]-Opening socket connection to server
172.16.143.6/172.16.143.6:2181
[INFO ][01:00:01]-Socket connection established to
172.16.143.6/172.16.143.6:2181, initiating session
[INFO ][01:00:01]-Unable to reconnect to ZooKeeper service, session
0x13f3b16dcb70005 has expired, closing socket connection
[INFO ][01:00:01]-Initiating client connection, connectString=
172.16.143.6:2181,172.16.143.7:2181,172.16.143.8:2181 sessionTimeout=6000
watcher=com.gabia.cage.ZkSessionManager@7977b9b
[INFO ][01:00:01]-EventThread shut down
[INFO ][01:00:01]-Opening socket connection to server /172.16.143.6:2181
[INFO ][01:00:01]-Socket connection established to
172.16.143.6/172.16.143.6:2181, initiating session
[INFO ][01:00:01]-Session establishment complete on server
172.16.143.6/172.16.143.6:2181, sessionid = 0x13f40fb42210001, negotiated
timeout = 6000
___________________________________________________________________________________________

--001a1133d77ef46acf04df8bc211--