You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@zookeeper.apache.org by "Yaohui Wu (JIRA)" <ji...@apache.org> on 2017/01/10 11:20:59 UTC

[jira] [Created] (ZOOKEEPER-2661) It costs about 5055 ms to create Zookeeper object for the first time.

Yaohui Wu created ZOOKEEPER-2661:
------------------------------------

             Summary: It costs about 5055 ms to create Zookeeper object for the first time.
                 Key: ZOOKEEPER-2661
                 URL: https://issues.apache.org/jira/browse/ZOOKEEPER-2661
             Project: ZooKeeper
          Issue Type: Bug
          Components: java client
    Affects Versions: 3.4.6
         Environment: See the description below.
            Reporter: Yaohui Wu


I create and close ZooKeeper for 10 times.  It costs about 5055 ms for the first time.
My unit test:

package org.example.zk.curator;

import org.apache.zookeeper.WatchedEvent;
import org.apache.zookeeper.Watcher;
import org.apache.zookeeper.ZooKeeper;
import org.apache.zookeeper.ZooKeeperMain;
import org.junit.Test;
import org.slf4j.Logger;
import org.slf4j.LoggerFactory;

import java.io.IOException;

/**
 * Created by yaohui on 2017/1/10.
 */
public class ZookeeperTest {

    private static final Logger LOGGER = LoggerFactory.getLogger(ZookeeperTest.class);

    @Test
    public void testConnect() throws Exception {
        Log4jConfigLoader.loadDefault();
        String connectString = "192.168.9.19:2181";
        for (int i = 0; i < 10; i++) {
            ZooKeeper zk = null;
            try {
                zk = createZk(connectString);
            } finally {
                if (zk != null)
                    zk.close();
            }
        }
    }

    private ZooKeeper createZk(String connectionString) throws IOException {
        LOGGER.info("connecting");
        long start = System.currentTimeMillis();
        ZooKeeper zk = new ZooKeeper(connectionString, 20000, new Watcher() {
            public void process(WatchedEvent watchedEvent) {
                ZooKeeperMain.printMessage("WATCHER::");
                ZooKeeperMain.printMessage(watchedEvent.toString());
            }
        });
        LOGGER.info("cost {} ms", (System.currentTimeMillis() - start));
        return zk;
    }
}

The full log output:

/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/bin/java -ea -Didea.launcher.port=7538 "-Didea.launcher.bin.path=/Applications/IntelliJ IDEA CE.app/Contents/bin" -Didea.junit.sm_runner -Dfile.encoding=UTF-8 -classpath "/Applications/IntelliJ IDEA CE.app/Contents/lib/idea_rt.jar:/Applications/IntelliJ IDEA CE.app/Contents/plugins/junit/lib/junit-rt.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/charsets.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/deploy.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/ext/dnsns.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/ext/localedata.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/ext/sunec.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/ext/sunjce_provider.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/ext/sunpkcs11.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/ext/zipfs.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/htmlconverter.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/javaws.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/jce.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/jfr.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/jfxrt.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/jsse.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/management-agent.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/plugin.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/resources.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/rt.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/lib/ant-javafx.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/lib/dt.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/lib/javafx-doclet.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/lib/javafx-mx.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/lib/jconsole.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/lib/sa-jdi.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/lib/tools.jar:/Users/yaohui/code/github/java-examples/zk-curator/target/test-classes:/Users/yaohui/code/github/java-examples/zk-curator/target/classes:/Users/yaohui/.m2/repository/org/apache/curator/curator-framework/2.8.0/curator-framework-2.8.0.jar:/Users/yaohui/.m2/repository/org/apache/curator/curator-client/2.8.0/curator-client-2.8.0.jar:/Users/yaohui/.m2/repository/org/slf4j/slf4j-api/1.7.6/slf4j-api-1.7.6.jar:/Users/yaohui/.m2/repository/org/apache/zookeeper/zookeeper/3.4.6/zookeeper-3.4.6.jar:/Users/yaohui/.m2/repository/com/google/guava/guava/16.0.1/guava-16.0.1.jar:/Users/yaohui/.m2/repository/log4j/log4j/1.2.17/log4j-1.2.17.jar:/Users/yaohui/.m2/repository/jline/jline/0.9.94/jline-0.9.94.jar:/Users/yaohui/.m2/repository/io/netty/netty/3.7.0.Final/netty-3.7.0.Final.jar:/Users/yaohui/.m2/repository/org/slf4j/slf4j-log4j12/1.7.6/slf4j-log4j12-1.7.6.jar:/Users/yaohui/.m2/repository/junit/junit/4.11/junit-4.11.jar:/Users/yaohui/.m2/repository/org/hamcrest/hamcrest-core/1.3/hamcrest-core-1.3.jar:/Users/yaohui/.m2/repository/com/alibaba/dns-cache-manipulator/1.5.1/dns-cache-manipulator-1.5.1.jar" com.intellij.rt.execution.application.AppMain com.intellij.rt.execution.junit.JUnitStarter -ideVersion5 org.example.zk.curator.ZookeeperTest,testConnect
19:01:01.282 0    INFO   org.example.zk.curator.ZookeeperTest     - connecting
19:01:06.312 5030 INFO         org.apache.zookeeper.ZooKeeper     - Client environment:zookeeper.version=3.4.6-1569965, built on 02/20/2014 09:09 GMT
19:01:06.312 5030 INFO         org.apache.zookeeper.ZooKeeper     - Client environment:host.name=localhost
19:01:06.312 5030 INFO         org.apache.zookeeper.ZooKeeper     - Client environment:java.version=1.7.0_79
19:01:06.312 5030 INFO         org.apache.zookeeper.ZooKeeper     - Client environment:java.vendor=Oracle Corporation
19:01:06.312 5030 INFO         org.apache.zookeeper.ZooKeeper     - Client environment:java.home=/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre
19:01:06.312 5030 INFO         org.apache.zookeeper.ZooKeeper     - Client environment:java.class.path=/Applications/IntelliJ IDEA CE.app/Contents/lib/idea_rt.jar:/Applications/IntelliJ IDEA CE.app/Contents/plugins/junit/lib/junit-rt.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/charsets.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/deploy.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/ext/dnsns.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/ext/localedata.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/ext/sunec.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/ext/sunjce_provider.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/ext/sunpkcs11.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/ext/zipfs.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/htmlconverter.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/javaws.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/jce.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/jfr.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/jfxrt.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/jsse.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/management-agent.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/plugin.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/resources.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/jre/lib/rt.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/lib/ant-javafx.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/lib/dt.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/lib/javafx-doclet.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/lib/javafx-mx.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/lib/jconsole.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/lib/sa-jdi.jar:/Library/Java/JavaVirtualMachines/jdk1.7.0_79.jdk/Contents/Home/lib/tools.jar:/Users/yaohui/code/github/java-examples/zk-curator/target/test-classes:/Users/yaohui/code/github/java-examples/zk-curator/target/classes:/Users/yaohui/.m2/repository/org/apache/curator/curator-framework/2.8.0/curator-framework-2.8.0.jar:/Users/yaohui/.m2/repository/org/apache/curator/curator-client/2.8.0/curator-client-2.8.0.jar:/Users/yaohui/.m2/repository/org/slf4j/slf4j-api/1.7.6/slf4j-api-1.7.6.jar:/Users/yaohui/.m2/repository/org/apache/zookeeper/zookeeper/3.4.6/zookeeper-3.4.6.jar:/Users/yaohui/.m2/repository/com/google/guava/guava/16.0.1/guava-16.0.1.jar:/Users/yaohui/.m2/repository/log4j/log4j/1.2.17/log4j-1.2.17.jar:/Users/yaohui/.m2/repository/jline/jline/0.9.94/jline-0.9.94.jar:/Users/yaohui/.m2/repository/io/netty/netty/3.7.0.Final/netty-3.7.0.Final.jar:/Users/yaohui/.m2/repository/org/slf4j/slf4j-log4j12/1.7.6/slf4j-log4j12-1.7.6.jar:/Users/yaohui/.m2/repository/junit/junit/4.11/junit-4.11.jar:/Users/yaohui/.m2/repository/org/hamcrest/hamcrest-core/1.3/hamcrest-core-1.3.jar:/Users/yaohui/.m2/repository/com/alibaba/dns-cache-manipulator/1.5.1/dns-cache-manipulator-1.5.1.jar
19:01:06.313 5031 INFO         org.apache.zookeeper.ZooKeeper     - Client environment:java.library.path=/Users/yaohui/Library/Java/Extensions:/Library/Java/Extensions:/Network/Library/Java/Extensions:/System/Library/Java/Extensions:/usr/lib/java:.
19:01:06.313 5031 INFO         org.apache.zookeeper.ZooKeeper     - Client environment:java.io.tmpdir=/var/folders/hk/126xqknn1cdf49247dpndcc00000gn/T/
19:01:06.313 5031 INFO         org.apache.zookeeper.ZooKeeper     - Client environment:java.compiler=<NA>
19:01:06.313 5031 INFO         org.apache.zookeeper.ZooKeeper     - Client environment:os.name=Mac OS X
19:01:06.313 5031 INFO         org.apache.zookeeper.ZooKeeper     - Client environment:os.arch=x86_64
19:01:06.313 5031 INFO         org.apache.zookeeper.ZooKeeper     - Client environment:os.version=10.12.2
19:01:06.313 5031 INFO         org.apache.zookeeper.ZooKeeper     - Client environment:user.name=yaohui
19:01:06.314 5032 INFO         org.apache.zookeeper.ZooKeeper     - Client environment:user.home=/Users/yaohui
19:01:06.314 5032 INFO         org.apache.zookeeper.ZooKeeper     - Client environment:user.dir=/Users/yaohui/code/github/java-examples/zk-curator
19:01:06.316 5034 INFO         org.apache.zookeeper.ZooKeeper     - Initiating client connection, connectString=192.168.9.19:2181 sessionTimeout=20000 watcher=org.example.zk.curator.ZookeeperTest$1@195bd6b9
19:01:06.319 5037 DEBUG       org.apache.zookeeper.ClientCnxn     - zookeeper.disableAutoWatchReset is false
19:01:06.341 5059 INFO   org.example.zk.curator.ZookeeperTest     - cost 5055 ms
19:01:06.341 5059 DEBUG        org.apache.zookeeper.ZooKeeper     - Closing session: 0x0
19:01:06.341 5059 DEBUG       org.apache.zookeeper.ClientCnxn     - Closing client for session: 0x0
19:01:06.347 5065 INFO        org.apache.zookeeper.ClientCnxn     - Opening socket connection to server 192.168.9.19/192.168.9.19:2181. Will not attempt to authenticate using SASL (unknown error)
19:01:06.359 5077 INFO        org.apache.zookeeper.ClientCnxn     - Socket connection established to 192.168.9.19/192.168.9.19:2181, initiating session
19:01:06.362 5080 DEBUG       org.apache.zookeeper.ClientCnxn     - Session establishment request sent on 192.168.9.19/192.168.9.19:2181
19:01:06.370 5088 TRACE org.apache.zookeeper.ClientCnxnSocket     - readConnectResult 37 0x[0,0,0,0,0,0,27,10,1,59,49,71,ffffff83,a,0,2b,0,0,0,10,4d,ffffffbb,ffffffba,6e,ffffff80,16,28,ffffff8b,3e,70,ffffffb0,39,10,19,39,19,0,]
19:01:06.373 5091 INFO        org.apache.zookeeper.ClientCnxn     - Session establishment complete on server 192.168.9.19/192.168.9.19:2181, sessionid = 0x1594971830a002b, negotiated timeout = 10000
19:01:06.380 5098 DEBUG       org.apache.zookeeper.ClientCnxn     - Reading reply sessionid:0x1594971830a002b, packet:: clientPath:null serverPath:null finished:false header:: 1,-11  replyHeader:: 1,34359739742,0  request:: null response:: null
19:01:06.380 5098 DEBUG       org.apache.zookeeper.ClientCnxn     - Disconnecting client for session: 0x1594971830a002b
19:01:06.381 5099 INFO         org.apache.zookeeper.ZooKeeper     - Session: 0x1594971830a002b closed
19:01:06.381 5099 DEBUG       org.apache.zookeeper.ClientCnxn     - An exception was thrown while closing send thread for session 0x1594971830a002b : Unable to read additional data from server sessionid 0x1594971830a002b, likely server has closed socket
19:01:06.381 5099 INFO   org.example.zk.curator.ZookeeperTest     - connecting
19:01:06.381 5099 INFO         org.apache.zookeeper.ZooKeeper     - Initiating client connection, connectString=192.168.9.19:2181 sessionTimeout=20000 watcher=org.example.zk.curator.ZookeeperTest$1@25c6ca49
19:01:06.382 5100 INFO   org.example.zk.curator.ZookeeperTest     - cost 1 ms
19:01:06.382 5100 DEBUG        org.apache.zookeeper.ZooKeeper     - Closing session: 0x0
19:01:06.382 5100 DEBUG       org.apache.zookeeper.ClientCnxn     - Closing client for session: 0x0
19:01:06.382 5100 INFO        org.apache.zookeeper.ClientCnxn     - Opening socket connection to server 192.168.9.19/192.168.9.19:2181. Will not attempt to authenticate using SASL (unknown error)
19:01:06.383 5101 INFO        org.apache.zookeeper.ClientCnxn     - Socket connection established to 192.168.9.19/192.168.9.19:2181, initiating session
19:01:06.384 5102 DEBUG       org.apache.zookeeper.ClientCnxn     - Session establishment request sent on 192.168.9.19/192.168.9.19:2181

WATCHER::

WatchedEvent state:SyncConnected type:None path:null
19:01:06.385 5103 INFO        org.apache.zookeeper.ClientCnxn     - EventThread shut down
19:01:06.387 5105 TRACE org.apache.zookeeper.ClientCnxnSocket     - readConnectResult 37 0x[0,0,0,0,0,0,27,10,1,59,49,71,ffffff83,a,0,2c,0,0,0,10,ffffffac,60,ffffff9d,6e,ffffff9f,ffffff90,ffffff84,ffffffe3,8,ffffffbf,ffffffb4,12,32,ffffffc5,ffffff9f,1a,0,]
19:01:06.387 5105 INFO        org.apache.zookeeper.ClientCnxn     - Session establishment complete on server 192.168.9.19/192.168.9.19:2181, sessionid = 0x1594971830a002c, negotiated timeout = 10000

WATCHER::

WatchedEvent state:SyncConnected type:None path:null
19:01:06.390 5108 DEBUG       org.apache.zookeeper.ClientCnxn     - Reading reply sessionid:0x1594971830a002c, packet:: clientPath:null serverPath:null finished:false header:: 1,-11  replyHeader:: 1,34359739744,0  request:: null response:: null
19:01:06.390 5108 DEBUG       org.apache.zookeeper.ClientCnxn     - Disconnecting client for session: 0x1594971830a002c
19:01:06.390 5108 DEBUG       org.apache.zookeeper.ClientCnxn     - An exception was thrown while closing send thread for session 0x1594971830a002c : Unable to read additional data from server sessionid 0x1594971830a002c, likely server has closed socket
19:01:06.390 5108 INFO        org.apache.zookeeper.ClientCnxn     - EventThread shut down
19:01:06.390 5108 INFO         org.apache.zookeeper.ZooKeeper     - Session: 0x1594971830a002c closed
19:01:06.391 5109 INFO   org.example.zk.curator.ZookeeperTest     - connecting
19:01:06.391 5109 INFO         org.apache.zookeeper.ZooKeeper     - Initiating client connection, connectString=192.168.9.19:2181 sessionTimeout=20000 watcher=org.example.zk.curator.ZookeeperTest$1@27d16a7f
19:01:06.391 5109 INFO   org.example.zk.curator.ZookeeperTest     - cost 0 ms
19:01:06.391 5109 DEBUG        org.apache.zookeeper.ZooKeeper     - Closing session: 0x0
19:01:06.391 5109 DEBUG       org.apache.zookeeper.ClientCnxn     - Closing client for session: 0x0
19:01:06.391 5109 INFO        org.apache.zookeeper.ClientCnxn     - Opening socket connection to server 192.168.9.19/192.168.9.19:2181. Will not attempt to authenticate using SASL (unknown error)
19:01:06.392 5110 INFO        org.apache.zookeeper.ClientCnxn     - Socket connection established to 192.168.9.19/192.168.9.19:2181, initiating session
19:01:06.393 5111 DEBUG       org.apache.zookeeper.ClientCnxn     - Session establishment request sent on 192.168.9.19/192.168.9.19:2181
19:01:06.396 5114 TRACE org.apache.zookeeper.ClientCnxnSocket     - readConnectResult 37 0x[0,0,0,0,0,0,27,10,1,59,49,71,ffffff83,a,0,2d,0,0,0,10,ffffffbf,ffffff81,ffffff97,6e,3f,ffffffdc,63,28,fffffffe,1,1c,3e,5,4e,ffffffb4,ffffffe7,0,]
19:01:06.396 5114 INFO        org.apache.zookeeper.ClientCnxn     - Session establishment complete on server 192.168.9.19/192.168.9.19:2181, sessionid = 0x1594971830a002d, negotiated timeout = 10000

WATCHER::

WatchedEvent state:SyncConnected type:None path:null
19:01:06.398 5116 DEBUG       org.apache.zookeeper.ClientCnxn     - Reading reply sessionid:0x1594971830a002d, packet:: clientPath:null serverPath:null finished:false header:: 1,-11  replyHeader:: 1,34359739746,0  request:: null response:: null
19:01:06.398 5116 DEBUG       org.apache.zookeeper.ClientCnxn     - Disconnecting client for session: 0x1594971830a002d
19:01:06.399 5117 INFO        org.apache.zookeeper.ClientCnxn     - EventThread shut down
19:01:06.399 5117 DEBUG       org.apache.zookeeper.ClientCnxn     - An exception was thrown while closing send thread for session 0x1594971830a002d : Unable to read additional data from server sessionid 0x1594971830a002d, likely server has closed socket
19:01:06.399 5117 INFO         org.apache.zookeeper.ZooKeeper     - Session: 0x1594971830a002d closed
19:01:06.399 5117 INFO   org.example.zk.curator.ZookeeperTest     - connecting
19:01:06.399 5117 INFO         org.apache.zookeeper.ZooKeeper     - Initiating client connection, connectString=192.168.9.19:2181 sessionTimeout=20000 watcher=org.example.zk.curator.ZookeeperTest$1@c1d5a3
19:01:06.399 5117 INFO   org.example.zk.curator.ZookeeperTest     - cost 0 ms
19:01:06.400 5118 DEBUG        org.apache.zookeeper.ZooKeeper     - Closing session: 0x0
19:01:06.400 5118 DEBUG       org.apache.zookeeper.ClientCnxn     - Closing client for session: 0x0
19:01:06.400 5118 INFO        org.apache.zookeeper.ClientCnxn     - Opening socket connection to server 192.168.9.19/192.168.9.19:2181. Will not attempt to authenticate using SASL (unknown error)
19:01:06.401 5119 INFO        org.apache.zookeeper.ClientCnxn     - Socket connection established to 192.168.9.19/192.168.9.19:2181, initiating session
19:01:06.401 5119 DEBUG       org.apache.zookeeper.ClientCnxn     - Session establishment request sent on 192.168.9.19/192.168.9.19:2181
19:01:06.404 5122 TRACE org.apache.zookeeper.ClientCnxnSocket     - readConnectResult 37 0x[0,0,0,0,0,0,27,10,1,59,49,71,ffffff83,a,0,2e,0,0,0,10,ffffff86,1e,ffffffa9,6e,5f,fffffff9,ffffffc5,59,1e,39,ffffffe6,ffffffbb,ffffff8b,ffffffb3,76,ffffff80,0,]
19:01:06.404 5122 INFO        org.apache.zookeeper.ClientCnxn     - Session establishment complete on server 192.168.9.19/192.168.9.19:2181, sessionid = 0x1594971830a002e, negotiated timeout = 10000

WATCHER::

WatchedEvent state:SyncConnected type:None path:null
19:01:06.407 5125 DEBUG       org.apache.zookeeper.ClientCnxn     - Reading reply sessionid:0x1594971830a002e, packet:: clientPath:null serverPath:null finished:false header:: 1,-11  replyHeader:: 1,34359739748,0  request:: null response:: null
19:01:06.408 5126 DEBUG       org.apache.zookeeper.ClientCnxn     - Disconnecting client for session: 0x1594971830a002e
19:01:06.408 5126 DEBUG       org.apache.zookeeper.ClientCnxn     - An exception was thrown while closing send thread for session 0x1594971830a002e : Unable to read additional data from server sessionid 0x1594971830a002e, likely server has closed socket
19:01:06.408 5126 INFO         org.apache.zookeeper.ZooKeeper     - Session: 0x1594971830a002e closed
19:01:06.408 5126 INFO   org.example.zk.curator.ZookeeperTest     - connecting
19:01:06.408 5126 INFO        org.apache.zookeeper.ClientCnxn     - EventThread shut down
19:01:06.408 5126 INFO         org.apache.zookeeper.ZooKeeper     - Initiating client connection, connectString=192.168.9.19:2181 sessionTimeout=20000 watcher=org.example.zk.curator.ZookeeperTest$1@55308275
19:01:06.409 5127 INFO   org.example.zk.curator.ZookeeperTest     - cost 1 ms
19:01:06.409 5127 INFO        org.apache.zookeeper.ClientCnxn     - Opening socket connection to server 192.168.9.19/192.168.9.19:2181. Will not attempt to authenticate using SASL (unknown error)
19:01:06.409 5127 DEBUG        org.apache.zookeeper.ZooKeeper     - Closing session: 0x0
19:01:06.409 5127 DEBUG       org.apache.zookeeper.ClientCnxn     - Closing client for session: 0x0
19:01:06.410 5128 INFO        org.apache.zookeeper.ClientCnxn     - Socket connection established to 192.168.9.19/192.168.9.19:2181, initiating session
19:01:06.410 5128 DEBUG       org.apache.zookeeper.ClientCnxn     - Session establishment request sent on 192.168.9.19/192.168.9.19:2181
19:01:06.413 5131 TRACE org.apache.zookeeper.ClientCnxnSocket     - readConnectResult 37 0x[0,0,0,0,0,0,27,10,1,59,49,71,ffffff83,a,0,2f,0,0,0,10,ffffff99,3f,ffffffa3,6e,ffffffff,44,ffffffa5,ffffff9e,13,7c,4d,ffffffe7,5e,3c,ffffff8b,4d,0,]
19:01:06.413 5131 INFO        org.apache.zookeeper.ClientCnxn     - Session establishment complete on server 192.168.9.19/192.168.9.19:2181, sessionid = 0x1594971830a002f, negotiated timeout = 10000

WATCHER::

WatchedEvent state:SyncConnected type:None path:null
19:01:06.416 5134 DEBUG       org.apache.zookeeper.ClientCnxn     - Reading reply sessionid:0x1594971830a002f, packet:: clientPath:null serverPath:null finished:false header:: 1,-11  replyHeader:: 1,34359739750,0  request:: null response:: null
19:01:06.416 5134 DEBUG       org.apache.zookeeper.ClientCnxn     - Disconnecting client for session: 0x1594971830a002f
19:01:06.417 5135 DEBUG       org.apache.zookeeper.ClientCnxn     - An exception was thrown while closing send thread for session 0x1594971830a002f : Unable to read additional data from server sessionid 0x1594971830a002f, likely server has closed socket
19:01:06.417 5135 INFO        org.apache.zookeeper.ClientCnxn     - EventThread shut down
19:01:06.417 5135 INFO         org.apache.zookeeper.ZooKeeper     - Session: 0x1594971830a002f closed
19:01:06.417 5135 INFO   org.example.zk.curator.ZookeeperTest     - connecting
19:01:06.417 5135 INFO         org.apache.zookeeper.ZooKeeper     - Initiating client connection, connectString=192.168.9.19:2181 sessionTimeout=20000 watcher=org.example.zk.curator.ZookeeperTest$1@1cfeaf9c
19:01:06.418 5136 INFO   org.example.zk.curator.ZookeeperTest     - cost 1 ms
19:01:06.418 5136 DEBUG        org.apache.zookeeper.ZooKeeper     - Closing session: 0x0
19:01:06.418 5136 DEBUG       org.apache.zookeeper.ClientCnxn     - Closing client for session: 0x0
19:01:06.418 5136 INFO        org.apache.zookeeper.ClientCnxn     - Opening socket connection to server 192.168.9.19/192.168.9.19:2181. Will not attempt to authenticate using SASL (unknown error)
19:01:06.419 5137 INFO        org.apache.zookeeper.ClientCnxn     - Socket connection established to 192.168.9.19/192.168.9.19:2181, initiating session
19:01:06.419 5137 DEBUG       org.apache.zookeeper.ClientCnxn     - Session establishment request sent on 192.168.9.19/192.168.9.19:2181
19:01:06.422 5140 TRACE org.apache.zookeeper.ClientCnxnSocket     - readConnectResult 37 0x[0,0,0,0,0,0,27,10,1,59,49,71,ffffff83,a,0,30,0,0,0,10,ffffff95,ffffffc2,41,6f,20,4b,18,5b,36,6c,69,53,c,ffffffce,60,ffffffac,0,]
19:01:06.422 5140 INFO        org.apache.zookeeper.ClientCnxn     - Session establishment complete on server 192.168.9.19/192.168.9.19:2181, sessionid = 0x1594971830a0030, negotiated timeout = 10000

WATCHER::

WatchedEvent state:SyncConnected type:None path:null
19:01:06.425 5143 DEBUG       org.apache.zookeeper.ClientCnxn     - Reading reply sessionid:0x1594971830a0030, packet:: clientPath:null serverPath:null finished:false header:: 1,-11  replyHeader:: 1,34359739752,0  request:: null response:: null
19:01:06.426 5144 DEBUG       org.apache.zookeeper.ClientCnxn     - Disconnecting client for session: 0x1594971830a0030
19:01:06.426 5144 DEBUG       org.apache.zookeeper.ClientCnxn     - An exception was thrown while closing send thread for session 0x1594971830a0030 : Unable to read additional data from server sessionid 0x1594971830a0030, likely server has closed socket
19:01:06.426 5144 INFO        org.apache.zookeeper.ClientCnxn     - EventThread shut down
19:01:06.426 5144 INFO         org.apache.zookeeper.ZooKeeper     - Session: 0x1594971830a0030 closed
19:01:06.426 5144 INFO   org.example.zk.curator.ZookeeperTest     - connecting
19:01:06.426 5144 INFO         org.apache.zookeeper.ZooKeeper     - Initiating client connection, connectString=192.168.9.19:2181 sessionTimeout=20000 watcher=org.example.zk.curator.ZookeeperTest$1@6e038230
19:01:06.427 5145 INFO   org.example.zk.curator.ZookeeperTest     - cost 1 ms
19:01:06.427 5145 DEBUG        org.apache.zookeeper.ZooKeeper     - Closing session: 0x0
19:01:06.427 5145 INFO        org.apache.zookeeper.ClientCnxn     - Opening socket connection to server 192.168.9.19/192.168.9.19:2181. Will not attempt to authenticate using SASL (unknown error)
19:01:06.427 5145 DEBUG       org.apache.zookeeper.ClientCnxn     - Closing client for session: 0x0
19:01:06.428 5146 INFO        org.apache.zookeeper.ClientCnxn     - Socket connection established to 192.168.9.19/192.168.9.19:2181, initiating session
19:01:06.428 5146 DEBUG       org.apache.zookeeper.ClientCnxn     - Session establishment request sent on 192.168.9.19/192.168.9.19:2181
19:01:06.432 5150 TRACE org.apache.zookeeper.ClientCnxnSocket     - readConnectResult 37 0x[0,0,0,0,0,0,27,10,1,59,49,71,ffffff83,a,0,31,0,0,0,10,ffffffa9,ffffffe3,3b,6f,ffffffc0,ffffff96,fffffff7,ffffff9f,2b,ffffffaf,ffffffd0,7e,ffffffe0,56,75,79,0,]
19:01:06.432 5150 INFO        org.apache.zookeeper.ClientCnxn     - Session establishment complete on server 192.168.9.19/192.168.9.19:2181, sessionid = 0x1594971830a0031, negotiated timeout = 10000

WATCHER::

WatchedEvent state:SyncConnected type:None path:null
19:01:06.435 5153 DEBUG       org.apache.zookeeper.ClientCnxn     - Reading reply sessionid:0x1594971830a0031, packet:: clientPath:null serverPath:null finished:false header:: 1,-11  replyHeader:: 1,34359739754,0  request:: null response:: null
19:01:06.435 5153 DEBUG       org.apache.zookeeper.ClientCnxn     - Disconnecting client for session: 0x1594971830a0031
19:01:06.435 5153 DEBUG       org.apache.zookeeper.ClientCnxn     - An exception was thrown while closing send thread for session 0x1594971830a0031 : Unable to read additional data from server sessionid 0x1594971830a0031, likely server has closed socket
19:01:06.435 5153 INFO        org.apache.zookeeper.ClientCnxn     - EventThread shut down
19:01:06.435 5153 INFO         org.apache.zookeeper.ZooKeeper     - Session: 0x1594971830a0031 closed
19:01:06.436 5154 INFO   org.example.zk.curator.ZookeeperTest     - connecting
19:01:06.436 5154 INFO         org.apache.zookeeper.ZooKeeper     - Initiating client connection, connectString=192.168.9.19:2181 sessionTimeout=20000 watcher=org.example.zk.curator.ZookeeperTest$1@71b29017
19:01:06.437 5155 INFO   org.example.zk.curator.ZookeeperTest     - cost 0 ms
19:01:06.437 5155 DEBUG        org.apache.zookeeper.ZooKeeper     - Closing session: 0x0
19:01:06.437 5155 DEBUG       org.apache.zookeeper.ClientCnxn     - Closing client for session: 0x0
19:01:06.437 5155 INFO        org.apache.zookeeper.ClientCnxn     - Opening socket connection to server 192.168.9.19/192.168.9.19:2181. Will not attempt to authenticate using SASL (unknown error)
19:01:06.438 5156 INFO        org.apache.zookeeper.ClientCnxn     - Socket connection established to 192.168.9.19/192.168.9.19:2181, initiating session
19:01:06.438 5156 DEBUG       org.apache.zookeeper.ClientCnxn     - Session establishment request sent on 192.168.9.19/192.168.9.19:2181
19:01:06.441 5159 TRACE org.apache.zookeeper.ClientCnxnSocket     - readConnectResult 37 0x[0,0,0,0,0,0,27,10,1,59,49,71,ffffff83,a,0,32,0,0,0,10,6f,ffffff80,4d,6f,ffffffe1,ffffffb3,59,ffffffd1,4b,ffffffe6,ffffff9a,fffffffc,65,ffffffbc,37,12,0,]
19:01:06.441 5159 INFO        org.apache.zookeeper.ClientCnxn     - Session establishment complete on server 192.168.9.19/192.168.9.19:2181, sessionid = 0x1594971830a0032, negotiated timeout = 10000

WATCHER::

WatchedEvent state:SyncConnected type:None path:null
19:01:06.444 5162 DEBUG       org.apache.zookeeper.ClientCnxn     - Reading reply sessionid:0x1594971830a0032, packet:: clientPath:null serverPath:null finished:false header:: 1,-11  replyHeader:: 1,34359739756,0  request:: null response:: null
19:01:06.445 5163 DEBUG       org.apache.zookeeper.ClientCnxn     - Disconnecting client for session: 0x1594971830a0032
19:01:06.445 5163 DEBUG       org.apache.zookeeper.ClientCnxn     - An exception was thrown while closing send thread for session 0x1594971830a0032 : Unable to read additional data from server sessionid 0x1594971830a0032, likely server has closed socket
19:01:06.445 5163 INFO         org.apache.zookeeper.ZooKeeper     - Session: 0x1594971830a0032 closed
19:01:06.445 5163 INFO        org.apache.zookeeper.ClientCnxn     - EventThread shut down
19:01:06.445 5163 INFO   org.example.zk.curator.ZookeeperTest     - connecting
19:01:06.446 5164 INFO         org.apache.zookeeper.ZooKeeper     - Initiating client connection, connectString=192.168.9.19:2181 sessionTimeout=20000 watcher=org.example.zk.curator.ZookeeperTest$1@7c27a30c
19:01:06.446 5164 INFO   org.example.zk.curator.ZookeeperTest     - cost 0 ms
19:01:06.446 5164 DEBUG        org.apache.zookeeper.ZooKeeper     - Closing session: 0x0
19:01:06.446 5164 DEBUG       org.apache.zookeeper.ClientCnxn     - Closing client for session: 0x0
19:01:06.446 5164 INFO        org.apache.zookeeper.ClientCnxn     - Opening socket connection to server 192.168.9.19/192.168.9.19:2181. Will not attempt to authenticate using SASL (unknown error)
19:01:06.447 5165 INFO        org.apache.zookeeper.ClientCnxn     - Socket connection established to 192.168.9.19/192.168.9.19:2181, initiating session
19:01:06.447 5165 DEBUG       org.apache.zookeeper.ClientCnxn     - Session establishment request sent on 192.168.9.19/192.168.9.19:2181
19:01:06.450 5168 TRACE org.apache.zookeeper.ClientCnxnSocket     - readConnectResult 37 0x[0,0,0,0,0,0,27,10,1,59,49,71,ffffff83,a,0,33,0,0,0,10,ffffff82,ffffffa1,47,6f,ffffff81,ffffffff,38,16,41,29,2,28,39,45,4c,ffffffdf,0,]
19:01:06.450 5168 INFO        org.apache.zookeeper.ClientCnxn     - Session establishment complete on server 192.168.9.19/192.168.9.19:2181, sessionid = 0x1594971830a0033, negotiated timeout = 10000

WATCHER::

WatchedEvent state:SyncConnected type:None path:null
19:01:06.453 5171 DEBUG       org.apache.zookeeper.ClientCnxn     - Reading reply sessionid:0x1594971830a0033, packet:: clientPath:null serverPath:null finished:false header:: 1,-11  replyHeader:: 1,34359739758,0  request:: null response:: null
19:01:06.454 5172 DEBUG       org.apache.zookeeper.ClientCnxn     - Disconnecting client for session: 0x1594971830a0033
19:01:06.454 5172 DEBUG       org.apache.zookeeper.ClientCnxn     - An exception was thrown while closing send thread for session 0x1594971830a0033 : Unable to read additional data from server sessionid 0x1594971830a0033, likely server has closed socket
19:01:06.454 5172 INFO        org.apache.zookeeper.ClientCnxn     - EventThread shut down
19:01:06.454 5172 INFO         org.apache.zookeeper.ZooKeeper     - Session: 0x1594971830a0033 closed
19:01:06.454 5172 INFO   org.example.zk.curator.ZookeeperTest     - connecting
19:01:06.454 5172 INFO         org.apache.zookeeper.ZooKeeper     - Initiating client connection, connectString=192.168.9.19:2181 sessionTimeout=20000 watcher=org.example.zk.curator.ZookeeperTest$1@3efd065d
19:01:06.455 5173 INFO   org.example.zk.curator.ZookeeperTest     - cost 1 ms
19:01:06.455 5173 DEBUG        org.apache.zookeeper.ZooKeeper     - Closing session: 0x0
19:01:06.455 5173 INFO        org.apache.zookeeper.ClientCnxn     - Opening socket connection to server 192.168.9.19/192.168.9.19:2181. Will not attempt to authenticate using SASL (unknown error)
19:01:06.455 5173 DEBUG       org.apache.zookeeper.ClientCnxn     - Closing client for session: 0x0
19:01:06.457 5175 INFO        org.apache.zookeeper.ClientCnxn     - Socket connection established to 192.168.9.19/192.168.9.19:2181, initiating session
19:01:06.457 5175 DEBUG       org.apache.zookeeper.ClientCnxn     - Session establishment request sent on 192.168.9.19/192.168.9.19:2181
19:01:06.460 5178 TRACE org.apache.zookeeper.ClientCnxnSocket     - readConnectResult 37 0x[0,0,0,0,0,0,27,10,1,59,49,71,ffffff83,a,0,34,0,0,0,10,ffffffe2,46,2a,6f,ffffffa0,79,ffffff95,6e,b,78,6,1,5b,fffffff1,ffffffb2,ffffffe0,0,]
19:01:06.460 5178 INFO        org.apache.zookeeper.ClientCnxn     - Session establishment complete on server 192.168.9.19/192.168.9.19:2181, sessionid = 0x1594971830a0034, negotiated timeout = 10000

WATCHER::

WatchedEvent state:SyncConnected type:None path:null
19:01:06.463 5181 DEBUG       org.apache.zookeeper.ClientCnxn     - Reading reply sessionid:0x1594971830a0034, packet:: clientPath:null serverPath:null finished:false header:: 1,-11  replyHeader:: 1,34359739760,0  request:: null response:: null
19:01:06.463 5181 DEBUG       org.apache.zookeeper.ClientCnxn     - Disconnecting client for session: 0x1594971830a0034
19:01:06.463 5181 DEBUG       org.apache.zookeeper.ClientCnxn     - An exception was thrown while closing send thread for session 0x1594971830a0034 : Unable to read additional data from server sessionid 0x1594971830a0034, likely server has closed socket
19:01:06.463 5181 INFO        org.apache.zookeeper.ClientCnxn     - EventThread shut down
19:01:06.463 5181 INFO         org.apache.zookeeper.ZooKeeper     - Session: 0x1594971830a0034 closed

Process finished with exit code 0



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