You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/01/22 03:32:47 UTC
[JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.7.0_10) - Build #
2442 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/2442/
Java: 32bit/jdk1.7.0_10 -server -XX:+UseG1GC
1 tests failed.
REGRESSION: org.apache.solr.cloud.RecoveryZkTest.testDistribSearch
Error Message:
expected:<218> but was:<2>
Stack Trace:
java.lang.AssertionError: expected:<218> but was:<2>
at __randomizedtesting.SeedInfo.seed([F6D24E1D219F7187:7734C00556C011BB]: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 8734 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.RecoveryZkTest
[junit4:junit4] 2> 0 T240 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4:junit4] 2> 4 T240 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-1358820463872
[junit4:junit4] 2> 12 T240 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4] 2> 17 T241 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4:junit4] 2> 95 T241 oaz.Environment.logEnv Server environment:zookeeper.version=3.4.5-1392090, built on 09/30/2012 17:52 GMT
[junit4:junit4] 2> 96 T241 oaz.Environment.logEnv Server environment:host.name=JenkinsSlave-PC
[junit4:junit4] 2> 96 T241 oaz.Environment.logEnv Server environment:java.version=1.7.0_10
[junit4:junit4] 2> 96 T241 oaz.Environment.logEnv Server environment:java.vendor=Oracle Corporation
[junit4:junit4] 2> 96 T241 oaz.Environment.logEnv Server environment:java.home=C:\Users\JenkinsSlave\tools\java\32bit\jdk1.7.0_10\jre
[junit4:junit4] 2> 97 T241 oaz.Environment.logEnv Server environment:java.class.path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\classes\test;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-test-framework\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\test-framework\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\codecs\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\analysis\common\lucene-analyzers-common-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\analysis\kuromoji\lucene-analyzers-kuromoji-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\analysis\phonetic\lucene-analyzers-phonetic-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\highlighter\lucene-highlighter-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\memory\lucene-memory-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\misc\lucene-misc-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\spatial\lucene-spatial-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\suggest\lucene-suggest-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\grouping\lucene-grouping-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\queries\lucene-queries-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\queryparser\lucene-queryparser-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\cglib-nodep-2.2.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\commons-cli-1.2.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\commons-codec-1.7.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\commons-fileupload-1.2.1.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\commons-lang-2.6.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\easymock-3.0.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\guava-13.0.1.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\javax.servlet-api-3.0.1.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\objenesis-1.2.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\spatial4j-0.3.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\commons-io-2.1.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\httpclient-4.1.3.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\httpcore-4.1.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\httpmime-4.1.3.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\jcl-over-slf4j-1.6.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\slf4j-api-1.6.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\slf4j-jdk14-1.6.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\wstx-asl-3.2.7.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\zookeeper-3.4.5.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-continuation-8.1.8.v20121106.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-deploy-8.1.8.v20121106.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-http-8.1.8.v20121106.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-io-8.1.8.v20121106.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-jmx-8.1.8.v20121106.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-security-8.1.8.v20121106.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-server-8.1.8.v20121106.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-servlet-8.1.8.v20121106.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-util-8.1.8.v20121106.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-webapp-8.1.8.v20121106.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-xml-8.1.8.v20121106.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\servlet-api-3.0.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\example-DIH\solr\db\lib\derby-10.9.1.0.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\example-DIH\solr\db\lib\hsqldb-1.8.0.10.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\core\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\test-framework\lib\junit-4.10.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\test-framework\lib\randomizedtesting-runner-2.0.8.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-launcher.jar;C:\Users\JenkinsSlave\.ant\lib\ivy-2.2.0.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-antlr.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-apache-bcel.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-apache-bsf.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-apache-log4j.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-apache-oro.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-apache-regexp.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-apache-resolver.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-apache-xalan2.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-commons-logging.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-commons-net.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-jai.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-javamail.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-jdepend.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-jmf.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-jsch.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-junit.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-junit4.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-netrexx.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-swing.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-testutil.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant.jar;C:\Users\JenkinsSlave\tools\java\32bit\jdk1.7.0_10\lib\tools.jar;C:\Users\JenkinsSlave\.ivy2\cache\com.carrotsearch.randomizedtesting\junit4-ant\jars\junit4-ant-2.0.8.jar
[junit4:junit4] 2> 98 T241 oaz.Environment.logEnv Server environment:java.library.path=C:\Users\JenkinsSlave\tools\java\32bit\jdk1.7.0_10\jre\bin;C:\Windows\Sun\Java\bin;C:\Windows\system32;C:\Windows;C:\Users\JenkinsSlave\tools\java\32bit\jdk1.7.0_10\bin;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;C:\Program Files (x86)\Subversion\bin;.
[junit4:junit4] 2> 98 T241 oaz.Environment.logEnv Server environment:java.io.tmpdir=.
[junit4:junit4] 2> 98 T241 oaz.Environment.logEnv Server environment:java.compiler=<NA>
[junit4:junit4] 2> 98 T241 oaz.Environment.logEnv Server environment:os.name=Windows 7
[junit4:junit4] 2> 98 T241 oaz.Environment.logEnv Server environment:os.arch=x86
[junit4:junit4] 2> 99 T241 oaz.Environment.logEnv Server environment:os.version=6.1
[junit4:junit4] 2> 99 T241 oaz.Environment.logEnv Server environment:user.name=JenkinsSlave
[junit4:junit4] 2> 99 T241 oaz.Environment.logEnv Server environment:user.home=C:\Users\JenkinsSlave
[junit4:junit4] 2> 99 T241 oaz.Environment.logEnv Server environment:user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4] 2> 138 T241 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4] 2> 138 T241 oazs.ZooKeeperServer.setMinSessionTimeout minSessionTimeout set to -1
[junit4:junit4] 2> 138 T241 oazs.ZooKeeperServer.setMaxSessionTimeout maxSessionTimeout set to -1
[junit4:junit4] 2> 143 T241 oazs.NIOServerCnxnFactory.configure binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4] 2> 215 T240 oasc.ZkTestServer.run start zk server on port:49446
[junit4:junit4] 2> 232 T241 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-1358820463871\zookeeper\server1\data\version-2\snapshot.0
[junit4:junit4] 2> 308 T240 oaz.Environment.logEnv Client environment:zookeeper.version=3.4.5-1392090, built on 09/30/2012 17:52 GMT
[junit4:junit4] 2> 309 T240 oaz.Environment.logEnv Client environment:host.name=JenkinsSlave-PC
[junit4:junit4] 2> 309 T240 oaz.Environment.logEnv Client environment:java.version=1.7.0_10
[junit4:junit4] 2> 309 T240 oaz.Environment.logEnv Client environment:java.vendor=Oracle Corporation
[junit4:junit4] 2> 309 T240 oaz.Environment.logEnv Client environment:java.home=C:\Users\JenkinsSlave\tools\java\32bit\jdk1.7.0_10\jre
[junit4:junit4] 2> 310 T240 oaz.Environment.logEnv Client environment:java.class.path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\classes\test;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-test-framework\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\test-framework\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\codecs\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\analysis\common\lucene-analyzers-common-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\analysis\kuromoji\lucene-analyzers-kuromoji-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\analysis\phonetic\lucene-analyzers-phonetic-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\highlighter\lucene-highlighter-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\memory\lucene-memory-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\misc\lucene-misc-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\spatial\lucene-spatial-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\suggest\lucene-suggest-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\grouping\lucene-grouping-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\queries\lucene-queries-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\queryparser\lucene-queryparser-5.0-SNAPSHOT.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\cglib-nodep-2.2.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\commons-cli-1.2.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\commons-codec-1.7.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\commons-fileupload-1.2.1.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\commons-lang-2.6.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\easymock-3.0.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\guava-13.0.1.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\javax.servlet-api-3.0.1.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\objenesis-1.2.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\lib\spatial4j-0.3.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\commons-io-2.1.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\httpclient-4.1.3.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\httpcore-4.1.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\httpmime-4.1.3.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\jcl-over-slf4j-1.6.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\slf4j-api-1.6.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\slf4j-jdk14-1.6.4.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\wstx-asl-3.2.7.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\lib\zookeeper-3.4.5.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-continuation-8.1.8.v20121106.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-deploy-8.1.8.v20121106.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-http-8.1.8.v20121106.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-io-8.1.8.v20121106.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-jmx-8.1.8.v20121106.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-security-8.1.8.v20121106.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-server-8.1.8.v20121106.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-servlet-8.1.8.v20121106.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-util-8.1.8.v20121106.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-webapp-8.1.8.v20121106.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\jetty-xml-8.1.8.v20121106.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\lib\servlet-api-3.0.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\example-DIH\solr\db\lib\derby-10.9.1.0.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\example\example-DIH\solr\db\lib\hsqldb-1.8.0.10.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\build\core\classes\java;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\test-framework\lib\junit-4.10.jar;C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\test-framework\lib\randomizedtesting-runner-2.0.8.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-launcher.jar;C:\Users\JenkinsSlave\.ant\lib\ivy-2.2.0.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-antlr.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-apache-bcel.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-apache-bsf.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-apache-log4j.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-apache-oro.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-apache-regexp.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-apache-resolver.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-apache-xalan2.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-commons-logging.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-commons-net.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-jai.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-javamail.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-jdepend.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-jmf.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-jsch.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-junit.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-junit4.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-netrexx.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-swing.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant-testutil.jar;C:\Users\JenkinsSlave\tools\hudson.tasks.Ant_AntInstallation\ANT_1.8.2\lib\ant.jar;C:\Users\JenkinsSlave\tools\java\32bit\jdk1.7.0_10\lib\tools.jar;C:\Users\JenkinsSlave\.ivy2\cache\com.carrotsearch.randomizedtesting\junit4-ant\jars\junit4-ant-2.0.8.jar
[junit4:junit4] 2> 312 T240 oaz.Environment.logEnv Client environment:java.library.path=C:\Users\JenkinsSlave\tools\java\32bit\jdk1.7.0_10\jre\bin;C:\Windows\Sun\Java\bin;C:\Windows\system32;C:\Windows;C:\Users\JenkinsSlave\tools\java\32bit\jdk1.7.0_10\bin;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;C:\Program Files (x86)\Subversion\bin;.
[junit4:junit4] 2> 312 T240 oaz.Environment.logEnv Client environment:java.io.tmpdir=.
[junit4:junit4] 2> 312 T240 oaz.Environment.logEnv Client environment:java.compiler=<NA>
[junit4:junit4] 2> 313 T240 oaz.Environment.logEnv Client environment:os.name=Windows 7
[junit4:junit4] 2> 313 T240 oaz.Environment.logEnv Client environment:os.arch=x86
[junit4:junit4] 2> 313 T240 oaz.Environment.logEnv Client environment:os.version=6.1
[junit4:junit4] 2> 314 T240 oaz.Environment.logEnv Client environment:user.name=JenkinsSlave
[junit4:junit4] 2> 315 T240 oaz.Environment.logEnv Client environment:user.home=C:\Users\JenkinsSlave
[junit4:junit4] 2> 315 T240 oaz.Environment.logEnv Client environment:user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4] 2> 323 T240 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:49446 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1e8443
[junit4:junit4] 2> 438 T240 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 451 T246 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:49446. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 452 T246 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:49446, initiating session
[junit4:junit4] 2> 453 T242 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:49449
[junit4:junit4] 2> 481 T242 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:49449
[junit4:junit4] 2> 496 T244 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4] 2> 530 T244 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c6002d60a0000 with negotiated timeout 10000 for client /127.0.0.1:49449
[junit4:junit4] 2> 530 T246 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:49446, sessionid = 0x13c6002d60a0000, negotiated timeout = 10000
[junit4:junit4] 2> 540 T247 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1e8443 name:ZooKeeperConnection Watcher:127.0.0.1:49446 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 540 T240 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 541 T240 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4] 2> 627 T245 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c6002d60a0000
[junit4:junit4] 2> 630 T240 oaz.ZooKeeper.close Session: 0x13c6002d60a0000 closed
[junit4:junit4] 2> 630 T247 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 631 T242 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:49449 which had sessionid 0x13c6002d60a0000
[junit4:junit4] 2> 631 T240 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:49446/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@710710
[junit4:junit4] 2> 633 T240 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 634 T248 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:49446. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 635 T248 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:49446, initiating session
[junit4:junit4] 2> 636 T242 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:49452
[junit4:junit4] 2> 636 T242 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:49452
[junit4:junit4] 2> 639 T244 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c6002d60a0001 with negotiated timeout 10000 for client /127.0.0.1:49452
[junit4:junit4] 2> 639 T248 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:49446, sessionid = 0x13c6002d60a0001, negotiated timeout = 10000
[junit4:junit4] 2> 639 T249 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@710710 name:ZooKeeperConnection Watcher:127.0.0.1:49446/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 639 T240 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 640 T240 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4] 2> 647 T240 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4] 2> 653 T240 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4] 2> 658 T240 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4] 2> 664 T240 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> 688 T240 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4] 2> 709 T240 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> 709 T240 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4] 2> 718 T240 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> 719 T240 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4] 2> 727 T240 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> 728 T240 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4] 2> 733 T240 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> 734 T240 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4] 2> 741 T240 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> 742 T240 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4] 2> 750 T240 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> 752 T240 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4] 2> 758 T240 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> 759 T240 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4] 2> 766 T240 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> 768 T240 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4] 2> 775 T245 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c6002d60a0001
[junit4:junit4] 2> 776 T249 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 777 T242 oazs.NIOServerCnxn.doIO WARNING caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x13c6002d60a0001, 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> 776 T240 oaz.ZooKeeper.close Session: 0x13c6002d60a0001 closed
[junit4:junit4] 2> 779 T242 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:49452 which had sessionid 0x13c6002d60a0001
[junit4:junit4] 2> 1179 T240 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 1183 T240 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:49453
[junit4:junit4] 2> 1183 T240 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 1184 T240 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 1184 T240 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-1358820464645
[junit4:junit4] 2> 1184 T240 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-1358820464645\solr.xml
[junit4:junit4] 2> 1184 T240 oasc.CoreContainer.<init> New CoreContainer 14888539
[junit4:junit4] 2> 1184 T240 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-1358820464645\'
[junit4:junit4] 2> 1184 T240 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-1358820464645\'
[junit4:junit4] 2> 1236 T240 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 60000
[junit4:junit4] 2> 1237 T240 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 1237 T240 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 1238 T240 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 1238 T240 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 1238 T240 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 1239 T240 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 1239 T240 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 1239 T240 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 1240 T240 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=60000&connTimeout=15000&retry=false
[junit4:junit4] 2> 1263 T240 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 1287 T240 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:49446/solr
[junit4:junit4] 2> 1307 T240 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 1308 T240 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:49446 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@16e9bd4
[junit4:junit4] 2> 1309 T240 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 1310 T259 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:49446. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 1311 T259 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:49446, initiating session
[junit4:junit4] 2> 1311 T242 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:49458
[junit4:junit4] 2> 1311 T242 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:49458
[junit4:junit4] 2> 1322 T244 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c6002d60a0002 with negotiated timeout 20000 for client /127.0.0.1:49458
[junit4:junit4] 2> 1322 T259 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:49446, sessionid = 0x13c6002d60a0002, negotiated timeout = 20000
[junit4:junit4] 2> 1322 T260 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@16e9bd4 name:ZooKeeperConnection Watcher:127.0.0.1:49446 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 1323 T240 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 1326 T245 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c6002d60a0002
[junit4:junit4] 2> 1327 T240 oaz.ZooKeeper.close Session: 0x13c6002d60a0002 closed
[junit4:junit4] 2> 1328 T260 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 1328 T242 oazs.NIOServerCnxn.doIO WARNING caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x13c6002d60a0002, 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> 1329 T242 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:49458 which had sessionid 0x13c6002d60a0002
[junit4:junit4] 2> 1334 T240 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=60000&connTimeout=15000
[junit4:junit4] 2> 1343 T240 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:49446/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@1d26d39
[junit4:junit4] 2> 1345 T240 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 1346 T261 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:49446. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 1347 T261 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:49446, initiating session
[junit4:junit4] 2> 1347 T242 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:49461
[junit4:junit4] 2> 1349 T242 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:49461
[junit4:junit4] 2> 1352 T261 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:49446, sessionid = 0x13c6002d60a0003, negotiated timeout = 20000
[junit4:junit4] 2> 1352 T262 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1d26d39 name:ZooKeeperConnection Watcher:127.0.0.1:49446/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 1352 T244 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c6002d60a0003 with negotiated timeout 20000 for client /127.0.0.1:49461
[junit4:junit4] 2> 1353 T240 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 1370 T245 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c6002d60a0003 type:create cxid:0x2 zxid:0x1a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 1380 T240 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4] 2> 1386 T240 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49453_
[junit4:junit4] 2> 1393 T245 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c6002d60a0003 type:delete cxid:0x7 zxid:0x1c txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:49453_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:49453_
[junit4:junit4] 2> 1395 T240 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:49453_
[junit4:junit4] 2> 1402 T240 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4] 2> 1428 T245 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c6002d60a0003 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> 1430 T240 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4] 2> 1435 T240 oasc.Overseer.start Overseer (id=89051657937747971-127.0.0.1:49453_-n_0000000000) starting
[junit4:junit4] 2> 1437 T245 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c6002d60a0003 type:create cxid:0x1a zxid:0x23 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 1446 T245 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c6002d60a0003 type:create cxid:0x1b zxid:0x24 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 1448 T245 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c6002d60a0003 type:create cxid:0x1c zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 1451 T245 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c6002d60a0003 type:create cxid:0x1d zxid:0x26 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 1454 T264 oasc.OverseerCollectionProcessor.run Process current queue of collection messages
[junit4:junit4] 2> 1454 T240 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4] 2> 1458 T240 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 1477 T263 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4] 2> 1485 T265 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-1358820464645\collection1
[junit4:junit4] 2> 1485 T265 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4] 2> 1487 T265 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 1487 T265 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4] 2> 1490 T265 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-1358820464645\collection1\'
[junit4:junit4] 2> 1494 T265 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-1358820464645/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 1496 T265 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-1358820464645/collection1/lib/README' to classloader
[junit4:junit4] 2> 1570 T265 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 1648 T265 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 1650 T265 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 1658 T265 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 2418 T265 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 2429 T265 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 2433 T265 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 2462 T265 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 2469 T265 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 2475 T265 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 2478 T265 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 2481 T265 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-1358820464645\collection1\, dataDir=.\org.apache.solr.cloud.RecoveryZkTest-1358820463871/control/data\
[junit4:junit4] 2> 2481 T265 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1930dd2
[junit4:junit4] 2> 2481 T265 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 2482 T265 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-1358820463871\control\data forceNew:false
[junit4:junit4] 2> 2482 T265 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358820463871\control\data
[junit4:junit4] 2> 2482 T265 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.RecoveryZkTest-1358820463871/control/data\index/
[junit4:junit4] 2> 2483 T265 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.RecoveryZkTest-1358820463871\control\data\index' doesn't exist. Creating new index...
[junit4:junit4] 2> 2484 T265 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-1358820463871\control\data\index forceNew:false
[junit4:junit4] 2> 2488 T265 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@656beb lockFactory=org.apache.lucene.store.NativeFSLockFactory@7a2aa6)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 2488 T265 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 2490 T265 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358820463871\control\data\index
[junit4:junit4] 2> 2491 T265 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4] 2> 2493 T265 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 2493 T265 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4] 2> 2494 T265 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 2494 T265 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4] 2> 2494 T265 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 2494 T265 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 2494 T265 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 2494 T265 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 2495 T265 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 2495 T265 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4] 2> 2496 T265 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4] 2> 2496 T265 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 2496 T265 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 2497 T265 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 2497 T265 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 2498 T265 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 2498 T265 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 2499 T265 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 2499 T265 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 2500 T265 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 2500 T265 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 2501 T265 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 2501 T265 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 2501 T265 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 2503 T265 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4] 2> 2503 T265 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4] 2> 2514 T265 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 2519 T265 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4] 2> 2522 T265 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> 2524 T265 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358820463871\control\data
[junit4:junit4] 2> 2525 T265 oass.SolrIndexSearcher.<init> Opening Searcher@1fe9c5c main
[junit4:junit4] 2> 2527 T265 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 2527 T265 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 2528 T265 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4] 2> 2539 T265 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4] 2> 2612 T266 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1fe9c5c main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 2620 T265 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 2621 T265 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 2623 T245 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c6002d60a0003 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> 2958 T263 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 2962 T263 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:49453_",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:49453"}
[junit4:junit4] 2> 2962 T263 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with numShards 1
[junit4:junit4] 2> 2978 T263 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 2980 T245 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c6002d60a0003 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> 3029 T262 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> 3610 T265 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 3610 T265 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:49453 collection:control_collection shard:shard1
[junit4:junit4] 2> 3624 T265 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4] 2> 3642 T265 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4:junit4] 2> 3672 T245 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c6002d60a0003 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> 3677 T265 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4] 2> 3678 T245 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c6002d60a0003 type:create cxid:0x73 zxid:0x37 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 3690 T265 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4] 2> 3692 T265 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4] 2> 3698 T265 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:49453/collection1/
[junit4:junit4] 2> 3698 T265 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4] 2> 3698 T265 oasc.SyncStrategy.syncToMe http://127.0.0.1:49453/collection1/ has no replicas
[junit4:junit4] 2> 3700 T265 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:49453/collection1/
[junit4:junit4] 2> 3701 T265 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4:junit4] 2> 3719 T245 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c6002d60a0003 type:create cxid:0x7d zxid:0x3b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 4510 T263 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 4537 T262 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> 4571 T265 oasc.ZkController.register We are http://127.0.0.1:49453/collection1/ and leader is http://127.0.0.1:49453/collection1/
[junit4:junit4] 2> 4572 T265 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:49453
[junit4:junit4] 2> 4572 T265 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4] 2> 4572 T265 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4] 2> 4572 T265 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 4576 T265 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 4581 T240 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4] 2> 4582 T240 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 4583 T240 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 4595 T240 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4] 2> 4611 T240 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:49446/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@652c3c
[junit4:junit4] 2> 4613 T240 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 4614 T267 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:49446. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 4614 T267 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:49446, initiating session
[junit4:junit4] 2> 4615 T242 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:49464
[junit4:junit4] 2> 4615 T242 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:49464
[junit4:junit4] 2> 4618 T244 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c6002d60a0004 with negotiated timeout 10000 for client /127.0.0.1:49464
[junit4:junit4] 2> 4618 T267 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:49446, sessionid = 0x13c6002d60a0004, negotiated timeout = 10000
[junit4:junit4] 2> 4618 T268 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@652c3c name:ZooKeeperConnection Watcher:127.0.0.1:49446/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 4619 T240 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 4620 T240 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 4627 T240 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4] 2> 4970 T240 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 4975 T240 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:49465
[junit4:junit4] 2> 4976 T240 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 4976 T240 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 4977 T240 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-1358820468494
[junit4:junit4] 2> 4978 T240 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-1358820468494\solr.xml
[junit4:junit4] 2> 4978 T240 oasc.CoreContainer.<init> New CoreContainer 10805734
[junit4:junit4] 2> 4979 T240 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-1358820468494\'
[junit4:junit4] 2> 4980 T240 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-1358820468494\'
[junit4:junit4] 2> 5039 T240 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 60000
[junit4:junit4] 2> 5039 T240 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 5039 T240 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 5040 T240 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 5040 T240 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 5040 T240 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 5040 T240 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 5041 T240 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 5041 T240 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 5042 T240 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=60000&connTimeout=15000&retry=false
[junit4:junit4] 2> 5059 T240 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 5081 T240 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:49446/solr
[junit4:junit4] 2> 5082 T240 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 5082 T240 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:49446 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@15553e1
[junit4:junit4] 2> 5084 T240 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 5085 T278 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:49446. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 5087 T278 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:49446, initiating session
[junit4:junit4] 2> 5087 T242 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:49470
[junit4:junit4] 2> 5087 T242 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:49470
[junit4:junit4] 2> 5107 T244 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c6002d60a0005 with negotiated timeout 20000 for client /127.0.0.1:49470
[junit4:junit4] 2> 5107 T278 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:49446, sessionid = 0x13c6002d60a0005, negotiated timeout = 20000
[junit4:junit4] 2> 5108 T279 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@15553e1 name:ZooKeeperConnection Watcher:127.0.0.1:49446 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 5108 T240 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 5111 T245 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c6002d60a0005
[junit4:junit4] 2> 5141 T279 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 5141 T240 oaz.ZooKeeper.close Session: 0x13c6002d60a0005 closed
[junit4:junit4] 2> 5142 T242 oazs.NIOServerCnxn.doIO WARNING caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x13c6002d60a0005, 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> 5144 T242 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:49470 which had sessionid 0x13c6002d60a0005
[junit4:junit4] 2> 5144 T240 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=60000&connTimeout=15000
[junit4:junit4] 2> 5161 T240 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:49446/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@156525a
[junit4:junit4] 2> 5165 T240 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 5166 T280 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:49446. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 5168 T280 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:49446, initiating session
[junit4:junit4] 2> 5169 T242 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:49473
[junit4:junit4] 2> 5169 T242 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:49473
[junit4:junit4] 2> 5193 T280 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:49446, sessionid = 0x13c6002d60a0006, negotiated timeout = 20000
[junit4:junit4] 2> 5196 T244 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c6002d60a0006 with negotiated timeout 20000 for client /127.0.0.1:49473
[junit4:junit4] 2> 5197 T281 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@156525a name:ZooKeeperConnection Watcher:127.0.0.1:49446/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 5204 T240 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 5205 T245 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c6002d60a0006 type:create cxid:0x1 zxid:0x49 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 5214 T245 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c6002d60a0006 type:create cxid:0x2 zxid:0x4a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 5217 T240 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49465_
[junit4:junit4] 2> 5218 T245 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c6002d60a0006 type:delete cxid:0x4 zxid:0x4b txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:49465_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:49465_
[junit4:junit4] 2> 5221 T240 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:49465_
[junit4:junit4] 2> 5226 T262 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> 5229 T268 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4] 2> 5231 T262 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4] 2> 5242 T240 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 5251 T282 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-1358820468494\collection1
[junit4:junit4] 2> 5253 T282 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4] 2> 5254 T282 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 5254 T282 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4] 2> 5259 T282 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-1358820468494\collection1\'
[junit4:junit4] 2> 5261 T282 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-1358820468494/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 5262 T282 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-1358820468494/collection1/lib/README' to classloader
[junit4:junit4] 2> 5318 T282 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 5399 T282 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 5401 T282 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 5413 T282 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 6449 T282 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 6466 T282 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 6473 T282 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 6529 T263 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 6533 T263 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:49453_",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:49453"}
[junit4:junit4] 2> 6579 T268 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> 6579 T262 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> 6580 T281 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> 6612 T282 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 6624 T282 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 6637 T282 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 6642 T282 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 6646 T282 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-1358820468494\collection1\, dataDir=.\org.apache.solr.cloud.RecoveryZkTest-1358820463871/jetty1\
[junit4:junit4] 2> 6646 T282 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1930dd2
[junit4:junit4] 2> 6647 T282 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 6647 T282 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-1358820463871\jetty1 forceNew:false
[junit4:junit4] 2> 6649 T282 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358820463871\jetty1
[junit4:junit4] 2> 6649 T282 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.RecoveryZkTest-1358820463871/jetty1\index/
[junit4:junit4] 2> 6651 T282 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.RecoveryZkTest-1358820463871\jetty1\index' doesn't exist. Creating new index...
[junit4:junit4] 2> 6651 T282 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-1358820463871\jetty1\index forceNew:false
[junit4:junit4] 2> 6656 T282 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@1e2083 lockFactory=org.apache.lucene.store.NativeFSLockFactory@ef1cb4)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 6656 T282 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 6659 T282 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358820463871\jetty1\index
[junit4:junit4] 2> 6659 T282 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4] 2> 6662 T282 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 6662 T282 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4] 2> 6663 T282 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 6663 T282 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4] 2> 6664 T282 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 6664 T282 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 6665 T282 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 6666 T282 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 6666 T282 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 6669 T282 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4] 2> 6670 T282 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4] 2> 6670 T282 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 6671 T282 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 6671 T282 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 6672 T282 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 6672 T282 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 6673 T282 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 6674 T282 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 6674 T282 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 6675 T282 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 6676 T282 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 6676 T282 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 6677 T282 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 6678 T282 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 6679 T282 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4] 2> 6680 T282 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4] 2> 6696 T282 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 6704 T282 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4] 2> 6706 T282 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> 6711 T282 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358820463871\jetty1
[junit4:junit4] 2> 6711 T282 oass.SolrIndexSearcher.<init> Opening Searcher@15894ee main
[junit4:junit4] 2> 6714 T282 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 6714 T282 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 6715 T282 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4] 2> 6733 T282 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4] 2> 6830 T282 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 6830 T282 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 6843 T283 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@15894ee main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 8236 T263 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 8237 T263 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:49465_",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:49465"}
[junit4:junit4] 2> 8237 T263 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 1
[junit4:junit4] 2> 8238 T263 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 8251 T281 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> 8251 T262 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> 8252 T268 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> 9004 T282 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 9004 T282 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:49465 collection:collection1 shard:shard1
[junit4:junit4] 2> 9006 T282 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4] 2> 9039 T245 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c6002d60a0006 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> 9044 T282 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4] 2> 9045 T245 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c6002d60a0006 type:create cxid:0x46 zxid:0x5c txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 9054 T282 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4] 2> 9054 T282 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4] 2> 9054 T282 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:49465/collection1/
[junit4:junit4] 2> 9054 T282 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4] 2> 9054 T282 oasc.SyncStrategy.syncToMe http://127.0.0.1:49465/collection1/ has no replicas
[junit4:junit4] 2> 9055 T282 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:49465/collection1/
[junit4:junit4] 2> 9055 T282 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4] 2> 9067 T245 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c6002d60a0006 type:create cxid:0x50 zxid:0x60 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 9882 T263 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 9983 T262 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> 9983 T281 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> 9983 T268 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> 10003 T282 oasc.ZkController.register We are http://127.0.0.1:49465/collection1/ and leader is http://127.0.0.1:49465/collection1/
[junit4:junit4] 2> 10003 T282 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:49465
[junit4:junit4] 2> 10003 T282 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4] 2> 10003 T282 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4] 2> 10004 T282 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 10026 T282 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 10050 T240 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4] 2> 10050 T240 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 10051 T240 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 10422 T240 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 10426 T240 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:49474
[junit4:junit4] 2> 10428 T240 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 10428 T240 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 10428 T240 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-1358820473929
[junit4:junit4] 2> 10428 T240 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-1358820473929\solr.xml
[junit4:junit4] 2> 10430 T240 oasc.CoreContainer.<init> New CoreContainer 8062186
[junit4:junit4] 2> 10431 T240 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-1358820473929\'
[junit4:junit4] 2> 10431 T240 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-1358820473929\'
[junit4:junit4] 2> 10484 T240 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 60000
[junit4:junit4] 2> 10484 T240 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 10484 T240 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 10485 T240 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 10486 T240 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 10486 T240 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 10487 T240 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 10487 T240 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 10488 T240 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 10488 T240 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=60000&connTimeout=15000&retry=false
[junit4:junit4] 2> 10506 T240 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 10528 T240 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:49446/solr
[junit4:junit4] 2> 10528 T240 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 10529 T240 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:49446 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@1750dba
[junit4:junit4] 2> 10531 T240 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 10532 T293 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:49446. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 10533 T293 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:49446, initiating session
[junit4:junit4] 2> 10533 T242 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:49479
[junit4:junit4] 2> 10534 T242 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:49479
[junit4:junit4] 2> 10546 T244 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c6002d60a0007 with negotiated timeout 20000 for client /127.0.0.1:49479
[junit4:junit4] 2> 10546 T293 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:49446, sessionid = 0x13c6002d60a0007, negotiated timeout = 20000
[junit4:junit4] 2> 10546 T294 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1750dba name:ZooKeeperConnection Watcher:127.0.0.1:49446 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 10547 T240 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 10550 T245 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c6002d60a0007
[junit4:junit4] 2> 10552 T240 oaz.ZooKeeper.close Session: 0x13c6002d60a0007 closed
[junit4:junit4] 2> 10552 T294 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 10553 T242 oazs.NIOServerCnxn.doIO WARNING caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x13c6002d60a0007, 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> 10552 T240 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=60000&connTimeout=15000
[junit4:junit4] 2> 10553 T242 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:49479 which had sessionid 0x13c6002d60a0007
[junit4:junit4] 2> 10565 T240 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:49446/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@924bf7
[junit4:junit4] 2> 10569 T240 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 10570 T295 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:49446. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 10570 T295 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:49446, initiating session
[junit4:junit4] 2> 10571 T242 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:49482
[junit4:junit4] 2> 10571 T242 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:49482
[junit4:junit4] 2> 10574 T295 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:49446, sessionid = 0x13c6002d60a0008, negotiated timeout = 20000
[junit4:junit4] 2> 10575 T244 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c6002d60a0008 with negotiated timeout 20000 for client /127.0.0.1:49482
[junit4:junit4] 2> 10575 T296 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@924bf7 name:ZooKeeperConnection Watcher:127.0.0.1:49446/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 10577 T240 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 10580 T245 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c6002d60a0008 type:create cxid:0x1 zxid:0x6d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 10583 T245 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c6002d60a0008 type:create cxid:0x2 zxid:0x6e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 10586 T240 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:49474_
[junit4:junit4] 2> 10587 T245 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c6002d60a0008 type:delete cxid:0x4 zxid:0x6f txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:49474_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:49474_
[junit4:junit4] 2> 10589 T240 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:49474_
[junit4:junit4] 2> 10597 T262 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> 10597 T268 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> 10597 T281 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> 10606 T262 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 10607 T268 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 10608 T281 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 10616 T240 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 10628 T297 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-1358820473929\collection1
[junit4:junit4] 2> 10628 T297 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4] 2> 10630 T297 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 10630 T297 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4] 2> 10636 T297 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-1358820473929\collection1\'
[junit4:junit4] 2> 10637 T297 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-1358820473929/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 10638 T297 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-1358820473929/collection1/lib/README' to classloader
[junit4:junit4] 2> 10699 T297 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 10812 T297 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 10816 T297 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 10826 T297 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 11617 T297 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 11629 T297 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 11634 T297 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 11675 T263 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 11678 T263 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:49465_",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:49465"}
[junit4:junit4] 2> 11680 T297 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 11694 T297 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 11697 T281 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> 11697 T296 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> 11698 T262 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> 11698 T268 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> 11707 T297 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 11727 T297 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 11734 T297 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-1358820473929\collection1\, dataDir=.\org.apache.solr.cloud.RecoveryZkTest-1358820463871/jetty2\
[junit4:junit4] 2> 11734 T297 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1930dd2
[junit4:junit4] 2> 11735 T297 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 11736 T297 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-1358820463871\jetty2 forceNew:false
[junit4:junit4] 2> 11736 T297 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358820463871\jetty2
[junit4:junit4] 2> 11737 T297 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.RecoveryZkTest-1358820463871/jetty2\index/
[junit4:junit4] 2> 11738 T297 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.RecoveryZkTest-1358820463871\jetty2\index' doesn't exist. Creating new index...
[junit4:junit4] 2> 11739 T297 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-1358820463871\jetty2\index forceNew:false
[junit4:junit4] 2> 11743 T297 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@16335dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@186804a)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 11744 T297 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 11746 T297 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358820463871\jetty2\index
[junit4:junit4] 2> 11746 T297 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4] 2> 11749 T297 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 11749 T297 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4] 2> 11749 T297 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 11750 T297 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4] 2> 11750 T297 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 11751 T297 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 11751 T297 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 11753 T297 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 11753 T297 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 11754 T297 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4] 2> 11754 T297 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4] 2> 11755 T297 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 11755 T297 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 11756 T297 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 11756 T297 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 11757 T297 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 11757 T297 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 11758 T297 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 11758 T297 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 11759 T297 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 11760 T297 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 11760 T297 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 11761 T297 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 11762 T297 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 11763 T297 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4] 2> 11763 T297 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4] 2> 11776 T297 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 11782 T297 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4] 2> 11784 T297 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> 11786 T297 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358820463871\jetty2
[junit4:junit4] 2> 11787 T297 oass.SolrIndexSearcher.<init> Opening Searcher@468428 main
[junit4:junit4] 2> 11789 T297 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 11790 T297 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 11790 T297 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4] 2> 11803 T297 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4] 2> 11910 T298 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@468428 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 11919 T297 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 11919 T297 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 13350 T263 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 13351 T263 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:49474_",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:49474"}
[junit4:junit4] 2> 13351 T263 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
[junit4:junit4] 2> 13352 T263 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 13364 T281 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> 13365 T296 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> 13365 T268 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> 13366 T262 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> 14078 T297 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 14078 T297 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:49474 collection:collection1 shard:shard1
[junit4:junit4] 2> 14095 T297 oasc.ZkController.register We are http://127.0.0.1:49474/collection1/ and leader is http://127.0.0.1:49465/collection1/
[junit4:junit4] 2> 14096 T297 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:49474
[junit4:junit4] 2> 14096 T297 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4] 2> 14096 T297 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4] 2> ASYNC NEW_CORE C39 name=collection1 org.apache.solr.core.SolrCore@24a28d url=http://127.0.0.1:49474/collection1 node=127.0.0.1:49474_ C39_STATE=coll:collection1 core:collection1 props:{shard=null, roles=null, state=down, core=collection1, collection=collection1, node_name=127.0.0.1:49474_, base_url=http://127.0.0.1:49474}
[junit4:junit4] 2> 14106 T299 C39 P49474 oasc.RecoveryStrategy.run Starting recovery process. core=collection1 recoveringAfterStartup=true
[junit4:junit4] 2> 14108 T297 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 14111 T299 C39 P49474 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4] 2> 14111 T240 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4] 2> 14111 T299 C39 P49474 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4] 2> 14112 T299 C39 P49474 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 14112 T240 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 14113 T240 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 14134 T299 C39 P49474 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 14135 T240 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> ASYNC NEW_CORE C40 name=collection1 org.apache.solr.core.SolrCore@5e4dd0 url=http://127.0.0.1:49453/collection1 node=127.0.0.1:49453_ C40_STATE=coll:control_collection core:collection1 props:{shard=shard1, roles=null, state=active, core=collection1, collection=control_collection, node_name=127.0.0.1:49453_, base_url=http://127.0.0.1:49453, leader=true}
[junit4:junit4] 2> 14216 T254 C40 P49453 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358820463871\control\data
[junit4:junit4] 2> 14221 T254 C40 P49453 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@656beb lockFactory=org.apache.lucene.store.NativeFSLockFactory@7a2aa6)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 14222 T254 C40 P49453 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 14228 T254 C40 P49453 /update {wt=javabin&version=2} {add=[1 (1424826541625311232)]} 0 44
[junit4:junit4] 2> 14228 T255 C40 P49453 /update {wt=javabin&version=2} {add=[10001 (1424826541625311233)]} 0 42
[junit4:junit4] 2> ASYNC NEW_CORE C41 name=collection1 org.apache.solr.core.SolrCore@1e1b8fb url=http://127.0.0.1:49465/collection1 node=127.0.0.1:49465_ C41_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:49465_, base_url=http://127.0.0.1:49465, leader=true}
[junit4:junit4] 2> 14243 T276 C41 P49465 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358820463871\jetty1
[junit4:junit4] 2> 14248 T276 C41 P49465 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@1e2083 lockFactory=org.apache.lucene.store.NativeFSLockFactory@ef1cb4)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 14249 T276 C41 P49465 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 14256 T277 C41 P49465 /update {wt=javabin&version=2} {add=[10001 (1424826541653622784)]} 0 14
[junit4:junit4] 2> 14267 T276 C41 P49465 /update {wt=javabin&version=2} {add=[1 (1424826541652574208)]} 0 26
[junit4:junit4] 2> 14275 T256 C40 P49453 /update {wt=javabin&version=2} {add=[10002 (1424826541682982912)]} 0 5
[junit4:junit4] 2> 14276 T258 C40 P49453 /update {wt=javabin&version=2} {add=[2 (1424826541686128640)]} 0 3
[junit4:junit4] 2> 14283 T272 C41 P49465 /update {wt=javabin&version=2} {add=[10002 (1424826541694517248)]} 0 2
[junit4:junit4] 2> 14286 T273 C41 P49465 /update {wt=javabin&version=2} {add=[2 (1424826541695565824)]} 0 4
[junit4:junit4] 2> 14290 T253 C40 P49453 /update {wt=javabin&version=2} {add=[10003 (1424826541702905856)]} 0 2
[junit4:junit4] 2> 14292 T255 C40 P49453 /update {wt=javabin&version=2} {add=[3 (1424826541702905857)]} 0 3
[junit4:junit4] 2> 14296 T274 C41 P49465 /update {wt=javabin&version=2} {add=[10003 (1424826541708148736)]} 0 3
[junit4:junit4] 2> 14298 T277 C41 P49465 /update {wt=javabin&version=2} {add=[3 (1424826541710245888)]} 0 2
[junit4:junit4] 2> 14302 T254 C40 P49453 /update {wt=javabin&version=2} {add=[10004 (1424826541713391616)]} 0 3
[junit4:junit4] 2> 14307 T257 C40 P49453 /update {wt=javabin&version=2} {add=[4 (1424826541719683072)]} 0 2
[junit4:junit4] 2> 14313 T276 C41 P49465 /update {wt=javabin&version=2} {add=[10004 (1424826541724925952)]} 0 3
[junit4:junit4] 2> 14317 T276 C41 P49465 /update {wt=javabin&version=2} {add=[4 (1424826541730168832)]} 0 2
[junit4:junit4] 2> 14320 T256 C40 P49453 /update {wt=javabin&version=2} {delete=[10001 (-1424826541735411712)]} 0 1
[junit4:junit4] 2> 14322 T258 C40 P49453 /update {wt=javabin&version=2} {delete=[1 (-1424826541736460288)]} 0 2
[junit4:junit4] 2> 14330 T274 C41 P49465 /update {wt=javabin&version=2} {delete=[1 (-1424826541743800320)]} 0 4
[junit4:junit4] 2> 14330 T273 C41 P49465 /update {wt=javabin&version=2} {delete=[10001 (-1424826541744848896)]} 0 4
[junit4:junit4] 2> 14336 T255 C40 P49453 /update {wt=javabin&version=2} {add=[5 (1424826541751140352)]} 0 3
[junit4:junit4] 2> 14343 T277 C41 P49465 /update {wt=javabin&version=2} {add=[5 (1424826541758480384)]} 0 3
[junit4:junit4] 2> 14350 T253 C40 P49453 /update {wt=javabin&version=2} {add=[10005 (1424826541751140353)]} 0 17
[junit4:junit4] 2> 14356 T254 C40 P49453 /update {wt=javabin&version=2} {add=[6 (1424826541768966144)]} 0 5
[junit4:junit4] 2> 14368 T272 C41 P49465 /update {wt=javabin&version=2} {add=[10005 (1424826541775257600)]} 0 10
[junit4:junit4] 2> 14373 T272 C41 P49465 /update {wt=javabin&version=2} {add=[6 (1424826541786791936)]} 0 4
[junit4:junit4] 2> 14382 T257 C40 P49453 /update {wt=javabin&version=2} {add=[10006 (1424826541795180544)]} 0 6
[junit4:junit4] 2> 14383 T256 C40 P49453 /update {wt=javabin&version=2} {delete=[3 (-1424826541801472000)]} 0 3
[junit4:junit4] 2> 14388 T274 C41 P49465 /update {wt=javabin&version=2} {delete=[3 (-1424826541806714880)]} 0 0
[junit4:junit4] 2> 14395 T273 C41 P49465 /update {wt=javabin&version=2} {add=[10006 (1424826541810909184)]} 0 4
[junit4:junit4] 2> 14451 T258 C40 P49453 /update {wt=javabin&version=2} {add=[7 (1424826541871726592)]} 0 54
[junit4:junit4] 2> 14460 T255 C40 P49453 /update {wt=javabin&version=2} {delete=[10003 (-1424826541882212352)]} 0 2
[junit4:junit4] 2> 14463 T277 C41 P49465 /update {wt=javabin&version=2} {add=[7 (1424826541884309504)]} 0 2
[junit4:junit4] 2> 14468 T272 C41 P49465 /update {wt=javabin&version=2} {delete=[10003 (-1424826541890600960)]} 0 1
[junit4:junit4] 2> 14472 T253 C40 P49453 /update {wt=javabin&version=2} {delete=[4 (-1424826541894795264)]} 0 2
[junit4:junit4] 2> 14486 T254 C40 P49453 /update {wt=javabin&version=2} {add=[10007 (1424826541905281024)]} 0 13
[junit4:junit4] 2> 14488 T274 C41 P49465 /update {wt=javabin&version=2} {delete=[4 (-1424826541911572480)]} 0 0
[junit4:junit4] 2> 14495 T257 C40 P49453 /update {wt=javabin&version=2} {add=[8 (1424826541915766784)]} 0 3
[junit4:junit4] 2> 14500 T273 C41 P49465 /update {wt=javabin&version=2} {add=[10007 (1424826541922058240)]} 0 3
[junit4:junit4] 2> 14505 T273 C41 P49465 /update {wt=javabin&version=2} {add=[8 (1424826541927301120)]} 0 3
[junit4:junit4] 2> 14511 T256 C40 P49453 /update {wt=javabin&version=2} {delete=[7 (-1424826541935689728)]} 0 2
[junit4:junit4] 2> 14514 T258 C40 P49453 /update {wt=javabin&version=2} {delete=[10004 (-1424826541938835456)]} 0 1
[junit4:junit4] 2> 14516 T276 C41 P49465 /update {wt=javabin&version=2} {delete=[7 (-1424826541940932608)]} 0 1
[junit4:junit4] 2> 14522 T255 C40 P49453 /update {wt=javabin&version=2} {add=[9 (1424826541944078336)]} 0 3
[junit4:junit4] 2> 14526 T272 C41 P49465 /update {wt=javabin&version=2} {delete=[10004 (-1424826541950369792)]} 0 0
[junit4:junit4] 2> 14530 T272 C41 P49465 /update {wt=javabin&version=2} {add=[9 (1424826541954564096)]} 0 2
[junit4:junit4] 2> 14537 T253 C40 P49453 /update {wt=javabin&version=2} {add=[10 (1424826541960855552)]} 0 2
[junit4:junit4] 2> 14542 T254 C40 P49453 /update {wt=javabin&version=2} {add=[10008 (1424826541966098432)]} 0 2
[junit4:junit4] 2> 14548 T273 C41 P49465 /update {wt=javabin&version=2} {add=[10 (1424826541972389888)]} 0 2
[junit4:junit4] 2> 14554 T277 C41 P49465 /update {wt=javabin&version=2} {add=[10008 (1424826541977632768)]} 0 3
[junit4:junit4] 2> 14558 T257 C40 P49453 /update {wt=javabin&version=2} {add=[11 (1424826541982875648)]} 0 2
[junit4:junit4] 2> 14560 T256 C40 P49453 /update {wt=javabin&version=2} {delete=[10007 (-1424826541987069952)]} 0 0
[junit4:junit4] 2> 14565 T276 C41 P49465 /update {wt=javabin&version=2} {add=[11 (1424826541991264256)]} 0 1
[junit4:junit4] 2> 14571 T258 C40 P49453 /update {wt=javabin&version=2} {delete=[9 (-1424826541998604288)]} 0 2
[junit4:junit4] 2> 14576 T272 C41 P49465 /update {wt=javabin&version=2} {delete=[10007 (-1424826542002798592)]} 0 3
[junit4:junit4] 2> 14578 T272 C41 P49465 /update {wt=javabin&version=2} {delete=[9 (-1424826542005944320)]} 0 0
[junit4:junit4] 2> 14604 T255 C40 P49453 /update {wt=javabin&version=2} {add=[12 (1424826542010138624)]} 0 22
[junit4:junit4] 2> 14609 T253 C40 P49453 /update {wt=javabin&version=2} {add=[10009 (1424826542035304448)]} 0 3
[junit4:junit4] 2> 14619 T277 C41 P49465 /update {wt=javabin&version=2} {add=[10009 (1424826542045790208)]} 0 4
[junit4:junit4] 2> 14622 T273 C41 P49465 /update {wt=javabin&version=2} {add=[12 (1424826542038450176)]} 0 14
[junit4:junit4] 2> 14629 T257 C40 P49453 /update {wt=javabin&version=2} {delete=[12 (-1424826542059421696)]} 0 2
[junit4:junit4] 2> 14630 T254 C40 P49453 /update {wt=javabin&version=2} {add=[10010 (1424826542059421697)]} 0 3
[junit4:junit4] 2> 14634 T276 C41 P49465 /update {wt=javabin&version=2} {delete=[12 (-1424826542064664576)]} 0 2
[junit4:junit4] 2> 14636 T272 C41 P49465 /update {wt=javabin&version=2} {add=[10010 (1424826542065713152)]} 0 2
[junit4:junit4] 2> 14640 T256 C40 P49453 /update {wt=javabin&version=2} {add=[13 (1424826542068858880)]} 0 2
[junit4:junit4] 2> 14641 T258 C40 P49453 /update {wt=javabin&version=2} {add=[10011 (1424826542070956032)]} 0 1
[junit4:junit4] 2> 14646 T274 C41 P49465 /update {wt=javabin&version=2} {add=[13 (1424826542076198912)]} 0 2
[junit4:junit4] 2> 14648 T277 C41 P49465 /update {wt=javabin&version=2} {add=[10011 (1424826542077247488)]} 0 2
[junit4:junit4] 2> 14651 T255 C40 P49453 /update {wt=javabin&version=2} {delete=[13 (-1424826542082490368)]} 0 2
[junit4:junit4] 2> 14652 T253 C40 P49453 /update {wt=javabin&version=2} {delete=[10009 (-1424826542082490369)]} 0 1
[junit4:junit4] 2> 14653 T273 C41 P49465 /update {wt=javabin&version=2} {delete=[13 (-1424826542084587520)]} 0 0
[junit4:junit4] 2> 14655 T273 C41 P49465 /update {wt=javabin&version=2} {delete=[10009 (-1424826542085636096)]} 0 1
[junit4:junit4] 2> 14658 T257 C40 P49453 /update {wt=javabin&version=2} {add=[14 (1424826542087733248)]} 0 2
[junit4:junit4] 2> 14659 T254 C40 P49453 /update {wt=javabin&version=2} {add=[10012 (1424826542089830400)]} 0 1
[junit4:junit4] 2> 14665 T274 C41 P49465 /update {wt=javabin&version=2} {add=[14 (1424826542094024704)]} 0 3
[junit4:junit4] 2> 14666 T272 C41 P49465 /update {wt=javabin&version=2} {add=[10012 (1424826542094024705)]} 0 4
[junit4:junit4] 2> 14671 T255 C40 P49453 /update {wt=javabin&version=2} {delete=[10012 (-1424826542103461888)]} 0 2
[junit4:junit4] 2> 14672 T256 C40 P49453 /update {wt=javabin&version=2} {add=[15 (1424826542101364736)]} 0 4
[junit4:junit4] 2> 14675 T277 C41 P49465 /update {wt=javabin&version=2} {delete=[10012 (-1424826542107656192)]} 0 1
[junit4:junit4] 2> 14677 T276 C41 P49465 /update {wt=javabin&version=2} {add=[15 (1424826542107656193)]} 0 2
[junit4:junit4] 2> 14680 T253 C40 P49453 /update {wt=javabin&version=2} {add=[10013 (1424826542110801920)]} 0 2
[junit4:junit4] 2> 14682 T253 C40 P49453 /update {wt=javabin&version=2} {add=[16 (1424826542114996224)]} 0 1
[junit4:junit4] 2> 14686 T273 C41 P49465 /update {wt=javabin&version=2} {add=[10013 (1424826542118141952)]} 0 1
[junit4:junit4] 2> 14688 T274 C41 P49465 /update {wt=javabin&version=2} {add=[16 (1424826542120239104)]} 0 2
[junit4:junit4] 2> 14690 T254 C40 P49453 /update {wt=javabin&version=2} {delete=[10013 (-1424826542123384832)]} 0 1
[junit4:junit4] 2> 14693 T272 C41 P49465 /update {wt=javabin&version=2} {delete=[10013 (-1424826542126530560)]} 0 1
[junit4:junit4] 2> 14693 T258 C40 P49453 /update {wt=javabin&version=2} {delete=[14 (-1424826542126530560)]} 0 1
[junit4:junit4] 2> 14697 T277 C41 P49465 /update {wt=javabin&version=2} {delete=[14 (-1424826542130724864)]} 0 1
[junit4:junit4] 2> 14707 T256 C40 P49453 /update {wt=javabin&version=2} {add=[17 (1424826542134919168)]} 0 6
[junit4:junit4] 2> 14709 T255 C40 P49453 /update {wt=javabin&version=2} {add=[10014 (1424826542129676288)]} 0 13
[junit4:junit4] 2> 14716 T273 C41 P49465 /update {wt=javabin&version=2} {add=[10014 (1424826542148550656)]} 0 2
[junit4:junit4] 2> 14724 T257 C40 P49453 /update {wt=javabin&version=2} {add=[10015 (1424826542154842112)]} 0 4
[junit4:junit4] 2> 14725 T276 C41 P49465 /update {wt=javabin&version=2} {add=[17 (1424826542144356352)]} 0 15
[junit4:junit4] 2> 14730 T253 C40 P49453 /update {wt=javabin&version=2} {add=[18 (1424826542164279296)]} 0 2
[junit4:junit4] 2> 14731 T274 C41 P49465 /update {wt=javabin&version=2} {add=[10015 (1424826542164279296)]} 0 2
[junit4:junit4] 2> 14737 T254 C40 P49453 /update {wt=javabin&version=2} {add=[10016 (1424826542170570752)]} 0 3
[junit4:junit4] 2> 14738 T272 C41 P49465 /update {wt=javabin&version=2} {add=[18 (1424826542172667904)]} 0 1
[junit4:junit4] 2> 14742 T258 C40 P49453 /update {wt=javabin&version=2} {delete=[15 (-1424826542177910784)]} 0 1
[junit4:junit4] 2> 14744 T277 C41 P49465 /update {wt=javabin&version=2} {add=[10016 (1424826542177910784)]} 0 3
[junit4:junit4] 2> 14747 T273 C41 P49465 /update {wt=javabin&version=2} {delete=[15 (-1424826542183153664)]} 0 1
[junit4:junit4] 2> 14749 T256 C40 P49453 /update {wt=javabin&version=2} {delete=[10014 (-1424826542185250816)]} 0 1
[junit4:junit4] 2> 14753 T276 C41 P49465 /update {wt=javabin&version=2} {delete=[10014 (-1424826542189445120)]} 0 2
[junit4:junit4] 2> 14753 T255 C40 P49453 /update {wt=javabin&version=2} {add=[19 (1424826542186299392)]} 0 3
[junit4:junit4] 2> 14758 T257 C40 P49453 /update {wt=javabin&version=2} {add=[10017 (1424826542192590848)]} 0 2
[junit4:junit4] 2> 14760 T274 C41 P49465 /update {wt=javabin&version=2} {add=[19 (1424826542195736576)]} 0 2
[junit4:junit4] 2> 14764 T274 C41 P49465 /update {wt=javabin&version=2} {add=[10017 (1424826542198882304)]} 0 3
[junit4:junit4] 2> 14765 T253 C40 P49453 /update {wt=javabin&version=2} {delete=[16 (-1424826542200979456)]} 0 1
[junit4:junit4] 2> 14770 T254 C40 P49453 /update {wt=javabin&version=2} {add=[10018 (1424826542204125184)]} 0 3
[junit4:junit4] 2> 14770 T277 C41 P49465 /update {wt=javabin&version=2} {delete=[16 (-1424826542207270912)]} 0 1
[junit4:junit4] 2> 14775 T273 C41 P49465 /update {wt=javabin&version=2} {add=[10018 (1424826542210416640)]} 0 2
[junit4:junit4] 2> 14776 T258 C40 P49453 /update {wt=javabin&version=2} {add=[20 (1424826542211465216)]} 0 3
[junit4:junit4] 2> 14779 T256 C40 P49453 /update {wt=javabin&version=2} {delete=[10015 (-1424826542216708096)]} 0 1
[junit4:junit4] 2> 14781 T276 C41 P49465 /update {wt=javabin&version=2} {add=[20 (1424826542217756672)]} 0 1
[junit4:junit4] 2> 14785 T272 C41 P49465 /update {wt=javabin&version=2} {delete=[10015 (-1424826542222999552)]} 0 1
[junit4:junit4] 2> 14789 T255 C40 P49453 /update {wt=javabin&version=2} {add=[21 (1424826542224048128)]} 0 3
[junit4:junit4] 2> 14795 T274 C41 P49465 /update {wt=javabin&version=2} {add=[21 (1424826542231388160)]} 0 2
[junit4:junit4] 2> 14796 T257 C40 P49453 /update {wt=javabin&version=2} {add=[10019 (1424826542226145280)]} 0 8
[junit4:junit4] 2> 14801 T253 C40 P49453 /update {wt=javabin&version=2} {add=[22 (1424826542236631040)]} 0 3
[junit4:junit4] 2> 14809 T273 C41 P49465 /update {wt=javabin&version=2} {add=[22 (1424826542247116800)]} 0 2
[junit4:junit4] 2> 14809 T277 C41 P49465 /update {wt=javabin&version=2} {add=[10019 (1424826542238728192)]} 0 9
[junit4:junit4] 2> 14814 T254 C40 P49453 /update {wt=javabin&version=2} {delete=[10016 (-1424826542252359680)]} 0 1
[junit4:junit4] 2> 14814 T258 C40 P49453 /update {wt=javabin&version=2} {delete=[18 (-1424826542253408256)]} 0 1
[junit4:junit4] 2> 14819 T272 C41 P49465 /update {wt=javabin&version=2} {delete=[10016 (-1424826542258651136)]} 0 2
[junit4:junit4] 2> 14820 T276 C41 P49465 /update {wt=javabin&version=2} {delete=[18 (-1424826542259699712)]} 0 2
[junit4:junit4] 2> 14826 T256 C40 P49453 /update {wt=javabin&version=2} {add=[23 (1424826542264942592)]} 0 2
[junit4:junit4] 2> 14830 T255 C40 P49453 /update {wt=javabin&version=2} {add=[10020 (1424826542269136896)]} 0 2
[junit4:junit4] 2> 14833 T274 C41 P49465 /update {wt=javabin&version=2} {add=[23 (1424826542271234048)]} 0 3
[junit4:junit4] 2> 14835 T273 C41 P49465 /update {wt=javabin&version=2} {add=[10020 (1424826542274379776)]} 0 1
[junit4:junit4] 2> 14838 T257 C40 P49453 /update {wt=javabin&version=2} {delete=[20 (-1424826542278574080)]} 0 1
[junit4:junit4] 2> 14844 T277 C41 P49465 /update {wt=javabin&version=2} {delete=[20 (-1424826542284865536)]} 0 3
[junit4:junit4] 2> 14845 T257 C40 P49453 /update {wt=javabin&version=2} {add=[10021 (1424826542282768384)]} 0 4
[junit4:junit4] 2> 14850 T254 C40 P49453 /update {wt=javabin&version=2} {add=[24 (1424826542288011264)]} 0 3
[junit4:junit4] 2> 14850 T272 C41 P49465 /update {wt=javabin&version=2} {add=[10021 (1424826542290108416)]} 0 1
[junit4:junit4] 2> 14855 T258 C40 P49453 /update {wt=javabin&version=2} {add=[10022 (1424826542294302720)]} 0 2
[junit4:junit4] 2> 14855 T276 C41 P49465 /update {wt=javabin&version=2} {add=[24 (1424826542294302720)]} 0 2
[junit4:junit4] 2> 14860 T274 C41 P49465 /update {wt=javabin&version=2} {add=[10022 (1424826542300594176)]} 0 2
[junit4:junit4] 2> 14860 T256 C40 P49453 /update {wt=javabin&version=2} {add=[25 (1424826542300594176)]} 0 2
[junit4:junit4] 2> 14866 T255 C40 P49453 /update {wt=javabin&version=2} {delete=[10018 (-1424826542307934208)]} 0 1
[junit4:junit4] 2> 14868 T277 C41 P49465 /update {wt=javabin&version=2} {add=[25 (1424826542307934208)]} 0 2
[junit4:junit4] 2> 14873 T272 C41 P49465 /update {wt=javabin&version=2} {delete=[10018 (-1424826542315274240)]} 0 2
[junit4:junit4] 2> 14880 T253 C40 P49453 /update {wt=javabin&version=2} {add=[26 (1424826542316322816)]} 0 6
[junit4:junit4] 2> 14888 T257 C40 P49453 /update {wt=javabin&version=2} {add=[10023 (1424826542327857152)]} 0 3
[junit4:junit4] 2> 14892 T276 C41 P49465 /update {wt=javabin&version=2} {add=[26 (1424826542332051456)]} 0 3
[junit4:junit4] 2> 14916 T254 C40 P49453 /update {wt=javabin&version=2} {delete=[24 (-1424826542359314432)]} 0 1
[junit4:junit4] 2> 14921 T273 C41 P49465 /update {wt=javabin&version=2} {add=[10023 (1424826542362460160)]} 0 3
[junit4:junit4] 2> 14964 T258 C40 P49453 /update {wt=javabin&version=2} {delete=[10020 (-1424826542410694656)]} 0 0
[junit4:junit4] 2> 14969 T274 C41 P49465 /update {wt=javabin&version=2} {delete=[24 (-1424826542414888960)]} 0 1
[junit4:junit4] 2> 14970 T277 C41 P49465 /update {wt=javabin&version=2} {delete=[10020 (-1424826542416986112)]} 0 0
[junit4:junit4] 2> 14985 T256 C40 P49453 /update {wt=javabin&version=2} {add=[10024 (1424826542421180416)]} 0 11
[junit4:junit4] 2> 15015 T255 C40 P49453 /update {wt=javabin&version=2} {add=[27 (1424826542453686272)]} 0 11
[junit4:junit4] 2> 15029 T272 C41 P49465 /update {wt=javabin&version=2} {add=[10024 (1424826542468366336)]} 0 11
[junit4:junit4] 2> 15035 T253 C40 P49453 /update {wt=javabin&version=2} {add=[10025 (1424826542484094976)]} 0 2
[junit4:junit4] 2> 15040 T276 C41 P49465 /update {wt=javabin&version=2} {add=[27 (1424826542488289280)]} 0 3
[junit4:junit4] 2> 15044 T273 C41 P49465 /update {wt=javabin&version=2} {add=[10025 (1424826542491435008)]} 0 3
[junit4:junit4] 2> 15050 T257 C40 P49453 /update {wt=javabin&version=2} {add=[10026 (1424826542498775040)]} 0 3
[junit4:junit4] 2> 15052 T254 C40 P49453 /update {wt=javabin&version=2} {delete=[26 (-1424826542502969344)]} 0 0
[junit4:junit4] 2> 15057 T274 C41 P49465 /update {wt=javabin&version=2} {add=[10026 (1424826542507163648)]} 0 1
[junit4:junit4] 2> 15059 T274 C41 P49465 /update {wt=javabin&version=2} {delete=[26 (-1424826542510309376)]} 0 1
[junit4:junit4] 2> 15061 T263 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 15063 T258 C40 P49453 /update {wt=javabin&version=2} {delete=[10024 (-1424826542514503680)]} 0 0
[junit4:junit4] 2> 15067 T258 C40 P49453 /update {wt=javabin&version=2} {add=[28 (1424826542516600832)]} 0 2
[junit4:junit4] 2> 15123 T263 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:49474_",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:49474"}
[junit4:junit4] 2> 15126 T272 C41 P49465 /update {wt=javabin&version=2} {delete=[10024 (-1424826542580563968)]} 0 1
[junit4:junit4] 2> 15129 T272 C41 P49465 /update {wt=javabin&version=2} {add=[28 (1424826542581612544)]} 0 2
[junit4:junit4] 2> 15131 T255 C40 P49453 /update {wt=javabin&version=2} {add=[10027 (1424826542584758272)]} 0 1
[junit4:junit4] 2> 15137 T276 C41 P49465 /update {wt=javabin&version=2} {add=[10027 (1424826542591049728)]} 0 1
[junit4:junit4] 2> 15155 T257 C40 P49453 /update {wt=javabin&version=2} {add=[29 (1424826542607826944)]} 0 3
[junit4:junit4] 2> 15159 T257 C40 P49453 /update {wt=javabin&version=2} {delete=[10026 (-1424826542615166976)]} 0 1
[junit4:junit4] 2> 15163 T274 C41 P49465 /update {wt=javabin&version=2} {add=[29 (1424826542617264128)]} 0 3
[junit4:junit4] 2> 15178 T258 C40 P49453 /update {wt=javabin&version=2} {add=[30 (1424826542634041344)]} 0 2
[junit4:junit4] 2> 15185 T277 C41 P49465 /update {wt=javabin&version=2} {delete=[10026 (-1424826542641381376)]} 0 1
[junit4:junit4] 2> 15189 T277 C41 P49465 /update {wt=javabin&version=2} {add=[30 (1424826542644527104)]} 0 2
[junit4:junit4] 2> 15194 T256 C40 P49453 /update {wt=javabin&version=2} {delete=[29 (-1424826542651867136)]} 0 0
[junit4:junit4] 2> 15203 T256 C40 P49453 /update {wt=javabin&version=2} {add=[10028 (1424826542658158592)]} 0 3
[junit4:junit4] 2> 15204 T276 C41 P49465 /update {wt=javabin&version=2} {delete=[29 (-1424826542662352896)]} 0 0
[junit4:junit4] 2> 15210 T273 C41 P49465 /update {wt=javabin&version=2} {add=[10028 (1424826542667595776)]} 0 1
[junit4:junit4] 2> 15214 T253 C40 P49453 /update {wt=javabin&version=2} {add=[31 (1424826542671790080)]} 0 2
[junit4:junit4] 2> 15223 T274 C41 P49465 /update {wt=javabin&version=2} {add=[31 (1424826542681227264)]} 0 3
[junit4:junit4] 2> 15232 T257 C40 P49453 /update {wt=javabin&version=2} {add=[10029 (1424826542675984384)]} 0 15
[junit4:junit4] 2> 15233 T254 C40 P49453 /update {wt=javabin&version=2} {delete=[30 (-1424826542692761600)]} 0 0
[junit4:junit4] 2> 15237 T277 C41 P49465 /update {wt=javabin&version=2} {delete=[30 (-1424826542696955904)]} 0 1
[junit4:junit4] 2> 15254 T272 C41 P49465 /update {wt=javabin&version=2} {add=[10029 (1424826542699053056)]} 0 15
[junit4:junit4] 2> 15258 T258 C40 P49453 /update {wt=javabin&version=2} {add=[32 (1424826542716878848)]} 0 3
[junit4:junit4] 2> 15265 T276 C41 P49465 /update {wt=javabin&version=2} {add=[32 (1424826542724218880)]} 0 3
[junit4:junit4] 2> 15271 T256 C40 P49453 /update {wt=javabin&version=2} {add=[10030 (1424826542730510336)]} 0 3
[junit4:junit4] 2> 15275 T256 C40 P49453 /update {wt=javabin&version=2} {add=[33 (1424826542734704640)]} 0 2
[junit4:junit4] 2> 15283 T274 C41 P49465 /update {wt=javabin&version=2} {add=[33 (1424826542742044672)]} 0 3
[junit4:junit4] 2> 15289 T296 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> 15289 T268 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> 15291 T262 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> 15292 T281 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> 15296 T277 C41 P49465 /update {wt=javabin&version=2} {add=[10030 (1424826542755676160)]} 0 3
[junit4:junit4] 2> 15298 T253 C40 P49453 /update {wt=javabin&version=2} {delete=[31 (-1424826542760919040)]} 0 0
[junit4:junit4] 2> 15302 T272 C41 P49465 /update {wt=javabin&version=2} {delete=[31 (-1424826542765113344)]} 0 0
[junit4:junit4] 2> 15312 T257 C40 P49453 /update {wt=javabin&version=2} {delete=[10029 (-1424826542774550528)]} 0 1
[junit4:junit4] 2> 15315 T257 C40 P49453 /update {wt=javabin&version=2} {add=[34 (1424826542776647680)]} 0 2
[junit4:junit4] 2> 15318 T276 C41 P49465 /update {wt=javabin&version=2} {delete=[10029 (-1424826542780841984)]} 0 0
[junit4:junit4] 2> 15323 T276 C41 P49465 /update {wt=javabin&version=2} {add=[34 (1424826542783987712)]} 0 4
[junit4:junit4] 2> 15327 T258 C40 P49453 /update {wt=javabin&version=2} {delete=[32 (-1424826542791327744)]} 0 0
[junit4:junit4] 2> 15332 T256 C40 P49453 /update {wt=javabin&version=2} {add=[10031 (1424826542794473472)]} 0 2
[junit4:junit4] 2> 15340 T273 C41 P49465 /update {wt=javabin&version=2} {delete=[32 (-1424826542804959232)]} 0 1
[junit4:junit4] 2> 15346 T273 C41 P49465 /update {wt=javabin&version=2} {add=[10031 (1424826542809153536)]} 0 3
[junit4:junit4] 2> 15352 T255 C40 P49453 /update {wt=javabin&version=2} {add=[35 (1424826542815444992)]} 0 3
[junit4:junit4] 2> 15408 T253 C40 P49453 /update {wt=javabin&version=2} {delete=[10030 (-1424826542876262400)]} 0 0
[junit4:junit4] 2> C39_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:49474_, base_url=http://127.0.0.1:49474}
[junit4:junit4] 2> 15448 T292 C39 P49474 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358820463871\jetty2
[junit4:junit4] 2> 15452 T292 C39 P49474 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@16335dd lockFactory=org.apache.lucene.store.NativeFSLockFactory@186804a)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 15453 T292 C39 P49474 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 15455 T287 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10030 (-1424826542893039616)]} 0 6
[junit4:junit4] 2> 15456 T292 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[35 (1424826542835367936)]} 0 10
[junit4:junit4] 2> 15457 T276 C41 P49465 /update {wt=javabin&version=2} {delete=[10030 (-1424826542893039616)]} 0 33
[junit4:junit4] 2> 15457 T272 C41 P49465 /update {wt=javabin&version=2} {add=[35 (1424826542835367936)]} 0 102
[junit4:junit4] 2> 15463 T257 C40 P49453 /update {wt=javabin&version=2} {add=[10032 (1424826542932885504)]} 0 3
[junit4:junit4] 2> 15463 T254 C40 P49453 /update {wt=javabin&version=2} {add=[36 (1424826542932885505)]} 0 1
[junit4:junit4] 2> 15472 T289 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10032 (1424826542937079808)]} 0 1
[junit4:junit4] 2> 15473 T288 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[36 (1424826542937079809)]} 0 2
[junit4:junit4] 2> 15473 T273 C41 P49465 /update {wt=javabin&version=2} {add=[10032 (1424826542937079808)]} 0 7
[junit4:junit4] 2> 15474 T274 C41 P49465 /update {wt=javabin&version=2} {add=[36 (1424826542937079809)]} 0 8
[junit4:junit4] 2> 15478 T258 C40 P49453 /update {wt=javabin&version=2} {add=[10033 (1424826542947565568)]} 0 2
[junit4:junit4] 2> 15517 T256 C40 P49453 /update {wt=javabin&version=2} {add=[37 (1424826542948614144)]} 0 40
[junit4:junit4] 2> 15534 T290 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10033 (1424826542991605760)]} 0 1
[junit4:junit4] 2> 15536 T277 C41 P49465 /update {wt=javabin&version=2} {add=[10033 (1424826542991605760)]} 0 18
[junit4:junit4] 2> 15540 T255 C40 P49453 /update {wt=javabin&version=2} {delete=[10031 (-1424826543014674432)]} 0 0
[junit4:junit4] 2> 15545 T291 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[37 (1424826542998945792)]} 0 2
[junit4:junit4] 2> 15547 T276 C41 P49465 /update {wt=javabin&version=2} {add=[37 (1424826542998945792)]} 0 22
[junit4:junit4] 2> 15550 T287 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10031 (-1424826543022014464)]} 0 0
[junit4:junit4] 2> 15552 T257 C40 P49453 /update {wt=javabin&version=2} {delete=[34 (-1424826543027257344)]} 0 1
[junit4:junit4] 2> 15553 T272 C41 P49465 /update {wt=javabin&version=2} {delete=[10031 (-1424826543022014464)]} 0 6
[junit4:junit4] 2> 15559 T254 C40 P49453 /update {wt=javabin&version=2} {add=[10034 (1424826543031451648)]} 0 3
[junit4:junit4] 2> 15562 T292 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[34 (-1424826543030403072)]} 0 1
[junit4:junit4] 2> 15563 T273 C41 P49465 /update {wt=javabin&version=2} {delete=[34 (-1424826543030403072)]} 0 8
[junit4:junit4] 2> 15567 T258 C40 P49453 /update {wt=javabin&version=2} {add=[38 (1424826543041937408)]} 0 2
[junit4:junit4] 2> 15571 T289 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10034 (1424826543039840256)]} 0 1
[junit4:junit4] 2> 15572 T274 C41 P49465 /update {wt=javabin&version=2} {add=[10034 (1424826543039840256)]} 0 8
[junit4:junit4] 2> 15577 T256 C40 P49453 /update {wt=javabin&version=2} {delete=[10032 (-1424826543051374592)]} 0 2
[junit4:junit4] 2> 15578 T288 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[38 (1424826543047180288)]} 0 1
[junit4:junit4] 2> 15579 T277 C41 P49465 /update {wt=javabin&version=2} {add=[38 (1424826543047180288)]} 0 8
[junit4:junit4] 2> 15584 T255 C40 P49453 /update {wt=javabin&version=2} {add=[39 (1424826543060811776)]} 0 2
[junit4:junit4] 2> 15586 T290 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10032 (-1424826543056617472)]} 0 0
[junit4:junit4] 2> 15587 T276 C41 P49465 /update {wt=javabin&version=2} {delete=[10032 (-1424826543056617472)]} 0 7
[junit4:junit4] 2> 15593 T253 C40 P49453 /update {wt=javabin&version=2} {add=[10035 (1424826543068151808)]} 0 2
[junit4:junit4] 2> 15594 T287 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[39 (1424826543065006080)]} 0 2
[junit4:junit4] 2> 15595 T272 C41 P49465 /update {wt=javabin&version=2} {add=[39 (1424826543065006080)]} 0 8
[junit4:junit4] 2> 15600 T257 C40 P49453 /update {wt=javabin&version=2} {delete=[36 (-1424826543076540416)]} 0 1
[junit4:junit4] 2> 15603 T292 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10035 (1424826543074443264)]} 0 2
[junit4:junit4] 2> 15606 T273 C41 P49465 /update {wt=javabin&version=2} {add=[10035 (1424826543074443264)]} 0 10
[junit4:junit4] 2> 15608 T289 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[36 (-1424826543080734720)]} 0 0
[junit4:junit4] 2> 15609 T274 C41 P49465 /update {wt=javabin&version=2} {delete=[36 (-1424826543080734720)]} 0 7
[junit4:junit4] 2> 15611 T254 C40 P49453 /update {wt=javabin&version=2} {add=[10036 (1424826543087026176)]} 0 2
[junit4:junit4] 2> 15629 T258 C40 P49453 /update {wt=javabin&version=2} {add=[40 (1424826543092269056)]} 0 16
[junit4:junit4] 2> 15644 T291 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[40 (1424826543111143424)]} 0 7
[junit4:junit4] 2> 15645 T276 C41 P49465 /update {wt=javabin&version=2} {add=[40 (1424826543111143424)]} 0 13
[junit4:junit4] 2> 15645 T288 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10036 (1424826543107997696)]} 0 10
[junit4:junit4] 2> 15646 T277 C41 P49465 /update {wt=javabin&version=2} {add=[10036 (1424826543107997696)]} 0 17
[junit4:junit4] 2> 15648 T256 C40 P49453 /update {wt=javabin&version=2} {delete=[37 (-1424826543127920640)]} 0 0
[junit4:junit4] 2> 15652 T255 C40 P49453 /update {wt=javabin&version=2} {add=[10037 (1424826543131066368)]} 0 1
[junit4:junit4] 2> 15657 T290 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[37 (-1424826543134212096)]} 0 0
[junit4:junit4] 2> 15659 T272 C41 P49465 /update {wt=javabin&version=2} {delete=[37 (-1424826543134212096)]} 0 6
[junit4:junit4] 2> 15665 T253 C40 P49453 /update {wt=javabin&version=2} {add=[41 (1424826543143649280)]} 0 2
[junit4:junit4] 2> 15670 T287 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10037 (1424826543140503552)]} 0 3
[junit4:junit4] 2> 15674 T272 C41 P49465 /update {wt=javabin&version=2} {add=[10037 (1424826543140503552)]} 0 14
[junit4:junit4] 2> 15677 T292 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[41 (1424826543153086464)]} 0 1
[junit4:junit4] 2> 15679 T274 C41 P49465 /update {wt=javabin&version=2} {add=[41 (1424826543153086464)]} 0 7
[junit4:junit4] 2> 15681 T257 C40 P49453 /update {wt=javabin&version=2} {delete=[10034 (-1424826543162523648)]} 0 0
[junit4:junit4] 2> 15683 T254 C40 P49453 /update {wt=javabin&version=2} {delete=[38 (-1424826543164620800)]} 0 1
[junit4:junit4] 2> 15689 T289 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[38 (-1424826543166717952)]} 0 0
[junit4:junit4] 2> 15690 T289 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10034 (-1424826543168815104)]} 0 0
[junit4:junit4] 2> 15691 T277 C41 P49465 /update {wt=javabin&version=2} {delete=[38 (-1424826543166717952)]} 0 6
[junit4:junit4] 2> 15694 T273 C41 P49465 /update {wt=javabin&version=2} {delete=[10034 (-1424826543168815104)]} 0 7
[junit4:junit4] 2> 15697 T258 C40 P49453 /update {wt=javabin&version=2} {add=[42 (1424826543178252288)]} 0 1
[junit4:junit4] 2> 15710 T256 C40 P49453 /update {wt=javabin&version=2} {add=[10038 (1424826543181398016)]} 0 11
[junit4:junit4] 2> 15739 T288 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[42 (1424826543185592320)]} 0 3
[junit4:junit4] 2> 15741 T288 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10038 (1424826543209709568)]} 0 1
[junit4:junit4] 2> 15742 T272 C41 P49465 /update {wt=javabin&version=2} {add=[10038 (1424826543209709568)]} 0 16
[junit4:junit4] 2> 15754 T276 C41 P49465 /update {wt=javabin&version=2} {add=[42 (1424826543185592320)]} 0 52
[junit4:junit4] 2> 15767 T255 C40 P49453 /update {wt=javabin&version=2} {add=[43 (1424826543250604032)]} 0 3
[junit4:junit4] 2> 15770 T255 C40 P49453 /update {wt=javabin&version=2} {add=[10039 (1424826543254798336)]} 0 2
[junit4:junit4] 2> 16014 T287 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[43 (1424826543257944064)]} 0 18
[junit4:junit4] 2> 16019 T274 C41 P49465 /update {wt=javabin&version=2} {add=[43 (1424826543257944064)]} 0 247
[junit4:junit4] 2> 16062 T257 C40 P49453 /update {wt=javabin&version=2} {delete=[39 (-1424826543560982528)]} 0 1
[junit4:junit4] 2> 16063 T289 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10039 (1424826543514845184)]} 0 3
[junit4:junit4] 2> 16064 T277 C41 P49465 /update {wt=javabin&version=2} {add=[10039 (1424826543514845184)]} 0 52
[junit4:junit4] 2> 16069 T291 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[39 (-1424826543566225408)]} 0 0
[junit4:junit4] 2> 16071 T277 C41 P49465 /update {wt=javabin&version=2} {delete=[39 (-1424826543566225408)]} 0 6
[junit4:junit4] 2> 16085 T258 C40 P49453 /update {wt=javabin&version=2} {delete=[10036 (-1424826543586148352)]} 0 0
[junit4:junit4] 2> 16090 T258 C40 P49453 /update {wt=javabin&version=2} {add=[44 (1424826543588245504)]} 0 4
[junit4:junit4] 2> 16098 T288 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10036 (-1424826543594536960)]} 0 0
[junit4:junit4] 2> 16100 T272 C41 P49465 /update {wt=javabin&version=2} {delete=[10036 (-1424826543594536960)]} 0 8
[junit4:junit4] 2> 16106 T255 C40 P49453 /update {wt=javabin&version=2} {add=[10040 (1424826543607119872)]} 0 1
[junit4:junit4] 2> 16107 T290 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[44 (1424826543596634112)]} 0 2
[junit4:junit4] 2> 16108 T276 C41 P49465 /update {wt=javabin&version=2} {add=[44 (1424826543596634112)]} 0 14
[junit4:junit4] 2> 16117 T253 C40 P49453 /update {wt=javabin&version=2} {add=[45 (1424826543617605632)]} 0 2
[junit4:junit4] 2> 16120 T287 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10040 (1424826543614459904)]} 0 3
[junit4:junit4] 2> 16121 T273 C41 P49465 /update {wt=javabin&version=2} {add=[10040 (1424826543614459904)]} 0 9
[junit4:junit4] 2> 16128 T257 C40 P49453 /update {wt=javabin&version=2} {delete=[10037 (-1424826543631237120)]} 0 1
[junit4:junit4] 2> 16136 T289 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10037 (-1424826543634382848)]} 0 0
[junit4:junit4] 2> 16139 T292 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[45 (1424826543623897088)]} 0 13
[junit4:junit4] 2> 16141 T277 C41 P49465 /update {wt=javabin&version=2} {add=[45 (1424826543623897088)]} 0 20
[junit4:junit4] 2> 16141 T274 C41 P49465 /update {wt=javabin&version=2} {delete=[10037 (-1424826543634382848)]} 0 10
[junit4:junit4] 2> 16144 T254 C40 P49453 /update {wt=javabin&version=2} {delete=[40 (-1424826543648014336)]} 0 0
[junit4:junit4] 2> 16151 T258 C40 P49453 /update {wt=javabin&version=2} {add=[10041 (1424826543650111488)]} 0 5
[junit4:junit4] 2> 16157 T291 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[40 (-1424826543655354368)]} 0 0
[junit4:junit4] 2> 16158 T272 C41 P49465 /update {wt=javabin&version=2} {delete=[40 (-1424826543655354368)]} 0 7
[junit4:junit4] 2> 16166 T288 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10041 (1424826543660597248)]} 0 7
[junit4:junit4] 2> 16167 T276 C41 P49465 /update {wt=javabin&version=2} {add=[10041 (1424826543660597248)]} 0 12
[junit4:junit4] 2> 16170 T256 C40 P49453 /update {wt=javabin&version=2} {add=[46 (1424826543675277312)]} 0 2
[junit4:junit4] 2> 16172 T255 C40 P49453 /update {wt=javabin&version=2} {delete=[10038 (-1424826543677374464)]} 0 0
[junit4:junit4] 2> 16180 T290 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10038 (-1424826543682617344)]} 0 1
[junit4:junit4] 2> 16182 T277 C41 P49465 /update {wt=javabin&version=2} {delete=[10038 (-1424826543682617344)]} 0 7
[junit4:junit4] 2> 16186 T287 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[46 (1424826543680520192)]} 0 4
[junit4:junit4] 2> 16188 T273 C41 P49465 /update {wt=javabin&version=2} {add=[46 (1424826543680520192)]} 0 14
[junit4:junit4] 2> 16189 T253 C40 P49453 /update {wt=javabin&version=2} {add=[10042 (1424826543693103104)]} 0 2
[junit4:junit4] 2> 16194 T257 C40 P49453 /update {wt=javabin&version=2} {add=[47 (1424826543698345984)]} 0 2
[junit4:junit4] 2> 16203 T289 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10042 (1424826543701491712)]} 0 3
[junit4:junit4] 2> 16205 T274 C41 P49465 /update {wt=javabin&version=2} {add=[10042 (1424826543701491712)]} 0 10
[junit4:junit4] 2> 16208 T292 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[47 (1424826543703588864)]} 0 1
[junit4:junit4] 2> 16210 T272 C41 P49465 /update {wt=javabin&version=2} {add=[47 (1424826543703588864)]} 0 13
[junit4:junit4] 2> 16212 T258 C40 P49453 /update {wt=javabin&version=2} {delete=[44 (-1424826543719317504)]} 0 0
[junit4:junit4] 2> 16221 T254 C40 P49453 /update {wt=javabin&version=2} {add=[10043 (1424826543717220352)]} 0 11
[junit4:junit4] 2> 16221 T291 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[44 (-1424826543724560384)]} 0 0
[junit4:junit4] 2> 16223 T276 C41 P49465 /update {wt=javabin&version=2} {delete=[44 (-1424826543724560384)]} 0 6
[junit4:junit4] 2> 16227 T256 C40 P49453 /update {wt=javabin&version=2} {add=[48 (1424826543732948992)]} 0 2
[junit4:junit4] 2> 16246 T288 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10043 (1424826543732948992)]} 0 2
[junit4:junit4] 2> 16251 T277 C41 P49465 /update {wt=javabin&version=2} {add=[10043 (1424826543732948992)]} 0 26
[junit4:junit4] 2> 16255 T290 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[48 (1424826543757066240)]} 0 1
[junit4:junit4] 2> 16256 T273 C41 P49465 /update {wt=javabin&version=2} {add=[48 (1424826543757066240)]} 0 8
[junit4:junit4] 2> 16260 T255 C40 P49453 /update {wt=javabin&version=2} {delete=[10039 (-1424826543768600576)]} 0 1
[junit4:junit4] 2> 16262 T253 C40 P49453 /update {wt=javabin&version=2} {add=[49 (1424826543770697728)]} 0 1
[junit4:junit4] 2> 16274 T287 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[49 (1424826543775940608)]} 0 3
[junit4:junit4] 2> 16275 T287 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10039 (-1424826543774892032)]} 0 0
[junit4:junit4] 2> 16276 T272 C41 P49465 /update {wt=javabin&version=2} {add=[49 (1424826543775940608)]} 0 10
[junit4:junit4] 2> 16278 T274 C41 P49465 /update {wt=javabin&version=2} {delete=[10039 (-1424826543774892032)]} 0 13
[junit4:junit4] 2> 16282 T257 C40 P49453 /update {wt=javabin&version=2} {add=[50 (1424826543791669248)]} 0 1
[junit4:junit4] 2> 16290 T258 C40 P49453 /update {wt=javabin&version=2} {add=[10044 (1424826543794814976)]} 0 6
[junit4:junit4] 2> 16297 T292 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[50 (1424826543802155008)]} 0 2
[junit4:junit4] 2> 16299 T276 C41 P49465 /update {wt=javabin&version=2} {add=[50 (1424826543802155008)]} 0 8
[junit4:junit4] 2> 16304 T254 C40 P49453 /update {wt=javabin&version=2} {delete=[47 (-1424826543815786496)]} 0 0
[junit4:junit4] 2> 16309 T291 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10044 (1424826543811592192)]} 0 3
[junit4:junit4] 2> 16310 T276 C41 P49465 /update {wt=javabin&version=2} {add=[10044 (1424826543811592192)]} 0 10
[junit4:junit4] 2> 16315 T288 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[47 (-1424826543823126528)]} 0 0
[junit4:junit4] 2> 16318 T256 C40 P49453 /update {wt=javabin&version=2} {add=[10045 (1424826543829417984)]} 0 1
[junit4:junit4] 2> 16319 T276 C41 P49465 /update {wt=javabin&version=2} {delete=[47 (-1424826543823126528)]} 0 8
[junit4:junit4] 2> 16325 T255 C40 P49453 /update {wt=javabin&version=2} {add=[51 (1424826543836758016)]} 0 1
[junit4:junit4] 2> 16355 T290 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10045 (1424826543839903744)]} 0 9
[junit4:junit4] 2> 16356 T272 C41 P49465 /update {wt=javabin&version=2} {add=[10045 (1424826543839903744)]} 0 30
[junit4:junit4] 2> 16359 T287 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[51 (1424826543842000896)]} 0 1
[junit4:junit4] 2> 16362 T274 C41 P49465 /update {wt=javabin&version=2} {add=[51 (1424826543842000896)]} 0 33
[junit4:junit4] 2> 16364 T253 C40 P49453 /update {wt=javabin&version=2} {delete=[10040 (-1424826543878701056)]} 0 0
[junit4:junit4] 2> 16365 T257 C40 P49453 /update {wt=javabin&version=2} {delete=[49 (-1424826543879749632)]} 0 0
[junit4:junit4] 2> 16370 T289 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10040 (-1424826543882895360)]} 0 0
[junit4:junit4] 2> 16372 T292 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[49 (-1424826543883943936)]} 0 0
[junit4:junit4] 2> 16373 T277 C41 P49465 /update {wt=javabin&version=2} {delete=[10040 (-1424826543882895360)]} 0 5
[junit4:junit4] 2> 16376 T273 C41 P49465 /update {wt=javabin&version=2} {delete=[49 (-1424826543883943936)]} 0 7
[junit4:junit4] 2> 16390 T258 C40 P49453 /update {wt=javabin&version=2} {add=[52 (1424826543902818304)]} 0 3
[junit4:junit4] 2> 16395 T258 C40 P49453 /update {wt=javabin&version=2} {add=[10046 (1424826543908061184)]} 0 3
[junit4:junit4] 2> 16410 T291 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[52 (1424826543916449792)]} 0 2
[junit4:junit4] 2> 16415 T288 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10046 (1424826543922741248)]} 0 2
[junit4:junit4] 2> 16417 T272 C41 P49465 /update {wt=javabin&version=2} {add=[10046 (1424826543922741248)]} 0 12
[junit4:junit4] 2> 16419 T276 C41 P49465 /update {wt=javabin&version=2} {add=[52 (1424826543916449792)]} 0 20
[junit4:junit4] 2> 16425 T255 C40 P49453 /update {wt=javabin&version=2} {add=[53 (1424826543940567041)]} 0 3
[junit4:junit4] 2> 16434 T256 C40 P49453 /update {wt=javabin&version=2} {add=[10047 (1424826543940567040)]} 0 12
[junit4:junit4] 2> 16441 T287 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[53 (1424826543952101376)]} 0 4
[junit4:junit4] 2> 16443 T277 C41 P49465 /update {wt=javabin&version=2} {add=[53 (1424826543952101376)]} 0 11
[junit4:junit4] 2> 16450 T257 C40 P49453 /update {wt=javabin&version=2} {add=[54 (1424826543966781440)]} 0 4
[junit4:junit4] 2> 16452 T289 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10047 (1424826543956295680)]} 0 1
[junit4:junit4] 2> 16456 T273 C41 P49465 /update {wt=javabin&version=2} {add=[10047 (1424826543956295680)]} 0 18
[junit4:junit4] 2> 16460 T258 C40 P49453 /update {wt=javabin&version=2} {delete=[10044 (-1424826543978315776)]} 0 1
[junit4:junit4] 2> 16461 T292 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[54 (1424826543975170048)]} 0 1
[junit4:junit4] 2> 16463 T272 C41 P49465 /update {wt=javabin&version=2} {add=[54 (1424826543975170048)]} 0 7
[junit4:junit4] 2> 16466 T292 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10044 (-1424826543982510080)]} 0 0
[junit4:junit4] 2> 16467 T275 C41 P49465 /update {wt=javabin&version=2} {delete=[10044 (-1424826543982510080)]} 0 4
[junit4:junit4] 2> 16470 T254 C40 P49453 /update {wt=javabin&version=2} {add=[55 (1424826543987752960)]} 0 3
[junit4:junit4] 2> 16473 T255 C40 P49453 /update {wt=javabin&version=2} {add=[10048 (1424826543991947264)]} 0 1
[junit4:junit4] 2> 16481 T288 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[55 (1424826543992995840)]} 0 2
[junit4:junit4] 2> 16482 T274 C41 P49465 /update {wt=javabin&version=2} {add=[55 (1424826543992995840)]} 0 9
[junit4:junit4] 2> 16485 T290 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10048 (1424826543999287296)]} 0 3
[junit4:junit4] 2> 16487 T276 C41 P49465 /update {wt=javabin&version=2} {add=[10048 (1424826543999287296)]} 0 9
[junit4:junit4] 2> 16488 T253 C40 P49453 /update {wt=javabin&version=2} {add=[56 (1424826544007675904)]} 0 2
[junit4:junit4] 2> 16493 T256 C40 P49453 /update {wt=javabin&version=2} {add=[10049 (1424826544012918784)]} 0 3
[junit4:junit4] 2> 16498 T287 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[56 (1424826544013967360)]} 0 2
[junit4:junit4] 2> 16500 T273 C41 P49465 /update {wt=javabin&version=2} {add=[56 (1424826544013967360)]} 0 8
[junit4:junit4] 2> 16505 T257 C40 P49453 /update {wt=javabin&version=2} {add=[57 (1424826544025501696)]} 0 2
[junit4:junit4] 2> 16512 T289 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10049 (1424826544017113088)]} 0 11
[junit4:junit4] 2> 16518 T272 C41 P49465 /update {wt=javabin&version=2} {add=[10049 (1424826544017113088)]} 0 22
[junit4:junit4] 2> 16522 T291 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[57 (1424826544037036032)]} 0 2
[junit4:junit4] 2> 16523 T275 C41 P49465 /update {wt=javabin&version=2} {add=[57 (1424826544037036032)]} 0 9
[junit4:junit4] 2> 16526 T258 C40 P49453 /update {wt=javabin&version=2} {add=[10050 (1424826544047521792)]} 0 1
[junit4:junit4] 2> 16527 T254 C40 P49453 /update {wt=javabin&version=2} {delete=[56 (-1424826544049618944)]} 0 0
[junit4:junit4] 2> 16541 T288 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[56 (-1424826544055910400)]} 0 0
[junit4:junit4] 2> 16544 T292 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10050 (1424826544054861824)]} 0 6
[junit4:junit4] 2> 16546 T276 C41 P49465 /update {wt=javabin&version=2} {delete=[56 (-1424826544055910400)]} 0 13
[junit4:junit4] 2> 16548 T274 C41 P49465 /update {wt=javabin&version=2} {add=[10050 (1424826544054861824)]} 0 17
[junit4:junit4] 2> 16553 T255 C40 P49453 /update {wt=javabin&version=2} {add=[58 (1424826544075833345)]} 0 2
[junit4:junit4] 2> 16552 T253 C40 P49453 /update {wt=javabin&version=2} {delete=[10047 (-1424826544075833344)]} 0 0
[junit4:junit4] 2> 16561 T290 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10047 (-1424826544083173376)]} 0 0
[junit4:junit4] 2> 16563 T273 C41 P49465 /update {wt=javabin&version=2} {delete=[10047 (-1424826544083173376)]} 0 5
[junit4:junit4] 2> 16566 T290 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[58 (1424826544082124800)]} 0 3
[junit4:junit4] 2> 16567 T277 C41 P49465 /update {wt=javabin&version=2} {add=[58 (1424826544082124800)]} 0 11
[junit4:junit4] 2> 16569 T256 C40 P49453 /update {wt=javabin&version=2} {add=[10051 (1424826544092610560)]} 0 2
[junit4:junit4] 2> 16582 T256 C40 P49453 /update {wt=javabin&version=2} {add=[59 (1424826544095756288)]} 0 12
[junit4:junit4] 2> 16584 T289 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10051 (1424826544099950592)]} 0 1
[junit4:junit4] 2> 16589 T272 C41 P49465 /update {wt=javabin&version=2} {add=[10051 (1424826544099950592)]} 0 15
[junit4:junit4] 2> 16592 T258 C40 P49453 /update {wt=javabin&version=2} {delete=[10049 (-1424826544117776384)]} 0 0
[junit4:junit4] 2> 16596 T291 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[59 (1424826544110436352)]} 0 1
[junit4:junit4] 2> 16597 T275 C41 P49465 /update {wt=javabin&version=2} {add=[59 (1424826544110436352)]} 0 13
[junit4:junit4] 2> 16599 T288 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10049 (-1424826544121970688)]} 0 1
[junit4:junit4] 2> 16599 T276 C41 P49465 /update {wt=javabin&version=2} {delete=[10049 (-1424826544121970688)]} 0 3
[junit4:junit4] 2> 16603 T254 C40 P49453 /update {wt=javabin&version=2} {add=[60 (1424826544128262144)]} 0 1
[junit4:junit4] 2> 16606 T255 C40 P49453 /update {wt=javabin&version=2} {add=[10052 (1424826544130359296)]} 0 2
[junit4:junit4] 2> 16615 T292 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[60 (1424826544132456448)]} 0 4
[junit4:junit4] 2> 16617 T274 C41 P49465 /update {wt=javabin&version=2} {add=[60 (1424826544132456448)]} 0 11
[junit4:junit4] 2> 16618 T292 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10052 (1424826544137699328)]} 0 1
[junit4:junit4] 2> 16619 T273 C41 P49465 /update {wt=javabin&version=2} {add=[10052 (1424826544137699328)]} 0 9
[junit4:junit4] 2> 16620 T253 C40 P49453 /update {wt=javabin&version=2} {delete=[60 (-1424826544147136512)]} 0 0
[junit4:junit4] 2> 16622 T257 C40 P49453 /update {wt=javabin&version=2} {add=[10053 (1424826544148185088)]} 0 1
[junit4:junit4] 2> 16627 T290 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[60 (-1424826544152379392)]} 0 0
[junit4:junit4] 2> 16628 T277 C41 P49465 /update {wt=javabin&version=2} {delete=[60 (-1424826544152379392)]} 0 4
[junit4:junit4] 2> 16632 T289 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10053 (1424826544153427968)]} 0 2
[junit4:junit4] 2> 16633 T272 C41 P49465 /update {wt=javabin&version=2} {add=[10053 (1424826544153427968)]} 0 7
[junit4:junit4] 2> 16635 T256 C40 P49453 /update {wt=javabin&version=2} {add=[61 (1424826544161816576)]} 0 2
[junit4:junit4] 2> 16637 T258 C40 P49453 /update {wt=javabin&version=2} {add=[10054 (1424826544163913728)]} 0 1
[junit4:junit4] 2> 16644 T291 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[61 (1424826544167059456)]} 0 1
[junit4:junit4] 2> 16646 T275 C41 P49465 /update {wt=javabin&version=2} {add=[61 (1424826544167059456)]} 0 9
[junit4:junit4] 2> 16659 T254 C40 P49453 /update {wt=javabin&version=2} {delete=[61 (-1424826544188030976)]} 0 9
[junit4:junit4] 2> 16669 T291 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10054 (1424826544169156608)]} 0 23
[junit4:junit4] 2> 16670 T276 C41 P49465 /update {wt=javabin&version=2} {add=[10054 (1424826544169156608)]} 0 29
[junit4:junit4] 2> 16673 T287 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[61 (-1424826544197468160)]} 0 0
[junit4:junit4] 2> 16676 T274 C41 P49465 /update {wt=javabin&version=2} {delete=[61 (-1424826544197468160)]} 0 10
[junit4:junit4] 2> 16680 T255 C40 P49453 /update {wt=javabin&version=2} {add=[10055 (1424826544209002496)]} 0 1
[junit4:junit4] 2> 16681 T253 C40 P49453 /update {wt=javabin&version=2} {add=[62 (1424826544210051072)]} 0 1
[junit4:junit4] 2> 16698 T292 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[62 (1424826544217391104)]} 0 6
[junit4:junit4] 2> 16700 T292 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10055 (1424826544220536832)]} 0 1
[junit4:junit4] 2> 16703 T277 C41 P49465 /update {wt=javabin&version=2} {add=[10055 (1424826544220536832)]} 0 13
[junit4:junit4] 2> 16706 T273 C41 P49465 /update {wt=javabin&version=2} {add=[62 (1424826544217391104)]} 0 20
[junit4:junit4] 2> 16710 T257 C40 P49453 /update {wt=javabin&version=2} {add=[10056 (1424826544238362624)]} 0 3
[junit4:junit4] 2> 16712 T256 C40 P49453 /update {wt=javabin&version=2} {add=[63 (1424826544241508352)]} 0 2
[junit4:junit4] 2> 16722 T289 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10056 (1424826544246751232)]} 0 2
[junit4:junit4] 2> 16725 T289 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[63 (1424826544248848384)]} 0 2
[junit4:junit4] 2> 16727 T275 C41 P49465 /update {wt=javabin&version=2} {add=[63 (1424826544248848384)]} 0 11
[junit4:junit4] 2> 16728 T272 C41 P49465 /update {wt=javabin&version=2} {add=[10056 (1424826544246751232)]} 0 13
[junit4:junit4] 2> 16736 T254 C40 P49453 /update {wt=javabin&version=2} {add=[10057 (1424826544264577024)]} 0 4
[junit4:junit4] 2> 16739 T258 C40 P49453 /update {wt=javabin&version=2} {add=[64 (1424826544262479872)]} 0 9
[junit4:junit4] 2> 16774 T291 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[64 (1424826544285548544)]} 0 2
[junit4:junit4] 2> 16779 T291 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10057 (1424826544283451392)]} 0 3
[junit4:junit4] 2> 16781 T276 C41 P49465 /update {wt=javabin&version=2} {add=[10057 (1424826544283451392)]} 0 38
[junit4:junit4] 2> 16782 T274 C41 P49465 /update {wt=javabin&version=2} {add=[64 (1424826544285548544)]} 0 30
[junit4:junit4] 2> 16785 T255 C40 P49453 /update {wt=javabin&version=2} {delete=[10056 (-1424826544320151552)]} 0 0
[junit4:junit4] 2> 16793 T253 C40 P49453 /update {wt=javabin&version=2} {add=[65 (1424826544326443008)]} 0 3
[junit4:junit4] 2> 16795 T292 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10056 (-1424826544324345856)]} 0 0
[junit4:junit4] 2> 16797 T277 C41 P49465 /update {wt=javabin&version=2} {delete=[10056 (-1424826544324345856)]} 0 8
[junit4:junit4] 2> 16802 T257 C40 P49453 /update {wt=javabin&version=2} {add=[10058 (1424826544337977344)]} 0 0
[junit4:junit4] 2> 16827 T290 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[65 (1424826544335880192)]} 0 14
[junit4:junit4] 2> 16832 T290 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10058 (1424826544344268800)]} 0 2
[junit4:junit4] 2> 16833 T273 C41 P49465 /update {wt=javabin&version=2} {add=[65 (1424826544335880192)]} 0 33
[junit4:junit4] 2> 16835 T275 C41 P49465 /update {wt=javabin&version=2} {add=[10058 (1424826544344268800)]} 0 28
[junit4:junit4] 2> 16838 T256 C40 P49453 /update {wt=javabin&version=2} {delete=[64 (-1424826544375726080)]} 0 0
[junit4:junit4] 2> 16846 T254 C40 P49453 /update {wt=javabin&version=2} {add=[10059 (1424826544383066112)]} 0 1
[junit4:junit4] 2> 16851 T291 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[64 (-1424826544382017536)]} 0 2
[junit4:junit4] 2> 16852 T272 C41 P49465 /update {wt=javabin&version=2} {delete=[64 (-1424826544382017536)]} 0 10
[junit4:junit4] 2> 16862 T255 C40 P49453 /update {wt=javabin&version=2} {add=[66 (1424826544398794752)]} 0 2
[junit4:junit4] 2> 16868 T287 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10059 (1424826544394600448)]} 0 4
[junit4:junit4] 2> 16873 T276 C41 P49465 /update {wt=javabin&version=2} {add=[10059 (1424826544394600448)]} 0 18
[junit4:junit4] 2> 16884 T292 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[66 (1424826544408231936)]} 0 9
[junit4:junit4] 2> 16886 T274 C41 P49465 /update {wt=javabin&version=2} {add=[66 (1424826544408231936)]} 0 17
[junit4:junit4] 2> 16892 T253 C40 P49453 /update {wt=javabin&version=2} {add=[67 (1424826544430252032)]} 0 2
[junit4:junit4] 2> 16897 T257 C40 P49453 /update {wt=javabin&version=2} {add=[10060 (1424826544436543488)]} 0 2
[junit4:junit4] 2> 16906 T290 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[67 (1424826544440737792)]} 0 1
[junit4:junit4] 2> 16908 T277 C41 P49465 /update {wt=javabin&version=2} {add=[67 (1424826544440737792)]} 0 9
[junit4:junit4] 2> 16912 T289 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10060 (1424826544443883520)]} 0 1
[junit4:junit4] 2> 16914 T256 C40 P49453 /update {wt=javabin&version=2} {delete=[66 (-1424826544455417856)]} 0 0
[junit4:junit4] 2> 16915 T273 C41 P49465 /update {wt=javabin&version=2} {add=[10060 (1424826544443883520)]} 0 12
[junit4:junit4] 2> 16925 T258 C40 P49453 /update {wt=javabin&version=2} {delete=[10060 (-1424826544466952192)]} 0 0
[junit4:junit4] 2> 16927 T288 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[66 (-1424826544463806464)]} 0 0
[junit4:junit4] 2> 16930 T272 C41 P49465 /update {wt=javabin&version=2} {delete=[66 (-1424826544463806464)]} 0 8
[junit4:junit4] 2> 16932 T291 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10060 (-1424826544471146496)]} 0 0
[junit4:junit4] 2> 16933 T276 C41 P49465 /update {wt=javabin&version=2} {delete=[10060 (-1424826544471146496)]} 0 4
[junit4:junit4] 2> 16936 T254 C40 P49453 /update {wt=javabin&version=2} {add=[68 (1424826544477437952)]} 0 2
[junit4:junit4] 2> 16948 T255 C40 P49453 /update {wt=javabin&version=2} {add=[10061 (1424826544487923712)]} 0 3
[junit4:junit4] 2> 16951 T287 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[68 (1424826544484777984)]} 0 1
[junit4:junit4] 2> 16954 T274 C41 P49465 /update {wt=javabin&version=2} {add=[68 (1424826544484777984)]} 0 12
[junit4:junit4] 2> 16967 T253 C40 P49453 /update {wt=javabin&version=2} {add=[69 (1424826544505749504)]} 0 6
[junit4:junit4] 2> 16972 T292 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10061 (1424826544500506624)]} 0 2
[junit4:junit4] 2> 16991 T275 C41 P49465 /update {wt=javabin&version=2} {add=[10061 (1424826544500506624)]} 0 34
[junit4:junit4] 2> 16995 T290 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[69 (1424826544520429568)]} 0 2
[junit4:junit4] 2> 16998 T277 C41 P49465 /update {wt=javabin&version=2} {add=[69 (1424826544520429568)]} 0 22
[junit4:junit4] 2> 17000 T257 C40 P49453 /update {wt=javabin&version=2} {delete=[10061 (-1424826544545595392)]} 0 0
[junit4:junit4] 2> 17080 T256 C40 P49453 /update {wt=javabin&version=2} {add=[70 (1424826544627384320)]} 0 71
[junit4:junit4] 2> 17088 T289 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[70 (1424826544632627201)]} 0 0
[junit4:junit4] 2> 17089 T289 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10061 (-1424826544632627200)]} 0 0
[junit4:junit4] 2> 17091 T272 C41 P49465 /update {wt=javabin&version=2} {add=[70 (1424826544632627201)]} 0 8
[junit4:junit4] 2> 17093 T273 C41 P49465 /update {wt=javabin&version=2} {delete=[10061 (-1424826544632627200)]} 0 11
[junit4:junit4] 2> 17096 T258 C40 P49453 /update {wt=javabin&version=2} {add=[71 (1424826544645210112)]} 0 1
[junit4:junit4] 2> 17100 T254 C40 P49453 /update {wt=javabin&version=2} {add=[10062 (1424826544648355840)]} 0 0
[junit4:junit4] 2> 17106 T291 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[71 (1424826544651501568)]} 0 1
[junit4:junit4] 2> 17109 T276 C41 P49465 /update {wt=javabin&version=2} {add=[71 (1424826544651501568)]} 0 9
[junit4:junit4] 2> 17120 T287 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10062 (1424826544654647296)]} 0 1
[junit4:junit4] 2> 17122 T274 C41 P49465 /update {wt=javabin&version=2} {add=[10062 (1424826544654647296)]} 0 18
[junit4:junit4] 2> 17125 T255 C40 P49453 /update {wt=javabin&version=2} {add=[72 (1424826544675618816)]} 0 2
[junit4:junit4] 2> 17127 T253 C40 P49453 /update {wt=javabin&version=2} {add=[10063 (1424826544678764544)]} 0 0
[junit4:junit4] 2> 17199 T292 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[72 (1424826544681910272)]} 0 66
[junit4:junit4] 2> 17208 T292 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10063 (1424826544684007424)]} 0 8
[junit4:junit4] 2> 17210 T277 C41 P49465 /update {wt=javabin&version=2} {add=[10063 (1424826544684007424)]} 0 78
[junit4:junit4] 2> 17214 T275 C41 P49465 /update {wt=javabin&version=2} {add=[72 (1424826544681910272)]} 0 84
[junit4:junit4] 2> 17216 T257 C40 P49453 /update {wt=javabin&version=2} {add=[10064 (1424826544771039232)]} 0 1
[junit4:junit4] 2> 17228 T256 C40 P49453 /update {wt=javabin&version=2} {delete=[67 (-1424826544784670720)]} 0 0
[junit4:junit4] 2> 17234 T289 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10064 (1424826544779427840)]} 0 4
[junit4:junit4] 2> 17236 T272 C41 P49465 /update {wt=javabin&version=2} {add=[10064 (1424826544779427840)]} 0 14
[junit4:junit4] 2> 17244 T258 C40 P49453 /update {wt=javabin&version=2} {add=[10065 (1424826544800399360)]} 0 2
[junit4:junit4] 2> 17248 T291 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[67 (-1424826544794107904)]} 0 1
[junit4:junit4] 2> 17249 T272 C41 P49465 /update {wt=javabin&version=2} {delete=[67 (-1424826544794107904)]} 0 12
[junit4:junit4] 2> 17255 T255 C40 P49453 /update {wt=javabin&version=2} {add=[73 (1424826544812982272)]} 0 1
[junit4:junit4] 2> 17256 T287 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10065 (1424826544807739392)]} 0 1
[junit4:junit4] 2> 17257 T276 C41 P49465 /update {wt=javabin&version=2} {add=[10065 (1424826544807739392)]} 0 7
[junit4:junit4] 2> 17262 T253 C40 P49453 /update {wt=javabin&version=2} {delete=[10064 (-1424826544820322304)]} 0 0
[junit4:junit4] 2> 17265 T292 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[73 (1424826544817176576)]} 0 1
[junit4:junit4] 2> 17266 T274 C41 P49465 /update {wt=javabin&version=2} {add=[73 (1424826544817176576)]} 0 7
[junit4:junit4] 2> 17269 T290 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10064 (-1424826544824516608)]} 0 0
[junit4:junit4] 2> 17271 T277 C41 P49465 /update {wt=javabin&version=2} {delete=[10064 (-1424826544824516608)]} 0 5
[junit4:junit4] 2> 17272 T257 C40 P49453 /update {wt=javabin&version=2} {add=[74 (1424826544829759488)]} 0 2
[junit4:junit4] 2> 17282 T289 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[74 (1424826544835002368)]} 0 2
[junit4:junit4] 2> 17284 T275 C41 P49465 /update {wt=javabin&version=2} {add=[74 (1424826544835002368)]} 0 8
[junit4:junit4] 2> 17286 T256 C40 P49453 /update {wt=javabin&version=2} {add=[10066 (1424826544832905216)]} 0 12
[junit4:junit4] 2> 17291 T258 C40 P49453 /update {wt=javabin&version=2} {add=[75 (1424826544847585280)]} 0 3
[junit4:junit4] 2> 17317 T288 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10066 (1424826544850731008)]} 0 1
[junit4:junit4] 2> 17320 T273 C41 P49465 /update {wt=javabin&version=2} {add=[10066 (1424826544850731008)]} 0 31
[junit4:junit4] 2> 17324 T291 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[75 (1424826544873799680)]} 0 1
[junit4:junit4] 2> 17328 T272 C41 P49465 /update {wt=javabin&version=2} {add=[75 (1424826544873799680)]} 0 33
[junit4:junit4] 2> 17332 T254 C40 P49453 /update {wt=javabin&version=2} {add=[10067 (1424826544892674048)]} 0 2
[junit4:junit4] 2> 17335 T254 C40 P49453 /update {wt=javabin&version=2} {delete=[69 (-1424826544894771200)]} 0 2
[junit4:junit4] 2> 17352 T287 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[69 (-1424826544901062656)]} 0 1
[junit4:junit4] 2> 17354 T287 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10067 (1424826544909451264)]} 0 1
[junit4:junit4] 2> 17357 T274 C41 P49465 /update {wt=javabin&version=2} {delete=[69 (-1424826544901062656)]} 0 18
[junit4:junit4] 2> 17359 T277 C41 P49465 /update {wt=javabin&version=2} {add=[10067 (1424826544909451264)]} 0 12
[junit4:junit4] 2> 17366 T253 C40 P49453 /update {wt=javabin&version=2} {add=[76 (1424826544927277056)]} 0 5
[junit4:junit4] 2> 17368 T257 C40 P49453 /update {wt=javabin&version=2} {delete=[10066 (-1424826544931471360)]} 0 0
[junit4:junit4] 2> 17379 T290 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[76 (1424826544935665664)]} 0 3
[junit4:junit4] 2> 17381 T289 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10066 (-1424826544938811392)]} 0 0
[junit4:junit4] 2> 17382 T275 C41 P49465 /update {wt=javabin&version=2} {add=[76 (1424826544935665664)]} 0 10
[junit4:junit4] 2> 17384 T273 C41 P49465 /update {wt=javabin&version=2} {delete=[10066 (-1424826544938811392)]} 0 9
[junit4:junit4] 2> 17388 T256 C40 P49453 /update {wt=javabin&version=2} {delete=[70 (-1424826544952442880)]} 0 1
[junit4:junit4] 2> 17393 T258 C40 P49453 /update {wt=javabin&version=2} {add=[10068 (1424826544954540032)]} 0 3
[junit4:junit4] 2> 17397 T288 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[70 (-1424826544958734336)]} 0 1
[junit4:junit4] 2> 17398 T276 C41 P49465 /update {wt=javabin&version=2} {delete=[70 (-1424826544958734336)]} 0 4
[junit4:junit4] 2> 17405 T254 C40 P49453 /update {wt=javabin&version=2} {add=[77 (1424826544969220096)]} 0 1
[junit4:junit4] 2> 17411 T291 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10068 (1424826544967122944)]} 0 2
[junit4:junit4] 2> 17415 T272 C41 P49465 /update {wt=javabin&version=2} {add=[10068 (1424826544967122944)]} 0 13
[junit4:junit4] 2> 17426 T255 C40 P49453 /update {wt=javabin&version=2} {add=[10069 (1424826544991240192)]} 0 2
[junit4:junit4] 2> 17427 T287 C39 P49474 /update {distrib.from=http://127.0.0.1:49465/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[77 (1424826544978657280)]} 0 10
[junit4:junit4] 2> 17427 T240 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 49474
[junit4:junit4] 2> 17428 T240 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=8062186
[junit4:junit4] 2> 17430 T240 oasc.RecoveryStrategy.close WARNING Stopping recovery for zkNodeName=127.0.0.1:49474__collection1core=collection1
[junit4:junit4] 2> 17430 T274 C41 P49465 /update {wt=javabin&version=2} {add=[77 (1424826544978657280)]} 0 18
[junit4:junit4] 2> 17437 T253 C40 P
[...truncated too long message...]
81363 T240 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-1358820463871\jetty2
[junit4:junit4] 2> 81363 T240 oasc.CachingDirectoryFactory.closeDirectory Closing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358820463871\jetty2
[junit4:junit4] 2> 81952 T333 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:49446. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4] 2> 102649 T240 oaz.ZooKeeper.close Session: 0x13c6002d60a000a closed
[junit4:junit4] 2> 102650 T334 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 102668 T240 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null}
[junit4:junit4] 2> 102887 T240 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=F6D24E1D219F7187 -Dtests.slow=true -Dtests.locale=ja_JP -Dtests.timezone=America/Argentina/Rio_Gallegos -Dtests.file.encoding=US-ASCII
[junit4:junit4] FAILURE 103s | RecoveryZkTest.testDistribSearch <<<
[junit4:junit4] > Throwable #1: java.lang.AssertionError: expected:<218> but was:<2>
[junit4:junit4] > at __randomizedtesting.SeedInfo.seed([F6D24E1D219F7187:7734C00556C011BB]: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> 102899 T240 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4] 2> NOTE: test params are: codec=Lucene41: {range_facet_l=PostingsFormat(name=NestedPulsing), text=MockVariableIntBlock(baseBlockSize=74), _version_=PostingsFormat(name=NestedPulsing), rnd_b=PostingsFormat(name=Memory doPackFST= false), intDefault=PostingsFormat(name=NestedPulsing), timestamp=PostingsFormat(name=NestedPulsing), id=PostingsFormat(name=Memory doPackFST= false), a_t=PostingsFormat(name=NestedPulsing), range_facet_sl=MockVariableIntBlock(baseBlockSize=74), range_facet_si=MockFixedIntBlock(blockSize=494), other_tl1=PostingsFormat(name=NestedPulsing), multiDefault=MockFixedIntBlock(blockSize=494), a_si=MockFixedIntBlock(blockSize=494)}, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=ja_JP, timezone=America/Argentina/Rio_Gallegos
[junit4:junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.7.0_10 (32-bit)/cpus=2,threads=1,free=89047224,total=289406976
[junit4:junit4] 2> NOTE: All tests run in this JVM: [StatsComponentTest, TestReload, TestPHPSerializedResponseWriter, XmlUpdateRequestHandlerTest, UpdateRequestProcessorFactoryTest, TestQueryUtils, SuggesterWFSTTest, CopyFieldTest, SuggesterTest, SpellingQueryConverterTest, TermVectorComponentDistributedTest, PluginInfoTest, DirectUpdateHandlerTest, TestSolrQueryParser, TestHashPartitioner, PrimitiveFieldTypeTest, TestPluginEnable, FastVectorHighlighterTest, UniqFieldsUpdateProcessorFactoryTest, SoftAutoCommitTest, DisMaxRequestHandlerTest, TimeZoneUtilsTest, ChaosMonkeyNothingIsSafeTest, OverseerCollectionProcessorTest, TestTrie, RequiredFieldsTest, IndexSchemaTest, TestOmitPositions, RecoveryZkTest]
[junit4:junit4] Completed in 102.93s, 1 test, 1 failure <<< FAILURES!
[...truncated 673 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:344: 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: 247 suites, 1031 tests, 1 failure, 14 ignored (9 assumptions)
Total time: 47 minutes 49 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 32bit/jdk1.7.0_10 -server -XX:+UseG1GC
Email was triggered for: Failure
Sending email for trigger: Failure