lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.7.0_10) - Build # 2492 - Failure!
Date Fri, 01 Feb 2013 16:37:31 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/2492/
Java: 32bit/jdk1.7.0_10 -client -XX:+UseParallelGC

1 tests failed.
REGRESSION:  org.apache.solr.cloud.RecoveryZkTest.testDistribSearch

Error Message:
expected:<190> but was:<2>

Stack Trace:
java.lang.AssertionError: expected:<190> but was:<2>
	at __randomizedtesting.SeedInfo.seed([B93FB1BA577F831F:38D93FA22020E323]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.failNotEquals(Assert.java:647)
	at org.junit.Assert.assertEquals(Assert.java:128)
	at org.junit.Assert.assertEquals(Assert.java:472)
	at org.junit.Assert.assertEquals(Assert.java:456)
	at org.apache.solr.cloud.RecoveryZkTest.doTest(RecoveryZkTest.java:106)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:794)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:601)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:722)




Build Log:
[...truncated 8732 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.RecoveryZkTest
[junit4:junit4]   2> 0 T796 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /xhr/
[junit4:junit4]   2> 4 T796 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-1359735080086
[junit4:junit4]   2> 9 T796 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 9 T797 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4:junit4]   2> 15 T797 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 16 T797 oazs.ZooKeeperServer.setMinSessionTimeout minSessionTimeout set to -1
[junit4:junit4]   2> 16 T797 oazs.ZooKeeperServer.setMaxSessionTimeout maxSessionTimeout set to -1
[junit4:junit4]   2> 17 T797 oazs.NIOServerCnxnFactory.configure binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 20 T797 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-1359735080083\zookeeper\server1\data\version-2\snapshot.0
[junit4:junit4]   2> 116 T796 oasc.ZkTestServer.run start zk server on port:60278
[junit4:junit4]   2> 116 T796 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:60278 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@bc631c
[junit4:junit4]   2> 118 T796 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 119 T802 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:60278. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 120 T802 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:60278, initiating session
[junit4:junit4]   2> 122 T798 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:60281
[junit4:junit4]   2> 123 T798 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:60281
[junit4:junit4]   2> 124 T800 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 133 T800 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c9686c4a30000 with negotiated timeout 10000 for client /127.0.0.1:60281
[junit4:junit4]   2> 133 T802 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:60278, sessionid = 0x13c9686c4a30000, negotiated timeout = 10000
[junit4:junit4]   2> 134 T803 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@bc631c name:ZooKeeperConnection Watcher:127.0.0.1:60278 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 135 T796 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 135 T796 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 141 T801 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c9686c4a30000
[junit4:junit4]   2> 143 T803 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 143 T798 oazs.NIOServerCnxn.doIO WARNING caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x13c9686c4a30000, 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> 143 T796 oaz.ZooKeeper.close Session: 0x13c9686c4a30000 closed
[junit4:junit4]   2> 146 T796 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:60278/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@33f685
[junit4:junit4]   2> 148 T798 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:60281 which had sessionid 0x13c9686c4a30000
[junit4:junit4]   2> 148 T796 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 149 T804 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:60278. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 151 T804 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:60278, initiating session
[junit4:junit4]   2> 151 T798 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:60284
[junit4:junit4]   2> 151 T798 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:60284
[junit4:junit4]   2> 153 T800 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c9686c4a30001 with negotiated timeout 10000 for client /127.0.0.1:60284
[junit4:junit4]   2> 154 T804 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:60278, sessionid = 0x13c9686c4a30001, negotiated timeout = 10000
[junit4:junit4]   2> 154 T805 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@33f685 name:ZooKeeperConnection Watcher:127.0.0.1:60278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 155 T796 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 155 T796 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 163 T796 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 169 T796 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 176 T796 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 183 T796 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> 184 T796 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 197 T796 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> 198 T796 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 206 T796 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> 207 T796 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 213 T796 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> 214 T796 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 221 T796 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> 222 T796 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 227 T796 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> 228 T796 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 237 T796 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> 238 T796 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 246 T796 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> 247 T796 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 254 T796 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> 255 T796 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 261 T801 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c9686c4a30001
[junit4:junit4]   2> 263 T796 oaz.ZooKeeper.close Session: 0x13c9686c4a30001 closed
[junit4:junit4]   2> 264 T798 oazs.NIOServerCnxn.doIO WARNING caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x13c9686c4a30001, 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> 265 T798 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:60284 which had sessionid 0x13c9686c4a30001
[junit4:junit4]   2> 264 T805 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 741 T796 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 748 T796 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60285
[junit4:junit4]   2> 749 T796 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 749 T796 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 750 T796 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-1359735080342
[junit4:junit4]   2> 750 T796 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-1359735080342\solr.xml
[junit4:junit4]   2> 751 T796 oasc.CoreContainer.<init> New CoreContainer 16328525
[junit4:junit4]   2> 752 T796 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-1359735080342\'
[junit4:junit4]   2> 752 T796 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-1359735080342\'
[junit4:junit4]   2> 831 T796 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 60000
[junit4:junit4]   2> 831 T796 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 832 T796 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 832 T796 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 833 T796 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 834 T796 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 836 T796 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 836 T796 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 836 T796 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 837 T796 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=60000&connTimeout=15000&retry=false
[junit4:junit4]   2> 864 T796 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 900 T796 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:60278/solr
[junit4:junit4]   2> 901 T796 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 901 T796 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:60278 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@4c642d
[junit4:junit4]   2> 902 T796 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 904 T815 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:60278. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 905 T815 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:60278, initiating session
[junit4:junit4]   2> 906 T798 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:60290
[junit4:junit4]   2> 906 T798 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:60290
[junit4:junit4]   2> 919 T800 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c9686c4a30002 with negotiated timeout 20000 for client /127.0.0.1:60290
[junit4:junit4]   2> 919 T815 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:60278, sessionid = 0x13c9686c4a30002, negotiated timeout = 20000
[junit4:junit4]   2> 919 T816 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4c642d name:ZooKeeperConnection Watcher:127.0.0.1:60278 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 920 T796 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 922 T801 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c9686c4a30002
[junit4:junit4]   2> 924 T816 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 924 T796 oaz.ZooKeeper.close Session: 0x13c9686c4a30002 closed
[junit4:junit4]   2> 925 T798 oazs.NIOServerCnxn.doIO WARNING caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x13c9686c4a30002, 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> 925 T798 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:60290 which had sessionid 0x13c9686c4a30002
[junit4:junit4]   2> 926 T796 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=60000&connTimeout=15000
[junit4:junit4]   2> 933 T796 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:60278/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@d9af50
[junit4:junit4]   2> 937 T796 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 938 T817 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:60278. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 939 T817 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:60278, initiating session
[junit4:junit4]   2> 939 T798 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:60293
[junit4:junit4]   2> 940 T798 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:60293
[junit4:junit4]   2> 942 T800 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c9686c4a30003 with negotiated timeout 20000 for client /127.0.0.1:60293
[junit4:junit4]   2> 942 T817 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:60278, sessionid = 0x13c9686c4a30003, negotiated timeout = 20000
[junit4:junit4]   2> 943 T818 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@d9af50 name:ZooKeeperConnection Watcher:127.0.0.1:60278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 943 T796 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 947 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c9686c4a30003 type:create cxid:0x2 zxid:0x1a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 950 T796 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 955 T796 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60285_xhr
[junit4:junit4]   2> 956 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c9686c4a30003 type:delete cxid:0x7 zxid:0x1c txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:60285_xhr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:60285_xhr
[junit4:junit4]   2> 959 T796 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60285_xhr
[junit4:junit4]   2> 966 T796 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 977 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c9686c4a30003 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> 980 T796 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 986 T796 oasc.Overseer.start Overseer (id=89111598209368067-127.0.0.1:60285_xhr-n_0000000000) starting
[junit4:junit4]   2> 987 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c9686c4a30003 type:create cxid:0x1a zxid:0x23 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 990 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c9686c4a30003 type:create cxid:0x1b zxid:0x24 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 993 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c9686c4a30003 type:create cxid:0x1c zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 996 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c9686c4a30003 type:create cxid:0x1d zxid:0x26 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 998 T820 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 999 T796 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 1005 T796 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 1009 T819 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 1016 T821 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-1359735080342\collection1
[junit4:junit4]   2> 1016 T821 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 1017 T821 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 1017 T821 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 1020 T821 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-1359735080342\collection1\'
[junit4:junit4]   2> 1022 T821 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-1359735080342/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 1022 T821 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-1359735080342/collection1/lib/README' to classloader
[junit4:junit4]   2> 1087 T821 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 1176 T821 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 1178 T821 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 1185 T821 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2007 T821 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 2021 T821 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2025 T821 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2046 T821 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2051 T821 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2056 T821 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2058 T821 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2059 T821 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-1359735080342\collection1\, dataDir=.\org.apache.solr.cloud.RecoveryZkTest-1359735080083/control/data\
[junit4:junit4]   2> 2060 T821 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@c0a9ec
[junit4:junit4]   2> 2060 T821 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 2060 T821 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-1359735080083\control\data forceNew:false
[junit4:junit4]   2> 2061 T821 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1359735080083\control\data
[junit4:junit4]   2> 2061 T821 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.RecoveryZkTest-1359735080083/control/data\index/
[junit4:junit4]   2> 2062 T821 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.RecoveryZkTest-1359735080083\control\data\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 2064 T821 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-1359735080083\control\data\index forceNew:false
[junit4:junit4]   2> 2069 T821 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@1058cae lockFactory=org.apache.lucene.store.NativeFSLockFactory@5db987)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 2069 T821 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 2071 T821 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1359735080083\control\data\index
[junit4:junit4]   2> 2072 T821 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 2073 T821 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 2073 T821 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 2073 T821 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 2074 T821 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 2074 T821 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 2074 T821 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 2075 T821 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 2075 T821 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2076 T821 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2076 T821 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 2076 T821 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 2077 T821 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2077 T821 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 2077 T821 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 2078 T821 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 2078 T821 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2078 T821 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2079 T821 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2079 T821 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2079 T821 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2080 T821 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2080 T821 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2081 T821 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2081 T821 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2081 T821 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 2082 T821 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 2094 T821 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2099 T821 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 2101 T821 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> 2103 T821 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1359735080083\control\data
[junit4:junit4]   2> 2103 T821 oass.SolrIndexSearcher.<init> Opening Searcher@1b67499 main
[junit4:junit4]   2> 2105 T821 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2106 T821 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2106 T821 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 2118 T821 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 2176 T822 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1b67499 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 2181 T821 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 2181 T821 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 2182 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c9686c4a30003 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> 2617 T819 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2618 T819 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:60285_xhr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:60285/xhr"}
[junit4:junit4]   2> 2618 T819 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with numShards 1
[junit4:junit4]   2> 2618 T819 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 2619 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c9686c4a30003 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> 2632 T818 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> 3221 T821 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 3221 T821 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:60285/xhr collection:control_collection shard:shard1
[junit4:junit4]   2> 3222 T821 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4:junit4]   2> 3238 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c9686c4a30003 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> 3240 T821 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 3240 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c9686c4a30003 type:create cxid:0x73 zxid:0x37 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3245 T821 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 3245 T821 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 3245 T821 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:60285/xhr/collection1/
[junit4:junit4]   2> 3246 T821 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 3246 T821 oasc.SyncStrategy.syncToMe http://127.0.0.1:60285/xhr/collection1/ has no replicas
[junit4:junit4]   2> 3246 T821 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:60285/xhr/collection1/
[junit4:junit4]   2> 3247 T821 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4:junit4]   2> 3254 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c9686c4a30003 type:create cxid:0x7d zxid:0x3b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4110 T819 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4124 T818 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> 4138 T821 oasc.ZkController.register We are http://127.0.0.1:60285/xhr/collection1/ and leader is http://127.0.0.1:60285/xhr/collection1/
[junit4:junit4]   2> 4138 T821 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:60285/xhr
[junit4:junit4]   2> 4138 T821 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 4139 T821 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 4139 T821 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 4142 T821 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4144 T796 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 4144 T796 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 4145 T796 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4157 T796 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 4165 T796 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:60278/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@99de3
[junit4:junit4]   2> 4166 T796 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4167 T823 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:60278. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 4168 T823 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:60278, initiating session
[junit4:junit4]   2> 4168 T798 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:60296
[junit4:junit4]   2> 4169 T798 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:60296
[junit4:junit4]   2> 4171 T800 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c9686c4a30004 with negotiated timeout 10000 for client /127.0.0.1:60296
[junit4:junit4]   2> 4171 T823 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:60278, sessionid = 0x13c9686c4a30004, negotiated timeout = 10000
[junit4:junit4]   2> 4172 T824 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@99de3 name:ZooKeeperConnection Watcher:127.0.0.1:60278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4172 T796 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4173 T796 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4175 T796 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 4524 T796 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 4527 T796 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60297
[junit4:junit4]   2> 4528 T796 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 4529 T796 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4529 T796 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-1359735084254
[junit4:junit4]   2> 4529 T796 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-1359735084254\solr.xml
[junit4:junit4]   2> 4530 T796 oasc.CoreContainer.<init> New CoreContainer 531206
[junit4:junit4]   2> 4531 T796 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-1359735084254\'
[junit4:junit4]   2> 4531 T796 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-1359735084254\'
[junit4:junit4]   2> 4590 T796 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 60000
[junit4:junit4]   2> 4591 T796 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4591 T796 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 4591 T796 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4592 T796 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4592 T796 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4593 T796 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4594 T796 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4594 T796 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4595 T796 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=60000&connTimeout=15000&retry=false
[junit4:junit4]   2> 4611 T796 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 4632 T796 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:60278/solr
[junit4:junit4]   2> 4633 T796 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 4634 T796 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:60278 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@1858f9b
[junit4:junit4]   2> 4635 T796 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4636 T834 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:60278. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 4637 T834 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:60278, initiating session
[junit4:junit4]   2> 4637 T798 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:60302
[junit4:junit4]   2> 4638 T798 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:60302
[junit4:junit4]   2> 4649 T800 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c9686c4a30005 with negotiated timeout 20000 for client /127.0.0.1:60302
[junit4:junit4]   2> 4649 T834 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:60278, sessionid = 0x13c9686c4a30005, negotiated timeout = 20000
[junit4:junit4]   2> 4650 T835 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1858f9b name:ZooKeeperConnection Watcher:127.0.0.1:60278 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4650 T796 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4652 T801 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c9686c4a30005
[junit4:junit4]   2> 4678 T796 oaz.ZooKeeper.close Session: 0x13c9686c4a30005 closed
[junit4:junit4]   2> 4678 T835 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 4678 T798 oazs.NIOServerCnxn.doIO WARNING caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x13c9686c4a30005, 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> 4679 T796 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=60000&connTimeout=15000
[junit4:junit4]   2> 4680 T798 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:60302 which had sessionid 0x13c9686c4a30005
[junit4:junit4]   2> 4688 T796 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:60278/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@1090d87
[junit4:junit4]   2> 4690 T796 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4691 T836 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:60278. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 4691 T836 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:60278, initiating session
[junit4:junit4]   2> 4691 T798 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:60305
[junit4:junit4]   2> 4691 T798 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:60305
[junit4:junit4]   2> 4694 T836 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:60278, sessionid = 0x13c9686c4a30006, negotiated timeout = 20000
[junit4:junit4]   2> 4694 T800 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c9686c4a30006 with negotiated timeout 20000 for client /127.0.0.1:60305
[junit4:junit4]   2> 4694 T837 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1090d87 name:ZooKeeperConnection Watcher:127.0.0.1:60278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4695 T796 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4696 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c9686c4a30006 type:create cxid:0x1 zxid:0x49 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4698 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c9686c4a30006 type:create cxid:0x2 zxid:0x4a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4700 T796 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60297_xhr
[junit4:junit4]   2> 4701 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c9686c4a30006 type:delete cxid:0x4 zxid:0x4b txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:60297_xhr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:60297_xhr
[junit4:junit4]   2> 4703 T796 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60297_xhr
[junit4:junit4]   2> 4706 T818 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   2> 4709 T824 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4709 T818 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4712 T796 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4721 T838 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-1359735084254\collection1
[junit4:junit4]   2> 4721 T838 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4721 T838 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4721 T838 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4725 T838 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-1359735084254\collection1\'
[junit4:junit4]   2> 4727 T838 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-1359735084254/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 4729 T838 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-1359735084254/collection1/lib/README' to classloader
[junit4:junit4]   2> 4795 T838 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 4867 T838 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4869 T838 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4873 T838 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5577 T819 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5578 T819 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:60285_xhr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:60285/xhr"}
[junit4:junit4]   2> 5590 T818 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> 5590 T824 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> 5592 T837 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> 5725 T838 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 5735 T838 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 5738 T838 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5761 T838 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5767 T838 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5773 T838 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5776 T838 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5778 T838 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-1359735084254\collection1\, dataDir=.\org.apache.solr.cloud.RecoveryZkTest-1359735080083/jetty1\
[junit4:junit4]   2> 5778 T838 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@c0a9ec
[junit4:junit4]   2> 5778 T838 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 5779 T838 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-1359735080083\jetty1 forceNew:false
[junit4:junit4]   2> 5779 T838 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1359735080083\jetty1
[junit4:junit4]   2> 5779 T838 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.RecoveryZkTest-1359735080083/jetty1\index/
[junit4:junit4]   2> 5781 T838 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.RecoveryZkTest-1359735080083\jetty1\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 5782 T838 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-1359735080083\jetty1\index forceNew:false
[junit4:junit4]   2> 5784 T838 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@111c47 lockFactory=org.apache.lucene.store.NativeFSLockFactory@19bcf16)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5784 T838 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 5786 T838 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1359735080083\jetty1\index
[junit4:junit4]   2> 5786 T838 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 5788 T838 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 5788 T838 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 5788 T838 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 5789 T838 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 5789 T838 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 5789 T838 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 5790 T838 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 5790 T838 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 5791 T838 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 5791 T838 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 5792 T838 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 5792 T838 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 5793 T838 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 5793 T838 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 5793 T838 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 5793 T838 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 5794 T838 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5794 T838 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5795 T838 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5795 T838 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5796 T838 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5796 T838 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5797 T838 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5797 T838 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5797 T838 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 5797 T838 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 5808 T838 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 5812 T838 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 5814 T838 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> 5817 T838 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1359735080083\jetty1
[junit4:junit4]   2> 5817 T838 oass.SolrIndexSearcher.<init> Opening Searcher@2413fe main
[junit4:junit4]   2> 5819 T838 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 5820 T838 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 5820 T838 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 5831 T838 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 5898 T839 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2413fe main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 5903 T838 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 5903 T838 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 7046 T819 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7047 T819 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:60297_xhr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:60297/xhr"}
[junit4:junit4]   2> 7047 T819 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 1
[junit4:junit4]   2> 7048 T819 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 7060 T837 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> 7061 T818 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> 7062 T824 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> 7826 T838 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 7827 T838 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:60297/xhr collection:collection1 shard:shard1
[junit4:junit4]   2> 7828 T838 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 7841 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c9686c4a30006 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> 7843 T838 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 7843 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c9686c4a30006 type:create cxid:0x46 zxid:0x5c txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7848 T838 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 7848 T838 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 7848 T838 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:60297/xhr/collection1/
[junit4:junit4]   2> 7848 T838 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 7849 T838 oasc.SyncStrategy.syncToMe http://127.0.0.1:60297/xhr/collection1/ has no replicas
[junit4:junit4]   2> 7849 T838 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:60297/xhr/collection1/
[junit4:junit4]   2> 7849 T838 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 7858 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c9686c4a30006 type:create cxid:0x50 zxid:0x60 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8510 T819 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8528 T818 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> 8529 T824 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> 8528 T837 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> 8545 T838 oasc.ZkController.register We are http://127.0.0.1:60297/xhr/collection1/ and leader is http://127.0.0.1:60297/xhr/collection1/
[junit4:junit4]   2> 8545 T838 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:60297/xhr
[junit4:junit4]   2> 8545 T838 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 8545 T838 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4]   2> 8545 T838 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 8550 T838 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8551 T796 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 8551 T796 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 8552 T796 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 8976 T796 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4]   2> 8980 T796 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:60306
[junit4:junit4]   2> 8981 T796 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 8982 T796 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 8982 T796 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-1359735088643
[junit4:junit4]   2> 8983 T796 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-1359735088643\solr.xml
[junit4:junit4]   2> 8983 T796 oasc.CoreContainer.<init> New CoreContainer 7348654
[junit4:junit4]   2> 8984 T796 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-1359735088643\'
[junit4:junit4]   2> 8984 T796 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-1359735088643\'
[junit4:junit4]   2> 9044 T796 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 60000
[junit4:junit4]   2> 9044 T796 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 9045 T796 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4]   2> 9045 T796 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 9046 T796 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 9047 T796 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 9047 T796 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 9047 T796 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 9048 T796 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 9048 T796 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=60000&connTimeout=15000&retry=false
[junit4:junit4]   2> 9081 T796 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 9113 T796 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:60278/solr
[junit4:junit4]   2> 9113 T796 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4]   2> 9114 T796 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:60278 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@1be8609
[junit4:junit4]   2> 9122 T849 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:60278. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 9123 T796 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 9124 T849 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:60278, initiating session
[junit4:junit4]   2> 9125 T798 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:60311
[junit4:junit4]   2> 9125 T798 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:60311
[junit4:junit4]   2> 9138 T800 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c9686c4a30007 with negotiated timeout 20000 for client /127.0.0.1:60311
[junit4:junit4]   2> 9138 T849 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:60278, sessionid = 0x13c9686c4a30007, negotiated timeout = 20000
[junit4:junit4]   2> 9138 T850 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1be8609 name:ZooKeeperConnection Watcher:127.0.0.1:60278 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 9140 T796 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 9142 T801 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c9686c4a30007
[junit4:junit4]   2> 9144 T796 oaz.ZooKeeper.close Session: 0x13c9686c4a30007 closed
[junit4:junit4]   2> 9145 T796 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=60000&connTimeout=15000
[junit4:junit4]   2> 9145 T798 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:60311 which had sessionid 0x13c9686c4a30007
[junit4:junit4]   2> 9146 T850 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 9152 T796 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:60278/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@998b84
[junit4:junit4]   2> 9155 T796 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 9156 T851 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:60278. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 9156 T851 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:60278, initiating session
[junit4:junit4]   2> 9158 T798 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:60314
[junit4:junit4]   2> 9159 T798 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:60314
[junit4:junit4]   2> 9161 T800 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c9686c4a30008 with negotiated timeout 20000 for client /127.0.0.1:60314
[junit4:junit4]   2> 9161 T851 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:60278, sessionid = 0x13c9686c4a30008, negotiated timeout = 20000
[junit4:junit4]   2> 9161 T852 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@998b84 name:ZooKeeperConnection Watcher:127.0.0.1:60278/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 9161 T796 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 9163 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c9686c4a30008 type:create cxid:0x1 zxid:0x6d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 9165 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c9686c4a30008 type:create cxid:0x2 zxid:0x6e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 9168 T796 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60306_xhr
[junit4:junit4]   2> 9169 T801 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c9686c4a30008 type:delete cxid:0x4 zxid:0x6f txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:60306_xhr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:60306_xhr
[junit4:junit4]   2> 9171 T796 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60306_xhr
[junit4:junit4]   2> 9176 T818 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> 9177 T824 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> 9178 T837 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 9179 T837 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> 9188 T824 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 9188 T818 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 9191 T796 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 9204 T853 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-1359735088643\collection1
[junit4:junit4]   2> 9204 T853 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 9207 T853 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 9208 T853 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 9211 T853 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-1359735088643\collection1\'
[junit4:junit4]   2> 9214 T853 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-1359735088643/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 9215 T853 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-1359735088643/collection1/lib/README' to classloader
[junit4:junit4]   2> 9301 T853 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 9405 T853 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 9411 T853 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 9419 T853 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 9982 T819 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9985 T819 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:60297_xhr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:60297/xhr"}
[junit4:junit4]   2> 9995 T837 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> 9996 T824 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> 9996 T852 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> 9995 T818 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> 10252 T853 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 10261 T853 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 10265 T853 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 10285 T853 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 10291 T853 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 10297 T853 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 10298 T853 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 10300 T853 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-1359735088643\collection1\, dataDir=.\org.apache.solr.cloud.RecoveryZkTest-1359735080083/jetty2\
[junit4:junit4]   2> 10300 T853 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@c0a9ec
[junit4:junit4]   2> 10300 T853 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 10301 T853 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-1359735080083\jetty2 forceNew:false
[junit4:junit4]   2> 10301 T853 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1359735080083\jetty2
[junit4:junit4]   2> 10302 T853 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.RecoveryZkTest-1359735080083/jetty2\index/
[junit4:junit4]   2> 10303 T853 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.RecoveryZkTest-1359735080083\jetty2\index' doesn't exist. Creating new index...
[junit4:junit4]   2> 10303 T853 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-1359735080083\jetty2\index forceNew:false
[junit4:junit4]   2> 10307 T853 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@77657a lockFactory=org.apache.lucene.store.NativeFSLockFactory@189dabb)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 10307 T853 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 10309 T853 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1359735080083\jetty2\index
[junit4:junit4]   2> 10310 T853 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 10311 T853 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 10312 T853 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 10312 T853 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 10312 T853 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 10313 T853 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 10313 T853 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 10313 T853 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 10313 T853 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 10313 T853 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 10314 T853 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 10315 T853 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 10315 T853 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 10315 T853 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 10316 T853 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 10316 T853 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 10316 T853 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 10317 T853 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10317 T853 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10318 T853 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10319 T853 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10319 T853 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10320 T853 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10320 T853 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10321 T853 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 10321 T853 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 10322 T853 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 10333 T853 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 10339 T853 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 10340 T853 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> 10343 T853 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1359735080083\jetty2
[junit4:junit4]   2> 10343 T853 oass.SolrIndexSearcher.<init> Opening Searcher@1565e71 main
[junit4:junit4]   2> 10345 T853 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 10345 T853 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 10345 T853 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 10368 T853 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 10442 T854 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1565e71 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 10450 T853 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4]   2> 10450 T853 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 11450 T819 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 11450 T819 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:60306_xhr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:60306/xhr"}
[junit4:junit4]   2> 11450 T819 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
[junit4:junit4]   2> 11450 T819 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4]   2> 11464 T837 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> 11464 T818 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> 11465 T824 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> 11465 T852 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> 12375 T853 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4]   2> 12375 T853 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:60306/xhr collection:collection1 shard:shard1
[junit4:junit4]   2> 12380 T853 oasc.ZkController.register We are http://127.0.0.1:60306/xhr/collection1/ and leader is http://127.0.0.1:60297/xhr/collection1/
[junit4:junit4]   2> 12380 T853 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:60306/xhr
[junit4:junit4]   2> 12381 T853 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 12381 T853 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C35 name=collection1 org.apache.solr.core.SolrCore@c2186f url=http://127.0.0.1:60306/xhr/collection1 node=127.0.0.1:60306_xhr C35_STATE=coll:collection1 core:collection1 props:{shard=null, roles=null, state=down, core=collection1, collection=collection1, node_name=127.0.0.1:60306_xhr, base_url=http://127.0.0.1:60306/xhr}
[junit4:junit4]   2> 12381 T855 C35 P60306 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 12382 T855 C35 P60306 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 12383 T855 C35 P60306 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4]   2> 12383 T853 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12383 T855 C35 P60306 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 12385 T796 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4]   2> 12385 T796 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 12386 T796 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 12386 T855 C35 P60306 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 12402 T796 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C36 name=collection1 org.apache.solr.core.SolrCore@167ae1e url=http://127.0.0.1:60285/xhr/collection1 node=127.0.0.1:60285_xhr C36_STATE=coll:control_collection core:collection1 props:{shard=shard1, roles=null, state=active, core=collection1, collection=control_collection, node_name=127.0.0.1:60285_xhr, base_url=http://127.0.0.1:60285/xhr, leader=true}
[junit4:junit4]   2> 12425 T809 C36 P60285 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1359735080083\control\data
[junit4:junit4]   2> 12430 T809 C36 P60285 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@1058cae lockFactory=org.apache.lucene.store.NativeFSLockFactory@5db987)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 12430 T809 C36 P60285 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 12438 T809 C36 P60285 /update {wt=javabin&version=2} {add=[1 (1425785584355377152)]} 0 14
[junit4:junit4]   2> 12438 T814 C36 P60285 /update {wt=javabin&version=2} {add=[10001 (1425785584355377153)]} 0 14
[junit4:junit4]   2> ASYNC  NEW_CORE C37 name=collection1 org.apache.solr.core.SolrCore@1b9f635 url=http://127.0.0.1:60297/xhr/collection1 node=127.0.0.1:60297_xhr C37_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:60297_xhr, base_url=http://127.0.0.1:60297/xhr, leader=true}
[junit4:junit4]   2> 12449 T833 C37 P60297 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1359735080083\jetty1
[junit4:junit4]   2> 12452 T833 C37 P60297 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@111c47 lockFactory=org.apache.lucene.store.NativeFSLockFactory@19bcf16)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 12453 T833 C37 P60297 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 12457 T828 C37 P60297 /update {wt=javabin&version=2} {add=[1 (1425785584381591553)]} 0 9
[junit4:junit4]   2> 12457 T833 C37 P60297 /update {wt=javabin&version=2} {add=[10001 (1425785584381591552)]} 0 9
[junit4:junit4]   2> 12462 T811 C36 P60285 /update {wt=javabin&version=2} {delete=[1 (-1425785584394174464)]} 0 1
[junit4:junit4]   2> 12462 T810 C36 P60285 /update {wt=javabin&version=2} {delete=[10001 (-1425785584394174465)]} 0 2
[junit4:junit4]   2> 12465 T830 C37 P60297 /update {wt=javabin&version=2} {delete=[1 (-1425785584398368768)]} 0 1
[junit4:junit4]   2> 12465 T829 C37 P60297 /update {wt=javabin&version=2} {delete=[10001 (-1425785584398368769)]} 0 1
[junit4:junit4]   2> 12467 T812 C36 P60285 /update {wt=javabin&version=2} {add=[2 (1425785584399417344)]} 0 1
[junit4:junit4]   2> 12470 T831 C37 P60297 /update {wt=javabin&version=2} {add=[2 (1425785584402563072)]} 0 1
[junit4:junit4]   2> 12474 T813 C36 P60285 /update {wt=javabin&version=2} {add=[10002 (1425785584400465920)]} 0 7
[junit4:junit4]   2> 12474 T814 C36 P60285 /update {wt=javabin&version=2} {add=[3 (1425785584405708800)]} 0 2
[junit4:junit4]   2> 12478 T833 C37 P60297 /update {wt=javabin&version=2} {add=[3 (1425785584410951680)]} 0 1
[junit4:junit4]   2> 12478 T828 C37 P60297 /update {wt=javabin&version=2} {add=[10002 (1425785584410951681)]} 0 1
[junit4:junit4]   2> 12481 T809 C36 P60285 /update {wt=javabin&version=2} {add=[4 (1425785584414097408)]} 0 1
[junit4:junit4]   2> 12481 T811 C36 P60285 /update {wt=javabin&version=2} {add=[10003 (1425785584415145984)]} 0 0
[junit4:junit4]   2> 12485 T830 C37 P60297 /update {wt=javabin&version=2} {add=[4 (1425785584418291712)]} 0 1
[junit4:junit4]   2> 12486 T829 C37 P60297 /update {wt=javabin&version=2} {add=[10003 (1425785584418291713)]} 0 2
[junit4:junit4]   2> 12489 T810 C36 P60285 /update {wt=javabin&version=2} {add=[5 (1425785584422486016)]} 0 1
[junit4:junit4]   2> 12491 T812 C36 P60285 /update {wt=javabin&version=2} {add=[10004 (1425785584425631744)]} 0 1
[junit4:junit4]   2> 12494 T831 C37 P60297 /update {wt=javabin&version=2} {add=[5 (1425785584426680320)]} 0 2
[junit4:junit4]   2> 12495 T833 C37 P60297 /update {wt=javabin&version=2} {add=[10004 (1425785584429826048)]} 0 1
[junit4:junit4]   2> 12496 T813 C36 P60285 /update {wt=javabin&version=2} {delete=[3 (-1425785584430874624)]} 0 0
[junit4:junit4]   2> 12499 T828 C37 P60297 /update {wt=javabin&version=2} {delete=[3 (-1425785584434020352)]} 0 0
[junit4:junit4]   2> 12499 T814 C36 P60285 /update {wt=javabin&version=2} {add=[10005 (1425785584431923200)]} 0 2
[junit4:junit4]   2> 12508 T830 C37 P60297 /update {wt=javabin&version=2} {add=[10005 (1425785584441360384)]} 0 3
[junit4:junit4]   2> 12514 T809 C36 P60285 /update {wt=javabin&version=2} {add=[6 (1425785584441360384)]} 0 9
[junit4:junit4]   2> 12516 T811 C36 P60285 /update {wt=javabin&version=2} {delete=[10003 (-1425785584451846144)]} 0 0
[junit4:junit4]   2> 12518 T829 C37 P60297 /update {wt=javabin&version=2} {add=[6 (1425785584452894720)]} 0 1
[junit4:junit4]   2> 12521 T831 C37 P60297 /update {wt=javabin&version=2} {delete=[10003 (-1425785584457089024)]} 0 1
[junit4:junit4]   2> 12523 T810 C36 P60285 /update {wt=javabin&version=2} {delete=[4 (-1425785584459186176)]} 0 1
[junit4:junit4]   2> 12525 T812 C36 P60285 /update {wt=javabin&version=2} {add=[10006 (1425785584460234752)]} 0 1
[junit4:junit4]   2> 12526 T833 C37 P60297 /update {wt=javabin&version=2} {delete=[4 (-1425785584462331904)]} 0 0
[junit4:junit4]   2> 12528 T828 C37 P60297 /update {wt=javabin&version=2} {add=[10006 (1425785584463380480)]} 0 1
[junit4:junit4]   2> 12529 T813 C36 P60285 /update {wt=javabin&version=2} {add=[7 (1425785584465477632)]} 0 1
[junit4:junit4]   2> 12531 T809 C36 P60285 /update {wt=javabin&version=2} {delete=[10004 (-1425785584467574784)]} 0 0
[junit4:junit4]   2> 12532 T830 C37 P60297 /update {wt=javabin&version=2} {add=[7 (1425785584468623360)]} 0 0
[junit4:junit4]   2> 12533 T829 C37 P60297 /update {wt=javabin&version=2} {delete=[10004 (-1425785584469671936)]} 0 0
[junit4:junit4]   2> 12535 T811 C36 P60285 /update {wt=javabin&version=2} {delete=[5 (-1425785584471769088)]} 0 0
[junit4:junit4]   2> 12537 T810 C36 P60285 /update {wt=javabin&version=2} {add=[10007 (1425785584472817664)]} 0 1
[junit4:junit4]   2> 12538 T831 C37 P60297 /update {wt=javabin&version=2} {delete=[5 (-1425785584474914816)]} 0 0
[junit4:junit4]   2> 12539 T812 C36 P60285 /update {wt=javabin&version=2} {add=[8 (1425785584475963392)]} 0 0
[junit4:junit4]   2> 12540 T833 C37 P60297 /update {wt=javabin&version=2} {add=[10007 (1425785584475963392)]} 0 1
[junit4:junit4]   2> 12542 T828 C37 P60297 /update {wt=javabin&version=2} {add=[8 (1425785584479109120)]} 0 0
[junit4:junit4]   2> 12544 T814 C36 P60285 /update {wt=javabin&version=2} {delete=[10005 (-1425785584481206272)]} 0 0
[junit4:junit4]   2> 12546 T813 C36 P60285 /update {wt=javabin&version=2} {add=[9 (1425785584482254848)]} 0 1
[junit4:junit4]   2> 12547 T830 C37 P60297 /update {wt=javabin&version=2} {delete=[10005 (-1425785584484352000)]} 0 0
[junit4:junit4]   2> 12549 T829 C37 P60297 /update {wt=javabin&version=2} {add=[9 (1425785584485400576)]} 0 1
[junit4:junit4]   2> 12550 T809 C36 P60285 /update {wt=javabin&version=2} {add=[10008 (1425785584487497728)]} 0 0
[junit4:junit4]   2> 12551 T811 C36 P60285 /update {wt=javabin&version=2} {delete=[9 (-1425785584488546304)]} 0 1
[junit4:junit4]   2> 12554 T831 C37 P60297 /update {wt=javabin&version=2} {add=[10008 (1425785584490643456)]} 0 1
[junit4:junit4]   2> 12555 T833 C37 P60297 /update {wt=javabin&version=2} {delete=[9 (-1425785584492740608)]} 0 1
[junit4:junit4]   2> 12561 T812 C36 P60285 /update {wt=javabin&version=2} {add=[10009 (1425785584499032064)]} 0 1
[junit4:junit4]   2> 12561 T810 C36 P60285 /update {wt=javabin&version=2} {add=[10 (1425785584499032065)]} 0 0
[junit4:junit4]   2> 12565 T830 C37 P60297 /update {wt=javabin&version=2} {add=[10009 (1425785584501129216)]} 0 2
[junit4:junit4]   2> 12565 T828 C37 P60297 /update {wt=javabin&version=2} {add=[10 (1425785584502177792)]} 0 2
[junit4:junit4]   2> 12571 T813 C36 P60285 /update {wt=javabin&version=2} {delete=[10009 (-1425785584508469248)]} 0 2
[junit4:junit4]   2> 12575 T814 C36 P60285 /update {wt=javabin&version=2} {add=[11 (1425785584507420672)]} 0 6
[junit4:junit4]   2> 12575 T829 C37 P60297 /update {wt=javabin&version=2} {delete=[10009 (-1425785584513712128)]} 0 1
[junit4:junit4]   2> 12578 T809 C36 P60285 /update {wt=javabin&version=2} {add=[10010 (1425785584515809280)]} 0 1
[junit4:junit4]   2> 12582 T833 C37 P60297 /update {wt=javabin&version=2} {add=[10010 (1425785584521052160)]} 0 0
[junit4:junit4]   2> 12582 T831 C37 P60297 /update {wt=javabin&version=2} {add=[11 (1425785584515809280)]} 0 5
[junit4:junit4]   2> 12586 T811 C36 P60285 /update {wt=javabin&version=2} {add=[10011 (1425785584524197888)]} 0 1
[junit4:junit4]   2> 12586 T812 C36 P60285 /update {wt=javabin&version=2} {add=[12 (1425785584524197889)]} 0 1
[junit4:junit4]   2> 12589 T830 C37 P60297 /update {wt=javabin&version=2} {add=[10011 (1425785584527343616)]} 0 1
[junit4:junit4]   2> 12589 T828 C37 P60297 /update {wt=javabin&version=2} {add=[12 (1425785584527343617)]} 0 1
[junit4:junit4]   2> 12593 T813 C36 P60285 /update {wt=javabin&version=2} {delete=[12 (-1425785584532586496)]} 0 1
[junit4:junit4]   2> 12593 T810 C36 P60285 /update {wt=javabin&version=2} {add=[10012 (1425785584530489344)]} 0 2
[junit4:junit4]   2> 12596 T833 C37 P60297 /update {wt=javabin&version=2} {delete=[12 (-1425785584535732224)]} 0 1
[junit4:junit4]   2> 12597 T829 C37 P60297 /update {wt=javabin&version=2} {add=[10012 (1425785584535732225)]} 0 2
[junit4:junit4]   2> 12599 T814 C36 P60285 /update {wt=javabin&version=2} {add=[13 (1425785584538877952)]} 0 0
[junit4:junit4]   2> 12601 T809 C36 P60285 /update {wt=javabin&version=2} {delete=[10012 (-1425785584540975104)]} 0 1
[junit4:junit4]   2> 12601 T831 C37 P60297 /update {wt=javabin&version=2} {add=[13 (1425785584540975104)]} 0 0
[junit4:junit4]   2> 12604 T830 C37 P60297 /update {wt=javabin&version=2} {delete=[10012 (-1425785584544120832)]} 0 1
[junit4:junit4]   2> 12604 T812 C36 P60285 /update {wt=javabin&version=2} {add=[14 (1425785584544120832)]} 0 0
[junit4:junit4]   2> 12607 T811 C36 P60285 /update {wt=javabin&version=2} {add=[10013 (1425785584546217984)]} 0 1
[junit4:junit4]   2> 12607 T828 C37 P60297 /update {wt=javabin&version=2} {add=[14 (1425785584546217984)]} 0 1
[junit4:junit4]   2> 12609 T810 C36 P60285 /update {wt=javabin&version=2} {delete=[13 (-1425785584549363712)]} 0 0
[junit4:junit4]   2> 12610 T833 C37 P60297 /update {wt=javabin&version=2} {add=[10013 (1425785584549363712)]} 0 1
[junit4:junit4]   2> 12614 T813 C36 P60285 /update {wt=javabin&version=2} {add=[10014 (1425785584553558016)]} 0 1
[junit4:junit4]   2> 12616 T829 C37 P60297 /update {wt=javabin&version=2} {delete=[13 (-1425785584556703744)]} 0 1
[junit4:junit4]   2> 12618 T831 C37 P60297 /update {wt=javabin&version=2} {add=[10014 (1425785584557752320)]} 0 1
[junit4:junit4]   2> 12621 T809 C36 P60285 /update {wt=javabin&version=2} {delete=[10013 (-1425785584561946624)]} 0 1
[junit4:junit4]   2> 12621 T814 C36 P60285 /update {wt=javabin&version=2} {add=[15 (1425785584561946625)]} 0 1
[junit4:junit4]   2> 12624 T830 C37 P60297 /update {wt=javabin&version=2} {delete=[10013 (-1425785584565092352)]} 0 1
[junit4:junit4]   2> 12625 T833 C37 P60297 /update {wt=javabin&version=2} {add=[15 (1425785584565092353)]} 0 1
[junit4:junit4]   2> 12627 T812 C36 P60285 /update {wt=javabin&version=2} {delete=[14 (-1425785584568238081)]} 0 0
[junit4:junit4]   2> 12631 T811 C36 P60285 /update {wt=javabin&version=2} {add=[10015 (1425785584568238080)]} 0 4
[junit4:junit4]   2> 12632 T829 C37 P60297 /update {wt=javabin&version=2} {delete=[14 (-1425785584573480960)]} 0 1
[junit4:junit4]   2> 12637 T810 C36 P60285 /update {wt=javabin&version=2} {add=[16 (1425785584576626688)]} 0 2
[junit4:junit4]   2> 12637 T831 C37 P60297 /update {wt=javabin&version=2} {add=[10015 (1425785584575578112)]} 0 3
[junit4:junit4]   2> 12640 T828 C37 P60297 /update {wt=javabin&version=2} {add=[16 (1425785584580820992)]} 0 1
[junit4:junit4]   2> 12641 T813 C36 P60285 /update {wt=javabin&version=2} {delete=[10014 (-1425785584582918144)]} 0 0
[junit4:junit4]   2> 12643 T809 C36 P60285 /update {wt=javabin&version=2} {delete=[15 (-1425785584585015296)]} 0 1
[junit4:junit4]   2> 12644 T833 C37 P60297 /update {wt=javabin&version=2} {delete=[10014 (-1425785584586063872)]} 0 1
[junit4:junit4]   2> 12646 T830 C37 P60297 /update {wt=javabin&version=2} {delete=[15 (-1425785584588161024)]} 0 1
[junit4:junit4]   2> 12647 T814 C36 P60285 /update {wt=javabin&version=2} {add=[10016 (1425785584589209600)]} 0 0
[junit4:junit4]   2> 12648 T812 C36 P60285 /update {wt=javabin&version=2} {add=[17 (1425785584589209601)]} 0 1
[junit4:junit4]   2> 12651 T829 C37 P60297 /update {wt=javabin&version=2} {add=[10016 (1425785584592355328)]} 0 1
[junit4:junit4]   2> 12652 T831 C37 P60297 /update {wt=javabin&version=2} {add=[17 (1425785584593403904)]} 0 1
[junit4:junit4]   2> 12654 T811 C36 P60285 /update {wt=javabin&version=2} {delete=[10015 (-1425785584596549632)]} 0 0
[junit4:junit4]   2> 12656 T810 C36 P60285 /update {wt=javabin&version=2} {delete=[17 (-1425785584598646784)]} 0 1
[junit4:junit4]   2> 12658 T828 C37 P60297 /update {wt=javabin&version=2} {delete=[10015 (-1425785584600743936)]} 0 1
[junit4:junit4]   2> 12659 T833 C37 P60297 /update {wt=javabin&version=2} {delete=[17 (-1425785584601792512)]} 0 1
[junit4:junit4]   2> 12662 T813 C36 P60285 /update {wt=javabin&version=2} {add=[10017 (1425785584602841088)]} 0 2
[junit4:junit4]   2> 12663 T809 C36 P60285 /update {wt=javabin&version=2} {add=[18 (1425785584604938240)]} 0 1
[junit4:junit4]   2> 12665 T830 C37 P60297 /update {wt=javabin&version=2} {add=[10017 (1425785584607035392)]} 0 1
[junit4:junit4]   2> 12665 T829 C37 P60297 /update {wt=javabin&version=2} {add=[18 (1425785584608083968)]} 0 0
[junit4:junit4]   2> 12669 T812 C36 P60285 /update {wt=javabin&version=2} {delete=[10017 (-1425785584612278272)]} 0 1
[junit4:junit4]   2> 12669 T814 C36 P60285 /update {wt=javabin&version=2} {add=[19 (1425785584611229696)]} 0 1
[junit4:junit4]   2> 12672 T828 C37 P60297 /update {wt=javabin&version=2} {delete=[10017 (-1425785584615424000)]} 0 1
[junit4:junit4]   2> 12673 T831 C37 P60297 /update {wt=javabin&version=2} {add=[19 (1425785584614375424)]} 0 2
[junit4:junit4]   2> 12676 T810 C36 P60285 /update {wt=javabin&version=2} {delete=[19 (-1425785584619618304)]} 0 1
[junit4:junit4]   2> 12676 T811 C36 P60285 /update {wt=javabin&version=2} {add=[10018 (1425785584617521152)]} 0 2
[junit4:junit4]   2> 12680 T833 C37 P60297 /update {wt=javabin&version=2} {delete=[19 (-1425785584623812608)]} 0 1
[junit4:junit4]   2> 12681 T829 C37 P60297 /update {wt=javabin&version=2} {add=[10018 (1425785584623812609)]} 0 1
[junit4:junit4]   2> 12682 T813 C36 P60285 /update {wt=javabin&version=2} {add=[20 (1425785584625909760)]} 0 0
[junit4:junit4]   2> 12685 T809 C36 P60285 /update {wt=javabin&version=2} {add=[10019 (1425785584628006912)]} 0 1
[junit4:junit4]   2> 12685 T830 C37 P60297 /update {wt=javabin&version=2} {add=[20 (1425785584629055488)]} 0 0
[junit4:junit4]   2> 12688 T828 C37 P60297 /update {wt=javabin&version=2} {add=[10019 (1425785584631152640)]} 0 1
[junit4:junit4]   2> 12689 T812 C36 P60285 /update {wt=javabin&version=2} {add=[21 (1425785584631152640)]} 0 2
[junit4:junit4]   2> 12691 T814 C36 P60285 /update {wt=javabin&version=2} {delete=[10019 (-1425785584635346944)]} 0 0
[junit4:junit4]   2> 12693 T831 C37 P60297 /update {wt=javabin&version=2} {add=[21 (1425785584636395520)]} 0 1
[junit4:junit4]   2> 12695 T833 C37 P60297 /update {wt=javabin&version=2} {delete=[10019 (-1425785584639541248)]} 0 0
[junit4:junit4]   2> 12696 T811 C36 P60285 /update {wt=javabin&version=2} {delete=[20 (-1425785584640589824)]} 0 0
[junit4:junit4]   2> 12698 T810 C36 P60285 /update {wt=javabin&version=2} {add=[10020 (1425785584641638400)]} 0 1
[junit4:junit4]   2> 12699 T829 C37 P60297 /update {wt=javabin&version=2} {delete=[20 (-1425785584643735552)]} 0 0
[junit4:junit4]   2> 12702 T830 C37 P60297 /update {wt=javabin&version=2} {add=[10020 (1425785584645832704)]} 0 1
[junit4:junit4]   2> 12702 T813 C36 P60285 /update {wt=javabin&version=2} {add=[22 (1425785584646881280)]} 0 0
[junit4:junit4]   2> 12703 T809 C36 P60285 /update {wt=javabin&version=2} {add=[10021 (1425785584647929856)]} 0 0
[junit4:junit4]   2> 12705 T828 C37 P60297 /update {wt=javabin&version=2} {add=[22 (1425785584650027008)]} 0 0
[junit4:junit4]   2> 12707 T831 C37 P60297 /update {wt=javabin&version=2} {add=[10021 (1425785584652124160)]} 0 0
[junit4:junit4]   2> 12707 T812 C36 P60285 /update {wt=javabin&version=2} {add=[23 (1425785584652124160)]} 0 0
[junit4:junit4]   2> 12709 T814 C36 P60285 /update {wt=javabin&version=2} {delete=[10020 (-1425785584654221312)]} 0 0
[junit4:junit4]   2> 12710 T833 C37 P60297 /update {wt=javabin&version=2} {add=[23 (1425785584655269888)]} 0 0
[junit4:junit4]   2> 12712 T829 C37 P60297 /update {wt=javabin&version=2} {delete=[10020 (-1425785584657367040)]} 0 0
[junit4:junit4]   2> 12713 T811 C36 P60285 /update {wt=javabin&version=2} {add=[24 (1425785584658415616)]} 0 0
[junit4:junit4]   2> 12714 T810 C36 P60285 /update {wt=javabin&version=2} {add=[10022 (1425785584659464192)]} 0 0
[junit4:junit4]   2> 12716 T830 C37 P60297 /update {wt=javabin&version=2} {add=[24 (1425785584661561344)]} 0 0
[junit4:junit4]   2> 12718 T828 C37 P60297 /update {wt=javabin&version=2} {add=[10022 (1425785584663658496)]} 0 0
[junit4:junit4]   2> 12720 T813 C36 P60285 /update {wt=javabin&version=2} {delete=[23 (-1425785584665755648)]} 0 1
[junit4:junit4]   2> 12721 T809 C36 P60285 /update {wt=javabin&version=2} {add=[10023 (1425785584665755649)]} 0 1
[junit4:junit4]   2> 12723 T831 C37 P60297 /update {wt=javabin&version=2} {delete=[23 (-1425785584668901376)]} 0 0
[junit4:junit4]   2> 12724 T833 C37 P60297 /update {wt=javabin&version=2} {add=[10023 (1425785584669949952)]} 0 0
[junit4:junit4]   2> 12726 T812 C36 P60285 /update {wt=javabin&version=2} {add=[25 (1425785584670998528)]} 0 1
[junit4:junit4]   2> 12728 T814 C36 P60285 /update {wt=javabin&version=2} {add=[10024 (1425785584673095680)]} 0 1
[junit4:junit4]   2> 12729 T829 C37 P60297 /update {wt=javabin&version=2} {add=[25 (1425785584675192832)]} 0 0
[junit4:junit4]   2> 12731 T830 C37 P60297 /update {wt=javabin&version=2} {add=[10024 (1425785584676241408)]} 0 1
[junit4:junit4]   2> 12734 T810 C36 P60285 /update {wt=javabin&version=2} {delete=[10023 (-1425785584680435712)]} 0 0
[junit4:junit4]   2> 12735 T811 C36 P60285 /update {wt=javabin&version=2} {add=[26 (1425785584678338560)]} 0 3
[junit4:junit4]   2> 12737 T828 C37 P60297 /update {wt=javabin&version=2} {delete=[10023 (-1425785584683581440)]} 0 0
[junit4:junit4]   2> 12740 T813 C36 P60285 /update {wt=javabin&version=2} {add=[10025 (1425785584686727168)]} 0 0
[junit4:junit4]   2> 12741 T831 C37 P60297 /update {wt=javabin&version=2} {add=[26 (1425785584684630016)]} 0 3
[junit4:junit4]   2> 12744 T833 C37 P60297 /update {wt=javabin&version=2} {add=[10025 (1425785584689872896)]} 0 1
[junit4:junit4]   2> 12744 T809 C36 P60285 /update {wt=javabin&version=2} {delete=[25 (-1425785584690921472)]} 0 0
[junit4:junit4]   2> 12746 T829 C37 P60297 /update {wt=javabin&version=2} {delete=[25 (-1425785584693018624)]} 0 0
[junit4:junit4]   2> 12747 T812 C36 P60285 /update {wt=javabin&version=2} {add=[10026 (1425785584693018624)]} 0 1
[junit4:junit4]   2> 12749 T830 C37 P60297 /update {wt=javabin&version=2} {add=[10026 (1425785584696164352)]} 0 0
[junit4:junit4]   2> 12750 T814 C36 P60285 /update {wt=javabin&version=2} {add=[27 (1425785584696164352)]} 0 1
[junit4:junit4]   2> 12751 T810 C36 P60285 /update {wt=javabin&version=2} {delete=[10025 (-1425785584698261504)]} 0 0
[junit4:junit4]   2> 12754 T828 C37 P60297 /update {wt=javabin&version=2} {add=[27 (1425785584699310080)]} 0 2
[junit4:junit4]   2> 12754 T831 C37 P60297 /update {wt=javabin&version=2} {delete=[10025 (-1425785584701407232)]} 0 0
[junit4:junit4]   2> 12757 T813 C36 P60285 /update {wt=javabin&version=2} {add=[28 (1425785584703504384)]} 0 1
[junit4:junit4]   2> 12758 T811 C36 P60285 /update {wt=javabin&version=2} {add=[10027 (1425785584703504385)]} 0 2
[junit4:junit4]   2> 12761 T833 C37 P60297 /update {wt=javabin&version=2} {add=[28 (1425785584707698688)]} 0 1
[junit4:junit4]   2> 12761 T829 C37 P60297 /update {wt=javabin&version=2} {add=[10027 (1425785584708747264)]} 0 0
[junit4:junit4]   2> 12762 T809 C36 P60285 /update {wt=javabin&version=2} {delete=[28 (-1425785584709795840)]} 0 0
[junit4:junit4]   2> 12765 T812 C36 P60285 /update {wt=javabin&version=2} {add=[10028 (1425785584711892992)]} 0 1
[junit4:junit4]   2> 12766 T830 C37 P60297 /update {wt=javabin&version=2} {delete=[28 (-1425785584713990144)]} 0 0
[junit4:junit4]   2> 12767 T831 C37 P60297 /update {wt=javabin&version=2} {add=[10028 (1425785584715038720)]} 0 0
[junit4:junit4]   2> 12769 T814 C36 P60285 /update {wt=javabin&version=2} {add=[29 (1425785584716087296)]} 0 1
[junit4:junit4]   2> 12769 T810 C36 P60285 /update {wt=javabin&version=2} {delete=[10028 (-1425785584717135872)]} 0 0
[junit4:junit4]   2> 12772 T828 C37 P60297 /update {wt=javabin&version=2} {add=[29 (1425785584719233024)]} 0 1
[junit4:junit4]   2> 12772 T833 C37 P60297 /update {wt=javabin&version=2} {delete=[10028 (-1425785584720281600)]} 0 0
[junit4:junit4]   2> 12773 T813 C36 P60285 /update {wt=javabin&version=2} {delete=[29 (-1425785584721330176)]} 0 0
[junit4:junit4]   2> 12775 T811 C36 P60285 /update {wt=javabin&version=2} {add=[10029 (1425785584723427328)]} 0 0
[junit4:junit4]   2> 12777 T829 C37 P60297 /update {wt=javabin&version=2} {delete=[29 (-1425785584725524480)]} 0 0
[junit4:junit4]   2> 12778 T829 C37 P60297 /update {wt=javabin&version=2} {add=[10029 (1425785584726573056)]} 0 0
[junit4:junit4]   2> 12780 T809 C36 P60285 /update {wt=javabin&version=2} {add=[30 (1425785584727621632)]} 0 1
[junit4:junit4]   2> 12781 T812 C36 P60285 /update {wt=javabin&version=2} {delete=[10029 (-1425785584729718784)]} 0 0
[junit4:junit4]   2> 12783 T831 C37 P60297 /update {wt=javabin&version=2} {add=[30 (1425785584731815936)]} 0 0
[junit4:junit4]   2> 12783 T833 C37 P60297 /update {wt=javabin&version=2} {delete=[10029 (-1425785584731815937)]} 0 0
[junit4:junit4]   2> 12787 T810 C36 P60285 /update {wt=javabin&version=2} {add=[10030 (1425785584736010240)]} 0 0
[junit4:junit4]   2> 12788 T814 C36 P60285 /update {wt=javabin&version=2} {add=[31 (1425785584733913088)]} 0 3
[junit4:junit4]   2> 12791 T828 C37 P60297 /update {wt=javabin&version=2} {add=[10030 (1425785584738107392)]} 0 2
[junit4:junit4]   2> 12791 T830 C37 P60297 /update {wt=javabin&version=2} {add=[31 (1425785584739155968)]} 0 1
[junit4:junit4]   2> 12794 T811 C36 P60285 /update {wt=javabin&version=2} {delete=[31 (-1425785584743350273)]} 0 0
[junit4:junit4]   2> 12795 T813 C36 P60285 /update {wt=javabin&version=2} {add=[10031 (1425785584743350272)]} 0 1
[junit4:junit4]   2> 12796 T829 C37 P60297 /update {wt=javabin&version=2} {delete=[31 (-1425785584745447424)]} 0 0
[junit4:junit4]   2> 12797 T833 C37 P60297 /update {wt=javabin&version=2} {add=[10031 (1425785584746496000)]} 0 0
[junit4:junit4]   2> 12799 T809 C36 P60285 /update {wt=javabin&version=2} {add=[32 (1425785584748593152)]} 0 0
[junit4:junit4]   2> 12800 T812 C36 P60285 /update {wt=javabin&version=2} {delete=[10031 (-1425785584749641728)]} 0 0
[junit4:junit4]   2> 12803 T831 C37 P60297 /update {wt=javabin&version=2} {add=[32 (1425785584751738880)]} 0 1
[junit4:junit4]   2> 12805 T828 C37 P60297 /update {wt=javabin&version=2} {delete=[10031 (-1425785584754884608)]} 0 0
[junit4:junit4]   2> 12807 T810 C36 P60285 /update {wt=javabin&version=2} {delete=[32 (-1425785584755933184)]} 0 1
[junit4:junit4]   2> 12809 T814 C36 P60285 /update {wt=javabin&version=2} {add=[10032 (1425785584758030336)]} 0 1
[junit4:junit4]   2> 12809 T830 C37 P60297 /update {wt=javabin&version=2} {delete=[32 (-1425785584759078912)]} 0 0
[junit4:junit4]   2> 12811 T829 C37 P60297 /update {wt=javabin&version=2} {add=[10032 (1425785584760127488)]} 0 1
[junit4:junit4]   2> 12812 T811 C36 P60285 /update {wt=javabin&version=2} {add=[33 (1425785584761176064)]} 0 1
[junit4:junit4]   2> 12813 T813 C36 P60285 /update {wt=javabin&version=2} {delete=[10032 (-1425785584763273216)]} 0 0
[junit4:junit4]   2> 12815 T833 C37 P60297 /update {wt=javabin&version=2} {add=[33 (1425785584764321792)]} 0 1
[junit4:junit4]   2> 12815 T831 C37 P60297 /update {wt=javabin&version=2} {delete=[10032 (-1425785584765370368)]} 0 0
[junit4:junit4]   2> 12817 T812 C36 P60285 /update {wt=javabin&version=2} {delete=[33 (-1425785584767467520)]} 0 0
[junit4:junit4]   2> 12819 T809 C36 P60285 /update {wt=javabin&version=2} {add=[10033 (1425785584768516096)]} 0 1
[junit4:junit4]   2> 12820 T828 C37 P60297 /update {wt=javabin&version=2} {delete=[33 (-1425785584770613248)]} 0 0
[junit4:junit4]   2> 12821 T830 C37 P60297 /update {wt=javabin&version=2} {add=[10033 (1425785584771661824)]} 0 0
[junit4:junit4]   2> 12823 T810 C36 P60285 /update {wt=javabin&version=2} {add=[34 (1425785584772710400)]} 0 1
[junit4:junit4]   2> 12824 T814 C36 P60285 /update {wt=javabin&version=2} {delete=[10033 (-1425785584774807552)]} 0 0
[junit4:junit4]   2> 12826 T829 C37 P60297 /update {wt=javabin&version=2} {add=[34 (1425785584775856128)]} 0 1
[junit4:junit4]   2> 12827 T833 C37 P60297 /update {wt=javabin&version=2} {delete=[10033 (-1425785584776904704)]} 0 1
[junit4:junit4]   2> 12828 T811 C36 P60285 /update {wt=javabin&version=2} {delete=[34 (-1425785584779001856)]} 0 0
[junit4:junit4]   2> 12829 T813 C36 P60285 /update {wt=javabin&version=2} {add=[10034 (1425785584780050432)]} 0 0
[junit4:junit4]   2> 12832 T831 C37 P60297 /update {wt=javabin&version=2} {delete=[34 (-1425785584783196160)]} 0 0
[junit4:junit4]   2> 12833 T828 C37 P60297 /update {wt=javabin&version=2} {add=[10034 (1425785584783196161)]} 0 1
[junit4:junit4]   2> 12834 T812 C36 P60285 /update {wt=javabin&version=2} {add=[35 (1425785584784244736)]} 0 1
[junit4:junit4]   2> 12835 T809 C36 P60285 /update {wt=javabin&version=2} {delete=[10034 (-1425785584786341888)]} 0 0
[junit4:junit4]   2> 12837 T830 C37 P60297 /update {wt=javabin&version=2} {add=[35 (1425785584788439040)]} 0 1
[junit4:junit4]   2> 12837 T829 C37 P60297 /update {wt=javabin&version=2} {delete=[10034 (-1425785584788439041)]} 0 0
[junit4:junit4]   2> 12838 T810 C36 P60285 /update {wt=javabin&version=2} {delete=[35 (-1425785584789487616)]} 0 0
[junit4:junit4]   2> 12842 T810 C36 P60285 /update {wt=javabin&version=2} {add=[10035 (1425785584791584768)]} 0 2
[junit4:junit4]   2> 12845 T833 C37 P60297 /update {wt=javabin&version=2} {add=[10035 (1425785584795779072)]} 0 1
[junit4:junit4]   2> 12847 T831 C37 P60297 /update {wt=javabin&version=2} {delete=[35 (-1425785584798924800)]} 0 0
[junit4:junit4]   2> 12848 T811 C36 P60285 /update {wt=javabin&version=2} {delete=[10035 (-1425785584799973376)]} 0 0
[junit4:junit4]   2> 12850 T828 C37 P60297 /update {wt=javabin&version=2} {delete=[10035 (-1425785584802070528)]} 0 0
[junit4:junit4]   2> 12851 T813 C36 P60285 /update {wt=javabin&version=2} {add=[36 (1425785584802070528)]} 0 1
[junit4:junit4]   2> 12853 T812 C36 P60285 /update {wt=javabin&version=2} {add=[10036 (1425785584805216256)]} 0 0
[junit4:junit4]   2> 12854 T830 C37 P60297 /update {wt=javabin&version=2} {add=[36 (1425785584805216256)]} 0 1
[junit4:junit4]   2> 12855 T829 C37 P60297 /update {wt=javabin&version=2} {add=[10036 (1425785584807313408)]} 0 0
[junit4:junit4]   2> 12857 T809 C36 P60285 /update {wt=javabin&version=2} {add=[37 (1425785584808361984)]} 0 1
[junit4:junit4]   2> 12859 T810 C36 P60285 /update {wt=javabin&version=2} {add=[10037 (1425785584810459136)]} 0 1
[junit4:junit4]   2> 12859 T833 C37 P60297 /update {wt=javabin&version=2} {add=[37 (1425785584811507712)]} 0 0
[junit4:junit4]   2> 12861 T831 C37 P60297 /update {wt=javabin&version=2} {add=[10037 (1425785584812556288)]} 0 1
[junit4:junit4]   2> 12861 T814 C36 P60285 /update {wt=javabin&version=2} {delete=[37 (-1425785584813604864)]} 0 0
[junit4:junit4]   2> 12863 T811 C36 P60285 /update {wt=javabin&version=2} {delete=[10037 (-1425785584815702016)]} 0 0
[junit4:junit4]   2> 12864 T828 C37 P60297 /update {wt=javabin&version=2} {delete=[10037 (-1425785584816750592)]} 0 0
[junit4:junit4]   2> 12864 T830 C37 P60297 /update {wt=javabin&version=2} {delete=[37 (-1425785584816750593)]} 0 0
[junit4:junit4]   2> 12868 T813 C36 P60285 /update {wt=javabin&version=2} {add=[10038 (1425785584819896320)]} 0 1
[junit4:junit4]   2> 12868 T812 C36 P60285 /update {wt=javabin&version=2} {add=[38 (1425785584819896321)]} 0 1
[junit4:junit4]   2> 12870 T829 C37 P60297 /update {wt=javabin&version=2} {add=[10038 (1425785584823042048)]} 0 0
[junit4:junit4]   2> 12870 T833 C37 P60297 /update {wt=javabin&version=2} {add=[38 (1425785584823042049)]} 0 0
[junit4:junit4]   2> 12872 T809 C36 P60285 /update {wt=javabin&version=2} {add=[10039 (1425785584825139200)]} 0 0
[junit4:junit4]   2> 12873 T810 C36 P60285 /update {wt=javabin&version=2} {add=[39 (1425785584826187776)]} 0 0
[junit4:junit4]   2> 12875 T831 C37 P60297 /update {wt=javabin&version=2} {add=[10039 (1425785584828284928)]} 0 0
[junit4:junit4]   2> 12876 T828 C37 P60297 /update {wt=javabin&version=2} {add=[39 (1425785584828284929)]} 0 1
[junit4:junit4]   2> 12877 T819 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12877 T814 C36 P60285 /update {wt=javabin&version=2} {add=[10040 (1425785584830382080)]} 0 0
[junit4:junit4]   2> 12878 T819 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:60306_xhr",
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:60306/xhr"}
[junit4:junit4]   2> 12881 T811 C36 P60285 /update {wt=javabin&version=2} {add=[40 (1425785584831430656)]} 0 3
[junit4:junit4]   2> 12881 T830 C37 P60297 /update {wt=javabin&version=2} {add=[10040 (1425785584834576384)]} 0 0
[junit4:junit4]   2> 12884 T813 C36 P60285 /update {wt=javabin&version=2} {delete=[10038 (-1425785584837722112)]} 0 0
[junit4:junit4]   2> 12886 T833 C37 P60297 /update {wt=javabin&version=2} {delete=[10038 (-1425785584839819264)]} 0 0
[junit4:junit4]   2> 12886 T829 C37 P60297 /update {wt=javabin&version=2} {add=[40 (1425785584837722112)]} 0 2
[junit4:junit4]   2> 12890 T809 C36 P60285 /update {wt=javabin&version=2} {delete=[38 (-1425785584842964993)]} 0 1
[junit4:junit4]   2> 12892 T818 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> 12892 T831 C37 P60297 /update {wt=javabin&version=2} {delete=[38 (-1425785584846110720)]} 0 0
[junit4:junit4]   2> 12892 T852 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> 12892 T824 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> 12892 T837 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> 12928 T812 C36 P60285 /update {wt=javabin&version=2} {add=[10041 (1425785584842964992)]} 0 39
[junit4:junit4]   2> 12929 T810 C36 P60285 /update {wt=javabin&version=2} {add=[41 (1425785584849256448)]} 0 34
[junit4:junit4]   2>  C35_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:60306_xhr, base_url=http://127.0.0.1:60306/xhr}
[junit4:junit4]   2> 12951 T848 C35 P60306 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1359735080083\jetty2
[junit4:junit4]   2> 12954 T848 C35 P60306 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@77657a lockFactory=org.apache.lucene.store.NativeFSLockFactory@189dabb)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 12955 T848 C35 P60306 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 12958 T848 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10041 (1425785584887005185)]} 0 14
[junit4:junit4]   2> 12958 T843 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[41 (1425785584887005184)]} 0 8
[junit4:junit4]   2> 12959 T830 C37 P60297 /update {wt=javabin&version=2} {add=[10041 (1425785584887005185)]} 0 28
[junit4:junit4]   2> 12960 T828 C37 P60297 /update {wt=javabin&version=2} {add=[41 (1425785584887005184)]} 0 29
[junit4:junit4]   2> 12962 T811 C36 P60285 /update {wt=javabin&version=2} {delete=[10039 (-1425785584919511041)]} 0 0
[junit4:junit4]   2> 12962 T813 C36 P60285 /update {wt=javabin&version=2} {delete=[39 (-1425785584919511040)]} 0 0
[junit4:junit4]   2> 12967 T845 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10039 (-1425785584921608192)]} 0 1
[junit4:junit4]   2> 12967 T844 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[39 (-1425785584921608193)]} 0 1
[junit4:junit4]   2> 12967 T833 C37 P60297 /update {wt=javabin&version=2} {delete=[39 (-1425785584921608193)]} 0 3
[junit4:junit4]   2> 12967 T829 C37 P60297 /update {wt=javabin&version=2} {delete=[10039 (-1425785584921608192)]} 0 3
[junit4:junit4]   2> 12970 T809 C36 P60285 /update {wt=javabin&version=2} {add=[10042 (1425785584927899648)]} 0 0
[junit4:junit4]   2> 12971 T814 C36 P60285 /update {wt=javabin&version=2} {add=[42 (1425785584927899649)]} 0 1
[junit4:junit4]   2> 12976 T846 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10042 (1425785584931045376)]} 0 1
[junit4:junit4]   2> 12977 T831 C37 P60297 /update {wt=javabin&version=2} {add=[10042 (1425785584931045376)]} 0 4
[junit4:junit4]   2> 12977 T847 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[42 (1425785584931045377)]} 0 1
[junit4:junit4]   2> 12978 T830 C37 P60297 /update {wt=javabin&version=2} {add=[42 (1425785584931045377)]} 0 5
[junit4:junit4]   2> 12978 T812 C36 P60285 /update {wt=javabin&version=2} {add=[10043 (1425785584936288256)]} 0 0
[junit4:junit4]   2> 12980 T810 C36 P60285 /update {wt=javabin&version=2} {add=[43 (1425785584937336832)]} 0 1
[junit4:junit4]   2> 12984 T848 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10043 (1425785584939433984)]} 0 0
[junit4:junit4]   2> 12985 T843 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[43 (1425785584941531136)]} 0 1
[junit4:junit4]   2> 12985 T828 C37 P60297 /update {wt=javabin&version=2} {add=[10043 (1425785584939433984)]} 0 4
[junit4:junit4]   2> 12985 T829 C37 P60297 /update {wt=javabin&version=2} {add=[43 (1425785584941531136)]} 0 2
[junit4:junit4]   2> 12987 T811 C36 P60285 /update {wt=javabin&version=2} {add=[10044 (1425785584945725440)]} 0 0
[junit4:junit4]   2> 12988 T813 C36 P60285 /update {wt=javabin&version=2} {add=[44 (1425785584946774016)]} 0 0
[junit4:junit4]   2> 12993 T845 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10044 (1425785584947822592)]} 0 1
[junit4:junit4]   2> 12993 T833 C37 P60297 /update {wt=javabin&version=2} {add=[10044 (1425785584947822592)]} 0 4
[junit4:junit4]   2> 12995 T844 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[44 (1425785584949919744)]} 0 1
[junit4:junit4]   2> 12995 T831 C37 P60297 /update {wt=javabin&version=2} {add=[44 (1425785584949919744)]} 0 4
[junit4:junit4]   2> 12996 T809 C36 P60285 /update {wt=javabin&version=2} {delete=[10040 (-1425785584955162624)]} 0 0
[junit4:junit4]   2> 12998 T814 C36 P60285 /update {wt=javabin&version=2} {delete=[40 (-1425785584957259776)]} 0 0
[junit4:junit4]   2> 13000 T846 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10040 (-1425785584958308352)]} 0 0
[junit4:junit4]   2> 13001 T830 C37 P60297 /update {wt=javabin&version=2} {delete=[10040 (-1425785584958308352)]} 0 2
[junit4:junit4]   2> 13003 T847 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[40 (-1425785584960405504)]} 0 0
[junit4:junit4]   2> 13004 T828 C37 P60297 /update {wt=javabin&version=2} {delete=[40 (-1425785584960405504)]} 0 3
[junit4:junit4]   2> 13005 T812 C36 P60285 /update {wt=javabin&version=2} {add=[10045 (1425785584964599808)]} 0 0
[junit4:junit4]   2> 13008 T812 C36 P60285 /update {wt=javabin&version=2} {add=[45 (1425785584965648384)]} 0 2
[junit4:junit4]   2> 13010 T848 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10045 (1425785584967745536)]} 0 0
[junit4:junit4]   2> 13011 T829 C37 P60297 /update {wt=javabin&version=2} {add=[10045 (1425785584967745536)]} 0 3
[junit4:junit4]   2> 13014 T811 C36 P60285 /update {wt=javabin&version=2} {add=[10046 (1425785584974036992)]} 0 0
[junit4:junit4]   2> 13016 T843 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[45 (1425785584969842688)]} 0 2
[junit4:junit4]   2> 13016 T833 C37 P60297 /update {wt=javabin&version=2} {add=[45 (1425785584969842688)]} 0 6
[junit4:junit4]   2> 13018 T813 C36 P60285 /update {wt=javabin&version=2} {add=[46 (1425785584978231296)]} 0 0
[junit4:junit4]   2> 13021 T845 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10046 (1425785584976134144)]} 0 1
[junit4:junit4]   2> 13022 T831 C37 P60297 /update {wt=javabin&version=2} {add=[10046 (1425785584976134144)]} 0 6
[junit4:junit4]   2> 13023 T809 C36 P60285 /update {wt=javabin&version=2} {delete=[10043 (-1425785584983474176)]} 0 0
[junit4:junit4]   2> 13024 T844 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[46 (1425785584982425600)]} 0 0
[junit4:junit4]   2> 13025 T830 C37 P60297 /update {wt=javabin&version=2} {add=[46 (1425785584982425600)]} 0 3
[junit4:junit4]   2> 13027 T814 C36 P60285 /update {wt=javabin&version=2} {delete=[43 (-1425785584987668480)]} 0 0
[junit4:junit4]   2> 13027 T846 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10043 (-1425785584986619904)]} 0 0
[junit4:junit4]   2> 13028 T829 C37 P60297 /update {wt=javabin&version=2} {delete=[10043 (-1425785584986619904)]} 0 2
[junit4:junit4]   2> 13031 T810 C36 P60285 /update {wt=javabin&version=2} {add=[10047 (1425785584990814208)]} 0 1
[junit4:junit4]   2> 13031 T847 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[43 (-1425785584989765632)]} 0 0
[junit4:junit4]   2> 13032 T833 C37 P60297 /update {wt=javabin&version=2} {delete=[43 (-1425785584989765632)]} 0 3
[junit4:junit4]   2> 13034 T812 C36 P60285 /update {wt=javabin&version=2} {add=[47 (1425785584995008512)]} 0 0
[junit4:junit4]   2> 13037 T848 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10047 (1425785584992911360)]} 0 1
[junit4:junit4]   2> 13038 T831 C37 P60297 /update {wt=javabin&version=2} {add=[10047 (1425785584992911360)]} 0 6
[junit4:junit4]   2> 13039 T811 C36 P60285 /update {wt=javabin&version=2} {delete=[10045 (-1425785585000251392)]} 0 0
[junit4:junit4]   2> 13041 T843 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[47 (1425785584999202816)]} 0 1
[junit4:junit4]   2> 13043 T830 C37 P60297 /update {wt=javabin&version=2} {add=[47 (1425785584999202816)]} 0 5
[junit4:junit4]   2> 13045 T845 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10045 (-1425785585003397120)]} 0 0
[junit4:junit4]   2> 13045 T813 C36 P60285 /update {wt=javabin&version=2} {delete=[45 (-1425785585006542848)]} 0 0
[junit4:junit4]   2> 13046 T828 C37 P60297 /update {wt=javabin&version=2} {delete=[10045 (-1425785585003397120)]} 0 4
[junit4:junit4]   2> 13049 T809 C36 P60285 /update {wt=javabin&version=2} {add=[10048 (1425785585010737152)]} 0 0
[junit4:junit4]   2> 13050 T844 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[45 (-1425785585009688576)]} 0 1
[junit4:junit4]   2> 13051 T829 C37 P60297 /update {wt=javabin&version=2} {delete=[45 (-1425785585009688576)]} 0 3
[junit4:junit4]   2> 13054 T814 C36 P60285 /update {wt=javabin&version=2} {add=[48 (1425785585014931456)]} 0 1
[junit4:junit4]   2> 13054 T846 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10048 (1425785585013882880)]} 0 0
[junit4:junit4]   2> 13057 T833 C37 P60297 /update {wt=javabin&version=2} {add=[10048 (1425785585013882880)]} 0 5
[junit4:junit4]   2> 13059 T810 C36 P60285 /update {wt=javabin&version=2} {add=[10049 (1425785585021222912)]} 0 0
[junit4:junit4]   2> 13060 T847 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[48 (1425785585019125760)]} 0 1
[junit4:junit4]   2> 13061 T831 C37 P60297 /update {wt=javabin&version=2} {add=[48 (1425785585019125760)]} 0 4
[junit4:junit4]   2> 13065 T848 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10049 (1425785585023320064)]} 0 1
[junit4:junit4]   2> 13066 T830 C37 P60297 /update {wt=javabin&version=2} {add=[10049 (1425785585023320064)]} 0 5
[junit4:junit4]   2> 13067 T812 C36 P60285 /update {wt=javabin&version=2} {add=[49 (1425785585027514368)]} 0 3
[junit4:junit4]   2> 13069 T811 C36 P60285 /update {wt=javabin&version=2} {delete=[10046 (-1425785585031708672)]} 0 0
[junit4:junit4]   2> 13074 T843 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[49 (1425785585032757248)]} 0 1
[junit4:junit4]   2> 13075 T828 C37 P60297 /update {wt=javabin&version=2} {add=[49 (1425785585032757248)]} 0 5
[junit4:junit4]   2> 13076 T843 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10046 (-1425785585035902976)]} 0 0
[junit4:junit4]   2> 13076 T829 C37 P60297 /update {wt=javabin&version=2} {delete=[10046 (-1425785585035902976)]} 0 3
[junit4:junit4]   2> 13077 T813 C36 P60285 /update {wt=javabin&version=2} {delete=[46 (-1425785585040097280)]} 0 0
[junit4:junit4]   2> 13078 T809 C36 P60285 /update {wt=javabin&version=2} {add=[10050 (1425785585041145856)]} 0 0
[junit4:junit4]   2> 13081 T844 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[46 (-1425785585043243008)]} 0 0
[junit4:junit4]   2> 13082 T833 C37 P60297 /update {wt=javabin&version=2} {delete=[46 (-1425785585043243008)]} 0 2
[junit4:junit4]   2> 13084 T846 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10050 (1425785585044291584)]} 0 0
[junit4:junit4]   2> 13086 T814 C36 P60285 /update {wt=javabin&version=2} {add=[50 (1425785585047437312)]} 0 2
[junit4:junit4]   2> 13087 T831 C37 P60297 /update {wt=javabin&version=2} {add=[10050 (1425785585044291584)]} 0 6
[junit4:junit4]   2> 13090 T810 C36 P60285 /update {wt=javabin&version=2} {add=[10051 (1425785585052680192)]} 0 1
[junit4:junit4]   2> 13094 T847 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[50 (1425785585051631616)]} 0 2
[junit4:junit4]   2> 13095 T830 C37 P60297 /update {wt=javabin&version=2} {add=[50 (1425785585051631616)]} 0 7
[junit4:junit4]   2> 13096 T848 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10051 (1425785585055825920)]} 0 1
[junit4:junit4]   2> 13097 T828 C37 P60297 /update {wt=javabin&version=2} {add=[10051 (1425785585055825920)]} 0 5
[junit4:junit4]   2> 13097 T812 C36 P60285 /update {wt=javabin&version=2} {add=[51 (1425785585061068800)]} 0 0
[junit4:junit4]   2> 13099 T811 C36 P60285 /update {wt=javabin&version=2} {add=[10052 (1425785585063165952)]} 0 0
[junit4:junit4]   2> 13103 T843 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[51 (1425785585065263104)]} 0 0
[junit4:junit4]   2> 13104 T829 C37 P60297 /update {wt=javabin&version=2} {add=[51 (1425785585065263104)]} 0 3
[junit4:junit4]   2> 13104 T845 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10052 (1425785585066311680)]} 0 0
[junit4:junit4]   2> 13105 T833 C37 P60297 /update {wt=javabin&version=2} {add=[10052 (1425785585066311680)]} 0 3
[junit4:junit4]   2> 13107 T813 C36 P60285 /update {wt=javabin&version=2} {add=[52 (1425785585071554560)]} 0 0
[junit4:junit4]   2> 13108 T809 C36 P60285 /update {wt=javabin&version=2} {delete=[10048 (-1425785585072603136)]} 0 0
[junit4:junit4]   2> 13113 T844 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10048 (-1425785585074700288)]} 0 0
[junit4:junit4]   2> 13114 T831 C37 P60297 /update {wt=javabin&version=2} {delete=[10048 (-1425785585074700288)]} 0 4
[junit4:junit4]   2> 13114 T846 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[52 (1425785585074700289)]} 0 1
[junit4:junit4]   2> 13114 T830 C37 P60297 /update {wt=javabin&version=2} {add=[52 (1425785585074700289)]} 0 4
[junit4:junit4]   2> 13115 T814 C36 P60285 /update {wt=javabin&version=2} {add=[10053 (1425785585079943168)]} 0 0
[junit4:junit4]   2> 13116 T810 C36 P60285 /update {wt=javabin&version=2} {delete=[48 (-1425785585080991744)]} 0 0
[junit4:junit4]   2> 13121 T847 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[48 (-1425785585084137472)]} 0 0
[junit4:junit4]   2> 13122 T829 C37 P60297 /update {wt=javabin&version=2} {delete=[48 (-1425785585084137472)]} 0 3
[junit4:junit4]   2> 13122 T848 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10053 (1425785585083088896)]} 0 0
[junit4:junit4]   2> 13124 T828 C37 P60297 /update {wt=javabin&version=2} {add=[10053 (1425785585083088896)]} 0 6
[junit4:junit4]   2> 13125 T812 C36 P60285 /update {wt=javabin&version=2} {add=[53 (1425785585089380352)]} 0 1
[junit4:junit4]   2> 13125 T811 C36 P60285 /update {wt=javabin&version=2} {delete=[10052 (-1425785585090428928)]} 0 0
[junit4:junit4]   2> 13130 T843 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10052 (-1425785585093574656)]} 0 0
[junit4:junit4]   2> 13130 T845 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[53 (1425785585091477504)]} 0 0
[junit4:junit4]   2> 13130 T831 C37 P60297 /update {wt=javabin&version=2} {delete=[10052 (-1425785585093574656)]} 0 2
[junit4:junit4]   2> 13131 T833 C37 P60297 /update {wt=javabin&version=2} {add=[53 (1425785585091477504)]} 0 5
[junit4:junit4]   2> 13133 T809 C36 P60285 /update {wt=javabin&version=2} {delete=[52 (-1425785585098817536)]} 0 0
[junit4:junit4]   2> 13133 T813 C36 P60285 /update {wt=javabin&version=2} {add=[10054 (1425785585098817537)]} 0 0
[junit4:junit4]   2> 13138 T846 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[52 (-1425785585100914689)]} 0 0
[junit4:junit4]   2> 13138 T844 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10054 (1425785585100914688)]} 0 0
[junit4:junit4]   2> 13139 T830 C37 P60297 /update {wt=javabin&version=2} {add=[10054 (1425785585100914688)]} 0 4
[junit4:junit4]   2> 13139 T829 C37 P60297 /update {wt=javabin&version=2} {delete=[52 (-1425785585100914689)]} 0 4
[junit4:junit4]   2> 13142 T814 C36 P60285 /update {wt=javabin&version=2} {add=[10055 (1425785585107206144)]} 0 1
[junit4:junit4]   2> 13142 T810 C36 P60285 /update {wt=javabin&version=2} {add=[54 (1425785585107206145)]} 0 1
[junit4:junit4]   2> 13147 T847 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10055 (1425785585110351872)]} 0 1
[junit4:junit4]   2> 13148 T828 C37 P60297 /update {wt=javabin&version=2} {add=[10055 (1425785585110351872)]} 0 4
[junit4:junit4]   2> 13148 T848 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[54 (1425785585111400448)]} 0 1
[junit4:junit4]   2> 13149 T831 C37 P60297 /update {wt=javabin&version=2} {add=[54 (1425785585111400448)]} 0 4
[junit4:junit4]   2> 13152 T811 C36 P60285 /update {wt=javabin&version=2} {add=[55 (1425785585117691904)]} 0 1
[junit4:junit4]   2> 13152 T812 C36 P60285 /update {wt=javabin&version=2} {add=[10056 (1425785585116643328)]} 0 2
[junit4:junit4]   2> 13160 T845 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10056 (1425785585122934785)]} 0 1
[junit4:junit4]   2> 13161 T833 C37 P60297 /update {wt=javabin&version=2} {add=[10056 (1425785585122934785)]} 0 5
[junit4:junit4]   2> 13162 T843 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[55 (1425785585122934784)]} 0 3
[junit4:junit4]   2> 13163 T829 C37 P60297 /update {wt=javabin&version=2} {add=[55 (1425785585122934784)]} 0 7
[junit4:junit4]   2> 13164 T809 C36 P60285 /update {wt=javabin&version=2} {add=[10057 (1425785585130274816)]} 0 1
[junit4:junit4]   2> 13166 T813 C36 P60285 /update {wt=javabin&version=2} {add=[56 (1425785585132371968)]} 0 1
[junit4:junit4]   2> 13169 T846 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10057 (1425785585134469120)]} 0 0
[junit4:junit4]   2> 13170 T830 C37 P60297 /update {wt=javabin&version=2} {add=[10057 (1425785585134469120)]} 0 4
[junit4:junit4]   2> 13172 T844 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[56 (1425785585135517696)]} 0 1
[junit4:junit4]   2> 13172 T828 C37 P60297 /update {wt=javabin&version=2} {add=[56 (1425785585135517696)]} 0 4
[junit4:junit4]   2> 13173 T814 C36 P60285 /update {wt=javabin&version=2} {delete=[10053 (-1425785585140760576)]} 0 0
[junit4:junit4]   2> 13176 T810 C36 P60285 /update {wt=javabin&version=2} {add=[57 (1425785585141809152)]} 0 2
[junit4:junit4]   2> 13178 T847 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10053 (-1425785585143906304)]} 0 0
[junit4:junit4]   2> 13179 T831 C37 P60297 /update {wt=javabin&version=2} {delete=[10053 (-1425785585143906304)]} 0 3
[junit4:junit4]   2> 13181 T812 C36 P60285 /update {wt=javabin&version=2} {add=[10058 (1425785585149149184)]} 0 0
[junit4:junit4]   2> 13181 T848 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[57 (1425785585146003456)]} 0 0
[junit4:junit4]   2> 13183 T833 C37 P60297 /update {wt=javabin&version=2} {add=[57 (1425785585146003456)]} 0 5
[junit4:junit4]   2> 13185 T811 C36 P60285 /update {wt=javabin&version=2} {delete=[53 (-1425785585153343488)]} 0 0
[junit4:junit4]   2> 13187 T845 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10058 (1425785585152294912)]} 0 0
[junit4:junit4]   2> 13189 T829 C37 P60297 /update {wt=javabin&version=2} {add=[10058 (1425785585152294912)]} 0 5
[junit4:junit4]   2> 13190 T843 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[53 (-1425785585157537792)]} 0 0
[junit4:junit4]   2> 13191 T830 C37 P60297 /update {wt=javabin&version=2} {delete=[53 (-1425785585157537792)]} 0 2
[junit4:junit4]   2> 13192 T809 C36 P60285 /update {wt=javabin&version=2} {add=[10059 (1425785585159634944)]} 0 1
[junit4:junit4]   2> 13195 T813 C36 P60285 /update {wt=javabin&version=2} {add=[58 (1425785585163829248)]} 0 0
[junit4:junit4]   2> 13197 T846 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10059 (1425785585163829248)]} 0 0
[junit4:junit4]   2> 13198 T828 C37 P60297 /update {wt=javabin&version=2} {add=[10059 (1425785585163829248)]} 0 3
[junit4:junit4]   2> 13200 T844 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[58 (1425785585165926400)]} 0 1
[junit4:junit4]   2> 13200 T814 C36 P60285 /update {wt=javabin&version=2} {delete=[10056 (-1425785585169072128)]} 0 0
[junit4:junit4]   2> 13201 T831 C37 P60297 /update {wt=javabin&version=2} {add=[58 (1425785585165926400)]} 0 4
[junit4:junit4]   2> 13203 T810 C36 P60285 /update {wt=javabin&version=2} {add=[59 (1425785585172217856)]} 0 0
[junit4:junit4]   2> 13205 T847 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10056 (-1425785585172217856)]} 0 0
[junit4:junit4]   2> 13206 T833 C37 P60297 /update {wt=javabin&version=2} {delete=[10056 (-1425785585172217856)]} 0 3
[junit4:junit4]   2> 13207 T812 C36 P60285 /update {wt=javabin&version=2} {add=[10060 (1425785585176412160)]} 0 0
[junit4:junit4]   2> 13209 T845 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[59 (1425785585175363584)]} 0 0
[junit4:junit4]   2> 13210 T829 C37 P60297 /update {wt=javabin&version=2} {add=[59 (1425785585175363584)]} 0 4
[junit4:junit4]   2> 13211 T811 C36 P60285 /update {wt=javabin&version=2} {delete=[56 (-1425785585180606464)]} 0 0
[junit4:junit4]   2> 13213 T843 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10060 (1425785585180606464)]} 0 0
[junit4:junit4]   2> 13214 T830 C37 P60297 /update {wt=javabin&version=2} {add=[10060 (1425785585180606464)]} 0 3
[junit4:junit4]   2> 13216 T846 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[56 (-1425785585183752192)]} 0 0
[junit4:junit4]   2> 13217 T809 C36 P60285 /update {wt=javabin&version=2} {delete=[10057 (-1425785585186897920)]} 0 0
[junit4:junit4]   2> 13217 T828 C37 P60297 /update {wt=javabin&version=2} {delete=[56 (-1425785585183752192)]} 0 3
[junit4:junit4]   2> 13221 T844 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10057 (-1425785585188995072)]} 0 0
[junit4:junit4]   2> 13222 T831 C37 P60297 /update {wt=javabin&version=2} {delete=[10057 (-1425785585188995072)]} 0 3
[junit4:junit4]   2> 13222 T813 C36 P60285 /update {wt=javabin&version=2} {add=[60 (1425785585188995072)]} 0 3
[junit4:junit4]   2> 13225 T814 C36 P60285 /update {wt=javabin&version=2} {add=[10061 (1425785585194237952)]} 0 1
[junit4:junit4]   2> 13231 T847 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[60 (1425785585195286528)]} 0 3
[junit4:junit4]   2> 13232 T848 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10061 (1425785585198432256)]} 0 1
[junit4:junit4]   2> 13232 T833 C37 P60297 /update {wt=javabin&version=2} {add=[60 (1425785585195286528)]} 0 7
[junit4:junit4]   2> 13233 T829 C37 P60297 /update {wt=javabin&version=2} {add=[10061 (1425785585198432256)]} 0 5
[junit4:junit4]   2> 13235 T810 C36 P60285 /update {wt=javabin&version=2} {delete=[57 (-1425785585204723713)]} 0 1
[junit4:junit4]   2> 13235 T812 C36 P60285 /update {wt=javabin&version=2} {add=[10062 (1425785585204723712)]} 0 1
[junit4:junit4]   2> 13238 T845 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[57 (-1425785585207869440)]} 0 0
[junit4:junit4]   2> 13239 T830 C37 P60297 /update {wt=javabin&version=2} {delete=[57 (-1425785585207869440)]} 0 2
[junit4:junit4]   2> 13240 T843 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10062 (1425785585208918016)]} 0 0
[junit4:junit4]   2> 13241 T828 C37 P60297 /update {wt=javabin&version=2} {add=[10062 (1425785585208918016)]} 0 3
[junit4:junit4]   2> 13242 T811 C36 P60285 /update {wt=javabin&version=2} {add=[61 (1425785585213112320)]} 0 0
[junit4:junit4]   2> 13244 T809 C36 P60285 /update {wt=javabin&version=2} {delete=[10058 (-1425785585215209472)]} 0 0
[junit4:junit4]   2> 13246 T846 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[61 (1425785585215209472)]} 0 0
[junit4:junit4]   2> 13247 T831 C37 P60297 /update {wt=javabin&version=2} {add=[61 (1425785585215209472)]} 0 3
[junit4:junit4]   2> 13249 T844 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10058 (-1425785585218355200)]} 0 0
[junit4:junit4]   2> 13250 T813 C36 P60285 /update {wt=javabin&version=2} {add=[62 (1425785585220452352)]} 0 1
[junit4:junit4]   2> 13250 T833 C37 P60297 /update {wt=javabin&version=2} {delete=[10058 (-1425785585218355200)]} 0 3
[junit4:junit4]   2> 13254 T814 C36 P60285 /update {wt=javabin&version=2} {add=[10063 (1425785585225695232)]} 0 0
[junit4:junit4]   2> 13256 T847 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[62 (1425785585224646656)]} 0 1
[junit4:junit4]   2> 13257 T829 C37 P60297 /update {wt=javabin&version=2} {add=[62 (1425785585224646656)]} 0 4
[junit4:junit4]   2> 13258 T848 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10063 (1425785585227792384)]} 0 0
[junit4:junit4]   2> 13260 T830 C37 P60297 /update {wt=javabin&version=2} {add=[10063 (1425785585227792384)]} 0 4
[junit4:junit4]   2> 13260 T812 C36 P60285 /update {wt=javabin&version=2} {delete=[58 (-1425785585231986688)]} 0 0
[junit4:junit4]   2> 13262 T810 C36 P60285 /update {wt=javabin&version=2} {add=[10064 (1425785585233035264)]} 0 1
[junit4:junit4]   2> 13263 T845 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[58 (-1425785585233035264)]} 0 0
[junit4:junit4]   2> 13264 T828 C37 P60297 /update {wt=javabin&version=2} {delete=[58 (-1425785585233035264)]} 0 3
[junit4:junit4]   2> 13266 T811 C36 P60285 /update {wt=javabin&version=2} {add=[63 (1425785585238278144)]} 0 0
[junit4:junit4]   2> 13268 T843 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10064 (1425785585237229568)]} 0 0
[junit4:junit4]   2> 13269 T831 C37 P60297 /update {wt=javabin&version=2} {add=[10064 (1425785585237229568)]} 0 4
[junit4:junit4]   2> 13272 T809 C36 P60285 /update {wt=javabin&version=2} {add=[10065 (1425785585243521024)]} 0 1
[junit4:junit4]   2> 13273 T846 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[63 (1425785585242472448)]} 0 1
[junit4:junit4]   2> 13274 T833 C37 P60297 /update {wt=javabin&version=2} {add=[63 (1425785585242472448)]} 0 4
[junit4:junit4]   2> 13276 T813 C36 P60285 /update {wt=javabin&version=2} {add=[64 (1425785585248763904)]} 0 0
[junit4:junit4]   2> 13278 T844 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10065 (1425785585247715328)]} 0 1
[junit4:junit4]   2> 13278 T829 C37 P60297 /update {wt=javabin&version=2} {add=[10065 (1425785585247715328)]} 0 3
[junit4:junit4]   2> 13282 T847 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[64 (1425785585251909632)]} 0 0
[junit4:junit4]   2> 13283 T830 C37 P60297 /update {wt=javabin&version=2} {add=[64 (1425785585251909632)]} 0 4
[junit4:junit4]   2> 13283 T814 C36 P60285 /update {wt=javabin&version=2} {add=[10066 (1425785585254006784)]} 0 2
[junit4:junit4]   2> 13287 T812 C36 P60285 /update {wt=javabin&version=2} {add=[65 (1425785585260298240)]} 0 1
[junit4:junit4]   2> 13293 T848 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10066 (1425785585260298240)]} 0 2
[junit4:junit4]   2> 13295 T828 C37 P60297 /update {wt=javabin&version=2} {add=[10066 (1425785585260298240)]} 0 9
[junit4:junit4]   2> 13298 T845 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[65 (1425785585265541120)]} 0 2
[junit4:junit4]   2> 13298 T810 C36 P60285 /update {wt=javabin&version=2} {delete=[10066 (-1425785585271832576)]} 0 0
[junit4:junit4]   2> 13299 T831 C37 P60297 /update {wt=javabin&version=2} {add=[65 (1425785585265541120)]} 0 8
[junit4:junit4]   2> 13318 T811 C36 P60285 /update {wt=javabin&version=2} {add=[66 (1425785585292804096)]} 0 1
[junit4:junit4]   2> 13320 T843 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10066 (-1425785585291755520)]} 0 0
[junit4:junit4]   2> 13323 T833 C37 P60297 /update {wt=javabin&version=2} {delete=[10066 (-1425785585291755520)]} 0 22
[junit4:junit4]   2> 13325 T846 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[66 (1425785585298046976)]} 0 0
[junit4:junit4]   2> 13326 T809 C36 P60285 /update {wt=javabin&version=2} {add=[10067 (1425785585300144128)]} 0 1
[junit4:junit4]   2> 13326 T829 C37 P60297 /update {wt=javabin&version=2} {add=[66 (1425785585298046976)]} 0 5
[junit4:junit4]   2> 13329 T813 C36 P60285 /update {wt=javabin&version=2} {delete=[66 (-1425785585304338432)]} 0 0
[junit4:junit4]   2> 13333 T844 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10067 (1425785585304338432)]} 0 1
[junit4:junit4]   2> 13334 T832 C37 P60297 /update {wt=javabin&version=2} {add=[10067 (1425785585304338432)]} 0 5
[junit4:junit4]   2> 13334 T844 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[66 (-1425785585307484160)]} 0 0
[junit4:junit4]   2> 13336 T830 C37 P60297 /update {wt=javabin&version=2} {delete=[66 (-1425785585307484160)]} 0 4
[junit4:junit4]   2> 13337 T812 C36 P60285 /update {wt=javabin&version=2} {add=[10068 (1425785585312727040)]} 0 0
[junit4:junit4]   2> 13339 T810 C36 P60285 /update {wt=javabin&version=2} {add=[67 (1425785585313775616)]} 0 1
[junit4:junit4]   2> 13343 T848 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10068 (1425785585315872768)]} 0 1
[junit4:junit4]   2> 13344 T828 C37 P60297 /update {wt=javabin&version=2} {add=[10068 (1425785585315872768)]} 0 4
[junit4:junit4]   2> 13344 T845 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[67 (1425785585316921344)]} 0 1
[junit4:junit4]   2> 13345 T831 C37 P60297 /update {wt=javabin&version=2} {add=[67 (1425785585316921344)]} 0 4
[junit4:junit4]   2> 13347 T811 C36 P60285 /update {wt=javabin&version=2} {add=[10069 (1425785585323212800)]} 0 0
[junit4:junit4]   2> 13349 T809 C36 P60285 /update {wt=javabin&version=2} {add=[68 (1425785585324261376)]} 0 1
[junit4:junit4]   2> 13353 T843 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[68 (1425785585327407104)]} 0 0
[junit4:junit4]   2> 13354 T833 C37 P60297 /update {wt=javabin&version=2} {add=[68 (1425785585327407104)]} 0 3
[junit4:junit4]   2> 13355 T846 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10069 (1425785585328455680)]} 0 1
[junit4:junit4]   2> 13356 T829 C37 P60297 /update {wt=javabin&version=2} {add=[10069 (1425785585328455680)]} 0 5
[junit4:junit4]   2> 13357 T813 C36 P60285 /update {wt=javabin&version=2} {add=[69 (1425785585333698560)]} 0 0
[junit4:junit4]   2> 13358 T814 C36 P60285 /update {wt=javabin&version=2} {delete=[10067 (-1425785585334747136)]} 0 0
[junit4:junit4]   2> 13363 T847 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10067 (-1425785585337892864)]} 0 0
[junit4:junit4]   2> 13363 T844 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[69 (1425785585336844288)]} 0 0
[junit4:junit4]   2> 13364 T830 C37 P60297 /update {wt=javabin&version=2} {delete=[10067 (-1425785585337892864)]} 0 3
[junit4:junit4]   2> 13364 T832 C37 P60297 /update {wt=javabin&version=2} {add=[69 (1425785585336844288)]} 0 4
[junit4:junit4]   2> 13367 T812 C36 P60285 /update {wt=javabin&version=2} {add=[10070 (1425785585343135744)]} 0 1
[junit4:junit4]   2> 13368 T810 C36 P60285 /update {wt=javabin&version=2} {delete=[67 (-1425785585345232896)]} 0 1
[junit4:junit4]   2> 13372 T848 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10070 (1425785585346281472)]} 0 1
[junit4:junit4]   2> 13374 T848 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[67 (-1425785585346281473)]} 0 0
[junit4:junit4]   2> 13374 T828 C37 P60297 /update {wt=javabin&version=2} {add=[10070 (1425785585346281472)]} 0 5
[junit4:junit4]   2> 13374 T831 C37 P60297 /update {wt=javabin&version=2} {delete=[67 (-1425785585346281473)]} 0 5
[junit4:junit4]   2> 13378 T809 C36 P60285 /update {wt=javabin&version=2} {add=[70 (1425785585354670080)]} 0 1
[junit4:junit4]   2> 13379 T811 C36 P60285 /update {wt=javabin&version=2} {add=[10071 (1425785585353621504)]} 0 3
[junit4:junit4]   2> 13386 T846 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[70 (1425785585358864385)]} 0 1
[junit4:junit4]   2> 13387 T843 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10071 (1425785585358864384)]} 0 2
[junit4:junit4]   2> 13388 T833 C37 P60297 /update {wt=javabin&version=2} {add=[70 (1425785585358864385)]} 0 7
[junit4:junit4]   2> 13388 T829 C37 P60297 /update {wt=javabin&version=2} {add=[10071 (1425785585358864384)]} 0 7
[junit4:junit4]   2> 13390 T814 C36 P60285 /update {wt=javabin&version=2} {delete=[10068 (-1425785585368301569)]} 0 0
[junit4:junit4]   2> 13390 T813 C36 P60285 /update {wt=javabin&version=2} {add=[71 (1425785585368301568)]} 0 0
[junit4:junit4]   2> 13395 T844 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10068 (-1425785585371447296)]} 0 0
[junit4:junit4]   2> 13396 T830 C37 P60297 /update {wt=javabin&version=2} {delete=[10068 (-1425785585371447296)]} 0 3
[junit4:junit4]   2> 13396 T847 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[71 (1425785585371447297)]} 0 0
[junit4:junit4]   2> 13398 T832 C37 P60297 /update {wt=javabin&version=2} {add=[71 (1425785585371447297)]} 0 5
[junit4:junit4]   2> 13399 T812 C36 P60285 /update {wt=javabin&version=2} {add=[10072 (1425785585376690176)]} 0 1
[junit4:junit4]   2> 13401 T810 C36 P60285 /update {wt=javabin&version=2} {delete=[68 (-1425785585379835904)]} 0 0
[junit4:junit4]   2> 13404 T845 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10072 (1425785585379835904)]} 0 0
[junit4:junit4]   2> 13405 T848 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[68 (-1425785585381933056)]} 0 0
[junit4:junit4]   2> 13406 T828 C37 P60297 /update {wt=javabin&version=2} {add=[10072 (1425785585379835904)]} 0 5
[junit4:junit4]   2> 13407 T831 C37 P60297 /update {wt=javabin&version=2} {delete=[68 (-1425785585381933056)]} 0 4
[junit4:junit4]   2> 13408 T809 C36 P60285 /update {wt=javabin&version=2} {delete=[10069 (-1425785585387175936)]} 0 0
[junit4:junit4]   2> 13409 T811 C36 P60285 /update {wt=javabin&version=2} {add=[72 (1425785585388224512)]} 0 0
[junit4:junit4]   2> 13412 T846 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10069 (-1425785585390321664)]} 0 0
[junit4:junit4]   2> 13413 T829 C37 P60297 /update {wt=javabin&version=2} {delete=[10069 (-1425785585390321664)]} 0 2
[junit4:junit4]   2> 13415 T843 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[72 (1425785585391370240)]} 0 0
[junit4:junit4]   2> 13415 T833 C37 P60297 /update {wt=javabin&version=2} {add=[72 (1425785585391370240)]} 0 3
[junit4:junit4]   2> 13417 T814 C36 P60285 /update {wt=javabin&version=2} {add=[10073 (1425785585395564544)]} 0 1
[junit4:junit4]   2> 13418 T813 C36 P60285 /update {wt=javabin&version=2} {delete=[69 (-1425785585397661696)]} 0 0
[junit4:junit4]   2> 13423 T844 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10073 (1425785585398710272)]} 0 1
[junit4:junit4]   2> 13423 T847 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[69 (-1425785585400807424)]} 0 0
[junit4:junit4]   2> 13424 T830 C37 P60297 /update {wt=javabin&version=2} {add=[10073 (1425785585398710272)]} 0 5
[junit4:junit4]   2> 13425 T832 C37 P60297 /update {wt=javabin&version=2} {delete=[69 (-1425785585400807424)]} 0 4
[junit4:junit4]   2> 13426 T812 C36 P60285 /update {wt=javabin&version=2} {add=[10074 (1425785585406050304)]} 0 0
[junit4:junit4]   2> 13427 T810 C36 P60285 /update {wt=javabin&version=2} {add=[73 (1425785585407098880)]} 0 0
[junit4:junit4]   2> 13432 T845 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10074 (1425785585408147456)]} 0 1
[junit4:junit4]   2> 13432 T828 C37 P60297 /update {wt=javabin&version=2} {add=[10074 (1425785585408147456)]} 0 4
[junit4:junit4]   2> 13434 T848 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[73 (1425785585410244608)]} 0 1
[junit4:junit4]   2> 13434 T829 C37 P60297 /update {wt=javabin&version=2} {add=[73 (1425785585410244608)]} 0 4
[junit4:junit4]   2> 13436 T809 C36 P60285 /update {wt=javabin&version=2} {add=[10075 (1425785585414438912)]} 0 2
[junit4:junit4]   2> 13437 T811 C36 P60285 /update {wt=javabin&version=2} {add=[74 (1425785585417584640)]} 0 0
[junit4:junit4]   2> 13441 T846 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10075 (1425785585419681792)]} 0 0
[junit4:junit4]   2> 13443 T833 C37 P60297 /update {wt=javabin&version=2} {add=[10075 (1425785585419681792)]} 0 4
[junit4:junit4]   2> 13444 T843 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[74 (1425785585419681793)]} 0 1
[junit4:junit4]   2> 13444 T830 C37 P60297 /update {wt=javabin&version=2} {add=[74 (1425785585419681793)]} 0 5
[junit4:junit4]   2> 13448 T814 C36 P60285 /update {wt=javabin&version=2} {add=[10076 (1425785585425973248)]} 0 3
[junit4:junit4]   2> 13451 T813 C36 P60285 /update {wt=javabin&version=2} {add=[75 (1425785585431216128)]} 0 4
[junit4:junit4]   2> 13462 T844 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10076 (1425785585431216128)]} 0 2
[junit4:junit4]   2> 13463 T832 C37 P60297 /update {wt=javabin&version=2} {add=[10076 (1425785585431216128)]} 0 13
[junit4:junit4]   2> 13464 T847 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[75 (1425785585438556160)]} 0 2
[junit4:junit4]   2> 13464 T831 C37 P60297 /update {wt=javabin&version=2} {add=[75 (1425785585438556160)]} 0 7
[junit4:junit4]   2> 13466 T812 C36 P60285 /update {wt=javabin&version=2} {delete=[10071 (-1425785585447993344)]} 0 0
[junit4:junit4]   2> 13466 T810 C36 P60285 /update {wt=javabin&version=2} {add=[76 (1425785585447993345)]} 0 0
[junit4:junit4]   2> 13472 T845 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10071 (-1425785585450090496)]} 0 0
[junit4:junit4]   2> 13472 T828 C37 P60297 /update {wt=javabin&version=2} {delete=[10071 (-1425785585450090496)]} 0 4
[junit4:junit4]   2> 13473 T848 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[76 (1425785585453236224)]} 0 0
[junit4:junit4]   2> 13475 T829 C37 P60297 /update {wt=javabin&version=2} {add=[76 (1425785585453236224)]} 0 5
[junit4:junit4]   2> 13475 T809 C36 P60285 /update {wt=javabin&version=2} {add=[10077 (1425785585457430528)]} 0 0
[junit4:junit4]   2> 13478 T811 C36 P60285 /update {wt=javabin&version=2} {delete=[71 (-1425785585460576256)]} 0 0
[junit4:junit4]   2> 13481 T846 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10077 (1425785585460576256)]} 0 0
[junit4:junit4]   2> 13482 T833 C37 P60297 /update {wt=javabin&version=2} {add=[10077 (1425785585460576256)]} 0 4
[junit4:junit4]   2> 13482 T843 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[71 (-1425785585463721984)]} 0 0
[junit4:junit4]   2> 13483 T830 C37 P60297 /update {wt=javabin&version=2} {delete=[71 (-1425785585463721984)]} 0 2
[junit4:junit4]   2> 13484 T814 C36 P60285 /update {wt=javabin&version=2} {delete=[10074 (-1425785585466867712)]} 0 0
[junit4:junit4]   2> 13487 T813 C36 P60285 /update {wt=javabin&version=2} {add=[77 (1425785585468964864)]} 0 1
[junit4:junit4]   2> 13488 T844 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10074 (-1425785585468964864)]} 0 0
[junit4:junit4]   2> 13488 T832 C37 P60297 /update {wt=javabin&version=2} {delete=[10074 (-1425785585468964864)]} 0 2
[junit4:junit4]   2> 13490 T812 C36 P60285 /update {wt=javabin&version=2} {add=[10078 (1425785585473159168)]} 0 0
[junit4:junit4]   2> 13491 T847 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[77 (1425785585471062016)]} 0 0
[junit4:junit4]   2> 13492 T831 C37 P60297 /update {wt=javabin&version=2} {add=[77 (1425785585471062016)]} 0 4
[junit4:junit4]   2> 13493 T810 C36 P60285 /update {wt=javabin&version=2} {delete=[74 (-1425785585476304896)]} 0 0
[junit4:junit4]   2> 13494 T845 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10078 (1425785585476304896)]} 0 0
[junit4:junit4]   2> 13495 T828 C37 P60297 /update {wt=javabin&version=2} {add=[10078 (1425785585476304896)]} 0 2
[junit4:junit4]   2> 13498 T809 C36 P60285 /update {wt=javabin&version=2} {add=[10079 (1425785585480499200)]} 0 1
[junit4:junit4]   2> 13499 T848 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[74 (-1425785585479450624)]} 0 0
[junit4:junit4]   2> 13499 T829 C37 P60297 /update {wt=javabin&version=2} {delete=[74 (-1425785585479450624)]} 0 3
[junit4:junit4]   2> 13501 T811 C36 P60285 /update {wt=javabin&version=2} {add=[78 (1425785585484693504)]} 0 0
[junit4:junit4]   2> 13504 T846 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10079 (1425785585483644928)]} 0 1
[junit4:junit4]   2> 13504 T833 C37 P60297 /update {wt=javabin&version=2} {add=[10079 (1425785585483644928)]} 0 4
[junit4:junit4]   2> 13505 T843 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[78 (1425785585487839232)]} 0 0
[junit4:junit4]   2> 13506 T830 C37 P60297 /update {wt=javabin&version=2} {add=[78 (1425785585487839232)]} 0 2
[junit4:junit4]   2> 13506 T814 C36 P60285 /update {wt=javabin&version=2} {delete=[10075 (-1425785585489936384)]} 0 0
[junit4:junit4]   2> 13508 T813 C36 P60285 /update {wt=javabin&version=2} {add=[79 (1425785585492033536)]} 0 0
[junit4:junit4]   2> 13511 T844 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10075 (-1425785585494130688)]} 0 0
[junit4:junit4]   2> 13512 T832 C37 P60297 /update {wt=javabin&version=2} {delete=[10075 (-1425785585494130688)]} 0 2
[junit4:junit4]   2> 13514 T847 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[79 (1425785585495179264)]} 0 0
[junit4:junit4]   2> 13515 T831 C37 P60297 /update {wt=javabin&version=2} {add=[79 (1425785585495179264)]} 0 4
[junit4:junit4]   2> 13515 T812 C36 P60285 /update {wt=javabin&version=2} {add=[10080 (1425785585499373568)]} 0 0
[junit4:junit4]   2> 13517 T810 C36 P60285 /update {wt=javabin&version=2} {delete=[75 (-1425785585501470720)]} 0 0
[junit4:junit4]   2> 13520 T845 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10080 (1425785585501470720)]} 0 0
[junit4:junit4]   2> 13521 T828 C37 P60297 /update {wt=javabin&version=2} {add=[10080 (1425785585501470720)]} 0 4
[junit4:junit4]   2> 13521 T845 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[75 (-1425785585504616448)]} 0 0
[junit4:junit4]   2> 13523 T829 C37 P60297 /update {wt=javabin&version=2} {delete=[75 (-1425785585504616448)]} 0 3
[junit4:junit4]   2> 13526 T809 C36 P60285 /update {wt=javabin&version=2} {add=[10081 (1425785585507762176)]} 0 3
[junit4:junit4]   2> 13526 T811 C36 P60285 /update {wt=javabin&version=2} {add=[80 (1425785585510907904)]} 0 0
[junit4:junit4]   2> 13533 T843 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10081 (1425785585514053632)]} 0 1
[junit4:junit4]   2> 13534 T833 C37 P60297 /update {wt=javabin&version=2} {add=[10081 (1425785585514053632)]} 0 5
[junit4:junit4]   2> 13534 T846 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[80 (1425785585514053633)]} 0 2
[junit4:junit4]   2> 13535 T830 C37 P60297 /update {wt=javabin&version=2} {add=[80 (1425785585514053633)]} 0 6
[junit4:junit4]   2> 13537 T814 C36 P60285 /update {wt=javabin&version=2} {delete=[10076 (-1425785585522442240)]} 0 0
[junit4:junit4]   2> 13537 T813 C36 P60285 /update {wt=javabin&version=2} {add=[81 (1425785585522442241)]} 0 0
[junit4:junit4]   2> 13542 T844 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10076 (-1425785585524539392)]} 0 0
[junit4:junit4]   2> 13542 T832 C37 P60297 /update {wt=javabin&version=2} {delete=[10076 (-1425785585524539392)]} 0 3
[junit4:junit4]   2> 13545 T847 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[81 (1425785585527685120)]} 0 1
[junit4:junit4]   2> 13545 T831 C37 P60297 /update {wt=javabin&version=2} {add=[81 (1425785585527685120)]} 0 3
[junit4:junit4]   2> 13546 T812 C36 P60285 /update {wt=javabin&version=2} {add=[10082 (1425785585530830848)]} 0 1
[junit4:junit4]   2> 13548 T810 C36 P60285 /update {wt=javabin&version=2} {delete=[76 (-1425785585533976576)]} 0 0
[junit4:junit4]   2> 13552 T848 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10082 (1425785585533976576)]} 0 1
[junit4:junit4]   2> 13553 T828 C37 P60297 /update {wt=javabin&version=2} {add=[10082 (1425785585533976576)]} 0 5
[junit4:junit4]   2> 13553 T845 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[76 (-1425785585536073728)]} 0 0
[junit4:junit4]   2> 13553 T829 C37 P60297 /update {wt=javabin&version=2} {delete=[76 (-1425785585536073728)]} 0 3
[junit4:junit4]   2> 13554 T809 C36 P60285 /update {wt=javabin&version=2} {delete=[10077 (-1425785585540268032)]} 0 0
[junit4:junit4]   2> 13557 T811 C36 P60285 /update {wt=javabin&version=2} {add=[82 (1425785585542365184)]} 0 1
[junit4:junit4]   2> 13561 T843 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10077 (-1425785585544462336)]} 0 1
[junit4:junit4]   2> 13562 T833 C37 P60297 /update {wt=javabin&version=2} {delete=[10077 (-1425785585544462336)]} 0 4
[junit4:junit4]   2> 13564 T846 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[82 (1425785585547608064)]} 0 0
[junit4:junit4]   2> 13565 T814 C36 P60285 /update {wt=javabin&version=2} {add=[10083 (1425785585550753792)]} 0 1
[junit4:junit4]   2> 13565 T830 C37 P60297 /update {wt=javabin&version=2} {add=[82 (1425785585547608064)]} 0 5
[junit4:junit4]   2> 13568 T813 C36 P60285 /update {wt=javabin&version=2} {delete=[77 (-1425785585554948096)]} 0 0
[junit4:junit4]   2> 13570 T844 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10083 (1425785585553899520)]} 0 1
[junit4:junit4]   2> 13571 T832 C37 P60297 /update {wt=javabin&version=2} {add=[10083 (1425785585553899520)]} 0 4
[junit4:junit4]   2> 13573 T812 C36 P60285 /update {wt=javabin&version=2} {delete=[10078 (-1425785585560190976)]} 0 0
[junit4:junit4]   2> 13573 T847 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[77 (-1425785585557045248)]} 0 0
[junit4:junit4]   2> 13574 T831 C37 P60297 /update {wt=javabin&version=2} {delete=[77 (-1425785585557045248)]} 0 4
[junit4:junit4]   2> 13576 T810 C36 P60285 /update {wt=javabin&version=2} {add=[83 (1425785585563336704)]} 0 0
[junit4:junit4]   2> 13578 T848 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10078 (-1425785585562288128)]} 0 0
[junit4:junit4]   2> 13579 T828 C37 P60297 /update {wt=javabin&version=2} {delete=[10078 (-1425785585562288128)]} 0 4
[junit4:junit4]   2> 13581 T809 C36 P60285 /update {wt=javabin&version=2} {add=[10084 (1425785585567531008)]} 0 1
[junit4:junit4]   2> 13583 T845 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[83 (1425785585567531008)]} 0 1
[junit4:junit4]   2> 13584 T829 C37 P60297 /update {wt=javabin&version=2} {add=[83 (1425785585567531008)]} 0 4
[junit4:junit4]   2> 13585 T843 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10084 (1425785585570676736)]} 0 0
[junit4:junit4]   2> 13585 T833 C37 P60297 /update {wt=javabin&version=2} {add=[10084 (1425785585570676736)]} 0 2
[junit4:junit4]   2> 13585 T811 C36 P60285 /update {wt=javabin&version=2} {delete=[78 (-1425785585572773888)]} 0 0
[junit4:junit4]   2> 13588 T814 C36 P60285 /update {wt=javabin&version=2} {delete=[10080 (-1425785585575919616)]} 0 0
[junit4:junit4]   2> 13591 T846 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[78 (-1425785585575919616)]} 0 0
[junit4:junit4]   2> 13592 T830 C37 P60297 /update {wt=javabin&version=2} {delete=[78 (-1425785585575919616)]} 0 4
[junit4:junit4]   2> 13594 T844 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10080 (-1425785585579065344)]} 0 0
[junit4:junit4]   2> 13595 T832 C37 P60297 /update {wt=javabin&version=2} {delete=[10080 (-1425785585579065344)]} 0 4
[junit4:junit4]   2> 13596 T813 C36 P60285 /update {wt=javabin&version=2} {add=[84 (1425785585583259648)]} 0 1
[junit4:junit4]   2> 13597 T812 C36 P60285 /update {wt=javabin&version=2} {add=[10085 (1425785585585356800)]} 0 0
[junit4:junit4]   2> 13600 T847 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[84 (1425785585585356800)]} 0 0
[junit4:junit4]   2> 13601 T831 C37 P60297 /update {wt=javabin&version=2} {add=[84 (1425785585585356800)]} 0 4
[junit4:junit4]   2> 13602 T848 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10085 (1425785585588502528)]} 0 0
[junit4:junit4]   2> 13602 T828 C37 P60297 /update {wt=javabin&version=2} {add=[10085 (1425785585588502528)]} 0 2
[junit4:junit4]   2> 13603 T810 C36 P60285 /update {wt=javabin&version=2} {delete=[80 (-1425785585591648256)]} 0 0
[junit4:junit4]   2> 13607 T809 C36 P60285 /update {wt=javabin&version=2} {add=[10086 (1425785585593745408)]} 0 2
[junit4:junit4]   2> 13608 T845 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[80 (-1425785585595842560)]} 0 0
[junit4:junit4]   2> 13609 T829 C37 P60297 /update {wt=javabin&version=2} {delete=[80 (-1425785585595842560)]} 0 2
[junit4:junit4]   2> 13612 T811 C36 P60285 /update {wt=javabin&version=2} {add=[85 (1425785585600036864)]} 0 1
[junit4:junit4]   2> 13615 T846 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10086 (1425785585598988288)]} 0 2
[junit4:junit4]   2> 13616 T833 C37 P60297 /update {wt=javabin&version=2} {add=[10086 (1425785585598988288)]} 0 6
[junit4:junit4]   2> 13618 T844 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[85 (1425785585603182592)]} 0 1
[junit4:junit4]   2> 13618 T814 C36 P60285 /update {wt=javabin&version=2} {add=[10087 (1425785585607376896)]} 0 0
[junit4:junit4]   2> 13618 T830 C37 P60297 /update {wt=javabin&version=2} {add=[85 (1425785585603182592)]} 0 4
[junit4:junit4]   2> 13620 T813 C36 P60285 /update {wt=javabin&version=2} {add=[86 (1425785585609474048)]} 0 0
[junit4:junit4]   2> 13622 T847 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10087 (1425785585609474048)]} 0 0
[junit4:junit4]   2> 13623 T832 C37 P60297 /update {wt=javabin&version=2} {add=[10087 (1425785585609474048)]} 0 3
[junit4:junit4]   2> 13624 T848 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[86 (1425785585612619776)]} 0 0
[junit4:junit4]   2> 13625 T831 C37 P60297 /update {wt=javabin&version=2} {add=[86 (1425785585612619776)]} 0 2
[junit4:junit4]   2> 13626 T812 C36 P60285 /update {wt=javabin&version=2} {add=[10088 (1425785585615765504)]} 0 0
[junit4:junit4]   2> 13628 T810 C36 P60285 /update {wt=javabin&version=2} {add=[87 (1425785585617862656)]} 0 0
[junit4:junit4]   2> 13632 T843 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10088 (1425785585618911232)]} 0 1
[junit4:junit4]   2> 13632 T828 C37 P60297 /update {wt=javabin&version=2} {add=[10088 (1425785585618911232)]} 0 3
[junit4:junit4]   2> 13634 T845 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[87 (1425785585621008384)]} 0 1
[junit4:junit4]   2> 13634 T829 C37 P60297 /update {wt=javabin&version=2} {add=[87 (1425785585621008384)]} 0 3
[junit4:junit4]   2> 13634 T809 C36 P60285 /update {wt=javabin&version=2} {add=[10089 (1425785585624154112)]} 0 0
[junit4:junit4]   2> 13636 T811 C36 P60285 /update {wt=javabin&version=2} {add=[88 (1425785585626251264)]} 0 0
[junit4:junit4]   2> 13640 T846 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10089 (1425785585627299840)]} 0 0
[junit4:junit4]   2> 13640 T833 C37 P60297 /update {wt=javabin&version=2} {add=[10089 (1425785585627299840)]} 0 3
[junit4:junit4]   2> 13641 T844 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[88 (1425785585629396992)]} 0 0
[junit4:junit4]   2> 13642 T830 C37 P60297 /update {wt=javabin&version=2} {add=[88 (1425785585629396992)]} 0 3
[junit4:junit4]   2> 13645 T814 C36 P60285 /update {wt=javabin&version=2} {delete=[10081 (-1425785585635688448)]} 0 2
[junit4:junit4]   2> 13646 T813 C36 P60285 /update {wt=javabin&version=2} {add=[89 (1425785585636737024)]} 0 0
[junit4:junit4]   2> 13650 T847 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10081 (-1425785585638834176)]} 0 0
[junit4:junit4]   2> 13650 T832 C37 P60297 /update {wt=javabin&version=2} {delete=[10081 (-1425785585638834176)]} 0 2
[junit4:junit4]   2> 13651 T848 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[89 (1425785585639882752)]} 0 0
[junit4:junit4]   2> 13652 T831 C37 P60297 /update {wt=javabin&version=2} {add=[89 (1425785585639882752)]} 0 3
[junit4:junit4]   2> 13652 T812 C36 P60285 /update {wt=javabin&version=2} {add=[10090 (1425785585643028480)]} 0 0
[junit4:junit4]   2> 13656 T810 C36 P60285 /update {wt=javabin&version=2} {delete=[81 (-1425785585647222784)]} 0 0
[junit4:junit4]   2> 13658 T843 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10090 (1425785585647222784)]} 0 1
[junit4:junit4]   2> 13658 T828 C37 P60297 /update {wt=javabin&version=2} {add=[10090 (1425785585647222784)]} 0 2
[junit4:junit4]   2> 13659 T845 C35 P60306 /update {distrib.from=http://127.0.0.1:60297/xhr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[81 (-1425785585648271360)]} 0 0
[junit4:junit4]   2> 1366

[...truncated too long message...]

jetty2\index
[junit4:junit4]   2> 76311 T796 oasc.CachingDirectoryFactory.close Closing directory when closing factory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1359735080083\jetty2\index
[junit4:junit4]   2> 76311 T796 oasc.CachingDirectoryFactory.closeDirectory Closing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1359735080083\jetty2\index
[junit4:junit4]   2> 76312 T796 oasc.CachingDirectoryFactory.close Closing directory when closing factory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1359735080083\jetty2
[junit4:junit4]   2> 76312 T796 oasc.CachingDirectoryFactory.closeDirectory Closing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1359735080083\jetty2
[junit4:junit4]   2> 76426 T887 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:60278. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   2> 95700 T796 oaz.ZooKeeper.close Session: 0x13c9686c4a3000a closed
[junit4:junit4]   2> 95700 T888 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 95712 T796 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/xhr,null}
[junit4:junit4]   2> 95913 T796 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
[junit4:junit4]   1> client port:0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=RecoveryZkTest -Dtests.method=testDistribSearch -Dtests.seed=B93FB1BA577F831F -Dtests.slow=true -Dtests.locale=ar_BH -Dtests.timezone=Africa/Maputo -Dtests.file.encoding=UTF-8
[junit4:junit4] FAILURE 95.9s | RecoveryZkTest.testDistribSearch <<<
[junit4:junit4]    > Throwable #1: java.lang.AssertionError: expected:<190> but was:<2>
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([B93FB1BA577F831F:38D93FA22020E323]:0)
[junit4:junit4]    > 	at org.junit.Assert.fail(Assert.java:93)
[junit4:junit4]    > 	at org.junit.Assert.failNotEquals(Assert.java:647)
[junit4:junit4]    > 	at org.junit.Assert.assertEquals(Assert.java:128)
[junit4:junit4]    > 	at org.junit.Assert.assertEquals(Assert.java:472)
[junit4:junit4]    > 	at org.junit.Assert.assertEquals(Assert.java:456)
[junit4:junit4]    > 	at org.apache.solr.cloud.RecoveryZkTest.doTest(RecoveryZkTest.java:106)
[junit4:junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:794)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4:junit4]    > 	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
[junit4:junit4]    > 	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4:junit4]    > 	at java.lang.reflect.Method.invoke(Method.java:601)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
[junit4:junit4]    > 	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
[junit4:junit4]    > 	at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
[junit4:junit4]    > 	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 95925 T796 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> NOTE: test params are: codec=FastDecompressionCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=420), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=FAST_DECOMPRESSION, chunkSize=420)), sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=ar_BH, timezone=Africa/Maputo
[junit4:junit4]   2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.7.0_10 (32-bit)/cpus=2,threads=1,free=165367560,total=220135424
[junit4:junit4]   2> NOTE: All tests run in this JVM: [ShardRoutingTest, TestStressRecovery, PrimitiveFieldTypeTest, ClusterStateUpdateTest, TestFiltering, TestDFRSimilarityFactory, TestFaceting, PrimUtilsTest, LeaderElectionIntegrationTest, DistributedSpellCheckComponentTest, OverseerCollectionProcessorTest, FullSolrCloudDistribCmdsTest, OutputWriterTest, TestTrie, ZkCLITest, TestCollationField, SpellCheckComponentTest, DirectUpdateHandlerTest, IndexReaderFactoryTest, TestJmxIntegration, DocumentAnalysisRequestHandlerTest, SynonymTokenizerTest, SuggesterTSTTest, RecoveryZkTest]
[junit4:junit4] Completed in 95.96s, 1 test, 1 failure <<< FAILURES!

[...truncated 707 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:346: 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: 249 suites, 1038 tests, 1 failure, 14 ignored (9 assumptions)

Total time: 53 minutes 48 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 32bit/jdk1.7.0_10 -client -XX:+UseParallelGC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message