[ 
https://issues.apache.org/jira/browse/ZOOKEEPER-2616?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=15584289#comment-15584289
 ] 

Ryan Zhang commented on ZOOKEEPER-2616:
---------------------------------------

Can you add a lot more logs in the processing path on the server side instead 
then? There is just not much logs to show anything on the server side to show 
when (or whether) does it get the request.

> ZK client fails to connect to ReadOnly server
> ---------------------------------------------
>
>                 Key: ZOOKEEPER-2616
>                 URL: https://issues.apache.org/jira/browse/ZOOKEEPER-2616
>             Project: ZooKeeper
>          Issue Type: Bug
>    Affects Versions: 3.5.2
>            Reporter: Benjamin Jaton
>            Priority: Critical
>         Attachments: test2_client.log, test2_server.log, 
> zookeeper-Administrator-server-QA-E8WIN11.log
>
>
> Only 1 of the 3 nodes of the ensemble is started. The server successfully 
> started in readonly ("Read-only server started").
> {code:title=client}System.setProperty("readonlymode.enabled", "true");
> String cs = "QA-E8WIN11:2181,QA-E8WIN12:2181,QA-E8WIN13:2181";
> ZooKeeper zk = new ZooKeeper(cs, 30000, null, true);
>               
> // wait for connection
> while (!zk.getState().isConnected()) {
>       Thread.sleep(1000);
>       logger.error(zk.getState());
> }
> zk.getData("/", false, new Stat());
> logger.error("DONE");{code}
> The client code above manages to acquire a connection ("CONNECTEDREADONLY") 
> but the subsequent getData fails with ConnectionLoss:
> {code:title=client log}2016-10-17 14:37:43 ERROR TestCuratorReadOnly:31 - 
> CONNECTEDREADONLY
> 2016-10-17 14:39:49 ERROR o.a.z.ClientCnxn:526 - Error while calling watcher 
> java.lang.NullPointerException
>       at 
> org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:524) 
> [zookeeper-3.5.2-alpha.jar:3.5.2-alpha--1]
>       at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:499) 
> [zookeeper-3.5.2-alpha.jar:3.5.2-alpha--1]
> Exception in thread "main" 
> org.apache.zookeeper.KeeperException$ConnectionLossException: KeeperErrorCode 
> = ConnectionLoss for /
>       at org.apache.zookeeper.KeeperException.create(KeeperException.java:99)
>       at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
>       at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1956)
>       at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1985)
>       at TestCuratorReadOnly.main(TestCuratorReadOnly.java:33){code}
> Full server logs are attached, but here are the relevant parts:
> {code:title=server log}
> 2016-10-17 14:37:31,375 [myid:1] - INFO  
> [Thread-2:ReadOnlyZooKeeperServer@73] - Read-only server started
> (...)
> 2016-10-17 14:37:55,241 [myid:1] - INFO  
> [NIOServerCxnFactory.AcceptThread:/0.0.0.0:2181:NIOServerCnxnFactory$AcceptThread@296]
>  - Accepted socket connection from /10.11.12.4:40800
> 2016-10-17 14:37:55,250 [myid:1] - INFO  
> [NIOWorkerThread-1:ZooKeeperServer@964] - Client attempting to establish new 
> session at /10.11.12.4:40800
> 2016-10-17 14:37:55,255 [myid:1] - INFO  [ProcessThread(sid:1 
> cport:-1)::ZooKeeperServer@678] - Established session 0x100024619520000 with 
> negotiated timeout 30000 for client /10.11.12.4:40800
> (...)
>  
> [org.apache.ZooKeeperService:name0=ReplicatedServer_id1,name1=replica.1,name2=ReadOnlyServer,name3=Connections,name4=10.11.12.4,name5=0x100024619520000]
> 2016-10-17 14:38:26,929 [myid:1] - INFO  [ProcessThread(sid:1 
> cport:-1)::NIOServerCnxn@607] - Closed socket connection for client 
> /10.11.12.4:40800 which had sessionid 0x100024619520000{code}
> The client and server are using official 3.5.2-alpha.
> {code:title=zoo.cfg}autopurge.purgeInterval=3
> initLimit=10
> syncLimit=5
> autopurge.snapRetainCount=3
> snapCount=10000
> minSessionTimeout=5000
> maxSessionTimeout=600000
> tickTime=2000
> admin.commandURL=/commands
> quorumListenOnAllIPs=true
> dataDir=C:/workspace/zookeeper-3.5.2-alpha/data
> admin.serverPort=8080
> admin.enableServer=false
> standaloneEnabled=false
> dynamicConfigFile=C:/workspace/zookeeper-3.5.2-alpha/conf/zoo.cfg.dynamic.10000046b{code}
> {code:title=zoo.cfg.dynamic.10000046b}server.1=QA-E8WIN11:2888:3888:participant;0.0.0.0:2181
> server.2=QA-E8WIN12:2888:3888:participant;0.0.0.0:2181
> server.3=QA-E8WIN13:2888:3888:participant;0.0.0.0:2181{code}



--
This message was sent by Atlassian JIRA
(v6.3.4#6332)

Reply via email to