You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/01/09 16:19:00 UTC
[JENKINS] Lucene-Solr-trunk-Windows (64bit/jdk1.7.0_10) - Build #
2383 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/2383/
Java: 64bit/jdk1.7.0_10 -XX:+UseG1GC
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.cloud.RecoveryZkTest
Error Message:
Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=3080, name=coreLoadExecutor-1811-thread-1, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.
Stack Trace:
com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=3080, name=coreLoadExecutor-1811-thread-1, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.
at __randomizedtesting.SeedInfo.seed([F1C952D1F8C6D9C7]:0)
at java.lang.Thread.getStackTrace(Thread.java:1567)
at com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(RandomizedContext.java:150)
at org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java:516)
at org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:976)
at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:874)
at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:866)
at org.apache.solr.core.MockDirectoryFactory.create(MockDirectoryFactory.java:33)
at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:267)
at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:223)
at org.apache.solr.core.SolrCore.getNewIndexDir(SolrCore.java:240)
at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:445)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:717)
at org.apache.solr.core.SolrCore.<init>(SolrCore.java:606)
at org.apache.solr.core.CoreContainer.createFromZk(CoreContainer.java:947)
at org.apache.solr.core.CoreContainer.create(CoreContainer.java:1029)
at org.apache.solr.core.CoreContainer$3.call(CoreContainer.java:627)
at org.apache.solr.core.CoreContainer$3.call(CoreContainer.java:622)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
at java.util.concurrent.FutureTask.run(FutureTask.java:166)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
at java.util.concurrent.FutureTask.run(FutureTask.java:166)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
at java.lang.Thread.run(Thread.java:722)
Caused by: java.lang.AssertionError: Directory not closed: BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@6f1e1116 lockFactory=org.apache.lucene.store.NativeFSLockFactory@222ecd48)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.lucene.util.CloseableDirectory.close(CloseableDirectory.java:47)
at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:602)
at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:599)
at com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(RandomizedContext.java:167)
at com.carrotsearch.randomizedtesting.RandomizedRunner$2.afterAlways(RandomizedRunner.java:615)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
... 1 more
Build Log:
[...truncated 9033 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.RecoveryZkTest
[junit4:junit4] 2> 0 T2983 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /rgsp/ll
[junit4:junit4] 2> 7 T2983 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-RecoveryZkTest-1357744064324
[junit4:junit4] 2> 11 T2983 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4] 2> 13 T2984 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4:junit4] 2> 16 T2984 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4] 2> 16 T2984 oazs.ZooKeeperServer.setMinSessionTimeout minSessionTimeout set to -1
[junit4:junit4] 2> 16 T2984 oazs.ZooKeeperServer.setMaxSessionTimeout maxSessionTimeout set to -1
[junit4:junit4] 2> 18 T2984 oazs.NIOServerCnxnFactory.configure binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4] 2> 19 T2984 oazsp.FileTxnSnapLog.save Snapshotting: 0x0 to C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357744064322\zookeeper\server1\data\version-2\snapshot.0
[junit4:junit4] 2> 121 T2983 oasc.ZkTestServer.run start zk server on port:51111
[junit4:junit4] 2> 121 T2983 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51111 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@543e944f
[junit4:junit4] 2> 123 T2983 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 124 T2989 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:51111. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 125 T2989 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51111, initiating session
[junit4:junit4] 2> 125 T2985 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:51114
[junit4:junit4] 2> 125 T2985 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:51114
[junit4:junit4] 2> 127 T2987 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4] 2> 137 T2987 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c1fda47500000 with negotiated timeout 10000 for client /127.0.0.1:51114
[junit4:junit4] 2> 137 T2989 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:51111, sessionid = 0x13c1fda47500000, negotiated timeout = 10000
[junit4:junit4] 2> 139 T2990 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@543e944f name:ZooKeeperConnection Watcher:127.0.0.1:51111 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 139 T2983 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 139 T2983 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4] 2> 144 T2988 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c1fda47500000
[junit4:junit4] 2> 146 T2990 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 146 T2985 oazs.NIOServerCnxn.doIO WARNING caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x13c1fda47500000, likely client has closed socket
[junit4:junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4:junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4:junit4] 2> at java.lang.Thread.run(Thread.java:722)
[junit4:junit4] 2>
[junit4:junit4] 2> 148 T2985 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:51114 which had sessionid 0x13c1fda47500000
[junit4:junit4] 2> 146 T2983 oaz.ZooKeeper.close Session: 0x13c1fda47500000 closed
[junit4:junit4] 2> 149 T2983 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51111/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@276d1b4b
[junit4:junit4] 2> 150 T2983 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 152 T2991 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:51111. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 153 T2991 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51111, initiating session
[junit4:junit4] 2> 153 T2985 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:51117
[junit4:junit4] 2> 153 T2985 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:51117
[junit4:junit4] 2> 156 T2987 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c1fda47500001 with negotiated timeout 10000 for client /127.0.0.1:51117
[junit4:junit4] 2> 156 T2991 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:51111, sessionid = 0x13c1fda47500001, negotiated timeout = 10000
[junit4:junit4] 2> 157 T2992 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@276d1b4b name:ZooKeeperConnection Watcher:127.0.0.1:51111/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 157 T2983 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 157 T2983 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4] 2> 160 T1883 oasc.OverseerCollectionProcessor.run WARNING Overseer cannot talk to ZK
[junit4:junit4] 2> 165 T2983 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4] 2> 170 T2983 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4] 2> 176 T2983 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4] 2> 183 T2983 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4] 2> 185 T2983 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4] 2> 195 T2983 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4:junit4] 2> 197 T2983 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4] 2> 203 T2983 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4] 2> 204 T2983 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4] 2> 209 T2983 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4] 2> 210 T2983 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4] 2> 216 T2983 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4:junit4] 2> 217 T2983 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4] 2> 222 T2983 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4] 2> 222 T2983 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4] 2> 228 T2983 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4] 2> 229 T2983 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4] 2> 238 T2983 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4] 2> 238 T2983 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4] 2> 243 T2983 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4] 2> 244 T2983 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4] 2> 250 T2988 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c1fda47500001
[junit4:junit4] 2> 253 T2985 oazs.NIOServerCnxn.doIO WARNING caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x13c1fda47500001, likely client has closed socket
[junit4:junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4:junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4:junit4] 2> at java.lang.Thread.run(Thread.java:722)
[junit4:junit4] 2>
[junit4:junit4] 2> 253 T2992 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 254 T2985 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:51117 which had sessionid 0x13c1fda47500001
[junit4:junit4] 2> 253 T2983 oaz.ZooKeeper.close Session: 0x13c1fda47500001 closed
[junit4:junit4] 2> 782 T2983 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 788 T2983 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51118
[junit4:junit4] 2> 790 T2983 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 790 T2983 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 791 T2983 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-controljetty-1357744064568
[junit4:junit4] 2> 791 T2983 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-controljetty-1357744064568\solr.xml
[junit4:junit4] 2> 791 T2983 oasc.CoreContainer.<init> New CoreContainer 1353495701
[junit4:junit4] 2> 792 T2983 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-controljetty-1357744064568\'
[junit4:junit4] 2> 792 T2983 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-controljetty-1357744064568\'
[junit4:junit4] 2> 843 T2983 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 60000
[junit4:junit4] 2> 843 T2983 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 844 T2983 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 844 T2983 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 844 T2983 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 845 T2983 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 845 T2983 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 845 T2983 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 846 T2983 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 846 T2983 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=60000&connTimeout=15000&retry=false
[junit4:junit4] 2> 862 T2983 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 883 T2983 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:51111/solr
[junit4:junit4] 2> 883 T2983 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 884 T2983 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51111 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@789ccfea
[junit4:junit4] 2> 885 T2983 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 886 T3002 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:51111. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 887 T3002 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51111, initiating session
[junit4:junit4] 2> 887 T2985 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:51123
[junit4:junit4] 2> 887 T2985 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:51123
[junit4:junit4] 2> 901 T2987 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c1fda47500002 with negotiated timeout 20000 for client /127.0.0.1:51123
[junit4:junit4] 2> 902 T3002 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:51111, sessionid = 0x13c1fda47500002, negotiated timeout = 20000
[junit4:junit4] 2> 902 T3003 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@789ccfea name:ZooKeeperConnection Watcher:127.0.0.1:51111 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 902 T2983 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 905 T2988 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c1fda47500002
[junit4:junit4] 2> 907 T3003 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 907 T2983 oaz.ZooKeeper.close Session: 0x13c1fda47500002 closed
[junit4:junit4] 2> 907 T2985 oazs.NIOServerCnxn.doIO WARNING caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x13c1fda47500002, likely client has closed socket
[junit4:junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4:junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4:junit4] 2> at java.lang.Thread.run(Thread.java:722)
[junit4:junit4] 2>
[junit4:junit4] 2> 908 T2983 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=60000&connTimeout=15000
[junit4:junit4] 2> 908 T2985 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:51123 which had sessionid 0x13c1fda47500002
[junit4:junit4] 2> 916 T2983 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51111/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@14c9ccaa
[junit4:junit4] 2> 917 T2983 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 919 T3004 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:51111. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 919 T3004 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51111, initiating session
[junit4:junit4] 2> 920 T2985 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:51126
[junit4:junit4] 2> 920 T2985 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:51126
[junit4:junit4] 2> 922 T3004 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:51111, sessionid = 0x13c1fda47500003, negotiated timeout = 20000
[junit4:junit4] 2> 922 T2987 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c1fda47500003 with negotiated timeout 20000 for client /127.0.0.1:51126
[junit4:junit4] 2> 923 T3005 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@14c9ccaa name:ZooKeeperConnection Watcher:127.0.0.1:51111/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 923 T2983 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 926 T2988 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c1fda47500003 type:create cxid:0x2 zxid:0x1a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 928 T2983 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4] 2> 933 T2983 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51118_rgsp%2Fll
[junit4:junit4] 2> 933 T2988 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c1fda47500003 type:delete cxid:0x7 zxid:0x1c txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:51118_rgsp%2Fll Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:51118_rgsp%2Fll
[junit4:junit4] 2> 935 T2983 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51118_rgsp%2Fll
[junit4:junit4] 2> 940 T2983 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4] 2> 948 T2988 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c1fda47500003 type:delete cxid:0x15 zxid:0x21 txntype:-1 reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4] 2> 950 T2983 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4] 2> 954 T2983 oasc.Overseer.start Overseer (id=88981115000324099-127.0.0.1:51118_rgsp%2Fll-n_0000000000) starting
[junit4:junit4] 2> 955 T2988 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c1fda47500003 type:create cxid:0x1a zxid:0x23 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 957 T2988 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c1fda47500003 type:create cxid:0x1b zxid:0x24 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 959 T2988 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c1fda47500003 type:create cxid:0x1c zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 960 T2988 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c1fda47500003 type:create cxid:0x1d zxid:0x26 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 962 T3007 oasc.OverseerCollectionProcessor.run Process current queue of collection messages
[junit4:junit4] 2> 963 T2983 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4] 2> 967 T2983 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 970 T3006 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4] 2> 975 T3008 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-controljetty-1357744064568\collection1
[junit4:junit4] 2> 976 T3008 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4] 2> 976 T3008 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 977 T3008 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4] 2> 979 T3008 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-controljetty-1357744064568\collection1\'
[junit4:junit4] 2> 980 T3008 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-controljetty-1357744064568/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 981 T3008 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-controljetty-1357744064568/collection1/lib/README' to classloader
[junit4:junit4] 2> 1037 T3008 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 1114 T3008 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 1115 T3008 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 1120 T3008 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 1787 T3008 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 1798 T3008 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 1802 T3008 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 1824 T3008 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 1832 T3008 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 1837 T3008 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 1839 T3008 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 1841 T3008 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-controljetty-1357744064568\collection1\, dataDir=.\org.apache.solr.cloud.RecoveryZkTest-1357744064322/control/data\
[junit4:junit4] 2> 1841 T3008 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@ab353de
[junit4:junit4] 2> 1842 T3008 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 1842 T3008 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357744064322\control\data forceNew:false
[junit4:junit4] 2> 1842 T3008 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357744064322\control\data
[junit4:junit4] 2> 1843 T3008 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.RecoveryZkTest-1357744064322/control/data\index/
[junit4:junit4] 2> 1844 T3008 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.RecoveryZkTest-1357744064322\control\data\index' doesn't exist. Creating new index...
[junit4:junit4] 2> 1844 T3008 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357744064322\control\data\index forceNew:false
[junit4:junit4] 2> 1849 T3008 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@2f7a83eb lockFactory=org.apache.lucene.store.NativeFSLockFactory@70ea3569)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 1850 T3008 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 1853 T3008 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357744064322\control\data\index
[junit4:junit4] 2> 1854 T3008 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4] 2> 1860 T3008 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 1860 T3008 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4] 2> 1861 T3008 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 1861 T3008 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4] 2> 1861 T3008 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 1861 T3008 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 1862 T3008 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 1862 T3008 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 1862 T3008 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 1863 T3008 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4] 2> 1863 T3008 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4] 2> 1863 T3008 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 1864 T3008 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 1864 T3008 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 1864 T3008 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 1865 T3008 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 1867 T3008 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1867 T3008 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1867 T3008 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1868 T3008 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1868 T3008 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1868 T3008 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1869 T3008 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1869 T3008 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 1869 T3008 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4] 2> 1874 T3008 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4] 2> 1892 T3008 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 1901 T3008 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4] 2> 1902 T3008 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4] 2> 1908 T3008 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357744064322\control\data
[junit4:junit4] 2> 1908 T3008 oass.SolrIndexSearcher.<init> Opening Searcher@7d78b4e main
[junit4:junit4] 2> 1912 T3008 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 1912 T3008 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 1913 T3008 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4] 2> 1937 T3008 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4] 2> 2046 T3009 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7d78b4e main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 2050 T3008 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 2050 T3008 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 2052 T2988 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c1fda47500003 type:create cxid:0x50 zxid:0x29 txntype:-1 reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4] 2> 2591 T3006 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 2592 T3006 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "shard":null,
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "state":"down",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "collection":"control_collection",
[junit4:junit4] 2> "node_name":"127.0.0.1:51118_rgsp%2Fll",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:51118/rgsp/ll"}
[junit4:junit4] 2> 2592 T3006 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with numShards 1
[junit4:junit4] 2> 2593 T3006 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 2593 T2988 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c1fda47500003 type:create cxid:0x58 zxid:0x2c txntype:-1 reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4] 2> 2607 T3005 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4] 2> 3127 T3008 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 3127 T3008 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:51118/rgsp/ll collection:control_collection shard:shard1
[junit4:junit4] 2> 3128 T3008 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4:junit4] 2> 3140 T2988 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c1fda47500003 type:delete cxid:0x72 zxid:0x36 txntype:-1 reqpath:n/a Error Path:/solr/collections/control_collection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/control_collection/leaders
[junit4:junit4] 2> 3141 T3008 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4] 2> 3141 T2988 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c1fda47500003 type:create cxid:0x73 zxid:0x37 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 3145 T3008 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4] 2> 3145 T3008 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4] 2> 3145 T3008 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:51118/rgsp/ll/collection1/
[junit4:junit4] 2> 3145 T3008 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4] 2> 3145 T3008 oasc.SyncStrategy.syncToMe http://127.0.0.1:51118/rgsp/ll/collection1/ has no replicas
[junit4:junit4] 2> 3145 T3008 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:51118/rgsp/ll/collection1/
[junit4:junit4] 2> 3145 T3008 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4:junit4] 2> 3152 T2988 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c1fda47500003 type:create cxid:0x7d zxid:0x3b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 4140 T3006 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 4223 T3005 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4] 2> 4273 T3008 oasc.ZkController.register We are http://127.0.0.1:51118/rgsp/ll/collection1/ and leader is http://127.0.0.1:51118/rgsp/ll/collection1/
[junit4:junit4] 2> 4273 T3008 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51118/rgsp/ll
[junit4:junit4] 2> 4273 T3008 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4] 2> 4273 T3008 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4] 2> 4274 T3008 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 4305 T3008 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 4307 T2983 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4] 2> 4307 T2983 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 4307 T2983 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 4314 T2983 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4] 2> 4319 T2983 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51111/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@7be6ba1a
[junit4:junit4] 2> 4321 T2983 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 4321 T3010 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:51111. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 4322 T3010 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51111, initiating session
[junit4:junit4] 2> 4322 T2985 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:51129
[junit4:junit4] 2> 4323 T2985 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:51129
[junit4:junit4] 2> 4353 T2987 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c1fda47500004 with negotiated timeout 10000 for client /127.0.0.1:51129
[junit4:junit4] 2> 4353 T3010 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:51111, sessionid = 0x13c1fda47500004, negotiated timeout = 10000
[junit4:junit4] 2> 4354 T3011 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7be6ba1a name:ZooKeeperConnection Watcher:127.0.0.1:51111/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 4354 T2983 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 4355 T2983 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 4358 T2983 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4] 2> 4691 T2983 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 4695 T2983 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51130
[junit4:junit4] 2> 4696 T2983 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 4697 T2983 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 4697 T2983 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty1-1357744068672
[junit4:junit4] 2> 4697 T2983 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty1-1357744068672\solr.xml
[junit4:junit4] 2> 4698 T2983 oasc.CoreContainer.<init> New CoreContainer 1988518680
[junit4:junit4] 2> 4698 T2983 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty1-1357744068672\'
[junit4:junit4] 2> 4699 T2983 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty1-1357744068672\'
[junit4:junit4] 2> 4742 T2983 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 60000
[junit4:junit4] 2> 4743 T2983 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 4743 T2983 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 4743 T2983 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 4744 T2983 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 4744 T2983 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 4745 T2983 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 4745 T2983 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 4745 T2983 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 4746 T2983 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=60000&connTimeout=15000&retry=false
[junit4:junit4] 2> 4760 T2983 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 4779 T2983 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:51111/solr
[junit4:junit4] 2> 4780 T2983 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 4780 T2983 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51111 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@a9f416b
[junit4:junit4] 2> 4782 T2983 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 4782 T3021 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:51111. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 4784 T3021 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51111, initiating session
[junit4:junit4] 2> 4784 T2985 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:51135
[junit4:junit4] 2> 4784 T2985 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:51135
[junit4:junit4] 2> 4801 T2987 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c1fda47500005 with negotiated timeout 20000 for client /127.0.0.1:51135
[junit4:junit4] 2> 4801 T3021 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:51111, sessionid = 0x13c1fda47500005, negotiated timeout = 20000
[junit4:junit4] 2> 4802 T3022 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@a9f416b name:ZooKeeperConnection Watcher:127.0.0.1:51111 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 4802 T2983 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 4804 T2988 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c1fda47500005
[junit4:junit4] 2> 4811 T3022 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 4811 T2985 oazs.NIOServerCnxn.doIO WARNING caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x13c1fda47500005, likely client has closed socket
[junit4:junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4:junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4:junit4] 2> at java.lang.Thread.run(Thread.java:722)
[junit4:junit4] 2>
[junit4:junit4] 2> 4811 T2983 oaz.ZooKeeper.close Session: 0x13c1fda47500005 closed
[junit4:junit4] 2> 4812 T2985 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:51135 which had sessionid 0x13c1fda47500005
[junit4:junit4] 2> 4812 T2983 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=60000&connTimeout=15000
[junit4:junit4] 2> 4817 T2983 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51111/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@258e398b
[junit4:junit4] 2> 4818 T2983 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 4819 T3023 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:51111. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 4820 T3023 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51111, initiating session
[junit4:junit4] 2> 4820 T2985 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:51138
[junit4:junit4] 2> 4820 T2985 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:51138
[junit4:junit4] 2> 4842 T2987 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c1fda47500006 with negotiated timeout 20000 for client /127.0.0.1:51138
[junit4:junit4] 2> 4842 T3023 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:51111, sessionid = 0x13c1fda47500006, negotiated timeout = 20000
[junit4:junit4] 2> 4842 T3024 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@258e398b name:ZooKeeperConnection Watcher:127.0.0.1:51111/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 4843 T2983 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 4844 T2988 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c1fda47500006 type:create cxid:0x1 zxid:0x49 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 4861 T2988 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c1fda47500006 type:create cxid:0x2 zxid:0x4a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 4892 T2983 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51130_rgsp%2Fll
[junit4:junit4] 2> 4893 T2988 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c1fda47500006 type:delete cxid:0x4 zxid:0x4b txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:51130_rgsp%2Fll Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:51130_rgsp%2Fll
[junit4:junit4] 2> 4912 T2983 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51130_rgsp%2Fll
[junit4:junit4] 2> 4932 T3011 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4] 2> 4932 T3005 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4] 2> 4932 T3005 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 4963 T2983 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 4972 T3025 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty1-1357744068672\collection1
[junit4:junit4] 2> 4972 T3025 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4] 2> 4973 T3025 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 4973 T3025 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4] 2> 4974 T3025 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty1-1357744068672\collection1\'
[junit4:junit4] 2> 4976 T3025 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-jetty1-1357744068672/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 4976 T3025 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-jetty1-1357744068672/collection1/lib/README' to classloader
[junit4:junit4] 2> 5028 T3025 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 5097 T3025 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 5099 T3025 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 5103 T3025 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 5743 T3025 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 5753 T3025 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 5757 T3025 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 5799 T3006 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 5800 T3006 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "shard":"shard1",
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "state":"active",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "collection":"control_collection",
[junit4:junit4] 2> "node_name":"127.0.0.1:51118_rgsp%2Fll",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:51118/rgsp/ll"}
[junit4:junit4] 2> 5875 T3024 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 5875 T3011 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 5875 T3005 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 5910 T3025 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 5916 T3025 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 5922 T3025 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 5924 T3025 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 5926 T3025 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty1-1357744068672\collection1\, dataDir=.\org.apache.solr.cloud.RecoveryZkTest-1357744064322/jetty1\
[junit4:junit4] 2> 5926 T3025 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@ab353de
[junit4:junit4] 2> 5927 T3025 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 5927 T3025 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357744064322\jetty1 forceNew:false
[junit4:junit4] 2> 5928 T3025 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357744064322\jetty1
[junit4:junit4] 2> 5928 T3025 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.RecoveryZkTest-1357744064322/jetty1\index/
[junit4:junit4] 2> 5929 T3025 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.RecoveryZkTest-1357744064322\jetty1\index' doesn't exist. Creating new index...
[junit4:junit4] 2> 5929 T3025 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357744064322\jetty1\index forceNew:false
[junit4:junit4] 2> 5932 T3025 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@2e065df8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@58a67a0d)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 5932 T3025 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 5934 T3025 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357744064322\jetty1\index
[junit4:junit4] 2> 5934 T3025 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4] 2> 5937 T3025 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 5937 T3025 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4] 2> 5937 T3025 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 5937 T3025 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4] 2> 5938 T3025 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 5938 T3025 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 5939 T3025 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 5939 T3025 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 5940 T3025 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 5940 T3025 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4] 2> 5941 T3025 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4] 2> 5941 T3025 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 5942 T3025 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 5942 T3025 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 5942 T3025 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 5943 T3025 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 5943 T3025 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 5944 T3025 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 5944 T3025 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 5945 T3025 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 5945 T3025 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 5946 T3025 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 5946 T3025 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 5948 T3025 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 5949 T3025 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4] 2> 5949 T3025 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4] 2> 5960 T3025 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 5965 T3025 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4] 2> 5966 T3025 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4] 2> 5968 T3025 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357744064322\jetty1
[junit4:junit4] 2> 5969 T3025 oass.SolrIndexSearcher.<init> Opening Searcher@7a976ef3 main
[junit4:junit4] 2> 5970 T3025 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 5970 T3025 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 5970 T3025 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4] 2> 5994 T3025 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4] 2> 6226 T3026 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7a976ef3 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 6231 T3025 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 6231 T3025 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 7407 T3006 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 7409 T3006 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "shard":null,
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "state":"down",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "node_name":"127.0.0.1:51130_rgsp%2Fll",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:51130/rgsp/ll"}
[junit4:junit4] 2> 7410 T3006 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 1
[junit4:junit4] 2> 7410 T3006 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 7470 T3011 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 7470 T3005 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 7470 T3024 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 8237 T3025 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 8237 T3025 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:51130/rgsp/ll collection:collection1 shard:shard1
[junit4:junit4] 2> 8238 T3025 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4] 2> 8331 T2988 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c1fda47500006 type:delete cxid:0x45 zxid:0x5b txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4] 2> 8349 T3025 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4] 2> 8349 T2988 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c1fda47500006 type:create cxid:0x46 zxid:0x5c txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 8400 T3025 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4] 2> 8400 T3025 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4] 2> 8400 T3025 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:51130/rgsp/ll/collection1/
[junit4:junit4] 2> 8400 T3025 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4] 2> 8400 T3025 oasc.SyncStrategy.syncToMe http://127.0.0.1:51130/rgsp/ll/collection1/ has no replicas
[junit4:junit4] 2> 8401 T3025 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:51130/rgsp/ll/collection1/
[junit4:junit4] 2> 8401 T3025 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4] 2> 8450 T2988 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c1fda47500006 type:create cxid:0x50 zxid:0x60 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 8993 T3006 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 9099 T3024 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 9099 T3011 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 9099 T3005 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 9119 T3025 oasc.ZkController.register We are http://127.0.0.1:51130/rgsp/ll/collection1/ and leader is http://127.0.0.1:51130/rgsp/ll/collection1/
[junit4:junit4] 2> 9119 T3025 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51130/rgsp/ll
[junit4:junit4] 2> 9119 T3025 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4] 2> 9119 T3025 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4] 2> 9119 T3025 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 9168 T3025 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 9170 T2983 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4] 2> 9170 T2983 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 9171 T2983 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 9521 T2983 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 9524 T2983 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:51139
[junit4:junit4] 2> 9526 T2983 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 9526 T2983 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 9527 T2983 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty2-1357744073492
[junit4:junit4] 2> 9527 T2983 oasc.CoreContainer$Initializer.initialize looking for solr.xml: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty2-1357744073492\solr.xml
[junit4:junit4] 2> 9527 T2983 oasc.CoreContainer.<init> New CoreContainer 1004627990
[junit4:junit4] 2> 9528 T2983 oasc.CoreContainer.load Loading CoreContainer using Solr Home: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty2-1357744073492\'
[junit4:junit4] 2> 9528 T2983 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty2-1357744073492\'
[junit4:junit4] 2> 9578 T2983 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 60000
[junit4:junit4] 2> 9578 T2983 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 9579 T2983 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 9579 T2983 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 9579 T2983 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 9580 T2983 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 9580 T2983 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 9580 T2983 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 9580 T2983 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 9581 T2983 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=60000&connTimeout=15000&retry=false
[junit4:junit4] 2> 9599 T2983 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 9625 T2983 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:51111/solr
[junit4:junit4] 2> 9626 T2983 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 9627 T2983 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51111 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@2b8381ab
[junit4:junit4] 2> 9629 T2983 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 9630 T3036 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:51111. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 9632 T3036 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51111, initiating session
[junit4:junit4] 2> 9632 T2985 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:51144
[junit4:junit4] 2> 9632 T2985 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:51144
[junit4:junit4] 2> 9650 T2987 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c1fda47500007 with negotiated timeout 20000 for client /127.0.0.1:51144
[junit4:junit4] 2> 9650 T3036 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:51111, sessionid = 0x13c1fda47500007, negotiated timeout = 20000
[junit4:junit4] 2> 9651 T3037 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2b8381ab name:ZooKeeperConnection Watcher:127.0.0.1:51111 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 9651 T2983 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 9652 T2988 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c1fda47500007
[junit4:junit4] 2> 9674 T3037 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 9674 T2985 oazs.NIOServerCnxn.doIO WARNING caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x13c1fda47500007, likely client has closed socket
[junit4:junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4:junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4:junit4] 2> at java.lang.Thread.run(Thread.java:722)
[junit4:junit4] 2>
[junit4:junit4] 2> 9674 T2983 oaz.ZooKeeper.close Session: 0x13c1fda47500007 closed
[junit4:junit4] 2> 9675 T2985 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:51144 which had sessionid 0x13c1fda47500007
[junit4:junit4] 2> 9675 T2983 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=60000&connTimeout=15000
[junit4:junit4] 2> 9680 T2983 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:51111/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@407c2c8b
[junit4:junit4] 2> 9682 T2983 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 9683 T3038 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:51111. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 9685 T3038 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:51111, initiating session
[junit4:junit4] 2> 9685 T2985 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:51147
[junit4:junit4] 2> 9686 T2985 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:51147
[junit4:junit4] 2> 9704 T2987 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c1fda47500008 with negotiated timeout 20000 for client /127.0.0.1:51147
[junit4:junit4] 2> 9704 T3038 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:51111, sessionid = 0x13c1fda47500008, negotiated timeout = 20000
[junit4:junit4] 2> 9705 T3039 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@407c2c8b name:ZooKeeperConnection Watcher:127.0.0.1:51111/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 9705 T2983 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 9706 T2988 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c1fda47500008 type:create cxid:0x1 zxid:0x6d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 9734 T2988 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c1fda47500008 type:create cxid:0x2 zxid:0x6e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 9765 T2983 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51139_rgsp%2Fll
[junit4:junit4] 2> 9765 T2988 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c1fda47500008 type:delete cxid:0x4 zxid:0x6f txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:51139_rgsp%2Fll Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:51139_rgsp%2Fll
[junit4:junit4] 2> 9795 T2983 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51139_rgsp%2Fll
[junit4:junit4] 2> 9814 T3024 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4:junit4] 2> 9815 T3005 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 9815 T3005 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 9816 T3011 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 9816 T3011 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 9819 T3024 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 9847 T2983 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 9854 T3040 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty2-1357744073492\collection1
[junit4:junit4] 2> 9854 T3040 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4] 2> 9855 T3040 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 9855 T3040 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4] 2> 9858 T3040 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty2-1357744073492\collection1\'
[junit4:junit4] 2> 9860 T3040 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-jetty2-1357744073492/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 9860 T3040 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.RecoveryZkTest-jetty2-1357744073492/collection1/lib/README' to classloader
[junit4:junit4] 2> 9912 T3040 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 9983 T3040 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 9984 T3040 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 9989 T3040 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 10649 T3040 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 10659 T3040 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 10663 T3040 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 10671 T3006 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 10671 T3006 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "shard":"shard1",
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "state":"active",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "node_name":"127.0.0.1:51130_rgsp%2Fll",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:51130/rgsp/ll"}
[junit4:junit4] 2> 10714 T3024 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 10714 T3011 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 10714 T3005 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 10715 T3039 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 10724 T3040 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 10728 T3040 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 10732 T3040 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 10735 T3040 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 10737 T3040 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-jetty2-1357744073492\collection1\, dataDir=.\org.apache.solr.cloud.RecoveryZkTest-1357744064322/jetty2\
[junit4:junit4] 2> 10737 T3040 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@ab353de
[junit4:junit4] 2> 10738 T3040 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 10739 T3040 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357744064322\jetty2 forceNew:false
[junit4:junit4] 2> 10739 T3040 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357744064322\jetty2
[junit4:junit4] 2> 10739 T3040 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.RecoveryZkTest-1357744064322/jetty2\index/
[junit4:junit4] 2> 10740 T3040 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.RecoveryZkTest-1357744064322\jetty2\index' doesn't exist. Creating new index...
[junit4:junit4] 2> 10741 T3040 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357744064322\jetty2\index forceNew:false
[junit4:junit4] 2> 10745 T3040 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@2ba14113 lockFactory=org.apache.lucene.store.NativeFSLockFactory@372bbeb5)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 10745 T3040 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 10747 T3040 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357744064322\jetty2\index
[junit4:junit4] 2> 10748 T3040 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4] 2> 10750 T3040 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 10750 T3040 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4] 2> 10751 T3040 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 10751 T3040 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4] 2> 10752 T3040 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 10752 T3040 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 10752 T3040 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 10753 T3040 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 10753 T3040 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 10754 T3040 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4] 2> 10754 T3040 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4] 2> 10755 T3040 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 10755 T3040 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 10756 T3040 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 10756 T3040 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 10756 T3040 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 10757 T3040 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 10757 T3040 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 10758 T3040 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 10758 T3040 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 10759 T3040 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 10759 T3040 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 10760 T3040 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 10760 T3040 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 10761 T3040 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4] 2> 10761 T3040 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4] 2> 10772 T3040 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 10777 T3040 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4] 2> 10779 T3040 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4] 2> 10781 T3040 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357744064322\jetty2
[junit4:junit4] 2> 10782 T3040 oass.SolrIndexSearcher.<init> Opening Searcher@7f321e72 main
[junit4:junit4] 2> 10784 T3040 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 10784 T3040 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 10785 T3040 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4] 2> 10796 T3040 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4] 2> 10856 T3041 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7f321e72 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 10861 T3040 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 10861 T3040 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 12222 T3006 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 12223 T3006 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "shard":null,
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "state":"down",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "node_name":"127.0.0.1:51139_rgsp%2Fll",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:51139/rgsp/ll"}
[junit4:junit4] 2> 12224 T3006 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
[junit4:junit4] 2> 12224 T3006 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 12237 T3024 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 12237 T3005 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 12238 T3039 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 12237 T3011 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 12863 T3040 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 12863 T3040 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:51139/rgsp/ll collection:collection1 shard:shard1
[junit4:junit4] 2> 12868 T3040 oasc.ZkController.register We are http://127.0.0.1:51139/rgsp/ll/collection1/ and leader is http://127.0.0.1:51130/rgsp/ll/collection1/
[junit4:junit4] 2> 12868 T3040 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51139/rgsp/ll
[junit4:junit4] 2> 12868 T3040 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4] 2> 12868 T3040 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4] 2> ASYNC NEW_CORE C194 name=collection1 org.apache.solr.core.SolrCore@6687a725 url=http://127.0.0.1:51139/rgsp/ll/collection1 node=127.0.0.1:51139_rgsp%2Fll C194_STATE=coll:collection1 core:collection1 props:{shard=null, roles=null, state=down, core=collection1, collection=collection1, node_name=127.0.0.1:51139_rgsp%2Fll, base_url=http://127.0.0.1:51139/rgsp/ll}
[junit4:junit4] 2> 12869 T3042 C194 P51139 oasc.RecoveryStrategy.run Starting recovery process. core=collection1 recoveringAfterStartup=true
[junit4:junit4] 2> 12871 T3042 C194 P51139 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4] 2> 12871 T3040 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 12871 T3042 C194 P51139 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4] 2> 12872 T3042 C194 P51139 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 12873 T2983 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4] 2> 12873 T2983 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 12874 T2983 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 12875 T3042 C194 P51139 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 12888 T2983 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> ASYNC NEW_CORE C195 name=collection1 org.apache.solr.core.SolrCore@bcc84d3 url=http://127.0.0.1:51118/rgsp/ll/collection1 node=127.0.0.1:51118_rgsp%2Fll C195_STATE=coll:control_collection core:collection1 props:{shard=shard1, roles=null, state=active, core=collection1, collection=control_collection, node_name=127.0.0.1:51118_rgsp%2Fll, base_url=http://127.0.0.1:51118/rgsp/ll, leader=true}
[junit4:junit4] 2> 12906 T2998 C195 P51118 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357744064322\control\data
[junit4:junit4] 2> 12910 T2998 C195 P51118 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@2f7a83eb lockFactory=org.apache.lucene.store.NativeFSLockFactory@70ea3569)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 12910 T2998 C195 P51118 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 12914 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[10001 (1423697853518184448)]} 0 5
[junit4:junit4] 2> 12914 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[1 (1423697853513990144)]} 0 9
[junit4:junit4] 2> ASYNC NEW_CORE C196 name=collection1 org.apache.solr.core.SolrCore@4c29c4e url=http://127.0.0.1:51130/rgsp/ll/collection1 node=127.0.0.1:51130_rgsp%2Fll C196_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:51130_rgsp%2Fll, base_url=http://127.0.0.1:51130/rgsp/ll, leader=true}
[junit4:junit4] 2> 12926 T3017 C196 P51130 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357744064322\jetty1
[junit4:junit4] 2> 12930 T3017 C196 P51130 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@2e065df8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@58a67a0d)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 12930 T3017 C196 P51130 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 12932 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[1 (1423697853534961664)]} 0 8
[junit4:junit4] 2> 12932 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[10001 (1423697853534961665)]} 0 8
[junit4:junit4] 2> 12938 T3000 C195 P51118 /update {wt=javabin&version=2} {delete=[1 (-1423697853546496000)]} 0 2
[junit4:junit4] 2> 12939 T3001 C195 P51118 /update {wt=javabin&version=2} {delete=[10001 (-1423697853546496001)]} 0 3
[junit4:junit4] 2> 12943 T3020 C196 P51130 /update {wt=javabin&version=2} {delete=[1 (-1423697853552787456)]} 0 1
[junit4:junit4] 2> 12943 T3019 C196 P51130 /update {wt=javabin&version=2} {delete=[10001 (-1423697853552787457)]} 0 1
[junit4:junit4] 2> 12947 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[2 (1423697853556981761)]} 0 1
[junit4:junit4] 2> 12948 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[10002 (1423697853556981760)]} 0 2
[junit4:junit4] 2> 12951 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[10002 (1423697853561176064)]} 0 1
[junit4:junit4] 2> 12952 T3015 C196 P51130 /update {wt=javabin&version=2} {add=[2 (1423697853561176065)]} 0 2
[junit4:junit4] 2> 12955 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[10003 (1423697853565370368)]} 0 1
[junit4:junit4] 2> 12956 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[3 (1423697853566418944)]} 0 1
[junit4:junit4] 2> 12959 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[10003 (1423697853569564672)]} 0 1
[junit4:junit4] 2> 12960 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[3 (1423697853570613248)]} 0 1
[junit4:junit4] 2> 12963 T3000 C195 P51118 /update {wt=javabin&version=2} {delete=[10003 (-1423697853574807552)]} 0 0
[junit4:junit4] 2> 12963 T3001 C195 P51118 /update {wt=javabin&version=2} {delete=[3 (-1423697853574807553)]} 0 0
[junit4:junit4] 2> 12965 T3019 C196 P51130 /update {wt=javabin&version=2} {delete=[10003 (-1423697853576904704)]} 0 0
[junit4:junit4] 2> 12965 T3017 C196 P51130 /update {wt=javabin&version=2} {delete=[3 (-1423697853576904705)]} 0 0
[junit4:junit4] 2> 12969 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[10004 (1423697853580050432)]} 0 1
[junit4:junit4] 2> 12969 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[4 (1423697853580050433)]} 0 1
[junit4:junit4] 2> 12973 T3015 C196 P51130 /update {wt=javabin&version=2} {add=[10004 (1423697853584244736)]} 0 1
[junit4:junit4] 2> 12974 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[4 (1423697853584244737)]} 0 2
[junit4:junit4] 2> 12976 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[10005 (1423697853587390464)]} 0 1
[junit4:junit4] 2> 12979 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[5 (1423697853589487616)]} 0 3
[junit4:junit4] 2> 12981 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[10005 (1423697853592633344)]} 0 1
[junit4:junit4] 2> 12986 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[5 (1423697853594730496)]} 0 4
[junit4:junit4] 2> 12986 T3001 C195 P51118 /update {wt=javabin&version=2} {add=[10006 (1423697853595779072)]} 0 3
[junit4:junit4] 2> 12992 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[6 (1423697853602070528)]} 0 3
[junit4:junit4] 2> 12992 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[10006 (1423697853602070528)]} 0 3
[junit4:junit4] 2> 12996 T3015 C196 P51130 /update {wt=javabin&version=2} {add=[6 (1423697853609410560)]} 0 0
[junit4:junit4] 2> 12996 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[10007 (1423697853609410560)]} 0 0
[junit4:junit4] 2> 13000 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[7 (1423697853612556288)]} 0 1
[junit4:junit4] 2> 13001 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[10007 (1423697853614653440)]} 0 1
[junit4:junit4] 2> 13002 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[7 (1423697853615702016)]} 0 0
[junit4:junit4] 2> 13006 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[10008 (1423697853618847744)]} 0 1
[junit4:junit4] 2> 13007 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[8 (1423697853620944896)]} 0 0
[junit4:junit4] 2> 13010 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[10008 (1423697853623042048)]} 0 1
[junit4:junit4] 2> 13012 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[8 (1423697853625139200)]} 0 1
[junit4:junit4] 2> 13013 T3001 C195 P51118 /update {wt=javabin&version=2} {delete=[10007 (-1423697853627236352)]} 0 0
[junit4:junit4] 2> 13014 T3000 C195 P51118 /update {wt=javabin&version=2} {delete=[7 (-1423697853628284928)]} 0 0
[junit4:junit4] 2> 13015 T3015 C196 P51130 /update {wt=javabin&version=2} {delete=[10007 (-1423697853629333504)]} 0 0
[junit4:junit4] 2> 13016 T3018 C196 P51130 /update {wt=javabin&version=2} {delete=[7 (-1423697853630382080)]} 0 0
[junit4:junit4] 2> 13019 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[10009 (1423697853632479232)]} 0 1
[junit4:junit4] 2> 13021 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[9 (1423697853633527808)]} 0 2
[junit4:junit4] 2> 13022 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[10009 (1423697853635624960)]} 0 1
[junit4:junit4] 2> 13023 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[9 (1423697853637722112)]} 0 0
[junit4:junit4] 2> 13025 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[10010 (1423697853638770688)]} 0 1
[junit4:junit4] 2> 13029 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[10010 (1423697853642964992)]} 0 1
[junit4:junit4] 2> 13029 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[10 (1423697853640867840)]} 0 3
[junit4:junit4] 2> 13033 T3001 C195 P51118 /update {wt=javabin&version=2} {add=[10011 (1423697853647159296)]} 0 1
[junit4:junit4] 2> 13036 T3015 C196 P51130 /update {wt=javabin&version=2} {add=[10 (1423697853647159296)]} 0 4
[junit4:junit4] 2> 13037 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[10011 (1423697853651353600)]} 0 1
[junit4:junit4] 2> 13039 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[11 (1423697853653450752)]} 0 1
[junit4:junit4] 2> 13041 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[10012 (1423697853655547904)]} 0 1
[junit4:junit4] 2> 13042 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[11 (1423697853656596480)]} 0 1
[junit4:junit4] 2> 13044 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[10012 (1423697853658693632)]} 0 1
[junit4:junit4] 2> 13044 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[12 (1423697853659742208)]} 0 0
[junit4:junit4] 2> 13047 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[10013 (1423697853661839360)]} 0 1
[junit4:junit4] 2> 13048 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[12 (1423697853663936512)]} 0 0
[junit4:junit4] 2> 13050 T3015 C196 P51130 /update {wt=javabin&version=2} {add=[10013 (1423697853664985088)]} 0 1
[junit4:junit4] 2> 13051 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[13 (1423697853667082240)]} 0 0
[junit4:junit4] 2> 13053 T3001 C195 P51118 /update {wt=javabin&version=2} {add=[10014 (1423697853669179392)]} 0 0
[junit4:junit4] 2> 13055 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[13 (1423697853671276544)]} 0 0
[junit4:junit4] 2> 13057 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[10014 (1423697853673373696)]} 0 0
[junit4:junit4] 2> 13058 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[14 (1423697853674422272)]} 0 0
[junit4:junit4] 2> 13059 T2997 C195 P51118 /update {wt=javabin&version=2} {delete=[10010 (-1423697853675470848)]} 0 0
[junit4:junit4] 2> 13062 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[14 (1423697853677568000)]} 0 1
[junit4:junit4] 2> 13063 T3019 C196 P51130 /update {wt=javabin&version=2} {delete=[10010 (-1423697853679665152)]} 0 0
[junit4:junit4] 2> 13064 T2998 C195 P51118 /update {wt=javabin&version=2} {delete=[10 (-1423697853680713728)]} 0 0
[junit4:junit4] 2> 13067 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[10015 (1423697853682810880)]} 0 1
[junit4:junit4] 2> 13067 T3015 C196 P51130 /update {wt=javabin&version=2} {delete=[10 (-1423697853683859456)]} 0 0
[junit4:junit4] 2> 13070 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[10015 (1423697853685956608)]} 0 1
[junit4:junit4] 2> 13074 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[15 (1423697853688053760)]} 0 4
[junit4:junit4] 2> 13075 T3001 C195 P51118 /update {wt=javabin&version=2} {add=[10016 (1423697853691199488)]} 0 1
[junit4:junit4] 2> 13079 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[15 (1423697853693296640)]} 0 3
[junit4:junit4] 2> 13080 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[10016 (1423697853694345216)]} 0 3
[junit4:junit4] 2> 13083 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[16 (1423697853699588096)]} 0 1
[junit4:junit4] 2> 13084 T2997 C195 P51118 /update {wt=javabin&version=2} {delete=[10013 (-1423697853701685248)]} 0 0
[junit4:junit4] 2> 13087 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[16 (1423697853703782400)]} 0 1
[junit4:junit4] 2> 13087 T3015 C196 P51130 /update {wt=javabin&version=2} {delete=[10013 (-1423697853704830976)]} 0 0
[junit4:junit4] 2> 13089 T2998 C195 P51118 /update {wt=javabin&version=2} {delete=[13 (-1423697853706928128)]} 0 0
[junit4:junit4] 2> 13093 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[10017 (1423697853710073856)]} 0 1
[junit4:junit4] 2> 13094 T3018 C196 P51130 /update {wt=javabin&version=2} {delete=[13 (-1423697853712171008)]} 0 0
[junit4:junit4] 2> 13097 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[10017 (1423697853714268160)]} 0 1
[junit4:junit4] 2> 13098 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[17 (1423697853715316736)]} 0 1
[junit4:junit4] 2> 13099 T3001 C195 P51118 /update {wt=javabin&version=2} {delete=[10015 (-1423697853717413888)]} 0 0
[junit4:junit4] 2> 13101 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[17 (1423697853718462464)]} 0 1
[junit4:junit4] 2> 13102 T3019 C196 P51130 /update {wt=javabin&version=2} {delete=[10015 (-1423697853720559616)]} 0 0
[junit4:junit4] 2> 13104 T2997 C195 P51118 /update {wt=javabin&version=2} {delete=[15 (-1423697853722656769)]} 0 0
[junit4:junit4] 2> 13105 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[10018 (1423697853722656768)]} 0 1
[junit4:junit4] 2> 13107 T3015 C196 P51130 /update {wt=javabin&version=2} {delete=[15 (-1423697853725802496)]} 0 0
[junit4:junit4] 2> 13109 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[10018 (1423697853726851072)]} 0 1
[junit4:junit4] 2> 13111 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[18 (1423697853728948224)]} 0 1
[junit4:junit4] 2> 13112 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[10019 (1423697853729996800)]} 0 1
[junit4:junit4] 2> 13114 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[18 (1423697853732093952)]} 0 1
[junit4:junit4] 2> 13115 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[10019 (1423697853733142528)]} 0 1
[junit4:junit4] 2> 13116 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[19 (1423697853735239680)]} 0 0
[junit4:junit4] 2> 13118 T3001 C195 P51118 /update {wt=javabin&version=2} {add=[10020 (1423697853737336832)]} 0 0
[junit4:junit4] 2> 13120 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[19 (1423697853739433984)]} 0 0
[junit4:junit4] 2> 13123 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[10020 (1423697853741531136)]} 0 1
[junit4:junit4] 2> 13125 T3000 C195 P51118 /update {wt=javabin&version=2} {delete=[10016 (-1423697853744676864)]} 0 0
[junit4:junit4] 2> 13129 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[20 (1423697853743628288)]} 0 5
[junit4:junit4] 2> 13130 T3018 C196 P51130 /update {wt=javabin&version=2} {delete=[10016 (-1423697853749919744)]} 0 0
[junit4:junit4] 2> 13133 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[10021 (1423697853752016896)]} 0 1
[junit4:junit4] 2> 13135 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[20 (1423697853752016896)]} 0 3
[junit4:junit4] 2> 13137 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[10021 (1423697853756211200)]} 0 1
[junit4:junit4] 2> 13138 T2999 C195 P51118 /update {wt=javabin&version=2} {delete=[16 (-1423697853758308352)]} 0 0
[junit4:junit4] 2> 13140 T2996 C195 P51118 /update {wt=javabin&version=2} {delete=[10018 (-1423697853760405504)]} 0 0
[junit4:junit4] 2> 13140 T3015 C196 P51130 /update {wt=javabin&version=2} {delete=[16 (-1423697853760405504)]} 0 0
[junit4:junit4] 2> 13142 T3019 C196 P51130 /update {wt=javabin&version=2} {delete=[10018 (-1423697853762502656)]} 0 0
[junit4:junit4] 2> 13144 T3001 C195 P51118 /update {wt=javabin&version=2} {add=[21 (1423697853763551232)]} 0 1
[junit4:junit4] 2> 13146 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[10022 (1423697853765648384)]} 0 1
[junit4:junit4] 2> 13149 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[21 (1423697853767745536)]} 0 2
[junit4:junit4] 2> 13150 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[10022 (1423697853770891264)]} 0 0
[junit4:junit4] 2> 13154 T2998 C195 P51118 /update {wt=javabin&version=2} {delete=[10019 (-1423697853775085568)]} 0 0
[junit4:junit4] 2> 13155 T2997 C195 P51118 /update {wt=javabin&version=2} {delete=[18 (-1423697853776134144)]} 0 1
[junit4:junit4] 2> 13156 T3017 C196 P51130 /update {wt=javabin&version=2} {delete=[10019 (-1423697853777182720)]} 0 0
[junit4:junit4] 2> 13157 T3015 C196 P51130 /update {wt=javabin&version=2} {delete=[18 (-1423697853778231296)]} 0 0
[junit4:junit4] 2> 13160 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[10023 (1423697853780328448)]} 0 0
[junit4:junit4] 2> 13163 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[22 (1423697853783474176)]} 0 1
[junit4:junit4] 2> 13165 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[10023 (1423697853785571328)]} 0 1
[junit4:junit4] 2> 13167 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[22 (1423697853788717056)]} 0 1
[junit4:junit4] 2> 13169 T3001 C195 P51118 /update {wt=javabin&version=2} {add=[10024 (1423697853789765632)]} 0 1
[junit4:junit4] 2> 13181 T3000 C195 P51118 /update {wt=javabin&version=2} {delete=[19 (-1423697853803397120)]} 0 0
[junit4:junit4] 2> 13182 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[10024 (1423697853804445696)]} 0 0
[junit4:junit4] 2> 13185 T2998 C195 P51118 /update {wt=javabin&version=2} {delete=[10020 (-1423697853807591424)]} 0 0
[junit4:junit4] 2> 13186 T3017 C196 P51130 /update {wt=javabin&version=2} {delete=[19 (-1423697853808640000)]} 0 0
[junit4:junit4] 2> 13188 T3015 C196 P51130 /update {wt=javabin&version=2} {delete=[10020 (-1423697853810737152)]} 0 0
[junit4:junit4] 2> 13192 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[23 (1423697853813882880)]} 0 1
[junit4:junit4] 2> 13193 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[10025 (1423697853815980032)]} 0 1
[junit4:junit4] 2> 13197 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[23 (1423697853819125760)]} 0 1
[junit4:junit4] 2> 13199 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[10025 (1423697853821222912)]} 0 1
[junit4:junit4] 2> 13203 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[24 (1423697853825417216)]} 0 1
[junit4:junit4] 2> 13204 T2996 C195 P51118 /update {wt=javabin&version=2} {delete=[10021 (-1423697853827514368)]} 0 0
[junit4:junit4] 2> 13209 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[24 (1423697853831708672)]} 0 1
[junit4:junit4] 2> 13211 T3020 C196 P51130 /update {wt=javabin&version=2} {delete=[10021 (-1423697853834854400)]} 0 0
[junit4:junit4] 2> 13217 T3000 C195 P51118 /update {wt=javabin&version=2} {delete=[20 (-1423697853841145856)]} 0 0
[junit4:junit4] 2> 13226 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[10026 (1423697853840097280)]} 0 11
[junit4:junit4] 2> 13231 T3015 C196 P51130 /update {wt=javabin&version=2} {delete=[20 (-1423697853854777344)]} 0 1
[junit4:junit4] 2> 13238 T3015 C196 P51130 /update {wt=javabin&version=2} {add=[10026 (1423697853855825920)]} 0 7
[junit4:junit4] 2> 13240 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[25 (1423697853864214528)]} 0 1
[junit4:junit4] 2> 13244 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[25 (1423697853868408832)]} 0 1
[junit4:junit4] 2> 13247 T2999 C195 P51118 /update {wt=javabin&version=2} {delete=[10024 (-1423697853872603136)]} 0 1
[junit4:junit4] 2> 13248 T2996 C195 P51118 /update {wt=javabin&version=2} {delete=[21 (-1423697853873651712)]} 0 0
[junit4:junit4] 2> 13249 T3020 C196 P51130 /update {wt=javabin&version=2} {delete=[10024 (-1423697853874700288)]} 0 0
[junit4:junit4] 2> 13251 T3017 C196 P51130 /update {wt=javabin&version=2} {delete=[21 (-1423697853876797440)]} 0 0
[junit4:junit4] 2> 13253 T3001 C195 P51118 /update {wt=javabin&version=2} {add=[10027 (1423697853877846016)]} 0 1
[junit4:junit4] 2> 13255 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[26 (1423697853879943168)]} 0 1
[junit4:junit4] 2> 13257 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[10027 (1423697853882040320)]} 0 1
[junit4:junit4] 2> 13260 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[26 (1423697853885186048)]} 0 1
[junit4:junit4] 2> 13262 T2998 C195 P51118 /update {wt=javabin&version=2} {delete=[24 (-1423697853888331776)]} 0 0
[junit4:junit4] 2> 13264 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[10028 (1423697853890428928)]} 0 1
[junit4:junit4] 2> 13265 T3018 C196 P51130 /update {wt=javabin&version=2} {delete=[24 (-1423697853891477504)]} 0 0
[junit4:junit4] 2> 13266 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[10028 (1423697853892526080)]} 0 0
[junit4:junit4] 2> 13270 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[27 (1423697853895671808)]} 0 1
[junit4:junit4] 2> 13273 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[27 (1423697853899866112)]} 0 0
[junit4:junit4] 2> 13274 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[10029 (1423697853897768960)]} 0 3
[junit4:junit4] 2> 13276 T3001 C195 P51118 /update {wt=javabin&version=2} {add=[28 (1423697853903011840)]} 0 0
[junit4:junit4] 2> 13279 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[10029 (1423697853906157568)]} 0 0
[junit4:junit4] 2> 13282 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[28 (1423697853908254720)]} 0 2
[junit4:junit4] 2> 13284 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[29 (1423697853911400448)]} 0 0
[junit4:junit4] 2> 13286 T2998 C195 P51118 /update {wt=javabin&version=2} {delete=[10029 (-1423697853913497600)]} 0 0
[junit4:junit4] 2> 13288 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[29 (1423697853915594752)]} 0 0
[junit4:junit4] 2> 13291 T3020 C196 P51130 /update {wt=javabin&version=2} {delete=[10029 (-1423697853918740480)]} 0 0
[junit4:junit4] 2> 13293 T2997 C195 P51118 /update {wt=javabin&version=2} {delete=[29 (-1423697853920837632)]} 0 0
[junit4:junit4] 2> 13296 T3019 C196 P51130 /update {wt=javabin&version=2} {delete=[29 (-1423697853923983360)]} 0 0
[junit4:junit4] 2> 13299 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[10030 (1423697853926080512)]} 0 1
[junit4:junit4] 2> 13304 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[30 (1423697853929226240)]} 0 4
[junit4:junit4] 2> 13307 T3015 C196 P51130 /update {wt=javabin&version=2} {add=[10030 (1423697853935517696)]} 0 0
[junit4:junit4] 2> 13312 T3015 C196 P51130 /update {wt=javabin&version=2} {add=[30 (1423697853936566272)]} 0 4
[junit4:junit4] 2> 13316 T3001 C195 P51118 /update {wt=javabin&version=2} {delete=[30 (-1423697853943906304)]} 0 1
[junit4:junit4] 2> 13317 T3001 C195 P51118 /update {wt=javabin&version=2} {delete=[10030 (-1423697853946003456)]} 0 0
[junit4:junit4] 2> 13318 T3017 C196 P51130 /update {wt=javabin&version=2} {delete=[30 (-1423697853947052032)]} 0 0
[junit4:junit4] 2> 13322 T3020 C196 P51130 /update {wt=javabin&version=2} {delete=[10030 (-1423697853951246336)]} 0 1
[junit4:junit4] 2> 13325 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[31 (1423697853952294912)]} 0 2
[junit4:junit4] 2> 13328 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[10031 (1423697853955440640)]} 0 2
[junit4:junit4] 2> 13330 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[31 (1423697853958586368)]} 0 1
[junit4:junit4] 2> 13334 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[32 (1423697853962780672)]} 0 1
[junit4:junit4] 2> 13338 T3015 C196 P51130 /update {wt=javabin&version=2} {add=[10031 (1423697853966974976)]} 0 1
[junit4:junit4] 2> 13339 T3015 C196 P51130 /update {wt=javabin&version=2} {add=[32 (1423697853969072128)]} 0 0
[junit4:junit4] 2> 13343 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[10032 (1423697853973266432)]} 0 0
[junit4:junit4] 2> 13344 T2996 C195 P51118 /update {wt=javabin&version=2} {delete=[32 (-1423697853974315008)]} 0 0
[junit4:junit4] 2> 13347 T3017 C196 P51130 /update {wt=javabin&version=2} {delete=[32 (-1423697853977460736)]} 0 0
[junit4:junit4] 2> 13348 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[10032 (1423697853978509312)]} 0 0
[junit4:junit4] 2> 13349 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[33 (1423697853979557888)]} 0 0
[junit4:junit4] 2> 13350 T2998 C195 P51118 /update {wt=javabin&version=2} {delete=[10032 (-1423697853980606464)]} 0 0
[junit4:junit4] 2> 13352 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[33 (1423697853982703616)]} 0 0
[junit4:junit4] 2> 13354 T3015 C196 P51130 /update {wt=javabin&version=2} {delete=[10032 (-1423697853984800768)]} 0 0
[junit4:junit4] 2> 13356 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[34 (1423697853986897920)]} 0 0
[junit4:junit4] 2> 13359 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[10033 (1423697853988995072)]} 0 1
[junit4:junit4] 2> 13360 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[34 (1423697853991092224)]} 0 0
[junit4:junit4] 2> 13362 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[10033 (1423697853992140800)]} 0 1
[junit4:junit4] 2> 13364 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[35 (1423697853995286528)]} 0 0
[junit4:junit4] 2> 13366 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[10034 (1423697853997383680)]} 0 0
[junit4:junit4] 2> 13368 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[35 (1423697853999480832)]} 0 0
[junit4:junit4] 2> 13370 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[10034 (1423697854001577984)]} 0 0
[junit4:junit4] 2> 13372 T2998 C195 P51118 /update {wt=javabin&version=2} {delete=[34 (-1423697854003675136)]} 0 0
[junit4:junit4] 2> 13376 T3015 C196 P51130 /update {wt=javabin&version=2} {delete=[34 (-1423697854007869440)]} 0 0
[junit4:junit4] 2> 13379 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[10035 (1423697854005772288)]} 0 5
[junit4:junit4] 2> 13381 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[36 (1423697854012063744)]} 0 1
[junit4:junit4] 2> 13386 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[36 (1423697854015209472)]} 0 3
[junit4:junit4] 2> 13389 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[10035 (1423697854020452352)]} 0 1
[junit4:junit4] 2> 13390 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[37 (1423697854022549504)]} 0 0
[junit4:junit4] 2> 13391 T3001 C195 P51118 /update {wt=javabin&version=2} {delete=[10034 (-1423697854023598080)]} 0 0
[junit4:junit4] 2> 13393 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[37 (1423697854025695232)]} 0 0
[junit4:junit4] 2> 13395 T3019 C196 P51130 /update {wt=javabin&version=2} {delete=[10034 (-1423697854027792384)]} 0 0
[junit4:junit4] 2> 13397 T3000 C195 P51118 /update {wt=javabin&version=2} {delete=[35 (-1423697854029889536)]} 0 0
[junit4:junit4] 2> 13399 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[10036 (1423697854030938112)]} 0 1
[junit4:junit4] 2> 13400 T3015 C196 P51130 /update {wt=javabin&version=2} {delete=[35 (-1423697854033035264)]} 0 0
[junit4:junit4] 2> 13404 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[38 (1423697854036180992)]} 0 1
[junit4:junit4] 2> 13405 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[10036 (1423697854038278144)]} 0 0
[junit4:junit4] 2> 13408 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[38 (1423697854040375296)]} 0 1
[junit4:junit4] 2> 13410 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[10037 (1423697854042472448)]} 0 1
[junit4:junit4] 2> 13411 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[39 (1423697854044569600)]} 0 0
[junit4:junit4] 2> 13415 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[10037 (1423697854046666752)]} 0 2
[junit4:junit4] 2> 13416 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[39 (1423697854048763904)]} 0 1
[junit4:junit4] 2> 13418 T3000 C195 P51118 /update {wt=javabin&version=2} {delete=[10035 (-1423697854051909633)]} 0 0
[junit4:junit4] 2> 13419 T3001 C195 P51118 /update {wt=javabin&version=2} {add=[40 (1423697854051909632)]} 0 1
[junit4:junit4] 2> 13421 T3018 C196 P51130 /update {wt=javabin&version=2} {delete=[10035 (-1423697854055055361)]} 0 0
[junit4:junit4] 2> 13421 T3015 C196 P51130 /update {wt=javabin&version=2} {add=[40 (1423697854055055360)]} 0 0
[junit4:junit4] 2> 13424 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[10038 (1423697854058201088)]} 0 0
[junit4:junit4] 2> 13425 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[41 (1423697854058201089)]} 0 1
[junit4:junit4] 2> 13427 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[10038 (1423697854061346816)]} 0 0
[junit4:junit4] 2> 13428 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[41 (1423697854062395392)]} 0 0
[junit4:junit4] 2> 13431 T2996 C195 P51118 /update {wt=javabin&version=2} {delete=[36 (-1423697854065541120)]} 0 0
[junit4:junit4] 2> 13433 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[10039 (1423697854064492544)]} 0 3
[junit4:junit4] 2> 13433 T3019 C196 P51130 /update {wt=javabin&version=2} {delete=[36 (-1423697854067638272)]} 0 0
[junit4:junit4] 2> 13438 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[42 (1423697854070784000)]} 0 2
[junit4:junit4] 2> 13439 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[10039 (1423697854069735424)]} 0 4
[junit4:junit4] 2> 13444 T3001 C195 P51118 /update {wt=javabin&version=2} {add=[10040 (1423697854079172608)]} 0 0
[junit4:junit4] 2> 13445 T3015 C196 P51130 /update {wt=javabin&version=2} {add=[42 (1423697854078124032)]} 0 2
[junit4:junit4] 2> 13448 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[10040 (1423697854083366912)]} 0 1
[junit4:junit4] 2> 13450 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[43 (1423697854085464064)]} 0 1
[junit4:junit4] 2> 13451 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[10041 (1423697854086512640)]} 0 0
[junit4:junit4] 2> 13453 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[43 (1423697854088609792)]} 0 0
[junit4:junit4] 2> 13460 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[10041 (1423697854090706944)]} 0 5
[junit4:junit4] 2> 13461 T2996 C195 P51118 /update {wt=javabin&version=2} {delete=[38 (-1423697854096998400)]} 0 0
[junit4:junit4] 2> 13462 T2999 C195 P51118 /update {wt=javabin&version=2} {delete=[10036 (-1423697854098046976)]} 0 0
[junit4:junit4] 2> 13464 T3018 C196 P51130 /update {wt=javabin&version=2} {delete=[10036 (-1423697854100144128)]} 0 0
[junit4:junit4] 2> 13464 T3015 C196 P51130 /update {wt=javabin&version=2} {delete=[38 (-1423697854100144129)]} 0 0
[junit4:junit4] 2> 13467 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[10042 (1423697854103289856)]} 0 0
[junit4:junit4] 2> 13474 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[10042 (1423697854110629888)]} 0 0
[junit4:junit4] 2> 13475 T3001 C195 P51118 /update {wt=javabin&version=2} {add=[44 (1423697854110629888)]} 0 1
[junit4:junit4] 2> 13482 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[10043 (1423697854117969920)]} 0 1
[junit4:junit4] 2> 13483 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[44 (1423697854120067072)]} 0 0
[junit4:junit4] 2> 13486 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[10043 (1423697854122164224)]} 0 1
[junit4:junit4] 2> 13487 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[45 (1423697854124261376)]} 0 0
[junit4:junit4] 2> 13489 T2996 C195 P51118 /update {wt=javabin&version=2} {delete=[10038 (-1423697854126358528)]} 0 0
[junit4:junit4] 2> 13491 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[45 (1423697854127407104)]} 0 1
[junit4:junit4] 2> 13502 T3015 C196 P51130 /update {wt=javabin&version=2} {delete=[10038 (-1423697854139990016)]} 0 0
[junit4:junit4] 2> 13504 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[46 (1423697854141038592)]} 0 1
[junit4:junit4] 2> 13507 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[46 (1423697854144184320)]} 0 1
[junit4:junit4] 2> 13511 T3001 C195 P51118 /update {wt=javabin&version=2} {add=[47 (1423697854148378624)]} 0 1
[junit4:junit4] 2> 13520 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[10044 (1423697854145232896)]} 0 13
[junit4:junit4] 2> 13526 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[10044 (1423697854164107264)]} 0 1
[junit4:junit4] 2> 13530 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[10045 (1423697854169350144)]} 0 0
[junit4:junit4] 2> 13535 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[10045 (1423697854173544448)]} 0 1
[junit4:junit4] 2> 13537 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[47 (1423697854156767232)]} 0 19
[junit4:junit4] 2> 13538 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[10046 (1423697854177738752)]} 0 0
[junit4:junit4] 2> 13540 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[48 (1423697854178787328)]} 0 1
[junit4:junit4] 2> 13542 T3015 C196 P51130 /update {wt=javabin&version=2} {add=[10046 (1423697854180884480)]} 0 1
[junit4:junit4] 2> 13542 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[48 (1423697854181933056)]} 0 0
[junit4:junit4] 2> 13544 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[10047 (1423697854184030208)]} 0 0
[junit4:junit4] 2> 13545 T3001 C195 P51118 /update {wt=javabin&version=2} {delete=[39 (-1423697854185078784)]} 0 0
[junit4:junit4] 2> 13548 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[10047 (1423697854187175936)]} 0 1
[junit4:junit4] 2> 13548 T3018 C196 P51130 /update {wt=javabin&version=2} {delete=[39 (-1423697854188224512)]} 0 0
[junit4:junit4] 2> 13551 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[10048 (1423697854190321664)]} 0 1
[junit4:junit4] 2> 13552 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[49 (1423697854191370240)]} 0 1
[junit4:junit4] 2> 13553 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[10048 (1423697854193467392)]} 0 0
[junit4:junit4] 2> 13556 T3015 C196 P51130 /update {wt=javabin&version=2} {add=[49 (1423697854195564544)]} 0 1
[junit4:junit4] 2> 13557 T2996 C195 P51118 /update {wt=javabin&version=2} {delete=[10039 (-1423697854197661696)]} 0 0
[junit4:junit4] 2> 13560 T2998 C195 P51118 /update {wt=javabin&version=2} {delete=[41 (-1423697854200807424)]} 0 1
[junit4:junit4] 2> 13560 T3017 C196 P51130 /update {wt=javabin&version=2} {delete=[10039 (-1423697854200807424)]} 0 0
[junit4:junit4] 2> 13563 T3019 C196 P51130 /update {wt=javabin&version=2} {delete=[41 (-1423697854203953152)]} 0 0
[junit4:junit4] 2> 13564 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[10049 (1423697854203953152)]} 0 1
[junit4:junit4] 2> 13568 T3001 C195 P51118 /update {wt=javabin&version=2} {add=[50 (1423697854208147456)]} 0 2
[junit4:junit4] 2> 13569 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[10049 (1423697854209196032)]} 0 1
[junit4:junit4] 2> 13573 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[50 (1423697854213390336)]} 0 1
[junit4:junit4] 2> 13574 T3000 C195 P51118 /update {wt=javabin&version=2} {delete=[10041 (-1423697854215487488)]} 0 0
[junit4:junit4] 2> 13576 T3015 C196 P51130 /update {wt=javabin&version=2} {delete=[10041 (-1423697854217584640)]} 0 0
[junit4:junit4] 2> 13578 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[51 (1423697854216536064)]} 0 2
[junit4:junit4] 2> 13582 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[10050 (1423697854222827520)]} 0 1
[junit4:junit4] 2> 13588 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[10050 (1423697854228070400)]} 0 2
[junit4:junit4] 2> 13592 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[10051 (1423697854233313280)]} 0 1
[junit4:junit4] 2> 13592 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[51 (1423697854225973248)]} 0 8
[junit4:junit4] 2> 13595 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[10051 (1423697854236459008)]} 0 1
[junit4:junit4] 2> 13597 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[52 (1423697854238556160)]} 0 1
[junit4:junit4] 2> 13599 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[10052 (1423697854240653312)]} 0 1
[junit4:junit4] 2> 13600 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[52 (1423697854241701888)]} 0 1
[junit4:junit4] 2> 13602 T3015 C196 P51130 /update {wt=javabin&version=2} {add=[10052 (1423697854243799040)]} 0 1
[junit4:junit4] 2> 13604 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[53 (1423697854246944768)]} 0 1
[junit4:junit4] 2> 13604 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[10053 (1423697854246944769)]} 0 0
[junit4:junit4] 2> 13609 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[10053 (1423697854251139072)]} 0 1
[junit4:junit4] 2> 13609 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[53 (1423697854251139073)]} 0 1
[junit4:junit4] 2> 13612 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[10054 (1423697854254284800)]} 0 1
[junit4:junit4] 2> 13613 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[54 (1423697854255333376)]} 0 1
[junit4:junit4] 2> 13615 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[10054 (1423697854257430528)]} 0 1
[junit4:junit4] 2> 13617 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[54 (1423697854260576256)]} 0 0
[junit4:junit4] 2> 13620 T3001 C195 P51118 /update {wt=javabin&version=2} {delete=[10042 (-1423697854262673408)]} 0 1
[junit4:junit4] 2> 13621 T2999 C195 P51118 /update {wt=javabin&version=2} {delete=[42 (-1423697854264770560)]} 0 0
[junit4:junit4] 2> 13623 T3015 C196 P51130 /update {wt=javabin&version=2} {delete=[10042 (-1423697854266867712)]} 0 0
[junit4:junit4] 2> 13624 T3019 C196 P51130 /update {wt=javabin&version=2} {delete=[42 (-1423697854267916288)]} 0 0
[junit4:junit4] 2> 13627 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[10055 (1423697854270013440)]} 0 1
[junit4:junit4] 2> 13630 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[10055 (1423697854274207744)]} 0 0
[junit4:junit4] 2> 13632 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[55 (1423697854272110592)]} 0 4
[junit4:junit4] 2> 13634 T2996 C195 P51118 /update {wt=javabin&version=2} {delete=[10043 (-1423697854278402048)]} 0 0
[junit4:junit4] 2> 13637 T3018 C196 P51130 /update {wt=javabin&version=2} {delete=[10043 (-1423697854281547776)]} 0 0
[junit4:junit4] 2> 13639 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[55 (1423697854280499200)]} 0 3
[junit4:junit4] 2> 13640 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[10056 (1423697854284693504)]} 0 0
[junit4:junit4] 2> 13642 T3001 C195 P51118 /update {wt=javabin&version=2} {delete=[43 (-1423697854286790656)]} 0 0
[junit4:junit4] 2> 13644 T3019 C196 P51130 /update {wt=javabin&version=2} {delete=[43 (-1423697854288887809)]} 0 0
[junit4:junit4] 2> 13645 T3015 C196 P51130 /update {wt=javabin&version=2} {add=[10056 (1423697854288887808)]} 0 1
[junit4:junit4] 2> 13647 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[56 (1423697854292033536)]} 0 0
[junit4:junit4] 2> 13649 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[10057 (1423697854294130688)]} 0 0
[junit4:junit4] 2> 13651 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[56 (1423697854296227840)]} 0 0
[junit4:junit4] 2> 13653 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[10057 (1423697854298324992)]} 0 0
[junit4:junit4] 2> 13655 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[57 (1423697854300422144)]} 0 0
[junit4:junit4] 2> 13658 T2996 C195 P51118 /update {wt=javabin&version=2} {delete=[10045 (-1423697854303567872)]} 0 1
[junit4:junit4] 2> 13660 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[57 (1423697854305665024)]} 0 1
[junit4:junit4] 2> 13662 T3019 C196 P51130 /update {wt=javabin&version=2} {delete=[10045 (-1423697854307762176)]} 0 0
[junit4:junit4] 2> 13664 T2998 C195 P51118 /update {wt=javabin&version=2} {delete=[45 (-1423697854309859328)]} 0 0
[junit4:junit4] 2> 13667 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[10058 (1423697854311956480)]} 0 1
[junit4:junit4] 2> 13669 T3015 C196 P51130 /update {wt=javabin&version=2} {delete=[45 (-1423697854314053632)]} 0 1
[junit4:junit4] 2> 13671 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[10058 (1423697854316150784)]} 0 1
[junit4:junit4] 2> 13672 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[58 (1423697854317199360)]} 0 1
[junit4:junit4] 2> 13674 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[10059 (1423697854319296512)]} 0 1
[junit4:junit4] 2> 13675 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[58 (1423697854321393664)]} 0 0
[junit4:junit4] 2> 13677 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[10059 (1423697854323490816)]} 0 0
[junit4:junit4] 2> 13679 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[59 (1423697854325587968)]} 0 0
[junit4:junit4] 2> 13681 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[10060 (1423697854327685120)]} 0 0
[junit4:junit4] 2> 13682 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[59 (1423697854328733696)]} 0 0
[junit4:junit4] 2> 13684 T3015 C196 P51130 /update {wt=javabin&version=2} {add=[10060 (1423697854329782272)]} 0 1
[junit4:junit4] 2> 13687 T2998 C195 P51118 /update {wt=javabin&version=2} {delete=[10046 (-1423697854333976576)]} 0 0
[junit4:junit4] 2> 13689 T3001 C195 P51118 /update {wt=javabin&version=2} {add=[60 (1423697854331879424)]} 0 4
[junit4:junit4] 2> 13691 T3017 C196 P51130 /update {wt=javabin&version=2} {delete=[10046 (-1423697854338170880)]} 0 0
[junit4:junit4] 2> 13695 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[10061 (1423697854341316608)]} 0 1
[junit4:junit4] 2> 13697 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[60 (1423697854339219456)]} 0 5
[junit4:junit4] 2> 13699 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[10061 (1423697854345510912)]} 0 1
[junit4:junit4] 2> 13701 T3000 C195 P51118 /update {wt=javabin&version=2} {delete=[46 (-1423697854348656640)]} 0 0
[junit4:junit4] 2> 13703 T2997 C195 P51118 /update {wt=javabin&version=2} {delete=[10047 (-1423697854350753792)]} 0 0
[junit4:junit4] 2> 13704 T3015 C196 P51130 /update {wt=javabin&version=2} {delete=[46 (-1423697854351802368)]} 0 0
[junit4:junit4] 2> 13706 T3018 C196 P51130 /update {wt=javabin&version=2} {delete=[10047 (-1423697854353899520)]} 0 0
[junit4:junit4] 2> 13707 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[61 (1423697854354948096)]} 0 0
[junit4:junit4] 2> 13709 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[10062 (1423697854357045248)]} 0 0
[junit4:junit4] 2> 13711 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[61 (1423697854359142400)]} 0 0
[junit4:junit4] 2> 13713 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[10062 (1423697854361239552)]} 0 0
[junit4:junit4] 2> 13715 T3001 C195 P51118 /update {wt=javabin&version=2} {delete=[47 (-1423697854363336704)]} 0 0
[junit4:junit4] 2> 13717 T3001 C195 P51118 /update {wt=javabin&version=2} {add=[10063 (1423697854364385280)]} 0 1
[junit4:junit4] 2> 13718 T3019 C196 P51130 /update {wt=javabin&version=2} {delete=[47 (-1423697854366482432)]} 0 0
[junit4:junit4] 2> 13721 T3015 C196 P51130 /update {wt=javabin&version=2} {add=[10063 (1423697854368579584)]} 0 1
[junit4:junit4] 2> 13722 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[62 (1423697854369628160)]} 0 1
[junit4:junit4] 2> 13724 T2997 C195 P51118 /update {wt=javabin&version=2} {delete=[10048 (-1423697854372773888)]} 0 0
[junit4:junit4] 2> 13724 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[62 (1423697854372773888)]} 0 0
[junit4:junit4] 2> 13726 T3017 C196 P51130 /update {wt=javabin&version=2} {delete=[10048 (-1423697854374871040)]} 0 0
[junit4:junit4] 2> 13728 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[63 (1423697854376968192)]} 0 0
[junit4:junit4] 2> 13729 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[10064 (1423697854378016768)]} 0 0
[junit4:junit4] 2> 13732 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[63 (1423697854380113920)]} 0 1
[junit4:junit4] 2> 13734 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[10064 (1423697854383259648)]} 0 1
[junit4:junit4] 2> 13736 T2999 C195 P51118 /update {wt=javabin&version=2} {delete=[48 (-1423697854384308224)]} 0 1
[junit4:junit4] 2> 13738 T3001 C195 P51118 /update {wt=javabin&version=2} {delete=[10049 (-1423697854387453952)]} 0 0
[junit4:junit4] 2> 13739 T3015 C196 P51130 /update {wt=javabin&version=2} {delete=[48 (-1423697854388502528)]} 0 0
[junit4:junit4] 2> 13741 T3018 C196 P51130 /update {wt=javabin&version=2} {delete=[10049 (-1423697854390599680)]} 0 0
[junit4:junit4] 2> 13742 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[64 (1423697854391648256)]} 0 0
[junit4:junit4] 2> 13744 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[10065 (1423697854393745408)]} 0 0
[junit4:junit4] 2> 13746 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[64 (1423697854395842560)]} 0 0
[junit4:junit4] 2> 13747 T3006 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 13748 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[10065 (1423697854396891136)]} 0 1
[junit4:junit4] 2> 13749 T3006 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4] 2> "operation":"state",
[junit4:junit4] 2> "numShards":"1",
[junit4:junit4] 2> "shard":"shard1",
[junit4:junit4] 2> "roles":null,
[junit4:junit4] 2> "state":"recovering",
[junit4:junit4] 2> "core":"collection1",
[junit4:junit4] 2> "collection":"collection1",
[junit4:junit4] 2> "node_name":"127.0.0.1:51139_rgsp%2Fll",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:51139/rgsp/ll"}
[junit4:junit4] 2> 13749 T2996 C195 P51118 /update {wt=javabin&version=2} {delete=[49 (-1423697854398988288)]} 0 0
[junit4:junit4] 2> 13751 T2998 C195 P51118 /update {wt=javabin&version=2} {delete=[10050 (-1423697854401085440)]} 0 0
[junit4:junit4] 2> 13752 T3019 C196 P51130 /update {wt=javabin&version=2} {delete=[49 (-1423697854402134016)]} 0 0
[junit4:junit4] 2> 13754 T3015 C196 P51130 /update {wt=javabin&version=2} {delete=[10050 (-1423697854404231168)]} 0 0
[junit4:junit4] 2> 13758 T3001 C195 P51118 /update {wt=javabin&version=2} {add=[10066 (1423697854407376896)]} 0 1
[junit4:junit4] 2> 13759 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[65 (1423697854405279744)]} 0 4
[junit4:junit4] 2> 13762 T3024 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 13763 T3011 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 13763 T3005 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 13763 T3039 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 13765 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[65 (1423697854412619776)]} 0 3
[junit4:junit4] 2> 13767 T3000 C195 P51118 /update {wt=javabin&version=2} {delete=[50 (-1423697854417862656)]} 0 0
[junit4:junit4] 2> 13768 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[10066 (1423697854411571200)]} 0 7
[junit4:junit4] 2> 13773 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[10067 (1423697854424154112)]} 0 1
[junit4:junit4] 2> C194_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:51139_rgsp%2Fll, base_url=http://127.0.0.1:51139/rgsp/ll}
[junit4:junit4] 2> 13782 T3031 C194 P51139 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1357744064322\jetty2
[junit4:junit4] 2> 13786 T3031 C194 P51139 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@2ba14113 lockFactory=org.apache.lucene.store.NativeFSLockFactory@372bbeb5)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 13787 T3031 C194 P51139 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 13789 T3031 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[50 (-1423697854421008384)]} 0 9
[junit4:junit4] 2> 13790 T3020 C196 P51130 /update {wt=javabin&version=2} {delete=[50 (-1423697854421008384)]} 0 20
[junit4:junit4] 2> 13790 T3032 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10067 (1423697854427299840)]} 0 3
[junit4:junit4] 2> 13791 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[10067 (1423697854427299840)]} 0 15
[junit4:junit4] 2> 13794 T3001 C195 P51118 /update {wt=javabin&version=2} {delete=[10055 (-1423697854446174209)]} 0 0
[junit4:junit4] 2> 13794 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[66 (1423697854446174208)]} 0 1
[junit4:junit4] 2> 13800 T3033 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10055 (-1423697854449319936)]} 0 1
[junit4:junit4] 2> 13801 T3015 C196 P51130 /update {wt=javabin&version=2} {delete=[10055 (-1423697854449319936)]} 0 4
[junit4:junit4] 2> 13801 T3034 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[66 (1423697854449319937)]} 0 1
[junit4:junit4] 2> 13802 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[66 (1423697854449319937)]} 0 5
[junit4:junit4] 2> 13804 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[10068 (1423697854455611392)]} 0 1
[junit4:junit4] 2> 13806 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[67 (1423697854457708544)]} 0 1
[junit4:junit4] 2> 13810 T3035 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10068 (1423697854459805696)]} 0 1
[junit4:junit4] 2> 13811 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[10068 (1423697854459805696)]} 0 4
[junit4:junit4] 2> 13811 T3030 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[67 (1423697854459805697)]} 0 1
[junit4:junit4] 2> 13812 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[67 (1423697854459805697)]} 0 5
[junit4:junit4] 2> 13813 T2997 C195 P51118 /update {wt=javabin&version=2} {delete=[10057 (-1423697854466097152)]} 0 0
[junit4:junit4] 2> 13815 T2996 C195 P51118 /update {wt=javabin&version=2} {delete=[55 (-1423697854468194304)]} 0 0
[junit4:junit4] 2> 13820 T3031 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10057 (-1423697854470291456)]} 0 0
[junit4:junit4] 2> 13821 T3019 C196 P51130 /update {wt=javabin&version=2} {delete=[10057 (-1423697854470291456)]} 0 4
[junit4:junit4] 2> 13821 T3032 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[55 (-1423697854472388608)]} 0 0
[junit4:junit4] 2> 13822 T3015 C196 P51130 /update {wt=javabin&version=2} {delete=[55 (-1423697854472388608)]} 0 3
[junit4:junit4] 2> 13824 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[10069 (1423697854476582912)]} 0 1
[junit4:junit4] 2> 13825 T3001 C195 P51118 /update {wt=javabin&version=2} {add=[68 (1423697854478680064)]} 0 0
[junit4:junit4] 2> 13830 T3033 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10069 (1423697854480777216)]} 0 0
[junit4:junit4] 2> 13832 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[10069 (1423697854480777216)]} 0 5
[junit4:junit4] 2> 13833 T3034 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[68 (1423697854482874368)]} 0 1
[junit4:junit4] 2> 13834 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[68 (1423697854482874368)]} 0 5
[junit4:junit4] 2> 13834 T2999 C195 P51118 /update {wt=javabin&version=2} {delete=[10058 (-1423697854488117248)]} 0 0
[junit4:junit4] 2> 13836 T3000 C195 P51118 /update {wt=javabin&version=2} {delete=[57 (-1423697854490214400)]} 0 0
[junit4:junit4] 2> 13839 T3035 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10058 (-1423697854491262976)]} 0 0
[junit4:junit4] 2> 13840 T3020 C196 P51130 /update {wt=javabin&version=2} {delete=[10058 (-1423697854491262976)]} 0 3
[junit4:junit4] 2> 13841 T3030 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[57 (-1423697854493360128)]} 0 0
[junit4:junit4] 2> 13842 T3019 C196 P51130 /update {wt=javabin&version=2} {delete=[57 (-1423697854493360128)]} 0 3
[junit4:junit4] 2> 13843 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[10070 (1423697854496505856)]} 0 1
[junit4:junit4] 2> 13845 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[69 (1423697854499651584)]} 0 0
[junit4:junit4] 2> 13849 T3031 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10070 (1423697854499651584)]} 0 1
[junit4:junit4] 2> 13849 T3015 C196 P51130 /update {wt=javabin&version=2} {add=[10070 (1423697854499651584)]} 0 4
[junit4:junit4] 2> 13851 T3032 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[69 (1423697854503845888)]} 0 0
[junit4:junit4] 2> 13852 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[69 (1423697854503845888)]} 0 3
[junit4:junit4] 2> 13854 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[10071 (1423697854505943040)]} 0 3
[junit4:junit4] 2> 13855 T3001 C195 P51118 /update {wt=javabin&version=2} {delete=[58 (-1423697854510137344)]} 0 0
[junit4:junit4] 2> 13861 T3033 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[58 (-1423697854514331648)]} 0 0
[junit4:junit4] 2> 13863 T3020 C196 P51130 /update {wt=javabin&version=2} {delete=[58 (-1423697854514331648)]} 0 4
[junit4:junit4] 2> 13864 T3033 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10071 (1423697854511185920)]} 0 0
[junit4:junit4] 2> 13864 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[10071 (1423697854511185920)]} 0 8
[junit4:junit4] 2> 13866 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[70 (1423697854520623104)]} 0 1
[junit4:junit4] 2> 13867 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[10072 (1423697854522720256)]} 0 0
[junit4:junit4] 2> 13878 T3030 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10072 (1423697854526914560)]} 0 4
[junit4:junit4] 2> 13879 T3015 C196 P51130 /update {wt=javabin&version=2} {add=[10072 (1423697854526914560)]} 0 8
[junit4:junit4] 2> 13879 T3035 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[70 (1423697854524817408)]} 0 6
[junit4:junit4] 2> 13879 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[70 (1423697854524817408)]} 0 10
[junit4:junit4] 2> 13882 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[10073 (1423697854537400320)]} 0 1
[junit4:junit4] 2> 13884 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[71 (1423697854538448896)]} 0 2
[junit4:junit4] 2> 13890 T3031 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10073 (1423697854542643200)]} 0 1
[junit4:junit4] 2> 13891 T3032 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[71 (1423697854542643201)]} 0 1
[junit4:junit4] 2> 13891 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[10073 (1423697854542643200)]} 0 5
[junit4:junit4] 2> 13892 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[71 (1423697854542643201)]} 0 6
[junit4:junit4] 2> 13895 T2998 C195 P51118 /update {wt=javabin&version=2} {delete=[10059 (-1423697854551031808)]} 0 1
[junit4:junit4] 2> 13896 T3001 C195 P51118 /update {wt=javabin&version=2} {add=[72 (1423697854552080384)]} 0 1
[junit4:junit4] 2> 13901 T3034 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10059 (-1423697854555226112)]} 0 0
[junit4:junit4] 2> 13902 T3015 C196 P51130 /update {wt=javabin&version=2} {delete=[10059 (-1423697854555226112)]} 0 4
[junit4:junit4] 2> 13904 T3033 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[72 (1423697854556274688)]} 0 2
[junit4:junit4] 2> 13905 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[72 (1423697854556274688)]} 0 6
[junit4:junit4] 2> 13909 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[10074 (1423697854565711872)]} 0 1
[junit4:junit4] 2> 13912 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[73 (1423697854568857600)]} 0 1
[junit4:junit4] 2> 13923 T3030 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[73 (1423697854576197632)]} 0 2
[junit4:junit4] 2> 13924 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[73 (1423697854576197632)]} 0 6
[junit4:junit4] 2> 13925 T3035 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10074 (1423697854578294784)]} 0 1
[junit4:junit4] 2> 13926 T3016 C196 P51130 /update {wt=javabin&version=2} {add=[10074 (1423697854578294784)]} 0 12
[junit4:junit4] 2> 13927 T2997 C195 P51118 /update {wt=javabin&version=2} {delete=[59 (-1423697854585634816)]} 0 0
[junit4:junit4] 2> 13930 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[10075 (1423697854587731968)]} 0 1
[junit4:junit4] 2> 13932 T3031 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[59 (-1423697854588780544)]} 0 0
[junit4:junit4] 2> 13932 T3017 C196 P51130 /update {wt=javabin&version=2} {delete=[59 (-1423697854588780544)]} 0 2
[junit4:junit4] 2> 13934 T3034 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10075 (1423697854590877696)]} 0 0
[junit4:junit4] 2> 13936 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[10075 (1423697854590877696)]} 0 4
[junit4:junit4] 2> 13936 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[74 (1423697854595072000)]} 0 0
[junit4:junit4] 2> 13938 T3001 C195 P51118 /update {wt=javabin&version=2} {delete=[10060 (-1423697854597169152)]} 0 0
[junit4:junit4] 2> 13942 T3033 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[74 (1423697854598217728)]} 0 0
[junit4:junit4] 2> 13944 T3015 C196 P51130 /update {wt=javabin&version=2} {add=[74 (1423697854598217728)]} 0 5
[junit4:junit4] 2> 13944 T3030 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10060 (-1423697854600314880)]} 0 0
[junit4:junit4] 2> 13945 T3019 C196 P51130 /update {wt=javabin&version=2} {delete=[10060 (-1423697854600314880)]} 0 4
[junit4:junit4] 2> 13949 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[10076 (1423697854607654912)]} 0 1
[junit4:junit4] 2> 13951 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[75 (1423697854606606336)]} 0 4
[junit4:junit4] 2> 13960 T3035 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[75 (1423697854614994944)]} 0 1
[junit4:junit4] 2> 13961 T3016 C196 P51130 /update {wt=javabin&version=2} {add=[75 (1423697854614994944)]} 0 6
[junit4:junit4] 2> 13966 T2997 C195 P51118 /update {wt=javabin&version=2} {delete=[60 (-1423697854626529280)]} 0 0
[junit4:junit4] 2> 13967 T3035 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10076 (1423697854612897792)]} 0 6
[junit4:junit4] 2> 13969 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[10076 (1423697854612897792)]} 0 16
[junit4:junit4] 2> 13971 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[10077 (1423697854631772160)]} 0 0
[junit4:junit4] 2> 13973 T3032 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[60 (-1423697854630723584)]} 0 0
[junit4:junit4] 2> 13974 T3017 C196 P51130 /update {wt=javabin&version=2} {delete=[60 (-1423697854630723584)]} 0 4
[junit4:junit4] 2> 13976 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[76 (1423697854635966464)]} 0 1
[junit4:junit4] 2> 13978 T3034 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10077 (1423697854634917888)]} 0 1
[junit4:junit4] 2> 13979 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[10077 (1423697854634917888)]} 0 5
[junit4:junit4] 2> 13981 T3001 C195 P51118 /update {wt=javabin&version=2} {delete=[10063 (-1423697854642257920)]} 0 0
[junit4:junit4] 2> 13987 T3033 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[76 (1423697854640160768)]} 0 4
[junit4:junit4] 2> 13988 T3015 C196 P51130 /update {wt=javabin&version=2} {add=[76 (1423697854640160768)]} 0 9
[junit4:junit4] 2> 13990 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[77 (1423697854651695104)]} 0 0
[junit4:junit4] 2> 13991 T3030 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10063 (-1423697854649597952)]} 0 0
[junit4:junit4] 2> 13992 T3019 C196 P51130 /update {wt=javabin&version=2} {delete=[10063 (-1423697854649597952)]} 0 4
[junit4:junit4] 2> 13997 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[10078 (1423697854657986560)]} 0 1
[junit4:junit4] 2> 14005 T3031 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[77 (1423697854657986560)]} 0 5
[junit4:junit4] 2> 14007 T3031 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10078 (1423697854663229440)]} 0 0
[junit4:junit4] 2> 14008 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[10078 (1423697854663229440)]} 0 7
[junit4:junit4] 2> 14009 T3016 C196 P51130 /update {wt=javabin&version=2} {add=[77 (1423697854657986560)]} 0 13
[junit4:junit4] 2> 14020 T2996 C195 P51118 /update {wt=javabin&version=2} {delete=[63 (-1423697854683152384)]} 0 1
[junit4:junit4] 2> 14020 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[10079 (1423697854683152385)]} 0 1
[junit4:junit4] 2> 14026 T3032 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[63 (-1423697854686298113)]} 0 0
[junit4:junit4] 2> 14029 T3032 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10079 (1423697854686298112)]} 0 2
[junit4:junit4] 2> 14030 T3020 C196 P51130 /update {wt=javabin&version=2} {delete=[63 (-1423697854686298113)]} 0 7
[junit4:junit4] 2> 14032 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[10079 (1423697854686298112)]} 0 9
[junit4:junit4] 2> 14035 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[10080 (1423697854698881024)]} 0 1
[junit4:junit4] 2> 14040 T3001 C195 P51118 /update {wt=javabin&version=2} {add=[78 (1423697854704123904)]} 0 0
[junit4:junit4] 2> 14045 T3033 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10080 (1423697854705172480)]} 0 0
[junit4:junit4] 2> 14047 T3030 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[78 (1423697854708318208)]} 0 1
[junit4:junit4] 2> 14048 T3015 C196 P51130 /update {wt=javabin&version=2} {add=[10080 (1423697854705172480)]} 0 7
[junit4:junit4] 2> 14048 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[78 (1423697854708318208)]} 0 5
[junit4:junit4] 2> 14050 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[10081 (1423697854714609664)]} 0 0
[junit4:junit4] 2> 14057 T3031 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10081 (1423697854719852544)]} 0 0
[junit4:junit4] 2> 14059 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[10081 (1423697854719852544)]} 0 4
[junit4:junit4] 2> 14061 T2996 C195 P51118 /update {wt=javabin&version=2} {delete=[10067 (-1423697854726144000)]} 0 0
[junit4:junit4] 2> 14066 T3035 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10067 (-1423697854729289728)]} 0 0
[junit4:junit4] 2> 14068 T3016 C196 P51130 /update {wt=javabin&version=2} {delete=[10067 (-1423697854729289728)]} 0 4
[junit4:junit4] 2> 14069 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[79 (1423697854717755392)]} 0 16
[junit4:junit4] 2> 14072 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[10082 (1423697854736629760)]} 0 1
[junit4:junit4] 2> 14094 T3032 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10082 (1423697854740824064)]} 0 1
[junit4:junit4] 2> 14096 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[10082 (1423697854740824064)]} 0 21
[junit4:junit4] 2> 14100 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[10083 (1423697854765989888)]} 0 1
[junit4:junit4] 2> 14112 T3033 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10083 (1423697854770184192)]} 0 1
[junit4:junit4] 2> 14113 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[10083 (1423697854770184192)]} 0 10
[junit4:junit4] 2> 14116 T3034 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[79 (1423697854736629760)]} 0 14
[junit4:junit4] 2> 14117 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[79 (1423697854736629760)]} 0 46
[junit4:junit4] 2> 14118 T3001 C195 P51118 /update {wt=javabin&version=2} {delete=[10068 (-1423697854785912832)]} 0 0
[junit4:junit4] 2> 14121 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[80 (1423697854788009984)]} 0 1
[junit4:junit4] 2> 14124 T3030 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10068 (-1423697854789058560)]} 0 0
[junit4:junit4] 2> 14125 T3018 C196 P51130 /update {wt=javabin&version=2} {delete=[10068 (-1423697854789058560)]} 0 4
[junit4:junit4] 2> 14126 T3031 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[80 (1423697854791155712)]} 0 0
[junit4:junit4] 2> 14127 T3016 C196 P51130 /update {wt=javabin&version=2} {add=[80 (1423697854791155712)]} 0 4
[junit4:junit4] 2> 14127 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[10084 (1423697854795350016)]} 0 0
[junit4:junit4] 2> 14130 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[81 (1423697854798495744)]} 0 0
[junit4:junit4] 2> 14133 T3035 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10084 (1423697854798495744)]} 0 0
[junit4:junit4] 2> 14135 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[10084 (1423697854798495744)]} 0 5
[junit4:junit4] 2> 14136 T3032 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[81 (1423697854801641472)]} 0 1
[junit4:junit4] 2> 14137 T3015 C196 P51130 /update {wt=javabin&version=2} {add=[81 (1423697854801641472)]} 0 4
[junit4:junit4] 2> 14138 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[10085 (1423697854806884352)]} 0 0
[junit4:junit4] 2> 14139 T2998 C195 P51118 /update {wt=javabin&version=2} {delete=[67 (-1423697854807932928)]} 0 0
[junit4:junit4] 2> 14143 T3033 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[67 (-1423697854810030080)]} 0 0
[junit4:junit4] 2> 14144 T3034 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10085 (1423697854808981504)]} 0 1
[junit4:junit4] 2> 14144 T3020 C196 P51130 /update {wt=javabin&version=2} {delete=[67 (-1423697854810030080)]} 0 3
[junit4:junit4] 2> 14144 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[10085 (1423697854808981504)]} 0 4
[junit4:junit4] 2> 14149 T3001 C195 P51118 /update {wt=javabin&version=2} {add=[10086 (1423697854817370112)]} 0 1
[junit4:junit4] 2> 14149 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[82 (1423697854817370113)]} 0 1
[junit4:junit4] 2> 14155 T3030 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10086 (1423697854820515840)]} 0 1
[junit4:junit4] 2> 14156 T3031 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[82 (1423697854821564416)]} 0 1
[junit4:junit4] 2> 14156 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[10086 (1423697854820515840)]} 0 5
[junit4:junit4] 2> 14157 T3016 C196 P51130 /update {wt=javabin&version=2} {add=[82 (1423697854821564416)]} 0 5
[junit4:junit4] 2> 14159 T2996 C195 P51118 /update {wt=javabin&version=2} {delete=[10070 (-1423697854828904448)]} 0 0
[junit4:junit4] 2> 14161 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[83 (1423697854829953024)]} 0 1
[junit4:junit4] 2> 14165 T3035 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10070 (-1423697854832050176)]} 0 0
[junit4:junit4] 2> 14166 T3017 C196 P51130 /update {wt=javabin&version=2} {delete=[10070 (-1423697854832050176)]} 0 4
[junit4:junit4] 2> 14169 T3032 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[83 (1423697854834147328)]} 0 2
[junit4:junit4] 2> 14170 T3015 C196 P51130 /update {wt=javabin&version=2} {add=[83 (1423697854834147328)]} 0 6
[junit4:junit4] 2> 14173 T3001 C195 P51118 /update {wt=javabin&version=2} {delete=[68 (-1423697854843584512)]} 0 0
[junit4:junit4] 2> 14177 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[10087 (1423697854839390208)]} 0 8
[junit4:junit4] 2> 14182 T3033 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[68 (-1423697854849875968)]} 0 1
[junit4:junit4] 2> 14182 T3020 C196 P51130 /update {wt=javabin&version=2} {delete=[68 (-1423697854849875968)]} 0 4
[junit4:junit4] 2> 14185 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[84 (1423697854856167424)]} 0 0
[junit4:junit4] 2> 14187 T3034 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10087 (1423697854851973120)]} 0 0
[junit4:junit4] 2> 14188 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[10087 (1423697854851973120)]} 0 7
[junit4:junit4] 2> 14192 T2996 C195 P51118 /update {wt=javabin&version=2} {delete=[10071 (-1423697854863507456)]} 0 1
[junit4:junit4] 2> 14198 T3030 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[84 (1423697854862458880)]} 0 2
[junit4:junit4] 2> 14200 T3030 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10071 (-1423697854865604608)]} 0 0
[junit4:junit4] 2> 14201 T3016 C196 P51130 /update {wt=javabin&version=2} {delete=[10071 (-1423697854865604608)]} 0 7
[junit4:junit4] 2> 14202 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[84 (1423697854862458880)]} 0 12
[junit4:junit4] 2> 14206 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[10088 (1423697854877138944)]} 0 1
[junit4:junit4] 2> 14209 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[85 (1423697854880284672)]} 0 2
[junit4:junit4] 2> 14218 T3032 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[85 (1423697854886576128)]} 0 1
[junit4:junit4] 2> 14220 T3035 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10088 (1423697854884478976)]} 0 4
[junit4:junit4] 2> 14222 T3015 C196 P51130 /update {wt=javabin&version=2} {add=[85 (1423697854886576128)]} 0 8
[junit4:junit4] 2> 14223 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[10088 (1423697854884478976)]} 0 12
[junit4:junit4] 2> 14226 T3001 C195 P51118 /update {wt=javabin&version=2} {add=[86 (1423697854897061888)]} 0 2
[junit4:junit4] 2> 14228 T3001 C195 P51118 /update {wt=javabin&version=2} {add=[10089 (1423697854900207616)]} 0 1
[junit4:junit4] 2> 14237 T3033 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10089 (1423697854904401920)]} 0 1
[junit4:junit4] 2> 14239 T3033 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[86 (1423697854906499072)]} 0 1
[junit4:junit4] 2> 14241 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[10089 (1423697854904401920)]} 0 10
[junit4:junit4] 2> 14242 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[86 (1423697854906499072)]} 0 9
[junit4:junit4] 2> 14244 T3000 C195 P51118 /update {wt=javabin&version=2} {delete=[10073 (-1423697854918033408)]} 0 0
[junit4:junit4] 2> 14246 T2996 C195 P51118 /update {wt=javabin&version=2} {delete=[70 (-1423697854920130560)]} 0 0
[junit4:junit4] 2> 14256 T3030 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10073 (-1423697854922227712)]} 0 5
[junit4:junit4] 2> 14261 T3016 C196 P51130 /update {wt=javabin&version=2} {delete=[10073 (-1423697854922227712)]} 0 14
[junit4:junit4] 2> 14265 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[10090 (1423697854939004928)]} 0 1
[junit4:junit4] 2> 14267 T3031 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[70 (-1423697854937956352)]} 0 0
[junit4:junit4] 2> 14268 T3018 C196 P51130 /update {wt=javabin&version=2} {delete=[70 (-1423697854937956352)]} 0 6
[junit4:junit4] 2> 14273 T3032 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10090 (1423697854944247808)]} 0 0
[junit4:junit4] 2> 14275 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[87 (1423697854950539264)]} 0 0
[junit4:junit4] 2> 14276 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[10090 (1423697854944247808)]} 0 7
[junit4:junit4] 2> 14280 T3001 C195 P51118 /update {wt=javabin&version=2} {delete=[10075 (-1423697854955782144)]} 0 0
[junit4:junit4] 2> 14285 T3035 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[87 (1423697854956830720)]} 0 0
[junit4:junit4] 2> 14286 T3035 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10075 (-1423697854959976448)]} 0 0
[junit4:junit4] 2> 14287 T3020 C196 P51130 /update {wt=javabin&version=2} {delete=[10075 (-1423697854959976448)]} 0 4
[junit4:junit4] 2> 14288 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[87 (1423697854956830720)]} 0 7
[junit4:junit4] 2> 14291 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[10091 (1423697854967316480)]} 0 0
[junit4:junit4] 2> 14292 T2997 C195 P51118 /update {wt=javabin&version=2} {delete=[71 (-1423697854968365056)]} 0 0
[junit4:junit4] 2> 14298 T3034 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10091 (1423697854970462208)]} 0 1
[junit4:junit4] 2> 14299 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[10091 (1423697854970462208)]} 0 5
[junit4:junit4] 2> 14302 T3030 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[71 (-1423697854975705088)]} 0 0
[junit4:junit4] 2> 14303 T2996 C195 P51118 /update {wt=javabin&version=2} {delete=[10079 (-1423697854979899392)]} 0 0
[junit4:junit4] 2> 14304 T3016 C196 P51130 /update {wt=javabin&version=2} {delete=[71 (-1423697854975705088)]} 0 5
[junit4:junit4] 2> 14307 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[88 (1423697854984093696)]} 0 0
[junit4:junit4] 2> 14308 T3031 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10079 (-1423697854983045120)]} 0 0
[junit4:junit4] 2> 14309 T3015 C196 P51130 /update {wt=javabin&version=2} {delete=[10079 (-1423697854983045120)]} 0 3
[junit4:junit4] 2> 14313 T3032 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[88 (1423697854987239424)]} 0 0
[junit4:junit4] 2> 14317 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[10092 (1423697854991433728)]} 0 3
[junit4:junit4] 2> 14319 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[88 (1423697854987239424)]} 0 9
[junit4:junit4] 2> 14325 T3001 C195 P51118 /update {wt=javabin&version=2} {add=[89 (1423697855002968064)]} 0 0
[junit4:junit4] 2> 14328 T3035 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10092 (1423697854998773760)]} 0 0
[junit4:junit4] 2> 14330 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[10092 (1423697854998773760)]} 0 9
[junit4:junit4] 2> 14333 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[10093 (1423697855010308096)]} 0 1
[junit4:junit4] 2> 14338 T3033 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[89 (1423697855009259520)]} 0 3
[junit4:junit4] 2> 14340 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[89 (1423697855009259520)]} 0 9
[junit4:junit4] 2> 14344 T3030 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10093 (1423697855017648128)]} 0 0
[junit4:junit4] 2> 14345 T3000 C195 P51118 /update {wt=javabin&version=2} {delete=[73 (-1423697855023939584)]} 0 0
[junit4:junit4] 2> 14346 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[10093 (1423697855017648128)]} 0 7
[junit4:junit4] 2> 14349 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[10094 (1423697855028133888)]} 0 0
[junit4:junit4] 2> 14352 T3031 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[73 (-1423697855028133888)]} 0 0
[junit4:junit4] 2> 14353 T3016 C196 P51130 /update {wt=javabin&version=2} {delete=[73 (-1423697855028133888)]} 0 4
[junit4:junit4] 2> 14357 T3032 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10094 (1423697855032328192)]} 0 1
[junit4:junit4] 2> 14358 T3016 C196 P51130 /update {wt=javabin&version=2} {add=[10094 (1423697855032328192)]} 0 5
[junit4:junit4] 2> 14359 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[90 (1423697855038619648)]} 0 0
[junit4:junit4] 2> 14362 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[10095 (1423697855041765376)]} 0 0
[junit4:junit4] 2> 14367 T3035 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[90 (1423697855042813952)]} 0 0
[junit4:junit4] 2> 14369 T3035 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10095 (1423697855045959680)]} 0 1
[junit4:junit4] 2> 14369 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[10095 (1423697855045959680)]} 0 4
[junit4:junit4] 2> 14371 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[90 (1423697855042813952)]} 0 8
[junit4:junit4] 2> 14374 T3001 C195 P51118 /update {wt=javabin&version=2} {delete=[75 (-1423697855054348288)]} 0 0
[junit4:junit4] 2> 14375 T3001 C195 P51118 /update {wt=javabin&version=2} {add=[10096 (1423697855055396864)]} 0 0
[junit4:junit4] 2> 14381 T3033 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[75 (-1423697855059591168)]} 0 0
[junit4:junit4] 2> 14382 T3033 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10096 (1423697855060639744)]} 0 0
[junit4:junit4] 2> 14384 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[10096 (1423697855060639744)]} 0 4
[junit4:junit4] 2> 14385 T3017 C196 P51130 /update {wt=javabin&version=2} {delete=[75 (-1423697855059591168)]} 0 6
[junit4:junit4] 2> 14388 T3000 C195 P51118 /update {wt=javabin&version=2} {delete=[10080 (-1423697855069028352)]} 0 0
[junit4:junit4] 2> 14389 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[91 (1423697855070076928)]} 0 0
[junit4:junit4] 2> 14393 T3031 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10080 (-1423697855071125504)]} 0 0
[junit4:junit4] 2> 14395 T3032 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[91 (1423697855073222656)]} 0 0
[junit4:junit4] 2> 14396 T3016 C196 P51130 /update {wt=javabin&version=2} {add=[91 (1423697855073222656)]} 0 4
[junit4:junit4] 2> 14397 T3015 C196 P51130 /update {wt=javabin&version=2} {delete=[10080 (-1423697855071125504)]} 0 7
[junit4:junit4] 2> 14400 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[10097 (1423697855081611264)]} 0 0
[junit4:junit4] 2> 14402 T2999 C195 P51118 /update {wt=javabin&version=2} {delete=[79 (-1423697855083708416)]} 0 0
[junit4:junit4] 2> 14406 T3035 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10097 (1423697855084756992)]} 0 0
[junit4:junit4] 2> 14408 T3035 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[79 (-1423697855086854144)]} 0 0
[junit4:junit4] 2> 14409 T3018 C196 P51130 /update {wt=javabin&version=2} {delete=[79 (-1423697855086854144)]} 0 4
[junit4:junit4] 2> 14409 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[10097 (1423697855084756992)]} 0 6
[junit4:junit4] 2> 14413 T3001 C195 P51118 /update {wt=javabin&version=2} {add=[92 (1423697855094194176)]} 0 1
[junit4:junit4] 2> 14417 T3001 C195 P51118 /update {wt=javabin&version=2} {add=[10098 (1423697855095242752)]} 0 4
[junit4:junit4] 2> 14426 T3033 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10098 (1423697855101534208)]} 0 0
[junit4:junit4] 2> 14428 T3033 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[92 (1423697855103631360)]} 0 0
[junit4:junit4] 2> 14430 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[92 (1423697855103631360)]} 0 9
[junit4:junit4] 2> 14431 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[10098 (1423697855101534208)]} 0 12
[junit4:junit4] 2> 14434 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[10099 (1423697855116214272)]} 0 1
[junit4:junit4] 2> 14435 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[93 (1423697855118311424)]} 0 0
[junit4:junit4] 2> 14444 T3031 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10099 (1423697855120408576)]} 0 3
[junit4:junit4] 2> 14445 T3016 C196 P51130 /update {wt=javabin&version=2} {add=[10099 (1423697855120408576)]} 0 8
[junit4:junit4] 2> 14447 T2999 C195 P51118 /update {wt=javabin&version=2} {delete=[10081 (-1423697855130894336)]} 0 0
[junit4:junit4] 2> 14449 T3032 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[93 (1423697855129845760)]} 0 1
[junit4:junit4] 2> 14452 T3016 C196 P51130 /update {wt=javabin&version=2} {add=[93 (1423697855129845760)]} 0 7
[junit4:junit4] 2> 14453 T3035 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10081 (-1423697855135088640)]} 0 0
[junit4:junit4] 2> 14454 T3018 C196 P51130 /update {wt=javabin&version=2} {delete=[10081 (-1423697855135088640)]} 0 4
[junit4:junit4] 2> 14456 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[94 (1423697855139282944)]} 0 1
[junit4:junit4] 2> 14457 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[10100 (1423697855141380096)]} 0 1
[junit4:junit4] 2> 14482 T3034 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10100 (1423697855162351616)]} 0 1
[junit4:junit4] 2> 14482 T3033 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[94 (1423697855164448768)]} 0 1
[junit4:junit4] 2> 14483 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[94 (1423697855164448768)]} 0 4
[junit4:junit4] 2> 14483 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[10100 (1423697855162351616)]} 0 6
[junit4:junit4] 2> 14489 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[95 (1423697855172837376)]} 0 2
[junit4:junit4] 2> 14492 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[10101 (1423697855177031680)]} 0 1
[junit4:junit4] 2> 14497 T3030 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10101 (1423697855181225984)]} 0 0
[junit4:junit4] 2> 14499 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[10101 (1423697855181225984)]} 0 5
[junit4:junit4] 2> 14502 T3031 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[95 (1423697855182274560)]} 0 1
[junit4:junit4] 2> 14509 T3015 C196 P51130 /update {wt=javabin&version=2} {add=[95 (1423697855182274560)]} 0 13
[junit4:junit4] 2> 14510 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[10102 (1423697855190663168)]} 0 6
[junit4:junit4] 2> 14526 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[96 (1423697855199051776)]} 0 14
[junit4:junit4] 2> 14527 T3032 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10102 (1423697855208488960)]} 0 1
[junit4:junit4] 2> 14528 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[10102 (1423697855208488960)]} 0 7
[junit4:junit4] 2> 14530 T2998 C195 P51118 /update {wt=javabin&version=2} {delete=[10082 (-1423697855217926144)]} 0 0
[junit4:junit4] 2> 14535 T3035 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[96 (1423697855216877568)]} 0 1
[junit4:junit4] 2> 14536 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[96 (1423697855216877568)]} 0 7
[junit4:junit4] 2> 14537 T3034 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10082 (-1423697855221071872)]} 0 0
[junit4:junit4] 2> 14538 T3017 C196 P51130 /update {wt=javabin&version=2} {delete=[10082 (-1423697855221071872)]} 0 6
[junit4:junit4] 2> 14539 T3001 C195 P51118 /update {wt=javabin&version=2} {delete=[80 (-1423697855227363328)]} 0 0
[junit4:junit4] 2> 14542 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[10103 (1423697855229460480)]} 0 1
[junit4:junit4] 2> 14547 T3033 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[80 (-1423697855232606208)]} 0 0
[junit4:junit4] 2> 14548 T3019 C196 P51130 /update {wt=javabin&version=2} {delete=[80 (-1423697855232606208)]} 0 5
[junit4:junit4] 2> 14551 T3030 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10103 (1423697855235751936)]} 0 1
[junit4:junit4] 2> 14552 T3016 C196 P51130 /update {wt=javabin&version=2} {add=[10103 (1423697855235751936)]} 0 5
[junit4:junit4] 2> 14555 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[97 (1423697855240994816)]} 0 3
[junit4:junit4] 2> 14555 T2996 C195 P51118 /update {wt=javabin&version=2} {delete=[10084 (-1423697855244140544)]} 0 0
[junit4:junit4] 2> 14560 T3031 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10084 (-1423697855246237696)]} 0 0
[junit4:junit4] 2> 14561 T3015 C196 P51130 /update {wt=javabin&version=2} {delete=[10084 (-1423697855246237696)]} 0 4
[junit4:junit4] 2> 14563 T3032 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[97 (1423697855246237697)]} 0 0
[junit4:junit4] 2> 14565 T3018 C196 P51130 /update {wt=javabin&version=2} {add=[97 (1423697855246237697)]} 0 8
[junit4:junit4] 2> 14566 T2999 C195 P51118 /update {wt=javabin&version=2} {add=[10104 (1423697855254626304)]} 0 1
[junit4:junit4] 2> 14568 T2998 C195 P51118 /update {wt=javabin&version=2} {add=[98 (1423697855257772032)]} 0 0
[junit4:junit4] 2> 14576 T3034 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[98 (1423697855261966336)]} 0 1
[junit4:junit4] 2> 14576 T3035 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10104 (1423697855259869184)]} 0 3
[junit4:junit4] 2> 14578 T3017 C196 P51130 /update {wt=javabin&version=2} {add=[98 (1423697855261966336)]} 0 6
[junit4:junit4] 2> 14578 T3020 C196 P51130 /update {wt=javabin&version=2} {add=[10104 (1423697855259869184)]} 0 8
[junit4:junit4] 2> 14582 T2997 C195 P51118 /update {wt=javabin&version=2} {add=[10105 (1423697855271403520)]} 0 1
[junit4:junit4] 2> 14582 T3000 C195 P51118 /update {wt=javabin&version=2} {add=[99 (1423697855271403521)]} 0 1
[junit4:junit4] 2> 14588 T3033 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10105 (1423697855274549248)]} 0 0
[junit4:junit4] 2> 14589 T3030 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[99 (1423697855275597824)]} 0 1
[junit4:junit4] 2> 14589 T3019 C196 P51130 /update {wt=javabin&version=2} {add=[10105 (1423697855274549248)]} 0 5
[junit4:junit4] 2> 14589 T3016 C196 P51130 /update {wt=javabin&version=2} {add=[99 (1423697855275597824)]} 0 4
[junit4:junit4] 2> 14592 T2996 C195 P51118 /update {wt=javabin&version=2} {add=[10106 (1423697855281889280)]} 0 1
[junit4:junit4] 2> 14592 T2999 C195 P51118 /update {wt=javabin&version=2} {delete=[81 (-1423697855282937856)]} 0 0
[junit4:junit4] 2> 14597 T3032 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/rgsp/ll/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[81 (-1423697855286083584)]} 0 0
[junit4:junit4] 2> 14598 T3031 C194 P51139 /update {distrib.from=http://127.0.0.1:51130/
[...truncated too long message...]
ZooKeeper.java:1468)
[junit4:junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:236)
[junit4:junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$6.execute(SolrZkClient.java:233)
[junit4:junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:65)
[junit4:junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getChildren(SolrZkClient.java:233)
[junit4:junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:84)
[junit4:junit4] 2> at org.apache.solr.cloud.LeaderElector.access$000(LeaderElector.java:55)
[junit4:junit4] 2> at org.apache.solr.cloud.LeaderElector$1.process(LeaderElector.java:129)
[junit4:junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.processEvent(ClientCnxn.java:519)
[junit4:junit4] 2> at org.apache.zookeeper.ClientCnxn$EventThread.run(ClientCnxn.java:495)
[junit4:junit4] 2>
[junit4:junit4] 2> 43980 T3079 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> NOTE: test params are: codec=Lucene41: {range_facet_l=MockFixedIntBlock(blockSize=83), text=Pulsing41(freqCutoff=10 minBlockSize=13 maxBlockSize=47), _version_=MockFixedIntBlock(blockSize=83), rnd_b=PostingsFormat(name=SimpleText), intDefault=MockFixedIntBlock(blockSize=83), timestamp=MockFixedIntBlock(blockSize=83), id=PostingsFormat(name=SimpleText), a_t=MockFixedIntBlock(blockSize=83), range_facet_sl=Pulsing41(freqCutoff=10 minBlockSize=13 maxBlockSize=47), range_facet_si=Pulsing41(freqCutoff=13 minBlockSize=13 maxBlockSize=47), other_tl1=MockFixedIntBlock(blockSize=83), multiDefault=Pulsing41(freqCutoff=13 minBlockSize=13 maxBlockSize=47), a_si=Pulsing41(freqCutoff=13 minBlockSize=13 maxBlockSize=47)}, sim=DefaultSimilarity, locale=th, timezone=Africa/Bamako
[junit4:junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.7.0_10 (64-bit)/cpus=2,threads=2,free=292385552,total=468713472
[junit4:junit4] 2> NOTE: All tests run in this JVM: [DirectSolrSpellCheckerTest, TestWriterPerf, BasicDistributedZkTest, TestWordDelimiterFilterFactory, BasicZkTest, ClusterStateUpdateTest, JsonLoaderTest, PluginInfoTest, ResourceLoaderTest, TestDistributedGrouping, PingRequestHandlerTest, SampleTest, TestBM25SimilarityFactory, SuggesterTSTTest, TestJmxMonitoredMap, QueryParsingTest, TestZkChroot, TestValueSourceCache, MinimalSchemaTest, TestDFRSimilarityFactory, NumericFieldsTest, DateFieldTest, UpdateParamsTest, CollectionsAPIDistributedZkTest, TestNumberUtils, PeerSyncTest, SortByFunctionTest, XmlUpdateRequestHandlerTest, StatsComponentTest, TestJmxIntegration, IndexReaderFactoryTest, URLClassifyProcessorTest, SystemInfoHandlerTest, SynonymTokenizerTest, ShardRoutingTest, ClusterStateTest, TestSuggestSpellingConverter, DirectUpdateHandlerTest, HighlighterConfigTest, AnalysisAfterCoreReloadTest, ShardRoutingCustomTest, IndexBasedSpellCheckerTest, BadIndexSchemaTest, JSONWriterTest, DocumentBuilderTest, OverseerTest, CoreAdminHandlerTest, TestCSVResponseWriter, TestComponentsName, TestBinaryResponseWriter, TestIndexSearcher, TestStressRecovery, TestSolrQueryParser, CSVRequestHandlerTest, ScriptEngineTest, TestDocSet, PolyFieldTest, TestBadConfig, TestPluginEnable, TestPropInject, TermVectorComponentDistributedTest, BasicDistributedZk2Test, TestLMJelinekMercerSimilarityFactory, IndexSchemaRuntimeFieldTest, ChaosMonkeySafeLeaderTest, TestRangeQuery, FieldAnalysisRequestHandlerTest, SuggesterWFSTTest, SolrPluginUtilsTest, LeaderElectionIntegrationTest, FastVectorHighlighterTest, TestExtendedDismaxParser, SolrCoreCheckLockOnStartupTest, TestRealTimeGet, TestQuerySenderNoQuery, LukeRequestHandlerTest, TestSearchPerf, SignatureUpdateProcessorFactoryTest, DistributedTermsComponentTest, RAMDirectoryFactoryTest, ZkNodePropsTest, ChaosMonkeyNothingIsSafeTest, QueryElevationComponentTest, HighlighterTest, XsltUpdateRequestHandlerTest, SolrCmdDistributorTest, DisMaxRequestHandlerTest, ZkSolrClientTest, RequiredFieldsTest, TestSystemIdResolver, TestCollationField, TestIndexingPerformance, OutputWriterTest, PrimUtilsTest, TestPseudoReturnFields, TestReversedWildcardFilterFactory, TestFuzzyAnalyzedSuggestions, TestSolrCoreProperties, TermsComponentTest, DebugComponentTest, DefaultValueUpdateProcessorTest, SpellingQueryConverterTest, TestBinaryField, MoreLikeThisHandlerTest, TimeZoneUtilsTest, TestAtomicUpdateErrorCases, FileBasedSpellCheckerTest, NotRequiredUniqueKeyTest, TestGroupingSearch, ShowFileRequestHandlerTest, QueryEqualityTest, SolrRequestParserTest, PrimitiveFieldTypeTest, TestSolrXMLSerializer, RecoveryZkTest]
[junit4:junit4] 2> NOTE: reproduce with: ant test -Dtestcase=RecoveryZkTest -Dtests.seed=F1C952D1F8C6D9C7 -Dtests.slow=true -Dtests.locale=th -Dtests.timezone=Africa/Bamako -Dtests.file.encoding=Cp1252
[junit4:junit4] ERROR 0.00s | RecoveryZkTest (suite) <<<
[junit4:junit4] > Throwable #1: com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=3080, name=coreLoadExecutor-1811-thread-1, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.
[junit4:junit4] > at __randomizedtesting.SeedInfo.seed([F1C952D1F8C6D9C7]:0)
[junit4:junit4] > at java.lang.Thread.getStackTrace(Thread.java:1567)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(RandomizedContext.java:150)
[junit4:junit4] > at org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java:516)
[junit4:junit4] > at org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:976)
[junit4:junit4] > at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:874)
[junit4:junit4] > at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:866)
[junit4:junit4] > at org.apache.solr.core.MockDirectoryFactory.create(MockDirectoryFactory.java:33)
[junit4:junit4] > at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:267)
[junit4:junit4] > at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:223)
[junit4:junit4] > at org.apache.solr.core.SolrCore.getNewIndexDir(SolrCore.java:240)
[junit4:junit4] > at org.apache.solr.core.SolrCore.initIndex(SolrCore.java:445)
[junit4:junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:717)
[junit4:junit4] > at org.apache.solr.core.SolrCore.<init>(SolrCore.java:606)
[junit4:junit4] > at org.apache.solr.core.CoreContainer.createFromZk(CoreContainer.java:947)
[junit4:junit4] > at org.apache.solr.core.CoreContainer.create(CoreContainer.java:1029)
[junit4:junit4] > at org.apache.solr.core.CoreContainer$3.call(CoreContainer.java:627)
[junit4:junit4] > at org.apache.solr.core.CoreContainer$3.call(CoreContainer.java:622)
[junit4:junit4] > at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
[junit4:junit4] > at java.util.concurrent.FutureTask.run(FutureTask.java:166)
[junit4:junit4] > at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
[junit4:junit4] > at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334)
[junit4:junit4] > at java.util.concurrent.FutureTask.run(FutureTask.java:166)
[junit4:junit4] > at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110)
[junit4:junit4] > at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
[junit4:junit4] > at java.lang.Thread.run(Thread.java:722)
[junit4:junit4] > Caused by: java.lang.AssertionError: Directory not closed: BaseDirectoryWrapper(org.apache.lucene.store.RAMDirectory@6f1e1116 lockFactory=org.apache.lucene.store.NativeFSLockFactory@222ecd48)
[junit4:junit4] > at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4] > at org.apache.lucene.util.CloseableDirectory.close(CloseableDirectory.java:47)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:602)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:599)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(RandomizedContext.java:167)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.RandomizedRunner$2.afterAlways(RandomizedRunner.java:615)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
[junit4:junit4] > at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4] > ... 1 more
[junit4:junit4] Completed in 44.03s, 1 test, 1 error <<< FAILURES!
[...truncated 386 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:353: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:178: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:428: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1176: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:841: There were test failures: 246 suites, 1029 tests, 1 suite-level error, 14 ignored (9 assumptions)
Total time: 45 minutes 38 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 64bit/jdk1.7.0_10 -XX:+UseG1GC
Email was triggered for: Failure
Sending email for trigger: Failure