2016-09-23 17:03:56,000 [myid:1] - INFO [SessionTracker:ZooKeeperServer@355] - Expiring session 0x3549d6bb9e70009, timeout of 10000ms exceeded 2016-09-23 17:03:56,000 [myid:1] - INFO [SessionTracker:ZooKeeperServer@355] - Expiring session 0x3549d6bb9e7000e, timeout of 10000ms exceeded 2016-09-23 17:03:56,000 [myid:1] - INFO [ProcessThread(sid:1 cport:-1)::PrepRequestProcessor@489] - Processed session termination for sessionid: 0x3549d6bb9e70009 2016-09-23 17:03:56,001 [myid:1] - INFO [ProcessThread(sid:1 cport:-1)::PrepRequestProcessor@489] - Processed session termination for sessionid: 0x3549d6bb9e7000e 2016-09-23 17:03:56,419 [myid:1] - ERROR [LearnerHandler-/172.31.20.188:52094:LearnerHandler@631] - Unexpected exception causing shutdown while sock still open java.net.SocketTimeoutException: Read timed out at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.socketRead(SocketInputStream.java:116) at java.net.SocketInputStream.read(SocketInputStream.java:170) at java.net.SocketInputStream.read(SocketInputStream.java:141) at java.io.BufferedInputStream.fill(BufferedInputStream.java:246) at java.io.BufferedInputStream.read(BufferedInputStream.java:265) at java.io.DataInputStream.readInt(DataInputStream.java:387) at org.apache.jute.BinaryInputArchive.readInt(BinaryInputArchive.java:63) at org.apache.zookeeper.server.quorum.QuorumPacket.deserialize(QuorumPacket.java:83) at org.apache.jute.BinaryInputArchive.readRecord(BinaryInputArchive.java:99) at org.apache.zookeeper.server.quorum.LearnerHandler.run(LearnerHandler.java:542) 2016-09-23 17:03:56,420 [myid:1] - WARN [LearnerHandler-/172.31.20.188:52094:LearnerHandler@644] - ******* GOODBYE /172.31.20.188:52094 ******** 2016-09-23 17:03:56,423 [myid:1] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@192] - Accepted socket connection from /172.31.7.244:42325 2016-09-23 17:03:56,423 [myid:1] - WARN [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@854] - Connection request from old client /172.31.7.244:42325; will be dropped if server is in r-o mode 2016-09-23 17:03:56,423 [myid:1] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@893] - Client attempting to renew session 0x3549d6bb9e7000e at /172.31.7.244:42325 2016-09-23 17:03:56,423 [myid:1] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@638] - Invalid session 0x3549d6bb9e7000e for client /172.31.7.244:42325, probably expired 2016-09-23 17:03:56,425 [myid:1] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1008] - Closed socket connection for client /172.31.7.244:42325 which had sessionid 0x3549d6bb9e7000e 2016-09-23 17:03:56,793 [myid:1] - INFO [WorkerReceiver[myid=1]:FastLeaderElection@600] - Notification: 1 (message format version), 3 (n.leader), 0x100008482 (n.zxid), 0x2 (n.round), LOOKING (n.state), 3 (n.sid), 0x1 (n.peerEpoch) LEADING (my state) 2016-09-23 17:03:56,995 [myid:1] - INFO [WorkerReceiver[myid=1]:FastLeaderElection@600] - Notification: 1 (message format version), 3 (n.leader), 0x100008482 (n.zxid), 0x2 (n.round), LOOKING (n.state), 3 (n.sid), 0x1 (n.peerEpoch) LEADING (my state) 2016-09-23 17:19:27,404 [myid:1] - WARN [RecvWorker:3:QuorumCnxManager$RecvWorker@810] - Connection broken for id 3, my id = 1, error = java.net.SocketException: 接続がタイムアウトしました at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.socketRead(SocketInputStream.java:116) at java.net.SocketInputStream.read(SocketInputStream.java:170) at java.net.SocketInputStream.read(SocketInputStream.java:141) at java.net.SocketInputStream.read(SocketInputStream.java:223) at java.io.DataInputStream.readInt(DataInputStream.java:387) at org.apache.zookeeper.server.quorum.QuorumCnxManager$RecvWorker.run(QuorumCnxManager.java:795) 2016-09-23 17:19:27,404 [myid:1] - WARN [RecvWorker:3:QuorumCnxManager$RecvWorker@813] - Interrupting SendWorker 2016-09-23 17:19:27,404 [myid:1] - WARN [SendWorker:3:QuorumCnxManager$SendWorker@727] - Interrupted while waiting for message on queue java.lang.InterruptedException at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.reportInterruptAfterWait(AbstractQueuedSynchronizer.java:2014) at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2088) at java.util.concurrent.ArrayBlockingQueue.poll(ArrayBlockingQueue.java:418) at org.apache.zookeeper.server.quorum.QuorumCnxManager.pollSendQueue(QuorumCnxManager.java:879) at org.apache.zookeeper.server.quorum.QuorumCnxManager.access$500(QuorumCnxManager.java:65) at org.apache.zookeeper.server.quorum.QuorumCnxManager$SendWorker.run(QuorumCnxManager.java:715) 2016-09-23 17:19:27,405 [myid:1] - WARN [SendWorker:3:QuorumCnxManager$SendWorker@736] - Send worker leaving thread 2016-09-23 17:19:39,016 [myid:1] - INFO [/172.31.7.71:3888:QuorumCnxManager$Listener@541] - Received connection request /172.31.20.188:40024 2016-09-23 17:19:39,018 [myid:1] - INFO [WorkerReceiver[myid=1]:FastLeaderElection@600] - Notification: 1 (message format version), 3 (n.leader), 0x100008482 (n.zxid), 0x2 (n.round), LOOKING (n.state), 3 (n.sid), 0x1 (n.peerEpoch) LEADING (my state) 2016-09-23 17:19:39,026 [myid:1] - INFO [LearnerHandler-/172.31.20.188:52097:LearnerHandler@329] - Follower sid: 3 : info : org.apache.zookeeper.server.quorum.QuorumPeer$QuorumServer@6219cf57 2016-09-23 17:19:39,029 [myid:1] - INFO [LearnerHandler-/172.31.20.188:52097:LearnerHandler@384] - Synchronizing with Follower sid: 3 maxCommittedLog=0x100008484 minCommittedLog=0x100008290 peerLastZxid=0x100008482 2016-09-23 17:19:39,029 [myid:1] - INFO [LearnerHandler-/172.31.20.188:52097:LearnerHandler@458] - Sending DIFF 2016-09-23 17:19:39,036 [myid:1] - INFO [LearnerHandler-/172.31.20.188:52097:LearnerHandler@518] - Received NEWLEADER-ACK message from 3