ZooKeeper JMX enabled by default Using config: /conf/zoo.cfg 2017-10-24 12:08:19,596 [myid:] - INFO [main:QuorumPeerConfig@124] - Reading configuration from: /conf/zoo.cfg 2017-10-24 12:08:19,611 [myid:] - INFO [main:QuorumPeer$QuorumServer@149] - Resolved hostname: zookeeper2 to address: zookeeper2/172.17.0.2 2017-10-24 12:08:19,611 [myid:] - INFO [main:QuorumPeer$QuorumServer@149] - Resolved hostname: zookeeper1 to address: zookeeper1/xx.xxx.x.xx2 2017-10-24 12:08:19,612 [myid:] - INFO [main:QuorumPeer$QuorumServer@149] - Resolved hostname: zookeeper0 to address: zookeeper0/xx.xxx.x.xx3 2017-10-24 12:08:19,612 [myid:] - INFO [main:QuorumPeerConfig@352] - Defaulting to majority quorums 2017-10-24 12:08:19,615 [myid:3] - INFO [main:DatadirCleanupManager@78] - autopurge.snapRetainCount set to 3 2017-10-24 12:08:19,615 [myid:3] - INFO [main:DatadirCleanupManager@79] - autopurge.purgeInterval set to 0 2017-10-24 12:08:19,615 [myid:3] - INFO [main:DatadirCleanupManager@101] - Purge task is not scheduled. 2017-10-24 12:08:19,624 [myid:3] - INFO [main:QuorumPeerMain@127] - Starting quorum peer 2017-10-24 12:08:19,635 [myid:3] - INFO [main:NIOServerCnxnFactory@89] - binding to port 0.0.0.0/0.0.0.0:2181 2017-10-24 12:08:19,641 [myid:3] - INFO [main:QuorumPeer@1019] - tickTime set to 2000 2017-10-24 12:08:19,641 [myid:3] - INFO [main:QuorumPeer@1039] - minSessionTimeout set to -1 2017-10-24 12:08:19,641 [myid:3] - INFO [main:QuorumPeer@1050] - maxSessionTimeout set to -1 2017-10-24 12:08:19,642 [myid:3] - INFO [main:QuorumPeer@1065] - initLimit set to 5 2017-10-24 12:08:19,653 [myid:3] - INFO [main:QuorumPeer@533] - currentEpoch not found! Creating with a reasonable default of 0. This should only happen when you are upgrading your installation 2017-10-24 12:08:19,662 [myid:3] - INFO [main:QuorumPeer@548] - acceptedEpoch not found! Creating with a reasonable default of 0. This should only happen when you are upgrading your installation 2017-10-24 12:08:19,672 [myid:3] - INFO [ListenerThread:QuorumCnxManager$Listener@534] - My election bind port: zookeeper2/172.17.0.2:3888 2017-10-24 12:08:19,679 [myid:3] - INFO [QuorumPeer[myid=3]/0.0.0.0:2181:QuorumPeer@774] - LOOKING 2017-10-24 12:08:19,680 [myid:3] - INFO [QuorumPeer[myid=3]/0.0.0.0:2181:FastLeaderElection@818] - New election. My id = 3, proposed zxid=0x0 2017-10-24 12:08:19,689 [myid:3] - INFO [WorkerReceiver[myid=3]:FastLeaderElection@600] - Notification: 1 (message format version), 3 (n.leader), 0x0 (n.zxid), 0x1 (n.round), LOOKING (n.state), 3 (n.sid), 0x0 (n.peerEpoch) LOOKING (my state) 2017-10-24 12:08:19,689 [myid:3] - INFO [WorkerReceiver[myid=3]:FastLeaderElection@600] - Notification: 1 (message format version), 2 (n.leader), 0x0 (n.zxid), 0x1 (n.round), LOOKING (n.state), 2 (n.sid), 0x0 (n.peerEpoch) LOOKING (my state) 2017-10-24 12:08:19,690 [myid:3] - INFO [WorkerReceiver[myid=3]:FastLeaderElection@600] - Notification: 1 (message format version), 3 (n.leader), 0x0 (n.zxid), 0x1 (n.round), LOOKING (n.state), 2 (n.sid), 0x0 (n.peerEpoch) LOOKING (my state) 2017-10-24 12:08:19,733 [myid:3] - INFO [WorkerReceiver[myid=3]:FastLeaderElection@600] - Notification: 1 (message format version), 1 (n.leader), 0x0 (n.zxid), 0x1 (n.round), LOOKING (n.state), 1 (n.sid), 0x0 (n.peerEpoch) LOOKING (my state) 2017-10-24 12:08:19,734 [myid:3] - INFO [WorkerReceiver[myid=3]:FastLeaderElection@600] - Notification: 1 (message format version), 3 (n.leader), 0x0 (n.zxid), 0x1 (n.round), LOOKING (n.state), 1 (n.sid), 0x0 (n.peerEpoch) LOOKING (my state) 2017-10-24 12:08:19,935 [myid:3] - INFO [QuorumPeer[myid=3]/0.0.0.0:2181:QuorumPeer@856] - LEADING 2017-10-24 12:08:19,938 [myid:3] - INFO [QuorumPeer[myid=3]/0.0.0.0:2181:Leader@59] - TCP NoDelay set to: true 2017-10-24 12:08:19,945 [myid:3] - INFO [QuorumPeer[myid=3]/0.0.0.0:2181:Environment@100] - Server environment:zookeeper.version=3.4.9-1757313, built on 08/23/2016 06:50 GMT 2017-10-24 12:08:19,945 [myid:3] - INFO [QuorumPeer[myid=3]/0.0.0.0:2181:Environment@100] - Server environment:host.name=zookeeper2 2017-10-24 12:08:19,945 [myid:3] - INFO [QuorumPeer[myid=3]/0.0.0.0:2181:Environment@100] - Server environment:java.version=1.8.0_121 2017-10-24 12:08:19,946 [myid:3] - INFO [QuorumPeer[myid=3]/0.0.0.0:2181:Environment@100] - Server environment:java.vendor=Oracle Corporation 2017-10-24 12:08:19,946 [myid:3] - INFO [QuorumPeer[myid=3]/0.0.0.0:2181:Environment@100] - Server environment:java.home=/usr/lib/jvm/java-8-openjdk-amd64/jre 2017-10-24 12:08:19,946 [myid:3] - INFO [QuorumPeer[myid=3]/0.0.0.0:2181:Environment@100] - Server environment:java.class.path=/zookeeper-3.4.9/bin/../build/classes:/zookeeper-3.4.9/bin/../build/lib/*.jar:/zookeeper-3.4.9/bin/../lib/slf4j-log4j12-1.6.1.jar:/zookeeper-3.4.9/bin/../lib/slf4j-api-1.6.1.jar:/zookeeper-3.4.9/bin/../lib/netty-3.10.5.Final.jar:/zookeeper-3.4.9/bin/../lib/log4j-1.2.16.jar:/zookeeper-3.4.9/bin/../lib/jline-0.9.94.jar:/zookeeper-3.4.9/bin/../zookeeper-3.4.9.jar:/zookeeper-3.4.9/bin/../src/java/lib/*.jar:/conf: 2017-10-24 12:08:19,946 [myid:3] - INFO [QuorumPeer[myid=3]/0.0.0.0:2181:Environment@100] - Server environment:java.library.path=/usr/java/packages/lib/amd64:/usr/lib/x86_64-linux-gnu/jni:/lib/x86_64-linux-gnu:/usr/lib/x86_64-linux-gnu:/usr/lib/jni:/lib:/usr/lib 2017-10-24 12:08:19,947 [myid:3] - INFO [QuorumPeer[myid=3]/0.0.0.0:2181:Environment@100] - Server environment:java.io.tmpdir=/tmp 2017-10-24 12:08:19,947 [myid:3] - INFO [QuorumPeer[myid=3]/0.0.0.0:2181:Environment@100] - Server environment:java.compiler= 2017-10-24 12:08:19,947 [myid:3] - INFO [QuorumPeer[myid=3]/0.0.0.0:2181:Environment@100] - Server environment:os.name=Linux 2017-10-24 12:08:19,947 [myid:3] - INFO [QuorumPeer[myid=3]/0.0.0.0:2181:Environment@100] - Server environment:os.arch=amd64 2017-10-24 12:08:19,948 [myid:3] - INFO [QuorumPeer[myid=3]/0.0.0.0:2181:Environment@100] - Server environment:os.version=4.4.0-92-generic 2017-10-24 12:08:19,948 [myid:3] - INFO [QuorumPeer[myid=3]/0.0.0.0:2181:Environment@100] - Server environment:user.name=zookeeper 2017-10-24 12:08:19,948 [myid:3] - INFO [QuorumPeer[myid=3]/0.0.0.0:2181:Environment@100] - Server environment:user.home=/home/zookeeper 2017-10-24 12:08:19,948 [myid:3] - INFO [QuorumPeer[myid=3]/0.0.0.0:2181:Environment@100] - Server environment:user.dir=/zookeeper-3.4.9 2017-10-24 12:08:19,950 [myid:3] - INFO [QuorumPeer[myid=3]/0.0.0.0:2181:ZooKeeperServer@173] - Created server with tickTime 2000 minSessionTimeout 4000 maxSessionTimeout 40000 datadir /datalog/version-2 snapdir /data/version-2 2017-10-24 12:08:19,951 [myid:3] - INFO [QuorumPeer[myid=3]/0.0.0.0:2181:Leader@361] - LEADING - LEADER ELECTION TOOK - 271 2017-10-24 12:08:19,961 [myid:3] - INFO [LearnerHandler-/xx.xxx.x.xx2:53762:LearnerHandler@329] - Follower sid: 2 : info : org.apache.zookeeper.server.quorum.QuorumPeer$QuorumServer@60477e01 2017-10-24 12:08:19,964 [myid:3] - INFO [LearnerHandler-/xx.xxx.x.xx3:53374:LearnerHandler@329] - Follower sid: 1 : info : org.apache.zookeeper.server.quorum.QuorumPeer$QuorumServer@7836d3f7 2017-10-24 12:08:19,976 [myid:3] - INFO [LearnerHandler-/xx.xxx.x.xx2:53762:LearnerHandler@384] - Synchronizing with Follower sid: 2 maxCommittedLog=0x0 minCommittedLog=0x0 peerLastZxid=0x0 2017-10-24 12:08:19,976 [myid:3] - INFO [LearnerHandler-/xx.xxx.x.xx2:53762:LearnerHandler@393] - leader and follower are in sync, zxid=0x0 2017-10-24 12:08:19,976 [myid:3] - INFO [LearnerHandler-/xx.xxx.x.xx2:53762:LearnerHandler@458] - Sending DIFF 2017-10-24 12:08:19,977 [myid:3] - INFO [LearnerHandler-/xx.xxx.x.xx3:53374:LearnerHandler@384] - Synchronizing with Follower sid: 1 maxCommittedLog=0x0 minCommittedLog=0x0 peerLastZxid=0x0 2017-10-24 12:08:19,977 [myid:3] - INFO [LearnerHandler-/xx.xxx.x.xx3:53374:LearnerHandler@393] - leader and follower are in sync, zxid=0x0 2017-10-24 12:08:19,977 [myid:3] - INFO [LearnerHandler-/xx.xxx.x.xx3:53374:LearnerHandler@458] - Sending DIFF 2017-10-24 12:08:19,988 [myid:3] - INFO [LearnerHandler-/xx.xxx.x.xx2:53762:LearnerHandler@518] - Received NEWLEADER-ACK message from 2 2017-10-24 12:08:19,988 [myid:3] - INFO [QuorumPeer[myid=3]/0.0.0.0:2181:Leader@952] - Have quorum of supporters, sids: [ 2,3 ]; starting up and setting last processed zxid: 0x100000000 2017-10-24 12:08:19,991 [myid:3] - INFO [LearnerHandler-/xx.xxx.x.xx3:53374:LearnerHandler@518] - Received NEWLEADER-ACK message from 1 2017-10-24 12:08:20,577 [myid:3] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@192] - Accepted socket connection from /xx.xxx.x.xx3:37752 2017-10-24 12:08:20,583 [myid:3] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@928] - Client attempting to establish new session at /xx.xxx.x.xx3:37752 2017-10-24 12:08:20,585 [myid:3] - INFO [SyncThread:3:FileTxnLog@203] - Creating new log file: log.100000001 2017-10-24 12:08:20,603 [myid:3] - INFO [CommitProcessor:3:ZooKeeperServer@673] - Established session 0x35f4e4893160000 with negotiated timeout 6000 for client /xx.xxx.x.xx3:37752 2017-10-24 12:08:20,643 [myid:3] - INFO [ProcessThread(sid:3 cport:-1)::PrepRequestProcessor@649] - Got user-level KeeperException when processing sessionid:0x35f4e4893160000 type:create cxid:0x5 zxid:0x100000003 txntype:-1 reqpath:n/a Error Path:/brokers Error:KeeperErrorCode = NoNode for /brokers 2017-10-24 12:08:20,687 [myid:3] - INFO [ProcessThread(sid:3 cport:-1)::PrepRequestProcessor@649] - Got user-level KeeperException when processing sessionid:0x35f4e4893160000 type:create cxid:0xb zxid:0x100000007 txntype:-1 reqpath:n/a Error Path:/config Error:KeeperErrorCode = NoNode for /config 2017-10-24 12:08:20,714 [myid:3] - INFO [ProcessThread(sid:3 cport:-1)::PrepRequestProcessor@649] - Got user-level KeeperException when processing sessionid:0x35f4e4893160000 type:create cxid:0x13 zxid:0x10000000c txntype:-1 reqpath:n/a Error Path:/admin Error:KeeperErrorCode = NoNode for /admin 2017-10-24 12:08:21,098 [myid:3] - INFO [ProcessThread(sid:3 cport:-1)::PrepRequestProcessor@649] - Got user-level KeeperException when processing sessionid:0x35f4e4893160000 type:setData cxid:0x21 zxid:0x100000012 txntype:-1 reqpath:n/a Error Path:/controller_epoch Error:KeeperErrorCode = NoNode for /controller_epoch 2017-10-24 12:08:21,160 [myid:3] - INFO [ProcessThread(sid:3 cport:-1)::PrepRequestProcessor@649] - Got user-level KeeperException when processing sessionid:0x35f4e4893160000 type:delete cxid:0x30 zxid:0x100000014 txntype:-1 reqpath:n/a Error Path:/admin/preferred_replica_election Error:KeeperErrorCode = NoNode for /admin/preferred_replica_election 2017-10-24 12:08:21,325 [myid:3] - INFO [ProcessThread(sid:3 cport:-1)::PrepRequestProcessor@649] - Got user-level KeeperException when processing sessionid:0x35f4e4893160000 type:create cxid:0x37 zxid:0x100000016 txntype:-1 reqpath:n/a Error Path:/brokers Error:KeeperErrorCode = NodeExists for /brokers 2017-10-24 12:08:21,325 [myid:3] - INFO [ProcessThread(sid:3 cport:-1)::PrepRequestProcessor@649] - Got user-level KeeperException when processing sessionid:0x35f4e4893160000 type:create cxid:0x38 zxid:0x100000017 txntype:-1 reqpath:n/a Error Path:/brokers/ids Error:KeeperErrorCode = NodeExists for /brokers/ids 2017-10-24 12:08:21,803 [myid:3] - INFO [ProcessThread(sid:3 cport:-1)::PrepRequestProcessor@649] - Got user-level KeeperException when processing sessionid:0x15f4e48930e0000 type:create cxid:0x13 zxid:0x100000019 txntype:-1 reqpath:n/a Error Path:/brokers Error:KeeperErrorCode = NodeExists for /brokers 2017-10-24 12:08:21,804 [myid:3] - INFO [ProcessThread(sid:3 cport:-1)::PrepRequestProcessor@649] - Got user-level KeeperException when processing sessionid:0x15f4e48930e0000 type:create cxid:0x14 zxid:0x10000001a txntype:-1 reqpath:n/a Error Path:/brokers/ids Error:KeeperErrorCode = NodeExists for /brokers/ids 2017-10-24 12:08:21,970 [myid:3] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@192] - Accepted socket connection from /xx.xxx.x.xx3:55744 2017-10-24 12:08:21,973 [myid:3] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@928] - Client attempting to establish new session at /xx.xxx.x.xx3:55744 2017-10-24 12:08:21,977 [myid:3] - INFO [CommitProcessor:3:ZooKeeperServer@673] - Established session 0x35f4e4893160001 with negotiated timeout 6000 for client /xx.xxx.x.xx3:55744 2017-10-24 12:08:22,301 [myid:3] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:NIOServerCnxnFactory@192] - Accepted socket connection from /xx.xxx.x.xx3:51306 2017-10-24 12:08:22,305 [myid:3] - INFO [NIOServerCxn.Factory:0.0.0.0/0.0.0.0:2181:ZooKeeperServer@928] - Client attempting to establish new session at /xx.xxx.x.xx3:51306 2017-10-24 12:08:22,309 [myid:3] - INFO [CommitProcessor:3:ZooKeeperServer@673] - Established session 0x35f4e4893160002 with negotiated timeout 6000 for client /xx.xxx.x.xx3:51306 2017-10-24 12:08:22,395 [myid:3] - INFO [ProcessThread(sid:3 cport:-1)::PrepRequestProcessor@649] - Got user-level KeeperException when processing sessionid:0x35f4e4893160001 type:create cxid:0x13 zxid:0x10000001e txntype:-1 reqpath:n/a Error Path:/brokers Error:KeeperErrorCode = NodeExists for /brokers 2017-10-24 12:08:22,396 [myid:3] - INFO [ProcessThread(sid:3 cport:-1)::PrepRequestProcessor@649] - Got user-level KeeperException when processing sessionid:0x35f4e4893160001 type:create cxid:0x14 zxid:0x10000001f txntype:-1 reqpath:n/a Error Path:/brokers/ids Error:KeeperErrorCode = NodeExists for /brokers/ids 2017-10-24 12:08:22,782 [myid:3] - INFO [ProcessThread(sid:3 cport:-1)::PrepRequestProcessor@649] - Got user-level KeeperException when processing sessionid:0x35f4e4893160002 type:create cxid:0x13 zxid:0x100000021 txntype:-1 reqpath:n/a Error Path:/brokers Error:KeeperErrorCode = NodeExists for /brokers 2017-10-24 12:08:22,783 [myid:3] - INFO [ProcessThread(sid:3 cport:-1)::PrepRequestProcessor@649] - Got user-level KeeperException when processing sessionid:0x35f4e4893160002 type:create cxid:0x14 zxid:0x100000022 txntype:-1 reqpath:n/a Error Path:/brokers/ids Error:KeeperErrorCode = NodeExists for /brokers/ids 2017-10-24 12:08:34,974 [myid:3] - INFO [ProcessThread(sid:3 cport:-1)::PrepRequestProcessor@649] - Got user-level KeeperException when processing sessionid:0x35f4e4893160000 type:setData cxid:0x52 zxid:0x100000024 txntype:-1 reqpath:n/a Error Path:/config/topics/testchainid Error:KeeperErrorCode = NoNode for /config/topics/testchainid 2017-10-24 12:08:34,979 [myid:3] - INFO [ProcessThread(sid:3 cport:-1)::PrepRequestProcessor@649] - Got user-level KeeperException when processing sessionid:0x35f4e4893160000 type:create cxid:0x53 zxid:0x100000025 txntype:-1 reqpath:n/a Error Path:/config/topics Error:KeeperErrorCode = NodeExists for /config/topics 2017-10-24 12:08:35,022 [myid:3] - INFO [ProcessThread(sid:3 cport:-1)::PrepRequestProcessor@649] - Got user-level KeeperException when processing sessionid:0x35f4e4893160000 type:create cxid:0x5d zxid:0x100000028 txntype:-1 reqpath:n/a Error Path:/brokers/topics/testchainid/partitions/0 Error:KeeperErrorCode = NoNode for /brokers/topics/testchainid/partitions/0 2017-10-24 12:08:35,026 [myid:3] - INFO [ProcessThread(sid:3 cport:-1)::PrepRequestProcessor@649] - Got user-level KeeperException when processing sessionid:0x35f4e4893160000 type:create cxid:0x5e zxid:0x100000029 txntype:-1 reqpath:n/a Error Path:/brokers/topics/testchainid/partitions Error:KeeperErrorCode = NoNode for /brokers/topics/testchainid/partitions