You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@helix.apache.org by "stuart meikle (JIRA)" <ji...@apache.org> on 2016/05/27 07:59:12 UTC

[jira] [Created] (HELIX-630) Unable to start simple Participant. Threading issues?

stuart meikle created HELIX-630:
-----------------------------------

             Summary: Unable to start simple Participant. Threading issues?
                 Key: HELIX-630
                 URL: https://issues.apache.org/jira/browse/HELIX-630
             Project: Apache Helix
          Issue Type: Bug
          Components: helix-core
    Affects Versions: 0.7.1, 0.6.5
         Environment: Windows 7 64 bit. Running from Intellij. 
            Reporter: stuart meikle


I have a controller app and a v simple participant app, both derived from the Quickstart.java. I start the controller app in intellij and then up to 3 participant apps. Sometimes the participant apps fail to start. I'll attach the logs below. Error appears to occur in manager.connect, and appears intermittently. I noticed you had an earlier bug back in 2014 with similar symptoms. 

--

D:\dev\bin\sun\jdk\1.8.0_25-64bit\bin\java -Didea.launcher.port=7578 -Didea.launcher.bin.path=D:\dev\bin\IntelliJIDEA14.1.5\bin -Dfile.encoding=windows-1252 -classpath D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\charsets.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\deploy.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\javaws.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\jce.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\jfr.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\jfxswt.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\jsse.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\management-agent.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\plugin.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\resources.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\rt.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\access-bridge-64.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\cldrdata.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\dnsns.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\jaccess.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\jfxrt.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\localedata.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\nashorn.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\sunec.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\sunjce_provider.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\sunmscapi.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\sunpkcs11.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\zipfs.jar;D:\dev\projects\helix-proto\build\classes\main;D:\dev\projects\helix-proto\build\resources\main;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.apache.helix\helix-core\0.7.1\bb2700adf022258e0d92b3facde1117a5f268ef7\helix-core-0.7.1.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\log4j\log4j\1.2.15\f0a0d2e29ed910808c33135a3a5a51bba6358f7b\log4j-1.2.15.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.apache.zookeeper\zookeeper\3.3.4\6471e17c92181da9e143559c4c4779925a5e6eb0\zookeeper-3.3.4.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.codehaus.jackson\jackson-core-asl\1.8.5\713a5564acb9a5467521bbb53221ab8e1fe65039\jackson-core-asl-1.8.5.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.codehaus.jackson\jackson-mapper-asl\1.8.5\56439095aa051521c5abb330235820c66886b5e5\jackson-mapper-asl-1.8.5.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\commons-io\commons-io\1.4\a8762d07e76cfde2395257a5da47ba7c1dbd3dce\commons-io-1.4.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\commons-cli\commons-cli\1.2\2bf96b7aa8b611c177d329452af1dc933e14501c\commons-cli-1.2.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\com.github.sgroschupf\zkclient\0.1\a6cf8ffca10fdda90fe00b0b23665435bae50183\zkclient-0.1.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.apache.commons\commons-math\2.1\b3c4bdc2778ddccceb8da2acec3e37bfa41303e9\commons-math-2.1.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\commons-codec\commons-codec\1.6\b7f0fc8f61ecadeb3695f0b9464755eee44374d4\commons-codec-1.6.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\com.google.guava\guava\15.0\ed727a8d9f247e2050281cb083f1c77b09dcb5cd\guava-15.0.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.yaml\snakeyaml\1.12\ebe66a6b88caab31d7a19571ad23656377523545\snakeyaml-1.12.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\jline\jline\0.9.94\99a18e9a44834afdebc467294e1138364c207402\jline-0.9.94.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\javax.mail\mail\1.4\1aa1579ae5ecd41920c4f355b0a9ef40b68315dd\mail-1.4.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\javax.activation\activation\1.1\e6cb541461c2834bdea3eb920f1884d1eb508b50\activation-1.1.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\junit\junit\3.8.1\99129f16442844f6a4a11ae22fbbee40b14d774f\junit-3.8.1.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.apache.helix\helix-examples\0.7.1\e502d53990f7ae6e685ac832f299b106bad8eb34\helix-examples-0.7.1.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\commons-logging\commons-logging-api\1.1\7d4cf5231d46c8524f9b9ed75bb2d1c69ab93322\commons-logging-api-1.1.jar;D:\dev\bin\IntelliJIDEA14.1.5\lib\idea_rt.jar com.intellij.rt.execution.application.AppMain petrotechnics.proscient.HelixParticipant 2
0 [main] INFO org.apache.helix.manager.zk.ZKHelixManager  - Create a zk-based cluster manager. zkSvr: localhost:2199, clusterName: HELIX_QUICKSTART, instanceName: localhost_12002, type: PARTICIPANT
8 [main] INFO org.apache.helix.HelixManagerProperties  - load helix-manager properties: {minimum_supported_version.batch_message=0.6.1, clustermanager.version=0.7.1, minimum_supported_version.participant=0.4}
21 [main] DEBUG org.I0Itec.zkclient.ZkConnection  - Creating new ZookKeeper instance to connect to localhost:2199.
21 [ZkClient-EventThread-14-localhost:2199] INFO org.I0Itec.zkclient.ZkEventThread  - Starting ZkClient event thread.
39 [main] INFO org.apache.zookeeper.ZooKeeper  - Client environment:zookeeper.version=3.3.3-1203054, built on 11/17/2011 05:47 GMT
39 [main] INFO org.apache.zookeeper.ZooKeeper  - Client environment:host.name=LA02340.petrotechnics.com
39 [main] INFO org.apache.zookeeper.ZooKeeper  - Client environment:java.version=1.8.0_25
40 [main] INFO org.apache.zookeeper.ZooKeeper  - Client environment:java.vendor=Oracle Corporation
40 [main] INFO org.apache.zookeeper.ZooKeeper  - Client environment:java.home=D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre
40 [main] INFO org.apache.zookeeper.ZooKeeper  - Client environment:java.class.path=D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\charsets.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\deploy.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\javaws.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\jce.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\jfr.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\jfxswt.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\jsse.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\management-agent.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\plugin.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\resources.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\rt.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\access-bridge-64.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\cldrdata.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\dnsns.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\jaccess.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\jfxrt.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\localedata.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\nashorn.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\sunec.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\sunjce_provider.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\sunmscapi.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\sunpkcs11.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\zipfs.jar;D:\dev\projects\helix-proto\build\classes\main;D:\dev\projects\helix-proto\build\resources\main;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.apache.helix\helix-core\0.7.1\bb2700adf022258e0d92b3facde1117a5f268ef7\helix-core-0.7.1.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\log4j\log4j\1.2.15\f0a0d2e29ed910808c33135a3a5a51bba6358f7b\log4j-1.2.15.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.apache.zookeeper\zookeeper\3.3.4\6471e17c92181da9e143559c4c4779925a5e6eb0\zookeeper-3.3.4.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.codehaus.jackson\jackson-core-asl\1.8.5\713a5564acb9a5467521bbb53221ab8e1fe65039\jackson-core-asl-1.8.5.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.codehaus.jackson\jackson-mapper-asl\1.8.5\56439095aa051521c5abb330235820c66886b5e5\jackson-mapper-asl-1.8.5.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\commons-io\commons-io\1.4\a8762d07e76cfde2395257a5da47ba7c1dbd3dce\commons-io-1.4.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\commons-cli\commons-cli\1.2\2bf96b7aa8b611c177d329452af1dc933e14501c\commons-cli-1.2.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\com.github.sgroschupf\zkclient\0.1\a6cf8ffca10fdda90fe00b0b23665435bae50183\zkclient-0.1.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.apache.commons\commons-math\2.1\b3c4bdc2778ddccceb8da2acec3e37bfa41303e9\commons-math-2.1.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\commons-codec\commons-codec\1.6\b7f0fc8f61ecadeb3695f0b9464755eee44374d4\commons-codec-1.6.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\com.google.guava\guava\15.0\ed727a8d9f247e2050281cb083f1c77b09dcb5cd\guava-15.0.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.yaml\snakeyaml\1.12\ebe66a6b88caab31d7a19571ad23656377523545\snakeyaml-1.12.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\jline\jline\0.9.94\99a18e9a44834afdebc467294e1138364c207402\jline-0.9.94.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\javax.mail\mail\1.4\1aa1579ae5ecd41920c4f355b0a9ef40b68315dd\mail-1.4.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\javax.activation\activation\1.1\e6cb541461c2834bdea3eb920f1884d1eb508b50\activation-1.1.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\junit\junit\3.8.1\99129f16442844f6a4a11ae22fbbee40b14d774f\junit-3.8.1.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.apache.helix\helix-examples\0.7.1\e502d53990f7ae6e685ac832f299b106bad8eb34\helix-examples-0.7.1.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\commons-logging\commons-logging-api\1.1\7d4cf5231d46c8524f9b9ed75bb2d1c69ab93322\commons-logging-api-1.1.jar;D:\dev\bin\IntelliJIDEA14.1.5\lib\idea_rt.jar
44 [main] INFO org.apache.zookeeper.ZooKeeper  - Client environment:java.library.path=D:\dev\bin\sun\jdk\1.8.0_25-64bit\bin;C:\WINDOWS\Sun\Java\bin;C:\WINDOWS\system32;C:\WINDOWS;d:\dev\bin\python\2.7.8;d:\dev\bin\python\2.7.8\scripts;D:\dev\bin\jython\2.5.4rc1;D:\dev\bin\Sencha\Cmd\5.1.0.26;D:\dev\bin\ruby\bin;D:\dev\bin\oracle\11g_express\app\oracle\product\11.2.0\server\bin;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\WINDOWS\System32\WindowsPowerShell\v1.0\;D:\dev\bin\python\2.7.8;D:\dev\bin\python\2.7.8\Scripts;D:\dev\bin\jython\2.5.4rc1;C:\Program Files (x86)\GnuWin32\bin;D:\dev\bin\Sencha\Cmd\5.0.0.160;C:\Program Files (x86)\Common Files\ColumbiaSoft Shared;C:\Program Files (x86)\ATI Technologies\ATI.ACE\Core-Static;C:\Program Files (x86)\Enterprise Vault\EVClient\;C:\Users\stuart.meikle\AppData\Local\Mozilla Firefox;d:\dev\bin\sun\jdk\1.8.0_71-64bit\bin;\d:\dev\bin\python\2.7.8;d:\dev\bin\python\2.7.8\scripts;D:\dev\bin\jython\2.5.4rc1;C:\Users\stuart.meikle\AppData\Local\Apps\cURL\bin;d:\dev\bin\accurev\6.1\bin;.
44 [main] INFO org.apache.zookeeper.ZooKeeper  - Client environment:java.io.tmpdir=C:\Users\STUART~1.MEI\AppData\Local\Temp\
44 [main] INFO org.apache.zookeeper.ZooKeeper  - Client environment:java.compiler=<NA>
44 [main] INFO org.apache.zookeeper.ZooKeeper  - Client environment:os.name=Windows 7
44 [main] INFO org.apache.zookeeper.ZooKeeper  - Client environment:os.arch=amd64
44 [main] INFO org.apache.zookeeper.ZooKeeper  - Client environment:os.version=6.1
44 [main] INFO org.apache.zookeeper.ZooKeeper  - Client environment:user.name=stuart.meikle
44 [main] INFO org.apache.zookeeper.ZooKeeper  - Client environment:user.home=C:\Users\stuart.meikle
44 [main] INFO org.apache.zookeeper.ZooKeeper  - Client environment:user.dir=D:\dev\projects\helix-proto
45 [main] INFO org.apache.zookeeper.ZooKeeper  - Initiating client connection, connectString=localhost:2199 sessionTimeout=30000 watcher=org.apache.helix.manager.zk.ZkClient@3d82c5f3
47 [main] DEBUG org.apache.zookeeper.ClientCnxn  - zookeeper.disableAutoWatchReset is false
96 [main] DEBUG org.I0Itec.zkclient.ZkClient  - Awaiting connection to Zookeeper server
96 [main-SendThread()] INFO org.apache.zookeeper.ClientCnxn  - Opening socket connection to server localhost/127.0.0.1:2199
97 [main] DEBUG org.I0Itec.zkclient.ZkClient  - Waiting for keeper state SyncConnected
98 [main-SendThread(localhost:2199)] INFO org.apache.zookeeper.ClientCnxn  - Socket connection established to localhost/127.0.0.1:2199, initiating session
99 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Session establishment request sent on localhost/127.0.0.1:2199
103 [main-SendThread(localhost:2199)] INFO org.apache.zookeeper.ClientCnxn  - Session establishment complete on server localhost/127.0.0.1:2199, sessionid = 0x154f13129940017, negotiated timeout = 30000
104 [main-EventThread] DEBUG org.I0Itec.zkclient.ZkClient  - Received event: WatchedEvent state:SyncConnected type:None path:null
105 [main-EventThread] INFO org.I0Itec.zkclient.ZkClient  - zookeeper state changed (SyncConnected)
105 [main-EventThread] DEBUG org.I0Itec.zkclient.ZkClient  - Leaving process event
105 [main] DEBUG org.I0Itec.zkclient.ZkClient  - State is SyncConnected
108 [main] DEBUG org.I0Itec.zkclient.ZkClient  - Waiting for keeper state SyncConnected
109 [main] DEBUG org.I0Itec.zkclient.ZkClient  - State is SyncConnected
109 [main] INFO org.apache.helix.manager.zk.ZkHelixConnection  - Handling new session, session id: 154f13129940017, zkconnection: State:CONNECTED Timeout:30000 sessionid:0x154f13129940017 local:/127.0.0.1:59700 remoteserver:localhost/127.0.0.1:2199 lastZxid:0 xid:1 sent:1 recv:1 queuedpkts:0 pendingresp:0 queuedevents:0
174 [main] INFO org.apache.helix.messaging.handling.HelixTaskExecutor  - Registered message handler factory for type: TASK_REPLY, poolSize: 40, factory: org.apache.helix.messaging.handling.AsyncCallbackService@1b0375b3, pool: java.util.concurrent.ThreadPoolExecutor@2f7c7260[Running, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 0]
177 [main] INFO org.apache.helix.participant.HelixStateMachineEngine  - Registering state model factory for state-model-definition: MyStateModel using factory-name: DEFAULT with: org.apache.helix.examples.MasterSlaveStateModelFactory@7c30a502
190 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 1,3  replyHeader:: 1,3676,0  request:: '/HELIX_QUICKSTART/IDEALSTATES,F  response:: s{3537,3537,1464335411894,1464335411894,0,1,0,0,0,1,3583} 
191 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 2,3  replyHeader:: 2,3676,0  request:: '/HELIX_QUICKSTART/CONFIGS/CLUSTER/HELIX_QUICKSTART,F  response:: s{3542,3543,1464335411907,1464335412034,1,0,0,0,112,0,3542} 
191 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 3,3  replyHeader:: 3,3676,0  request:: '/HELIX_QUICKSTART/CONFIGS/PARTICIPANT,F  response:: s{3544,3544,1464335412036,1464335412036,0,3,0,0,0,3,3573} 
192 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 4,3  replyHeader:: 4,3676,0  request:: '/HELIX_QUICKSTART/CONFIGS/RESOURCE,F  response:: s{3545,3545,1464335412038,1464335412038,0,0,0,0,0,0,3545} 
193 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 5,3  replyHeader:: 5,3676,0  request:: '/HELIX_QUICKSTART/PROPERTYSTORE,F  response:: s{3546,3546,1464335412039,1464335412039,0,0,0,0,0,0,3546} 
193 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 6,3  replyHeader:: 6,3676,0  request:: '/HELIX_QUICKSTART/LIVEINSTANCES,F  response:: s{3547,3547,1464335412041,1464335412041,0,4,0,0,0,2,3663} 
194 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 7,3  replyHeader:: 7,3676,0  request:: '/HELIX_QUICKSTART/INSTANCES,F  response:: s{3548,3548,1464335412043,1464335412043,0,3,0,0,0,3,3575} 
195 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 8,3  replyHeader:: 8,3676,0  request:: '/HELIX_QUICKSTART/EXTERNALVIEW,F  response:: s{3549,3549,1464335412044,1464335412044,0,1,0,0,0,1,3589} 
196 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 9,3  replyHeader:: 9,3676,0  request:: '/HELIX_QUICKSTART/CONTROLLER,F  response:: s{3551,3551,1464335412047,1464335412047,0,5,0,0,0,5,3586} 
196 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 10,3  replyHeader:: 10,3676,0  request:: '/HELIX_QUICKSTART/STATEMODELDEFS,F  response:: s{3550,3550,1464335412046,1464335412046,0,1,0,0,0,1,3581} 
197 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 11,3  replyHeader:: 11,3676,0  request:: '/HELIX_QUICKSTART/CONTROLLER/MESSAGES,F  response:: s{3553,3553,1464335412057,1464335412057,0,0,0,0,0,0,3553} 
197 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 12,3  replyHeader:: 12,3676,0  request:: '/HELIX_QUICKSTART/CONTROLLER/ERRORS,F  response:: s{3555,3555,1464335412061,1464335412061,0,0,0,0,0,0,3555} 
198 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 13,3  replyHeader:: 13,3676,0  request:: '/HELIX_QUICKSTART/CONTROLLER/STATUSUPDATES,F  response:: s{3554,3554,1464335412059,1464335412059,0,0,0,0,0,0,3554} 
198 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 14,3  replyHeader:: 14,3676,0  request:: '/HELIX_QUICKSTART/CONTROLLER/HISTORY,F  response:: s{3552,3587,1464335412055,1464335412283,1,0,0,0,150,0,3552} 
201 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 15,3  replyHeader:: 15,3676,0  request:: '/HELIX_QUICKSTART/IDEALSTATES,F  response:: s{3537,3537,1464335411894,1464335411894,0,1,0,0,0,1,3583} 
202 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 16,3  replyHeader:: 16,3676,0  request:: '/HELIX_QUICKSTART/CONFIGS/CLUSTER/HELIX_QUICKSTART,F  response:: s{3542,3543,1464335411907,1464335412034,1,0,0,0,112,0,3542} 
203 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 17,3  replyHeader:: 17,3676,0  request:: '/HELIX_QUICKSTART/CONFIGS/PARTICIPANT,F  response:: s{3544,3544,1464335412036,1464335412036,0,3,0,0,0,3,3573} 
203 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 18,3  replyHeader:: 18,3676,0  request:: '/HELIX_QUICKSTART/CONFIGS/RESOURCE,F  response:: s{3545,3545,1464335412038,1464335412038,0,0,0,0,0,0,3545} 
204 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 19,3  replyHeader:: 19,3676,0  request:: '/HELIX_QUICKSTART/PROPERTYSTORE,F  response:: s{3546,3546,1464335412039,1464335412039,0,0,0,0,0,0,3546} 
204 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 20,3  replyHeader:: 20,3676,0  request:: '/HELIX_QUICKSTART/LIVEINSTANCES,F  response:: s{3547,3547,1464335412041,1464335412041,0,4,0,0,0,2,3663} 
205 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 21,3  replyHeader:: 21,3676,0  request:: '/HELIX_QUICKSTART/INSTANCES,F  response:: s{3548,3548,1464335412043,1464335412043,0,3,0,0,0,3,3575} 
205 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 22,3  replyHeader:: 22,3676,0  request:: '/HELIX_QUICKSTART/EXTERNALVIEW,F  response:: s{3549,3549,1464335412044,1464335412044,0,1,0,0,0,1,3589} 
206 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 23,3  replyHeader:: 23,3676,0  request:: '/HELIX_QUICKSTART/CONTROLLER,F  response:: s{3551,3551,1464335412047,1464335412047,0,5,0,0,0,5,3586} 
206 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 24,3  replyHeader:: 24,3676,0  request:: '/HELIX_QUICKSTART/STATEMODELDEFS,F  response:: s{3550,3550,1464335412046,1464335412046,0,1,0,0,0,1,3581} 
207 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 25,3  replyHeader:: 25,3676,0  request:: '/HELIX_QUICKSTART/CONTROLLER/MESSAGES,F  response:: s{3553,3553,1464335412057,1464335412057,0,0,0,0,0,0,3553} 
208 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 26,3  replyHeader:: 26,3676,0  request:: '/HELIX_QUICKSTART/CONTROLLER/ERRORS,F  response:: s{3555,3555,1464335412061,1464335412061,0,0,0,0,0,0,3555} 
208 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 27,3  replyHeader:: 27,3676,0  request:: '/HELIX_QUICKSTART/CONTROLLER/STATUSUPDATES,F  response:: s{3554,3554,1464335412059,1464335412059,0,0,0,0,0,0,3554} 
209 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 28,3  replyHeader:: 28,3676,0  request:: '/HELIX_QUICKSTART/CONTROLLER/HISTORY,F  response:: s{3552,3587,1464335412055,1464335412283,1,0,0,0,150,0,3552} 
212 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 29,4  replyHeader:: 29,3676,0  request:: '/HELIX_QUICKSTART/CONFIGS/CLUSTER/HELIX_QUICKSTART,F  response:: #7bda202022696422203a202248454c49585f515549434b5354415254222cda2020226d61704669656c647322203a207bda20207d2cda2020226c6973744669656c647322203a207bda20207d2cda20202273696d706c654669656c647322203a207bda20207dda7d,s{3542,3543,1464335411907,1464335412034,1,0,0,0,112,0,3542} 
394 [main] INFO org.apache.helix.manager.zk.ZkHelixParticipant  - instance: localhost_12002 auto-joining HELIX_QUICKSTART is false
395 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 30,3  replyHeader:: 30,3676,0  request:: '/HELIX_QUICKSTART/CONFIGS/PARTICIPANT/localhost_12002,F  response:: s{3573,3573,1464335412115,1464335412115,0,0,0,0,203,0,3573} 
396 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 31,3  replyHeader:: 31,3676,0  request:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES,F  response:: s{3576,3576,1464335412128,1464335412128,0,4,0,0,0,0,3632} 
398 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 32,3  replyHeader:: 32,3676,0  request:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES,F  response:: s{3577,3577,1464335412130,1464335412130,0,1,0,0,0,1,3627} 
399 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 33,3  replyHeader:: 33,3676,0  request:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/STATUSUPDATES,F  response:: s{3579,3579,1464335412135,1464335412135,0,0,0,0,0,0,3579} 
400 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 34,3  replyHeader:: 34,3676,0  request:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/ERRORS,F  response:: s{3578,3578,1464335412133,1464335412133,0,0,0,0,0,0,3578} 
431 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 35,1  replyHeader:: 35,3677,0  request:: '/HELIX_QUICKSTART/LIVEINSTANCES/localhost_12002,#7bda202022696422203a20226c6f63616c686f73745f3132303032222cda20202273696d706c654669656c647322203a207bda202020202248454c49585f56455253494f4e22203a2022302e372e31222cda20202020224c4956455f494e5354414e434522203a202238363434404c413032333430222cda202020202253455353494f4e5f494422203a202231353466313331323939343030313722da20207d2cda2020226c6973744669656c647322203a207bda20207d2cda2020226d61704669656c647322203a207bda20207dda7d,v{s{31,s{'world,'anyone}}},1  response:: '/HELIX_QUICKSTART/LIVEINSTANCES/localhost_12002 
435 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 36,8  replyHeader:: 36,3677,0  request:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES,F  response:: v{'154f13129940005} 
436 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 37,8  replyHeader:: 37,3677,0  request:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940005,F  response:: v{'MyResource} 
444 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940005/MyResource serverPath:/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940005/MyResource finished:false header:: 38,4  replyHeader:: 38,3677,0  request:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940005/MyResource,F  response:: #7bda202022696422203a20224d795265736f75726365222cda20202273696d706c654669656c647322203a207bda202020202242415443485f4d4553534147455f4d4f444522203a202266616c7365222cda20202020224255434b45545f53495a4522203a202230222cda202020202253455353494f4e5f494422203a2022313534663133313239393430303035222cda202020202253544154455f4d4f44454c5f44454622203a20224d7953746174654d6f64656c222cda202020202253544154455f4d4f44454c5f464143544f52595f4e414d4522203a202244454641554c5422da20207d2cda2020226c6973744669656c647322203a207bda20207d2cda2020226d61704669656c647322203a207bda20202020224d795265736f757263655f3022203a207bda2020202020202243555252454e545f535441544522203a2022534c415645222cda20202020202022494e464f22203a202222da202020207dda20207dda7d,s{3628,3631,1464335433721,1464335433789,1,0,0,0,377,0,3628} 
456 [main] INFO org.apache.helix.manager.zk.ZkHelixParticipant  - Carrying over old session: 154f13129940005, resource: MyResource to current session: 154f13129940017
470 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 39,4  replyHeader:: 39,3677,0  request:: '/HELIX_QUICKSTART/STATEMODELDEFS/MyStateModel,F  response:: #7bda202022696422203a20224d7953746174654d6f64656c222cda2020226d61704669656c647322203a207bda202020202244524f505045442e6d65746122203a207bda20202020202022636f756e7422203a20222d3122da202020207d2cda202020202244524f505045442e6e65787422203a207bda2020202020202244524f5050454422203a202244524f5050454422da202020207d2cda20202020224d41535445522e6d65746122203a207bda20202020202022636f756e7422203a20223122da202020207d2cda20202020224d41535445522e6e65787422203a207bda202020202020224d415354455222203a20224d4153544552222cda20202020202022534c41564522203a2022534c415645222cda2020202020202244524f5050454422203a2022534c415645222cda202020202020224f46464c494e4522203a2022534c41564522da202020207d2cda20202020224f46464c494e452e6d65746122203a207bda20202020202022636f756e7422203a20222d3122da202020207d2cda20202020224f46464c494e452e6e65787422203a207bda20202020202022534c41564522203a2022534c415645222cda202020202020224d415354455222203a2022534c415645222cda2020202020202244524f5050454422203a202244524f50504544222cda202020202020224f46464c494e4522203a20224f46464c494e4522da202020207d2cda2020202022534c4156452e6d65746122203a207bda20202020202022636f756e7422203a20225222da202020207d2cda2020202022534c4156452e6e65787422203a207bda20202020202022534c41564522203a2022534c415645222cda202020202020224d415354455222203a20224d4153544552222cda2020202020202244524f5050454422203a20224f46464c494e45222cda202020202020224f46464c494e4522203a20224f46464c494e4522da202020207dda20207d2cda2020226c6973744669656c647322203a207bda202020202253544154455f5052494f524954595f4c49535422203a205b20224d4153544552222c2022534c415645222c202244524f50504544222c20224f46464c494e4522205d2cda202020202253544154455f5452414e534954494f4e5f5052494f524954594c49535422203a205b2022534c4156452d4d4153544552222c2022534c4156452d4f46464c494e45222c20224f46464c494e452d534c415645222c20224f46464c494e452d44524f50504544222c20224d41535445522d534c41564522205dda20207d2cda20202273696d706c654669656c647322203a207bda2020202022494e495449414c5f535441544522203a20224f46464c494e4522da20207dda7d,s{3581,3581,1464335412170,1464335412170,0,0,0,0,1040,0,3581} 
486 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 40,4  replyHeader:: 40,3677,-101  request:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940017/MyResource,F  response::  
508 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 41,1  replyHeader:: 41,3678,-101  request:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940017/MyResource,#7bda202022696422203a20224d795265736f75726365222cda20202273696d706c654669656c647322203a207bda202020202242415443485f4d4553534147455f4d4f444522203a202266616c7365222cda20202020224255434b45545f53495a4522203a202230222cda202020202253455353494f4e5f494422203a2022313534663133313239393430303137222cda202020202253544154455f4d4f44454c5f44454622203a20224d7953746174654d6f64656c222cda202020202253544154455f4d4f44454c5f464143544f52595f4e414d4522203a202244454641554c5422da20207d2cda2020226c6973744669656c647322203a207bda20207d2cda2020226d61704669656c647322203a207bda20202020224d795265736f757263655f3022203a207bda2020202020202243555252454e545f535441544522203a20224f46464c494e4522da202020207dda20207dda7d,v{s{31,s{'world,'anyone}}},0  response::  
512 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 42,1  replyHeader:: 42,3679,0  request:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940017,,v{s{31,s{'world,'anyone}}},0  response:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940017 
525 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 43,1  replyHeader:: 43,3680,0  request:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940017/MyResource,#7bda202022696422203a20224d795265736f75726365222cda20202273696d706c654669656c647322203a207bda202020202242415443485f4d4553534147455f4d4f444522203a202266616c7365222cda20202020224255434b45545f53495a4522203a202230222cda202020202253455353494f4e5f494422203a2022313534663133313239393430303137222cda202020202253544154455f4d4f44454c5f44454622203a20224d7953746174654d6f64656c222cda202020202253544154455f4d4f44454c5f464143544f52595f4e414d4522203a202244454641554c5422da20207d2cda2020226c6973744669656c647322203a207bda20207d2cda2020226d61704669656c647322203a207bda20202020224d795265736f757263655f3022203a207bda2020202020202243555252454e545f535441544522203a20224f46464c494e4522da202020207dda20207dda7d,v{s{31,s{'world,'anyone}}},0  response:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940017/MyResource 
526 [main] INFO org.apache.helix.manager.zk.ZkHelixParticipant  - Removing current states from previous sessions. path: /HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940005
535 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 44,2  replyHeader:: 44,3681,-111  request:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940005,-1  response:: null
541 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 45,8  replyHeader:: 45,3681,0  request:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940005,F  response:: v{'MyResource} 
543 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 46,8  replyHeader:: 46,3681,0  request:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940005/MyResource,F  response:: v{} 
550 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 47,2  replyHeader:: 47,3682,0  request:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940005/MyResource,-1  response:: null
554 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 48,2  replyHeader:: 48,3683,0  request:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940005,-1  response:: null
559 [main] INFO org.apache.helix.manager.zk.ZkCallbackHandler  - 1 START:INVOKE /HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
559 [main] INFO org.apache.helix.manager.zk.ZkCallbackHandler  - localhost_12002 subscribes child-change. path: /HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@709ba3fb
564 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 49,3  replyHeader:: 49,3683,0  request:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES,T  response:: s{3576,3576,1464335412128,1464335412128,0,4,0,0,0,0,3632} 
568 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 50,8  replyHeader:: 50,3683,0  request:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES,T  response:: v{} 
574 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 51,8  replyHeader:: 51,3683,0  request:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES,T  response:: v{} 
576 [main] INFO org.apache.helix.messaging.handling.HelixTaskExecutor  - Init HelixTaskExecutor
576 [main] INFO org.apache.helix.monitoring.mbeans.MessageQueueMonitor  - Register MBean: ClusterStatus: cluster=HELIX_QUICKSTART,messageQueue=localhost_12002
580 [main] INFO org.apache.helix.messaging.handling.HelixTaskExecutor  - Skip init a new thread pool for type: TASK_REPLY, already existing pool: java.util.concurrent.ThreadPoolExecutor@2f7c7260[Running, pool size = 0, active threads = 0, queued tasks = 0, completed tasks = 0], isShutdown: false
580 [main] INFO org.apache.helix.messaging.handling.HelixTaskExecutor  - No Messages to process
580 [main] INFO org.apache.helix.manager.zk.ZkCallbackHandler  - 1 END:INVOKE /HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 21ms
580 [main] INFO org.apache.helix.manager.zk.ZkHelixConnection  - role: org.apache.helix.manager.zk.ZkHelixParticipant@49e4cb85 added listener: org.apache.helix.messaging.handling.HelixTaskExecutor@709ba3fb for type: MESSAGES to path: /HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES
581 [main] INFO org.apache.helix.participant.HelixStateMachineEngine  - Registering state model factory for state-model-definition: SchedulerTaskQueue using factory-name: DEFAULT with: org.apache.helix.participant.statemachine.ScheduledTaskStateModelFactory@345965f2
581 [main] INFO org.apache.helix.messaging.DefaultMessagingService  - registering msg factory for type STATE_TRANSITION
587 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 52,3  replyHeader:: 52,3683,0  request:: '/HELIX_QUICKSTART/IDEALSTATES,F  response:: s{3537,3537,1464335411894,1464335411894,0,1,0,0,0,1,3583} 
597 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Got notification sessionid:0x154f13129940017
598 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Got WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES for sessionid 0x154f13129940017
599 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 53,3  replyHeader:: 53,3684,0  request:: '/HELIX_QUICKSTART/CONFIGS/CLUSTER/HELIX_QUICKSTART,F  response:: s{3542,3543,1464335411907,1464335412034,1,0,0,0,112,0,3542} 
599 [main-EventThread] DEBUG org.I0Itec.zkclient.ZkClient  - Received event: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES
599 [main-EventThread] DEBUG org.I0Itec.zkclient.ZkEventThread  - New event: ZkEvent[Children of /HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES changed sent to org.apache.helix.manager.zk.ZkCallbackHandler@5618c5db]
599 [main-EventThread] DEBUG org.I0Itec.zkclient.ZkClient  - Leaving process event
599 [ZkClient-EventThread-14-localhost:2199] DEBUG org.I0Itec.zkclient.ZkEventThread  - Delivering event #1 ZkEvent[Children of /HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES changed sent to org.apache.helix.manager.zk.ZkCallbackHandler@5618c5db]
601 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 54,3  replyHeader:: 54,3684,0  request:: '/HELIX_QUICKSTART/CONFIGS/PARTICIPANT,F  response:: s{3544,3544,1464335412036,1464335412036,0,3,0,0,0,3,3573} 
601 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 55,3  replyHeader:: 55,3684,0  request:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES,T  response:: s{3576,3576,1464335412128,1464335412128,0,5,0,0,0,1,3684} 
604 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 56,3  replyHeader:: 56,3684,0  request:: '/HELIX_QUICKSTART/CONFIGS/RESOURCE,F  response:: s{3545,3545,1464335412038,1464335412038,0,0,0,0,0,0,3545} 
604 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 57,8  replyHeader:: 57,3684,0  request:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES,T  response:: v{'00e5f8b8-9260-41d6-beb2-9ae366806613} 
604 [ZkClient-EventThread-14-localhost:2199] INFO org.apache.helix.manager.zk.ZkCallbackHandler  - 14 START:INVOKE /HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES listener:org.apache.helix.messaging.handling.HelixTaskExecutor
604 [ZkClient-EventThread-14-localhost:2199] INFO org.apache.helix.manager.zk.ZkCallbackHandler  - localhost_12002 subscribes child-change. path: /HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES, listener: org.apache.helix.messaging.handling.HelixTaskExecutor@709ba3fb
608 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 58,3  replyHeader:: 58,3684,0  request:: '/HELIX_QUICKSTART/PROPERTYSTORE,F  response:: s{3546,3546,1464335412039,1464335412039,0,0,0,0,0,0,3546} 
608 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 59,3  replyHeader:: 59,3684,0  request:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES,T  response:: s{3576,3576,1464335412128,1464335412128,0,5,0,0,0,1,3684} 
609 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 60,3  replyHeader:: 60,3684,0  request:: '/HELIX_QUICKSTART/LIVEINSTANCES,F  response:: s{3547,3547,1464335412041,1464335412041,0,5,0,0,0,3,3677} 
611 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 61,3  replyHeader:: 61,3684,0  request:: '/HELIX_QUICKSTART/INSTANCES,F  response:: s{3548,3548,1464335412043,1464335412043,0,3,0,0,0,3,3575} 
611 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 62,8  replyHeader:: 62,3684,0  request:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES,T  response:: v{'00e5f8b8-9260-41d6-beb2-9ae366806613} 
614 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 63,3  replyHeader:: 63,3685,0  request:: '/HELIX_QUICKSTART/EXTERNALVIEW,F  response:: s{3549,3549,1464335412044,1464335412044,0,1,0,0,0,1,3589} 
614 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 64,8  replyHeader:: 64,3685,0  request:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES,T  response:: v{'00e5f8b8-9260-41d6-beb2-9ae366806613} 
616 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 65,3  replyHeader:: 65,3685,0  request:: '/HELIX_QUICKSTART/CONTROLLER,F  response:: s{3551,3551,1464335412047,1464335412047,0,5,0,0,0,5,3586} 
617 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES/00e5f8b8-9260-41d6-beb2-9ae366806613 serverPath:/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES/00e5f8b8-9260-41d6-beb2-9ae366806613 finished:false header:: 66,4  replyHeader:: 66,3685,0  request:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES/00e5f8b8-9260-41d6-beb2-9ae366806613,F  response:: #7bda202022696422203a202230306535663862382d393236302d343164362d626562322d396165333636383036363133222cda2020226d61704669656c647322203a207bda20207d2cda2020226c6973744669656c647322203a207bda20207d2cda20202273696d706c654669656c647322203a207bda20202020224352454154455f54494d455354414d5022203a202231343634333335343736363431222cda2020202022436c75737465724576656e744e616d6522203a202263757272656e7453746174654368616e6765222cda202020202246524f4d5f535441544522203a20224f46464c494e45222cda20202020224d53475f494422203a202230306535663862382d393236302d343164362d626562322d396165333636383036363133222cda20202020224d53475f535441544522203a20226e6577222cda20202020224d53475f5459504522203a202253544154455f5452414e534954494f4e222cda2020202022504152544954494f4e5f4e414d4522203a20224d795265736f757263655f30222cda20202020225245534f555243455f4e414d4522203a20224d795265736f75726365222cda20202020225352435f4e414d4522203a20226c6f63616c686f73745f39313030222cda20202020225352435f53455353494f4e5f494422203a2022313534663133313239393430303032222cda202020202253544154455f4d4f44454c5f44454622203a20224d7953746174654d6f64656c222cda202020202253544154455f4d4f44454c5f464143544f52595f4e414d4522203a202244454641554c54222cda20202020225447545f4e414d4522203a20226c6f63616c686f73745f3132303032222cda20202020225447545f53455353494f4e5f494422203a2022313534663133313239393430303137222cda2020202022544f5f535441544522203a2022534c41564522da20207dda7d,s{3684,3684,1464335476644,1464335476644,0,0,0,0,722,0,3684} 
625 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 67,3  replyHeader:: 67,3685,0  request:: '/HELIX_QUICKSTART/STATEMODELDEFS,F  response:: s{3550,3550,1464335412046,1464335412046,0,1,0,0,0,1,3581} 
626 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 68,3  replyHeader:: 68,3685,0  request:: '/HELIX_QUICKSTART/CONTROLLER/MESSAGES,F  response:: s{3553,3553,1464335412057,1464335412057,0,0,0,0,0,0,3553} 
626 [main-SendThread(localhost:2199)] DEBUG org.apache.zookeeper.ClientCnxn  - Reading reply sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null finished:false header:: 69,8  replyHeader:: 69,3685,0  request:: '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940017,F  response:: v{'MyResource} 
627 [ZkClient-EventThread-14-localhost:2199] ERROR org.apache.helix.messaging.handling.HelixTaskExecutor  - Failed to create message handler for 00e5f8b8-9260-41d6-beb2-9ae366806613
java.lang.NullPointerException
	at org.apache.helix.messaging.handling.HelixTaskExecutor.createMessageHandler(HelixTaskExecutor.java:661)
	at org.apache.helix.messaging.handling.HelixTaskExecutor.onMessage(HelixTaskExecutor.java:581)
	at org.apache.helix.manager.zk.ZkCallbackHandler.invoke(ZkCallbackHandler.java:202)
	at org.apache.helix.manager.zk.ZkCallbackHandler.handleChildChange(ZkCallbackHandler.java:405)
	at org.I0Itec.zkclient.ZkClient$7.run(ZkClient.java:568)
	at org.I0Itec.zkclient.ZkEventThread.run(ZkEventThread.java:71)



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

Re: [jira] [Created] (HELIX-630) Unable to start simple Participant. Threading issues?

Posted by kishore g <g....@gmail.com>.
Can you provide the participant code snippet. My guess is you are
registering the statemodelfactory after connecting to the cluster. Here is
the code from quickstart. Can you confirm you have the right order

MasterSlaveStateModelFactory stateModelFactory = new
MasterSlaveStateModelFactory(instanceName); StateMachineEngine stateMach =
manager.getStateMachineEngine();
stateMach.registerStateModelFactory(STATE_MODEL_NAME, stateModelFactory);
manager.connect();


On Fri, May 27, 2016 at 12:59 AM, stuart meikle (JIRA) <ji...@apache.org>
wrote:

> stuart meikle created HELIX-630:
> -----------------------------------
>
>              Summary: Unable to start simple Participant. Threading issues?
>                  Key: HELIX-630
>                  URL: https://issues.apache.org/jira/browse/HELIX-630
>              Project: Apache Helix
>           Issue Type: Bug
>           Components: helix-core
>     Affects Versions: 0.7.1, 0.6.5
>          Environment: Windows 7 64 bit. Running from Intellij.
>             Reporter: stuart meikle
>
>
> I have a controller app and a v simple participant app, both derived from
> the Quickstart.java. I start the controller app in intellij and then up to
> 3 participant apps. Sometimes the participant apps fail to start. I'll
> attach the logs below. Error appears to occur in manager.connect, and
> appears intermittently. I noticed you had an earlier bug back in 2014 with
> similar symptoms.
>
> --
>
> D:\dev\bin\sun\jdk\1.8.0_25-64bit\bin\java -Didea.launcher.port=7578
> -Didea.launcher.bin.path=D:\dev\bin\IntelliJIDEA14.1.5\bin
> -Dfile.encoding=windows-1252 -classpath
> D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\charsets.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\deploy.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\javaws.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\jce.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\jfr.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\jfxswt.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\jsse.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\management-agent.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\plugin.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\resources.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\rt.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\access-bridge-64.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\cldrdata.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\dnsns.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\jaccess.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\jfxrt.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\localedata.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\nashorn.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\sunec.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\sunjce_provider.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\sunmscapi.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\sunpkcs11.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\zipfs.jar;D:\dev\projects\helix-proto\build\classes\main;D:\dev\projects\helix-proto\build\resources\main;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.apache.helix\helix-core\0.7.1\bb2700adf022258e0d92b3facde1117a5f268ef7\helix-core-0.7.1.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\log4j\log4j\1.2.15\f0a0d2e29ed910808c33135a3a5a51bba6358f7b\log4j-1.2.15.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.apache.zookeeper\zookeeper\3.3.4\6471e17c92181da9e143559c4c4779925a5e6eb0\zookeeper-3.3.4.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.codehaus.jackson\jackson-core-asl\1.8.5\713a5564acb9a5467521bbb53221ab8e1fe65039\jackson-core-asl-1.8.5.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.codehaus.jackson\jackson-mapper-asl\1.8.5\56439095aa051521c5abb330235820c66886b5e5\jackson-mapper-asl-1.8.5.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\commons-io\commons-io\1.4\a8762d07e76cfde2395257a5da47ba7c1dbd3dce\commons-io-1.4.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\commons-cli\commons-cli\1.2\2bf96b7aa8b611c177d329452af1dc933e14501c\commons-cli-1.2.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\com.github.sgroschupf\zkclient\0.1\a6cf8ffca10fdda90fe00b0b23665435bae50183\zkclient-0.1.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.apache.commons\commons-math\2.1\b3c4bdc2778ddccceb8da2acec3e37bfa41303e9\commons-math-2.1.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\commons-codec\commons-codec\1.6\b7f0fc8f61ecadeb3695f0b9464755eee44374d4\commons-codec-1.6.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\com.google.guava\guava\15.0\ed727a8d9f247e2050281cb083f1c77b09dcb5cd\guava-15.0.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.yaml\snakeyaml\1.12\ebe66a6b88caab31d7a19571ad23656377523545\snakeyaml-1.12.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\jline\jline\0.9.94\99a18e9a44834afdebc467294e1138364c207402\jline-0.9.94.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\javax.mail\mail\1.4\1aa1579ae5ecd41920c4f355b0a9ef40b68315dd\mail-1.4.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\javax.activation\activation\1.1\e6cb541461c2834bdea3eb920f1884d1eb508b50\activation-1.1.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\junit\junit\3.8.1\99129f16442844f6a4a11ae22fbbee40b14d774f\junit-3.8.1.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.apache.helix\helix-examples\0.7.1\e502d53990f7ae6e685ac832f299b106bad8eb34\helix-examples-0.7.1.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\commons-logging\commons-logging-api\1.1\7d4cf5231d46c8524f9b9ed75bb2d1c69ab93322\commons-logging-api-1.1.jar;D:\dev\bin\IntelliJIDEA14.1.5\lib\idea_rt.jar
> com.intellij.rt.execution.application.AppMain
> petrotechnics.proscient.HelixParticipant 2
> 0 [main] INFO org.apache.helix.manager.zk.ZKHelixManager  - Create a
> zk-based cluster manager. zkSvr: localhost:2199, clusterName:
> HELIX_QUICKSTART, instanceName: localhost_12002, type: PARTICIPANT
> 8 [main] INFO org.apache.helix.HelixManagerProperties  - load
> helix-manager properties: {minimum_supported_version.batch_message=0.6.1,
> clustermanager.version=0.7.1, minimum_supported_version.participant=0.4}
> 21 [main] DEBUG org.I0Itec.zkclient.ZkConnection  - Creating new
> ZookKeeper instance to connect to localhost:2199.
> 21 [ZkClient-EventThread-14-localhost:2199] INFO
> org.I0Itec.zkclient.ZkEventThread  - Starting ZkClient event thread.
> 39 [main] INFO org.apache.zookeeper.ZooKeeper  - Client
> environment:zookeeper.version=3.3.3-1203054, built on 11/17/2011 05:47 GMT
> 39 [main] INFO org.apache.zookeeper.ZooKeeper  - Client environment:
> host.name=LA02340.petrotechnics.com
> 39 [main] INFO org.apache.zookeeper.ZooKeeper  - Client
> environment:java.version=1.8.0_25
> 40 [main] INFO org.apache.zookeeper.ZooKeeper  - Client
> environment:java.vendor=Oracle Corporation
> 40 [main] INFO org.apache.zookeeper.ZooKeeper  - Client
> environment:java.home=D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre
> 40 [main] INFO org.apache.zookeeper.ZooKeeper  - Client
> environment:java.class.path=D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\charsets.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\deploy.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\javaws.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\jce.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\jfr.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\jfxswt.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\jsse.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\management-agent.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\plugin.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\resources.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\rt.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\access-bridge-64.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\cldrdata.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\dnsns.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\jaccess.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\jfxrt.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\localedata.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\nashorn.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\sunec.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\sunjce_provider.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\sunmscapi.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\sunpkcs11.jar;D:\dev\bin\sun\jdk\1.8.0_25-64bit\jre\lib\ext\zipfs.jar;D:\dev\projects\helix-proto\build\classes\main;D:\dev\projects\helix-proto\build\resources\main;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.apache.helix\helix-core\0.7.1\bb2700adf022258e0d92b3facde1117a5f268ef7\helix-core-0.7.1.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\log4j\log4j\1.2.15\f0a0d2e29ed910808c33135a3a5a51bba6358f7b\log4j-1.2.15.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.apache.zookeeper\zookeeper\3.3.4\6471e17c92181da9e143559c4c4779925a5e6eb0\zookeeper-3.3.4.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.codehaus.jackson\jackson-core-asl\1.8.5\713a5564acb9a5467521bbb53221ab8e1fe65039\jackson-core-asl-1.8.5.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.codehaus.jackson\jackson-mapper-asl\1.8.5\56439095aa051521c5abb330235820c66886b5e5\jackson-mapper-asl-1.8.5.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\commons-io\commons-io\1.4\a8762d07e76cfde2395257a5da47ba7c1dbd3dce\commons-io-1.4.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\commons-cli\commons-cli\1.2\2bf96b7aa8b611c177d329452af1dc933e14501c\commons-cli-1.2.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\com.github.sgroschupf\zkclient\0.1\a6cf8ffca10fdda90fe00b0b23665435bae50183\zkclient-0.1.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.apache.commons\commons-math\2.1\b3c4bdc2778ddccceb8da2acec3e37bfa41303e9\commons-math-2.1.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\commons-codec\commons-codec\1.6\b7f0fc8f61ecadeb3695f0b9464755eee44374d4\commons-codec-1.6.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\com.google.guava\guava\15.0\ed727a8d9f247e2050281cb083f1c77b09dcb5cd\guava-15.0.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.yaml\snakeyaml\1.12\ebe66a6b88caab31d7a19571ad23656377523545\snakeyaml-1.12.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\jline\jline\0.9.94\99a18e9a44834afdebc467294e1138364c207402\jline-0.9.94.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\javax.mail\mail\1.4\1aa1579ae5ecd41920c4f355b0a9ef40b68315dd\mail-1.4.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\javax.activation\activation\1.1\e6cb541461c2834bdea3eb920f1884d1eb508b50\activation-1.1.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\junit\junit\3.8.1\99129f16442844f6a4a11ae22fbbee40b14d774f\junit-3.8.1.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\org.apache.helix\helix-examples\0.7.1\e502d53990f7ae6e685ac832f299b106bad8eb34\helix-examples-0.7.1.jar;C:\Users\stuart.meikle\.gradle\caches\modules-2\files-2.1\commons-logging\commons-logging-api\1.1\7d4cf5231d46c8524f9b9ed75bb2d1c69ab93322\commons-logging-api-1.1.jar;D:\dev\bin\IntelliJIDEA14.1.5\lib\idea_rt.jar
> 44 [main] INFO org.apache.zookeeper.ZooKeeper  - Client
> environment:java.library.path=D:\dev\bin\sun\jdk\1.8.0_25-64bit\bin;C:\WINDOWS\Sun\Java\bin;C:\WINDOWS\system32;C:\WINDOWS;d:\dev\bin\python\2.7.8;d:\dev\bin\python\2.7.8\scripts;D:\dev\bin\jython\2.5.4rc1;D:\dev\bin\Sencha\Cmd\5.1.0.26;D:\dev\bin\ruby\bin;D:\dev\bin\oracle\11g_express\app\oracle\product\11.2.0\server\bin;C:\WINDOWS\system32;C:\WINDOWS;C:\WINDOWS\System32\Wbem;C:\WINDOWS\System32\WindowsPowerShell\v1.0\;D:\dev\bin\python\2.7.8;D:\dev\bin\python\2.7.8\Scripts;D:\dev\bin\jython\2.5.4rc1;C:\Program
> Files (x86)\GnuWin32\bin;D:\dev\bin\Sencha\Cmd\5.0.0.160;C:\Program Files
> (x86)\Common Files\ColumbiaSoft Shared;C:\Program Files (x86)\ATI
> Technologies\ATI.ACE\Core-Static;C:\Program Files (x86)\Enterprise
> Vault\EVClient\;C:\Users\stuart.meikle\AppData\Local\Mozilla
> Firefox;d:\dev\bin\sun\jdk\1.8.0_71-64bit\bin;\d:\dev\bin\python\2.7.8;d:\dev\bin\python\2.7.8\scripts;D:\dev\bin\jython\2.5.4rc1;C:\Users\stuart.meikle\AppData\Local\Apps\cURL\bin;d:\dev\bin\accurev\6.1\bin;.
> 44 [main] INFO org.apache.zookeeper.ZooKeeper  - Client
> environment:java.io.tmpdir=C:\Users\STUART~1.MEI\AppData\Local\Temp\
> 44 [main] INFO org.apache.zookeeper.ZooKeeper  - Client
> environment:java.compiler=<NA>
> 44 [main] INFO org.apache.zookeeper.ZooKeeper  - Client environment:
> os.name=Windows 7
> 44 [main] INFO org.apache.zookeeper.ZooKeeper  - Client
> environment:os.arch=amd64
> 44 [main] INFO org.apache.zookeeper.ZooKeeper  - Client
> environment:os.version=6.1
> 44 [main] INFO org.apache.zookeeper.ZooKeeper  - Client environment:
> user.name=stuart.meikle
> 44 [main] INFO org.apache.zookeeper.ZooKeeper  - Client
> environment:user.home=C:\Users\stuart.meikle
> 44 [main] INFO org.apache.zookeeper.ZooKeeper  - Client
> environment:user.dir=D:\dev\projects\helix-proto
> 45 [main] INFO org.apache.zookeeper.ZooKeeper  - Initiating client
> connection, connectString=localhost:2199 sessionTimeout=30000
> watcher=org.apache.helix.manager.zk.ZkClient@3d82c5f3
> 47 [main] DEBUG org.apache.zookeeper.ClientCnxn  -
> zookeeper.disableAutoWatchReset is false
> 96 [main] DEBUG org.I0Itec.zkclient.ZkClient  - Awaiting connection to
> Zookeeper server
> 96 [main-SendThread()] INFO org.apache.zookeeper.ClientCnxn  - Opening
> socket connection to server localhost/127.0.0.1:2199
> 97 [main] DEBUG org.I0Itec.zkclient.ZkClient  - Waiting for keeper state
> SyncConnected
> 98 [main-SendThread(localhost:2199)] INFO org.apache.zookeeper.ClientCnxn
> - Socket connection established to localhost/127.0.0.1:2199, initiating
> session
> 99 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Session establishment request sent on
> localhost/127.0.0.1:2199
> 103 [main-SendThread(localhost:2199)] INFO
> org.apache.zookeeper.ClientCnxn  - Session establishment complete on server
> localhost/127.0.0.1:2199, sessionid = 0x154f13129940017, negotiated
> timeout = 30000
> 104 [main-EventThread] DEBUG org.I0Itec.zkclient.ZkClient  - Received
> event: WatchedEvent state:SyncConnected type:None path:null
> 105 [main-EventThread] INFO org.I0Itec.zkclient.ZkClient  - zookeeper
> state changed (SyncConnected)
> 105 [main-EventThread] DEBUG org.I0Itec.zkclient.ZkClient  - Leaving
> process event
> 105 [main] DEBUG org.I0Itec.zkclient.ZkClient  - State is SyncConnected
> 108 [main] DEBUG org.I0Itec.zkclient.ZkClient  - Waiting for keeper state
> SyncConnected
> 109 [main] DEBUG org.I0Itec.zkclient.ZkClient  - State is SyncConnected
> 109 [main] INFO org.apache.helix.manager.zk.ZkHelixConnection  - Handling
> new session, session id: 154f13129940017, zkconnection: State:CONNECTED
> Timeout:30000 sessionid:0x154f13129940017 local:/127.0.0.1:59700
> remoteserver:localhost/127.0.0.1:2199 lastZxid:0 xid:1 sent:1 recv:1
> queuedpkts:0 pendingresp:0 queuedevents:0
> 174 [main] INFO org.apache.helix.messaging.handling.HelixTaskExecutor  -
> Registered message handler factory for type: TASK_REPLY, poolSize: 40,
> factory: org.apache.helix.messaging.handling.AsyncCallbackService@1b0375b3,
> pool: java.util.concurrent.ThreadPoolExecutor@2f7c7260[Running, pool size
> = 0, active threads = 0, queued tasks = 0, completed tasks = 0]
> 177 [main] INFO org.apache.helix.participant.HelixStateMachineEngine  -
> Registering state model factory for state-model-definition: MyStateModel
> using factory-name: DEFAULT with:
> org.apache.helix.examples.MasterSlaveStateModelFactory@7c30a502
> 190 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 1,3  replyHeader:: 1,3676,0  request::
> '/HELIX_QUICKSTART/IDEALSTATES,F  response::
> s{3537,3537,1464335411894,1464335411894,0,1,0,0,0,1,3583}
> 191 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 2,3  replyHeader:: 2,3676,0  request::
> '/HELIX_QUICKSTART/CONFIGS/CLUSTER/HELIX_QUICKSTART,F  response::
> s{3542,3543,1464335411907,1464335412034,1,0,0,0,112,0,3542}
> 191 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 3,3  replyHeader:: 3,3676,0  request::
> '/HELIX_QUICKSTART/CONFIGS/PARTICIPANT,F  response::
> s{3544,3544,1464335412036,1464335412036,0,3,0,0,0,3,3573}
> 192 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 4,3  replyHeader:: 4,3676,0  request::
> '/HELIX_QUICKSTART/CONFIGS/RESOURCE,F  response::
> s{3545,3545,1464335412038,1464335412038,0,0,0,0,0,0,3545}
> 193 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 5,3  replyHeader:: 5,3676,0  request::
> '/HELIX_QUICKSTART/PROPERTYSTORE,F  response::
> s{3546,3546,1464335412039,1464335412039,0,0,0,0,0,0,3546}
> 193 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 6,3  replyHeader:: 6,3676,0  request::
> '/HELIX_QUICKSTART/LIVEINSTANCES,F  response::
> s{3547,3547,1464335412041,1464335412041,0,4,0,0,0,2,3663}
> 194 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 7,3  replyHeader:: 7,3676,0  request::
> '/HELIX_QUICKSTART/INSTANCES,F  response::
> s{3548,3548,1464335412043,1464335412043,0,3,0,0,0,3,3575}
> 195 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 8,3  replyHeader:: 8,3676,0  request::
> '/HELIX_QUICKSTART/EXTERNALVIEW,F  response::
> s{3549,3549,1464335412044,1464335412044,0,1,0,0,0,1,3589}
> 196 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 9,3  replyHeader:: 9,3676,0  request::
> '/HELIX_QUICKSTART/CONTROLLER,F  response::
> s{3551,3551,1464335412047,1464335412047,0,5,0,0,0,5,3586}
> 196 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 10,3  replyHeader:: 10,3676,0  request::
> '/HELIX_QUICKSTART/STATEMODELDEFS,F  response::
> s{3550,3550,1464335412046,1464335412046,0,1,0,0,0,1,3581}
> 197 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 11,3  replyHeader:: 11,3676,0  request::
> '/HELIX_QUICKSTART/CONTROLLER/MESSAGES,F  response::
> s{3553,3553,1464335412057,1464335412057,0,0,0,0,0,0,3553}
> 197 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 12,3  replyHeader:: 12,3676,0  request::
> '/HELIX_QUICKSTART/CONTROLLER/ERRORS,F  response::
> s{3555,3555,1464335412061,1464335412061,0,0,0,0,0,0,3555}
> 198 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 13,3  replyHeader:: 13,3676,0  request::
> '/HELIX_QUICKSTART/CONTROLLER/STATUSUPDATES,F  response::
> s{3554,3554,1464335412059,1464335412059,0,0,0,0,0,0,3554}
> 198 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 14,3  replyHeader:: 14,3676,0  request::
> '/HELIX_QUICKSTART/CONTROLLER/HISTORY,F  response::
> s{3552,3587,1464335412055,1464335412283,1,0,0,0,150,0,3552}
> 201 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 15,3  replyHeader:: 15,3676,0  request::
> '/HELIX_QUICKSTART/IDEALSTATES,F  response::
> s{3537,3537,1464335411894,1464335411894,0,1,0,0,0,1,3583}
> 202 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 16,3  replyHeader:: 16,3676,0  request::
> '/HELIX_QUICKSTART/CONFIGS/CLUSTER/HELIX_QUICKSTART,F  response::
> s{3542,3543,1464335411907,1464335412034,1,0,0,0,112,0,3542}
> 203 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 17,3  replyHeader:: 17,3676,0  request::
> '/HELIX_QUICKSTART/CONFIGS/PARTICIPANT,F  response::
> s{3544,3544,1464335412036,1464335412036,0,3,0,0,0,3,3573}
> 203 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 18,3  replyHeader:: 18,3676,0  request::
> '/HELIX_QUICKSTART/CONFIGS/RESOURCE,F  response::
> s{3545,3545,1464335412038,1464335412038,0,0,0,0,0,0,3545}
> 204 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 19,3  replyHeader:: 19,3676,0  request::
> '/HELIX_QUICKSTART/PROPERTYSTORE,F  response::
> s{3546,3546,1464335412039,1464335412039,0,0,0,0,0,0,3546}
> 204 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 20,3  replyHeader:: 20,3676,0  request::
> '/HELIX_QUICKSTART/LIVEINSTANCES,F  response::
> s{3547,3547,1464335412041,1464335412041,0,4,0,0,0,2,3663}
> 205 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 21,3  replyHeader:: 21,3676,0  request::
> '/HELIX_QUICKSTART/INSTANCES,F  response::
> s{3548,3548,1464335412043,1464335412043,0,3,0,0,0,3,3575}
> 205 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 22,3  replyHeader:: 22,3676,0  request::
> '/HELIX_QUICKSTART/EXTERNALVIEW,F  response::
> s{3549,3549,1464335412044,1464335412044,0,1,0,0,0,1,3589}
> 206 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 23,3  replyHeader:: 23,3676,0  request::
> '/HELIX_QUICKSTART/CONTROLLER,F  response::
> s{3551,3551,1464335412047,1464335412047,0,5,0,0,0,5,3586}
> 206 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 24,3  replyHeader:: 24,3676,0  request::
> '/HELIX_QUICKSTART/STATEMODELDEFS,F  response::
> s{3550,3550,1464335412046,1464335412046,0,1,0,0,0,1,3581}
> 207 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 25,3  replyHeader:: 25,3676,0  request::
> '/HELIX_QUICKSTART/CONTROLLER/MESSAGES,F  response::
> s{3553,3553,1464335412057,1464335412057,0,0,0,0,0,0,3553}
> 208 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 26,3  replyHeader:: 26,3676,0  request::
> '/HELIX_QUICKSTART/CONTROLLER/ERRORS,F  response::
> s{3555,3555,1464335412061,1464335412061,0,0,0,0,0,0,3555}
> 208 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 27,3  replyHeader:: 27,3676,0  request::
> '/HELIX_QUICKSTART/CONTROLLER/STATUSUPDATES,F  response::
> s{3554,3554,1464335412059,1464335412059,0,0,0,0,0,0,3554}
> 209 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 28,3  replyHeader:: 28,3676,0  request::
> '/HELIX_QUICKSTART/CONTROLLER/HISTORY,F  response::
> s{3552,3587,1464335412055,1464335412283,1,0,0,0,150,0,3552}
> 212 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 29,4  replyHeader:: 29,3676,0  request::
> '/HELIX_QUICKSTART/CONFIGS/CLUSTER/HELIX_QUICKSTART,F  response::
> #7bda202022696422203a202248454c49585f515549434b5354415254222cda2020226d61704669656c647322203a207bda20207d2cda2020226c6973744669656c647322203a207bda20207d2cda20202273696d706c654669656c647322203a207bda20207dda7d,s{3542,3543,1464335411907,1464335412034,1,0,0,0,112,0,3542}
> 394 [main] INFO org.apache.helix.manager.zk.ZkHelixParticipant  -
> instance: localhost_12002 auto-joining HELIX_QUICKSTART is false
> 395 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 30,3  replyHeader:: 30,3676,0  request::
> '/HELIX_QUICKSTART/CONFIGS/PARTICIPANT/localhost_12002,F  response::
> s{3573,3573,1464335412115,1464335412115,0,0,0,0,203,0,3573}
> 396 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 31,3  replyHeader:: 31,3676,0  request::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES,F  response::
> s{3576,3576,1464335412128,1464335412128,0,4,0,0,0,0,3632}
> 398 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 32,3  replyHeader:: 32,3676,0  request::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES,F  response::
> s{3577,3577,1464335412130,1464335412130,0,1,0,0,0,1,3627}
> 399 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 33,3  replyHeader:: 33,3676,0  request::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/STATUSUPDATES,F  response::
> s{3579,3579,1464335412135,1464335412135,0,0,0,0,0,0,3579}
> 400 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 34,3  replyHeader:: 34,3676,0  request::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/ERRORS,F  response::
> s{3578,3578,1464335412133,1464335412133,0,0,0,0,0,0,3578}
> 431 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 35,1  replyHeader:: 35,3677,0  request::
> '/HELIX_QUICKSTART/LIVEINSTANCES/localhost_12002,#7bda202022696422203a20226c6f63616c686f73745f3132303032222cda20202273696d706c654669656c647322203a207bda202020202248454c49585f56455253494f4e22203a2022302e372e31222cda20202020224c4956455f494e5354414e434522203a202238363434404c413032333430222cda202020202253455353494f4e5f494422203a202231353466313331323939343030313722da20207d2cda2020226c6973744669656c647322203a207bda20207d2cda2020226d61704669656c647322203a207bda20207dda7d,v{s{31,s{'world,'anyone}}},1
> response:: '/HELIX_QUICKSTART/LIVEINSTANCES/localhost_12002
> 435 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 36,8  replyHeader:: 36,3677,0  request::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES,F  response::
> v{'154f13129940005}
> 436 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 37,8  replyHeader:: 37,3677,0  request::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940005,F
> response:: v{'MyResource}
> 444 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet::
> clientPath:/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940005/MyResource
> serverPath:/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940005/MyResource
> finished:false header:: 38,4  replyHeader:: 38,3677,0  request::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940005/MyResource,F
> response::
> #7bda202022696422203a20224d795265736f75726365222cda20202273696d706c654669656c647322203a207bda202020202242415443485f4d4553534147455f4d4f444522203a202266616c7365222cda20202020224255434b45545f53495a4522203a202230222cda202020202253455353494f4e5f494422203a2022313534663133313239393430303035222cda202020202253544154455f4d4f44454c5f44454622203a20224d7953746174654d6f64656c222cda202020202253544154455f4d4f44454c5f464143544f52595f4e414d4522203a202244454641554c5422da20207d2cda2020226c6973744669656c647322203a207bda20207d2cda2020226d61704669656c647322203a207bda20202020224d795265736f757263655f3022203a207bda2020202020202243555252454e545f535441544522203a2022534c415645222cda20202020202022494e464f22203a202222da202020207dda20207dda7d,s{3628,3631,1464335433721,1464335433789,1,0,0,0,377,0,3628}
> 456 [main] INFO org.apache.helix.manager.zk.ZkHelixParticipant  - Carrying
> over old session: 154f13129940005, resource: MyResource to current session:
> 154f13129940017
> 470 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 39,4  replyHeader:: 39,3677,0  request::
> '/HELIX_QUICKSTART/STATEMODELDEFS/MyStateModel,F  response::
> #7bda202022696422203a20224d7953746174654d6f64656c222cda2020226d61704669656c647322203a207bda202020202244524f505045442e6d65746122203a207bda20202020202022636f756e7422203a20222d3122da202020207d2cda202020202244524f505045442e6e65787422203a207bda2020202020202244524f5050454422203a202244524f5050454422da202020207d2cda20202020224d41535445522e6d65746122203a207bda20202020202022636f756e7422203a20223122da202020207d2cda20202020224d41535445522e6e65787422203a207bda202020202020224d415354455222203a20224d4153544552222cda20202020202022534c41564522203a2022534c415645222cda2020202020202244524f5050454422203a2022534c415645222cda202020202020224f46464c494e4522203a2022534c41564522da202020207d2cda20202020224f46464c494e452e6d65746122203a207bda20202020202022636f756e7422203a20222d3122da202020207d2cda20202020224f46464c494e452e6e65787422203a207bda20202020202022534c41564522203a2022534c415645222cda202020202020224d415354455222203a2022534c415645222cda2020202020202244524f5050454422203a202244524f50504544222cda202020202020224f46464c494e4522203a20224f46464c494e4522da202020207d2cda2020202022534c4156452e6d65746122203a207bda20202020202022636f756e7422203a20225222da202020207d2cda2020202022534c4156452e6e65787422203a207bda20202020202022534c41564522203a2022534c415645222cda202020202020224d415354455222203a20224d4153544552222cda2020202020202244524f5050454422203a20224f46464c494e45222cda202020202020224f46464c494e4522203a20224f46464c494e4522da202020207dda20207d2cda2020226c6973744669656c647322203a207bda202020202253544154455f5052494f524954595f4c49535422203a205b20224d4153544552222c2022534c415645222c202244524f50504544222c20224f46464c494e4522205d2cda202020202253544154455f5452414e534954494f4e5f5052494f524954594c49535422203a205b2022534c4156452d4d4153544552222c2022534c4156452d4f46464c494e45222c20224f46464c494e452d534c415645222c20224f46464c494e452d44524f50504544222c20224d41535445522d534c41564522205dda20207d2cda20202273696d706c654669656c647322203a207bda2020202022494e495449414c5f535441544522203a20224f46464c494e4522da20207dda7d,s{3581,3581,1464335412170,1464335412170,0,0,0,0,1040,0,3581}
> 486 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 40,4  replyHeader:: 40,3677,-101  request::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940017/MyResource,F
> response::
> 508 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 41,1  replyHeader:: 41,3678,-101  request::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940017/MyResource,#7bda202022696422203a20224d795265736f75726365222cda20202273696d706c654669656c647322203a207bda202020202242415443485f4d4553534147455f4d4f444522203a202266616c7365222cda20202020224255434b45545f53495a4522203a202230222cda202020202253455353494f4e5f494422203a2022313534663133313239393430303137222cda202020202253544154455f4d4f44454c5f44454622203a20224d7953746174654d6f64656c222cda202020202253544154455f4d4f44454c5f464143544f52595f4e414d4522203a202244454641554c5422da20207d2cda2020226c6973744669656c647322203a207bda20207d2cda2020226d61704669656c647322203a207bda20202020224d795265736f757263655f3022203a207bda2020202020202243555252454e545f535441544522203a20224f46464c494e4522da202020207dda20207dda7d,v{s{31,s{'world,'anyone}}},0
> response::
> 512 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 42,1  replyHeader:: 42,3679,0  request::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940017,,v{s{31,s{'world,'anyone}}},0
> response::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940017
> 525 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 43,1  replyHeader:: 43,3680,0  request::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940017/MyResource,#7bda202022696422203a20224d795265736f75726365222cda20202273696d706c654669656c647322203a207bda202020202242415443485f4d4553534147455f4d4f444522203a202266616c7365222cda20202020224255434b45545f53495a4522203a202230222cda202020202253455353494f4e5f494422203a2022313534663133313239393430303137222cda202020202253544154455f4d4f44454c5f44454622203a20224d7953746174654d6f64656c222cda202020202253544154455f4d4f44454c5f464143544f52595f4e414d4522203a202244454641554c5422da20207d2cda2020226c6973744669656c647322203a207bda20207d2cda2020226d61704669656c647322203a207bda20202020224d795265736f757263655f3022203a207bda2020202020202243555252454e545f535441544522203a20224f46464c494e4522da202020207dda20207dda7d,v{s{31,s{'world,'anyone}}},0
> response::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940017/MyResource
> 526 [main] INFO org.apache.helix.manager.zk.ZkHelixParticipant  - Removing
> current states from previous sessions. path:
> /HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940005
> 535 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 44,2  replyHeader:: 44,3681,-111  request::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940005,-1
> response:: null
> 541 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 45,8  replyHeader:: 45,3681,0  request::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940005,F
> response:: v{'MyResource}
> 543 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 46,8  replyHeader:: 46,3681,0  request::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940005/MyResource,F
> response:: v{}
> 550 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 47,2  replyHeader:: 47,3682,0  request::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940005/MyResource,-1
> response:: null
> 554 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 48,2  replyHeader:: 48,3683,0  request::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940005,-1
> response:: null
> 559 [main] INFO org.apache.helix.manager.zk.ZkCallbackHandler  - 1
> START:INVOKE /HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES
> listener:org.apache.helix.messaging.handling.HelixTaskExecutor
> 559 [main] INFO org.apache.helix.manager.zk.ZkCallbackHandler  -
> localhost_12002 subscribes child-change. path:
> /HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES, listener:
> org.apache.helix.messaging.handling.HelixTaskExecutor@709ba3fb
> 564 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 49,3  replyHeader:: 49,3683,0  request::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES,T  response::
> s{3576,3576,1464335412128,1464335412128,0,4,0,0,0,0,3632}
> 568 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 50,8  replyHeader:: 50,3683,0  request::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES,T  response:: v{}
> 574 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 51,8  replyHeader:: 51,3683,0  request::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES,T  response:: v{}
> 576 [main] INFO org.apache.helix.messaging.handling.HelixTaskExecutor  -
> Init HelixTaskExecutor
> 576 [main] INFO org.apache.helix.monitoring.mbeans.MessageQueueMonitor  -
> Register MBean: ClusterStatus:
> cluster=HELIX_QUICKSTART,messageQueue=localhost_12002
> 580 [main] INFO org.apache.helix.messaging.handling.HelixTaskExecutor  -
> Skip init a new thread pool for type: TASK_REPLY, already existing pool:
> java.util.concurrent.ThreadPoolExecutor@2f7c7260[Running, pool size = 0,
> active threads = 0, queued tasks = 0, completed tasks = 0], isShutdown:
> false
> 580 [main] INFO org.apache.helix.messaging.handling.HelixTaskExecutor  -
> No Messages to process
> 580 [main] INFO org.apache.helix.manager.zk.ZkCallbackHandler  - 1
> END:INVOKE /HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES
> listener:org.apache.helix.messaging.handling.HelixTaskExecutor Took: 21ms
> 580 [main] INFO org.apache.helix.manager.zk.ZkHelixConnection  - role:
> org.apache.helix.manager.zk.ZkHelixParticipant@49e4cb85 added listener:
> org.apache.helix.messaging.handling.HelixTaskExecutor@709ba3fb for type:
> MESSAGES to path: /HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES
> 581 [main] INFO org.apache.helix.participant.HelixStateMachineEngine  -
> Registering state model factory for state-model-definition:
> SchedulerTaskQueue using factory-name: DEFAULT with:
> org.apache.helix.participant.statemachine.ScheduledTaskStateModelFactory@345965f2
> 581 [main] INFO org.apache.helix.messaging.DefaultMessagingService  -
> registering msg factory for type STATE_TRANSITION
> 587 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 52,3  replyHeader:: 52,3683,0  request::
> '/HELIX_QUICKSTART/IDEALSTATES,F  response::
> s{3537,3537,1464335411894,1464335411894,0,1,0,0,0,1,3583}
> 597 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Got notification
> sessionid:0x154f13129940017
> 598 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Got WatchedEvent state:SyncConnected
> type:NodeChildrenChanged
> path:/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES for sessionid
> 0x154f13129940017
> 599 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 53,3  replyHeader:: 53,3684,0  request::
> '/HELIX_QUICKSTART/CONFIGS/CLUSTER/HELIX_QUICKSTART,F  response::
> s{3542,3543,1464335411907,1464335412034,1,0,0,0,112,0,3542}
> 599 [main-EventThread] DEBUG org.I0Itec.zkclient.ZkClient  - Received
> event: WatchedEvent state:SyncConnected type:NodeChildrenChanged
> path:/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES
> 599 [main-EventThread] DEBUG org.I0Itec.zkclient.ZkEventThread  - New
> event: ZkEvent[Children of
> /HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES changed sent to
> org.apache.helix.manager.zk.ZkCallbackHandler@5618c5db]
> 599 [main-EventThread] DEBUG org.I0Itec.zkclient.ZkClient  - Leaving
> process event
> 599 [ZkClient-EventThread-14-localhost:2199] DEBUG
> org.I0Itec.zkclient.ZkEventThread  - Delivering event #1 ZkEvent[Children
> of /HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES changed sent to
> org.apache.helix.manager.zk.ZkCallbackHandler@5618c5db]
> 601 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 54,3  replyHeader:: 54,3684,0  request::
> '/HELIX_QUICKSTART/CONFIGS/PARTICIPANT,F  response::
> s{3544,3544,1464335412036,1464335412036,0,3,0,0,0,3,3573}
> 601 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 55,3  replyHeader:: 55,3684,0  request::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES,T  response::
> s{3576,3576,1464335412128,1464335412128,0,5,0,0,0,1,3684}
> 604 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 56,3  replyHeader:: 56,3684,0  request::
> '/HELIX_QUICKSTART/CONFIGS/RESOURCE,F  response::
> s{3545,3545,1464335412038,1464335412038,0,0,0,0,0,0,3545}
> 604 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 57,8  replyHeader:: 57,3684,0  request::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES,T  response::
> v{'00e5f8b8-9260-41d6-beb2-9ae366806613}
> 604 [ZkClient-EventThread-14-localhost:2199] INFO
> org.apache.helix.manager.zk.ZkCallbackHandler  - 14 START:INVOKE
> /HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES
> listener:org.apache.helix.messaging.handling.HelixTaskExecutor
> 604 [ZkClient-EventThread-14-localhost:2199] INFO
> org.apache.helix.manager.zk.ZkCallbackHandler  - localhost_12002 subscribes
> child-change. path: /HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES,
> listener: org.apache.helix.messaging.handling.HelixTaskExecutor@709ba3fb
> 608 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 58,3  replyHeader:: 58,3684,0  request::
> '/HELIX_QUICKSTART/PROPERTYSTORE,F  response::
> s{3546,3546,1464335412039,1464335412039,0,0,0,0,0,0,3546}
> 608 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 59,3  replyHeader:: 59,3684,0  request::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES,T  response::
> s{3576,3576,1464335412128,1464335412128,0,5,0,0,0,1,3684}
> 609 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 60,3  replyHeader:: 60,3684,0  request::
> '/HELIX_QUICKSTART/LIVEINSTANCES,F  response::
> s{3547,3547,1464335412041,1464335412041,0,5,0,0,0,3,3677}
> 611 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 61,3  replyHeader:: 61,3684,0  request::
> '/HELIX_QUICKSTART/INSTANCES,F  response::
> s{3548,3548,1464335412043,1464335412043,0,3,0,0,0,3,3575}
> 611 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 62,8  replyHeader:: 62,3684,0  request::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES,T  response::
> v{'00e5f8b8-9260-41d6-beb2-9ae366806613}
> 614 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 63,3  replyHeader:: 63,3685,0  request::
> '/HELIX_QUICKSTART/EXTERNALVIEW,F  response::
> s{3549,3549,1464335412044,1464335412044,0,1,0,0,0,1,3589}
> 614 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 64,8  replyHeader:: 64,3685,0  request::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES,T  response::
> v{'00e5f8b8-9260-41d6-beb2-9ae366806613}
> 616 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 65,3  replyHeader:: 65,3685,0  request::
> '/HELIX_QUICKSTART/CONTROLLER,F  response::
> s{3551,3551,1464335412047,1464335412047,0,5,0,0,0,5,3586}
> 617 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet::
> clientPath:/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES/00e5f8b8-9260-41d6-beb2-9ae366806613
> serverPath:/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES/00e5f8b8-9260-41d6-beb2-9ae366806613
> finished:false header:: 66,4  replyHeader:: 66,3685,0  request::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/MESSAGES/00e5f8b8-9260-41d6-beb2-9ae366806613,F
> response::
> #7bda202022696422203a202230306535663862382d393236302d343164362d626562322d396165333636383036363133222cda2020226d61704669656c647322203a207bda20207d2cda2020226c6973744669656c647322203a207bda20207d2cda20202273696d706c654669656c647322203a207bda20202020224352454154455f54494d455354414d5022203a202231343634333335343736363431222cda2020202022436c75737465724576656e744e616d6522203a202263757272656e7453746174654368616e6765222cda202020202246524f4d5f535441544522203a20224f46464c494e45222cda20202020224d53475f494422203a202230306535663862382d393236302d343164362d626562322d396165333636383036363133222cda20202020224d53475f535441544522203a20226e6577222cda20202020224d53475f5459504522203a202253544154455f5452414e534954494f4e222cda2020202022504152544954494f4e5f4e414d4522203a20224d795265736f757263655f30222cda20202020225245534f555243455f4e414d4522203a20224d795265736f75726365222cda20202020225352435f4e414d4522203a20226c6f63616c686f73745f39313030222cda20202020225352435f53455353494f4e5f494422203a2022313534663133313239393430303032222cda202020202253544154455f4d4f44454c5f44454622203a20224d7953746174654d6f64656c222cda202020202253544154455f4d4f44454c5f464143544f52595f4e414d4522203a202244454641554c54222cda20202020225447545f4e414d4522203a20226c6f63616c686f73745f3132303032222cda20202020225447545f53455353494f4e5f494422203a2022313534663133313239393430303137222cda2020202022544f5f535441544522203a2022534c41564522da20207dda7d,s{3684,3684,1464335476644,1464335476644,0,0,0,0,722,0,3684}
> 625 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 67,3  replyHeader:: 67,3685,0  request::
> '/HELIX_QUICKSTART/STATEMODELDEFS,F  response::
> s{3550,3550,1464335412046,1464335412046,0,1,0,0,0,1,3581}
> 626 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 68,3  replyHeader:: 68,3685,0  request::
> '/HELIX_QUICKSTART/CONTROLLER/MESSAGES,F  response::
> s{3553,3553,1464335412057,1464335412057,0,0,0,0,0,0,3553}
> 626 [main-SendThread(localhost:2199)] DEBUG
> org.apache.zookeeper.ClientCnxn  - Reading reply
> sessionid:0x154f13129940017, packet:: clientPath:null serverPath:null
> finished:false header:: 69,8  replyHeader:: 69,3685,0  request::
> '/HELIX_QUICKSTART/INSTANCES/localhost_12002/CURRENTSTATES/154f13129940017,F
> response:: v{'MyResource}
> 627 [ZkClient-EventThread-14-localhost:2199] ERROR
> org.apache.helix.messaging.handling.HelixTaskExecutor  - Failed to create
> message handler for 00e5f8b8-9260-41d6-beb2-9ae366806613
> java.lang.NullPointerException
>         at
> org.apache.helix.messaging.handling.HelixTaskExecutor.createMessageHandler(HelixTaskExecutor.java:661)
>         at
> org.apache.helix.messaging.handling.HelixTaskExecutor.onMessage(HelixTaskExecutor.java:581)
>         at
> org.apache.helix.manager.zk.ZkCallbackHandler.invoke(ZkCallbackHandler.java:202)
>         at
> org.apache.helix.manager.zk.ZkCallbackHandler.handleChildChange(ZkCallbackHandler.java:405)
>         at org.I0Itec.zkclient.ZkClient$7.run(ZkClient.java:568)
>         at org.I0Itec.zkclient.ZkEventThread.run(ZkEventThread.java:71)
>
>
>
> --
> This message was sent by Atlassian JIRA
> (v6.3.4#6332)
>