[
https://issues.apache.org/jira/browse/ZOOKEEPER-1330?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=16950692#comment-16950692
]
MachelCheng edited comment on ZOOKEEPER-1330 at 10/14/19 3:39 AM:
------------------------------------------------------------------
from the follower, the log as follows:
2019-10-12 23:06:45,112 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@215] - Accepted
socket connection from /192.168.101.1:58720
2019-10-12 23:06:45,112 [myid:1] - WARN
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@383] - Exception
causing close of session 0x0: ZooKeeperServer not running
2019-10-12 23:06:45,112 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1056] - Closed socket
connection for client /192.168.101.1:58720 (no session established for client)
2019-10-12 23:06:46,514 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@215] - Accepted
socket connection from /192.168.101.2:44684
2019-10-12 23:06:46,514 [myid:1] - WARN
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@383] - Exception
causing close of session 0x0: ZooKeeperServer not running
2019-10-12 23:06:46,514 [myid:1] INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1056] - Closed socket
connection for client /192.168.101.2:44684 (no session established for client)
2019-10-12 23:06:46,676 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@215] - Accepted
socket connection from /192.168.101.2:44690
2019-10-12 23:06:46,677 [myid:1] - WARN
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@383] - Exception
causing close of session 0x0: ZooKeeperServer not running
2019-10-12 23:06:46,677 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1056] - Closed socket
connection for client /192.168.101.2:44690 (no session established for client)
2019-10-12 23:06:49,135 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@215] - Accepted
socket connection from /192.168.101.1:58818
2019-10-12 23:06:49,135 [myid:1] - WARN
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@383] - Exception
causing close of session 0x0: ZooKeeperServer not running
2019-10-12 23:06:49,135 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1056] - Closed socket
connection for client /192.168.101.1:58818 (no session established for client)
2019-10-12 23:06:46,676 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@215] - Accepted
socket connection from /192.168.101.2:44690
2019-10-12 23:06:46,677 [myid:1] - WARN
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@383] - Exception
causing close of session 0x0: ZooKeeperServer not running
2019-10-12 23:06:46,677 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1056] - Closed socket
connection for client /192.168.101.2:44690 (no session established for client)
2019-10-12 23:06:49,135 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@215] - Accepted
socket connection from /192.168.101.1:58818
2019-10-12 23:06:49,135 [myid:1] - WARN
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@383] - Exception
causing close of session 0x0: ZooKeeperServer not running
2019-10-12 23:06:49,135 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1056] - Closed socket
connection for client /192.168.101.1:58818 (no session established for client)
2019-10-12 23:06:51,191 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@215] - Accepted
socket connection from /127.0.0.1:55068
2019-10-12 23:06:51,191 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@908] - Processing srvr
command from /127.0.0.1:55068
2019-10-12 23:06:51,192 [myid:1] - INFO [Thread-739:NIOServerCnxn@1056] -
Closed socket connection for client /127.0.0.1:55068 (no session established
for client)
2019-10-12 23:06:52,156 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@215] - Accepted
socket connection from /192.168.101.1:58880
2019-10-12 23:06:52,156 [myid:1] - WARN
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@383] - Exception
causing close of session 0x0: ZooKeeperServer not running
2019-10-12 23:06:52,156 [myid:1] -
INFO[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1056] - Closed
socket connection for client /192.168.101.1:58880 (no session established for
client)
2019-10-12 23:06:54,176 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@215] - Accepted
socket connection from /192.168.101.1:58906
2019-10-12 23:06:54,177 [myid:1] - WARN
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@383] - Exception
causing close of session 0x0: ZooKeeperServer not running
2019-10-12 23:06:54,177 [myid:1] -
INFO[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1056] - Closed
socket connection for client /192.168.101.1:58906 (no session established for
client)
2019-10-12 23:06:54,492 [myid:1] - INFO
[WorkerReceiver[myid=1]:FastLeaderElection@595]- Notification: 1 (message
format version), 3 (n.leader), 0x8e000036ef (n.zxid), 0x12 (n.round), LOOKING
(n.state), 3 (n.sid),0x8e (n.peerEpoch) LOOKING (my state)
2019-10-12 23:06:54,492 [myid:1] - INFO
[WorkerReceiver[myid=1]:FastLeaderElection@595]- Notification: 1 (message
format version), 3 (n.leader), 0x8e000036ef (n.zxid), 0x12 (n.round), LOOKING
(n.state), 1 (n.sid),0x8e (n.peerEpoch) LOOKING (my state)
2019-10-12 23:06:54,692 [myid:1] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:2181:QuorumPeer@979] - FOLLOWING
2019-10-12 23:06:54,692 [myid:1] -
INFO[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:2181:ZooKeeperServer@174] - Created
server with tickTime 2000 minSessionTimeout 4000 maxSessionTimeout 40000
datadir /opt/zookeeper/data/version-2 snapdir /opt/zookeeper/data/version-2
2019-10-12 23:06:54,692 [myid:1] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:2181:Follower@65] - FOLLOWING - LEADER
ELECTION TOOK - 374511
was (Author: machelcheng):
from the follower, the log as follows:
2019-10-12 23:06:45,112 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@215] - Accepted
socket connection from /192.168.101.1:587202019-10-12 23:06:45,112 [myid:1] -
WARN [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@383] - Exception
causing close of session 0x0: ZooKeeperServer not running2019-10-12
23:06:45,112 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1056] - Closed socket
connection for client /192.168.101.1:58720 (no session established for
client)2019-10-12 23:06:46,514 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@215] - Accepted
socket connection from /192.168.101.2:446842019-10-12 23:06:46,514 [myid:1] -
WARN [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@383] - Exception
causing close of session 0x0: ZooKeeperServer not running2019-10-12
23:06:46,514 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1056] - Closed socket
connection for client /192.168.101.2:44684 (no session established for
client)2019-10-12 23:06:46,676 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@215] - Accepted
socket connection from /192.168.101.2:446902019-10-12 23:06:46,677 [myid:1] -
WARN [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@383] - Exception
causing close of session 0x0: ZooKeeperServer not running2019-10-12
23:06:46,677 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1056] - Closed socket
connection for client /192.168.101.2:44690 (no session established for
client)2019-10-12 23:06:49,135 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@215] - Accepted
socket connection from /192.168.101.1:588182019-10-12 23:06:49,135 [myid:1] -
WARN [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@383] - Exception
causing close of session 0x0: ZooKeeperServer not running2019-10-12
23:06:49,135 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1056] - Closed socket
connection for client /192.168.101.1:58818 (no session established for client)
2019-10-12 23:06:46,676 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@215] - Accepted
socket connection from /192.168.101.2:446902019-10-12 23:06:46,677 [myid:1] -
WARN [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@383] - Exception
causing close of session 0x0: ZooKeeperServer not running2019-10-12
23:06:46,677 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1056] - Closed socket
connection for client /192.168.101.2:44690 (no session established for
client)2019-10-12 23:06:49,135 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@215] - Accepted
socket connection from /192.168.101.1:588182019-10-12 23:06:49,135 [myid:1] -
WARN [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@383] - Exception
causing close of session 0x0: ZooKeeperServer not running2019-10-12
23:06:49,135 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1056] - Closed socket
connection for client /192.168.101.1:58818 (no session established for
client)2019-10-12 23:06:51,191 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@215] - Accepted
socket connection from /127.0.0.1:550682019-10-12 23:06:51,191 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@908] - Processing srvr
command from /127.0.0.1:550682019-10-12 23:06:51,192 [myid:1] - INFO
[Thread-739:NIOServerCnxn@1056] - Closed socket connection for client
/127.0.0.1:55068 (no session established for client)2019-10-12 23:06:52,156
[myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@215] - Accepted
socket connection from /192.168.101.1:588802019-10-12 23:06:52,156 [myid:1] -
WARN [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@383] - Exception
causing close of session 0x0: ZooKeeperServer not running2019-10-12
23:06:52,156 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1056] - Closed socket
connection for client /192.168.101.1:58880 (no session established for
client)2019-10-12 23:06:54,176 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@215] - Accepted
socket connection from /192.168.101.1:589062019-10-12 23:06:54,177 [myid:1] -
WARN [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@383] - Exception
causing close of session 0x0: ZooKeeperServer not running2019-10-12
23:06:54,177 [myid:1] - INFO
[NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxn@1056] - Closed socket
connection for client /192.168.101.1:58906 (no session established for
client)2019-10-12 23:06:54,492 [myid:1] - INFO
[WorkerReceiver[myid=1]:FastLeaderElection@595] - Notification: 1 (message
format version), 3 (n.leader), 0x8e000036ef (n.zxid), 0x12 (n.round), LOOKING
(n.state), 3 (n.sid), 0x8e (n.peerEpoch) LOOKING (my state)2019-10-12
23:06:54,492 [myid:1] - INFO [WorkerReceiver[myid=1]:FastLeaderElection@595] -
Notification: 1 (message format version), 3 (n.leader), 0x8e000036ef (n.zxid),
0x12 (n.round), LOOKING (n.state), 1 (n.sid), 0x8e (n.peerEpoch) LOOKING (my
state)2019-10-12 23:06:54,692 [myid:1] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:2181:QuorumPeer@979] - FOLLOWING2019-10-12
23:06:54,692 [myid:1] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:2181:ZooKeeperServer@174] - Created server
with tickTime 2000 minSessionTimeout 4000 maxSessionTimeout 40000 datadir
/opt/zookeeper/data/version-2 snapdir /opt/zookeeper/data/version-22019-10-12
23:06:54,692 [myid:1] - INFO
[QuorumPeer[myid=1]/0:0:0:0:0:0:0:0:2181:Follower@65] - FOLLOWING - LEADER
ELECTION TOOK - 374511
> Zookeeper server not serving the client request even after completion of
> Leader election
> ----------------------------------------------------------------------------------------
>
> Key: ZOOKEEPER-1330
> URL: https://issues.apache.org/jira/browse/ZOOKEEPER-1330
> Project: ZooKeeper
> Issue Type: Bug
> Components: server
> Affects Versions: 3.4.0
> Environment: 3 zk quorum
> Reporter: amith
> Priority: Minor
> Fix For: 3.6.0, 3.5.7
>
>
> Have a cluster of 3 zookeepers
> 90 clients are connected to the server
> leader got killed and started
> the other 2 zookeeper started FLE and Leader was elected
> But its taking nearly 10 sec for this server to server requests and saying
> "ZooKeeperServer not running" message..?
> Why is this even after Leader election SERVER IS NOT RUNNING !!!!!!!!!!
> 2011-12-19 16:12:29,732 [myid:2] - WARN
> [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2182:NIOServerCnxn@354] - Exception
> causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not
> running
> 2011-12-19 16:12:29,733 [myid:2] - INFO
> [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2182:NIOServerCnxn@1000] - Closed
> socket connection for client /10.18.47.148:51965 (no session established for
> client)
> 2011-12-19 16:12:29,753 [myid:2] - INFO
> [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2182:QuorumPeer@747] - LEADING
> 2011-12-19 16:12:29,762 [myid:2] - INFO
> [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2182:Leader@58] - TCP NoDelay set to: true
> 2011-12-19 16:12:29,765 [myid:2] - INFO
> [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2182:ZooKeeperServer@168] - Created
> server with tickTime 2000 minSessionTimeout 4000 maxSessionTimeout 40000
> datadir ../dataDir/version-2 snapdir ../dataDir/version-2
> 2011-12-19 16:12:29,766 [myid:2] - INFO
> [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2182:Leader@294] - LEADING - LEADER
> ELECTION TOOK - 4663
> 2011-12-19 16:12:29,776 [myid:2] - INFO
> [QuorumPeer[myid=2]/0:0:0:0:0:0:0:0:2182:FileSnap@83] - Reading snapshot
> ../dataDir/version-2/snapshot.100013661
> 2011-12-19 16:12:29,831 [myid:2] - INFO
> [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2182:NIOServerCnxnFactory@213] -
> Accepted socket connection from /10.18.47.148:51982
> 2011-12-19 16:12:29,831 [myid:2] - WARN
> [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2182:NIOServerCnxn@354] - Exception
> causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not
> running
> 2011-12-19 16:12:29,832 [myid:2] - INFO
> [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2182:NIOServerCnxn@1000] - Closed
> socket connection for client /10.18.47.148:51982 (no session established for
> client)
> 2011-12-19 16:12:29,884 [myid:2] - INFO
> [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2182:NIOServerCnxnFactory@213] -
> Accepted socket connection from /10.18.47.148:51989
> 2011-12-19 16:12:29,884 [myid:2] - WARN
> [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2182:NIOServerCnxn@354] - Exception
> causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not
> running
--
This message was sent by Atlassian Jira
(v8.3.4#803005)
