You are viewing a plain text version of this content. The canonical link for it is here.
Posted to commits@helix.apache.org by "stuart meikle (JIRA)" <ji...@apache.org> on 2016/05/27 07:59:13 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)