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/19 00:01:25 UTC
[JENKINS] Lucene-Solr-trunk-Windows (64bit/jdk1.6.0_38) - Build #
2425 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/2425/
Java: 64bit/jdk1.6.0_38 -XX:+UseParallelGC
1 tests failed.
REGRESSION: org.apache.solr.cloud.RecoveryZkTest.testDistribSearch
Error Message:
expected:<133> but was:<2>
Stack Trace:
java.lang.AssertionError: expected:<133> but was:<2>
at __randomizedtesting.SeedInfo.seed([32995DA31B4DD4C3:B37FD3BB6C12B4FF]: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:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
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:662)
Build Log:
[...truncated 8034 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.RecoveryZkTest
[junit4:junit4] 2> 0 T302 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /_o/
[junit4:junit4] 2> 4 T302 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-1358548656691
[junit4:junit4] 2> 12 T302 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4] 2> 17 T303 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4:junit4] 2> 86 T303 oaz.Environment.logEnv Server environment:zookeeper.version=3.4.5-1392090, built on 09/30/2012 17:52 GMT
[junit4:junit4] 2> 86 T303 oaz.Environment.logEnv Server environment:host.name=JenkinsSlave-PC
[junit4:junit4] 2> 86 T303 oaz.Environment.logEnv Server environment:java.version=1.6.0_38
[junit4:junit4] 2> 87 T303 oaz.Environment.logEnv Server environment:java.vendor=Sun Microsystems Inc.
[junit4:junit4] 2> 87 T303 oaz.Environment.logEnv Server environment:java.home=C:\Users\JenkinsSlave\tools\java\64bit\jdk1.6.0_38\jre
[junit4:junit4] 2> 87 T303 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\64bit\jdk1.6.0_38\lib\tools.jar;C:\Users\JenkinsSlave\.ivy2\cache\com.carrotsearch.randomizedtesting\junit4-ant\jars\junit4-ant-2.0.8.jar
[junit4:junit4] 2> 87 T303 oaz.Environment.logEnv Server environment:java.library.path=C:\Users\JenkinsSlave\tools\java\64bit\jdk1.6.0_38\jre\bin;C:\Windows\Sun\Java\bin;C:\Windows\system32;C:\Windows;C:\Users\JenkinsSlave\tools\java\64bit\jdk1.6.0_38\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> 88 T303 oaz.Environment.logEnv Server environment:java.io.tmpdir=.
[junit4:junit4] 2> 88 T303 oaz.Environment.logEnv Server environment:java.compiler=<NA>
[junit4:junit4] 2> 88 T303 oaz.Environment.logEnv Server environment:os.name=Windows 7
[junit4:junit4] 2> 88 T303 oaz.Environment.logEnv Server environment:os.arch=amd64
[junit4:junit4] 2> 88 T303 oaz.Environment.logEnv Server environment:os.version=6.1
[junit4:junit4] 2> 89 T303 oaz.Environment.logEnv Server environment:user.name=JenkinsSlave
[junit4:junit4] 2> 89 T303 oaz.Environment.logEnv Server environment:user.home=C:\Users\JenkinsSlave
[junit4:junit4] 2> 89 T303 oaz.Environment.logEnv Server environment:user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4] 2> 126 T303 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4] 2> 126 T303 oazs.ZooKeeperServer.setMinSessionTimeout minSessionTimeout set to -1
[junit4:junit4] 2> 126 T303 oazs.ZooKeeperServer.setMaxSessionTimeout maxSessionTimeout set to -1
[junit4:junit4] 2> 128 T303 oazs.NIOServerCnxnFactory.configure binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4] 2> 194 T303 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-1358548656690\zookeeper\server1\data\version-2\snapshot.0
[junit4:junit4] 2> 214 T302 oasc.ZkTestServer.run start zk server on port:52647
[junit4:junit4] 2> 355 T302 oaz.Environment.logEnv Client environment:zookeeper.version=3.4.5-1392090, built on 09/30/2012 17:52 GMT
[junit4:junit4] 2> 356 T302 oaz.Environment.logEnv Client environment:host.name=JenkinsSlave-PC
[junit4:junit4] 2> 356 T302 oaz.Environment.logEnv Client environment:java.version=1.6.0_38
[junit4:junit4] 2> 356 T302 oaz.Environment.logEnv Client environment:java.vendor=Sun Microsystems Inc.
[junit4:junit4] 2> 356 T302 oaz.Environment.logEnv Client environment:java.home=C:\Users\JenkinsSlave\tools\java\64bit\jdk1.6.0_38\jre
[junit4:junit4] 2> 357 T302 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\64bit\jdk1.6.0_38\lib\tools.jar;C:\Users\JenkinsSlave\.ivy2\cache\com.carrotsearch.randomizedtesting\junit4-ant\jars\junit4-ant-2.0.8.jar
[junit4:junit4] 2> 358 T302 oaz.Environment.logEnv Client environment:java.library.path=C:\Users\JenkinsSlave\tools\java\64bit\jdk1.6.0_38\jre\bin;C:\Windows\Sun\Java\bin;C:\Windows\system32;C:\Windows;C:\Users\JenkinsSlave\tools\java\64bit\jdk1.6.0_38\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> 358 T302 oaz.Environment.logEnv Client environment:java.io.tmpdir=.
[junit4:junit4] 2> 359 T302 oaz.Environment.logEnv Client environment:java.compiler=<NA>
[junit4:junit4] 2> 359 T302 oaz.Environment.logEnv Client environment:os.name=Windows 7
[junit4:junit4] 2> 359 T302 oaz.Environment.logEnv Client environment:os.arch=amd64
[junit4:junit4] 2> 360 T302 oaz.Environment.logEnv Client environment:os.version=6.1
[junit4:junit4] 2> 360 T302 oaz.Environment.logEnv Client environment:user.name=JenkinsSlave
[junit4:junit4] 2> 360 T302 oaz.Environment.logEnv Client environment:user.home=C:\Users\JenkinsSlave
[junit4:junit4] 2> 367 T302 oaz.Environment.logEnv Client environment:user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4] 2> 396 T302 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52647 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@180cf393
[junit4:junit4] 2> 490 T302 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 502 T308 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:52647. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4] 2> 503 T308 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:52647, initiating session
[junit4:junit4] 2> 503 T304 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:52650
[junit4:junit4] 2> 536 T304 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:52650
[junit4:junit4] 2> 549 T306 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4] 2> 586 T306 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c4fcf63140000 with negotiated timeout 10000 for client /127.0.0.1:52650
[junit4:junit4] 2> 586 T308 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:52647, sessionid = 0x13c4fcf63140000, negotiated timeout = 10000
[junit4:junit4] 2> 595 T309 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@180cf393 name:ZooKeeperConnection Watcher:127.0.0.1:52647 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 596 T302 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 600 T302 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4] 2> 684 T307 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c4fcf63140000
[junit4:junit4] 2> 687 T302 oaz.ZooKeeper.close Session: 0x13c4fcf63140000 closed
[junit4:junit4] 2> 688 T302 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52647/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@514e7b87
[junit4:junit4] 2> 688 T304 oazs.NIOServerCnxn.doIO WARNING caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x13c4fcf63140000, 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:662)
[junit4:junit4] 2>
[junit4:junit4] 2> 689 T309 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 692 T302 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 693 T304 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:52650 which had sessionid 0x13c4fcf63140000
[junit4:junit4] 2> 694 T310 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:52647. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4] 2> 696 T310 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:52647, initiating session
[junit4:junit4] 2> 696 T304 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:52653
[junit4:junit4] 2> 696 T304 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:52653
[junit4:junit4] 2> 698 T306 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c4fcf63140001 with negotiated timeout 10000 for client /127.0.0.1:52653
[junit4:junit4] 2> 698 T310 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:52647, sessionid = 0x13c4fcf63140001, negotiated timeout = 10000
[junit4:junit4] 2> 699 T311 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@514e7b87 name:ZooKeeperConnection Watcher:127.0.0.1:52647/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 699 T302 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 706 T302 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4] 2> 716 T302 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4] 2> 723 T302 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4] 2> 730 T302 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4] 2> 738 T302 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> 739 T302 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4] 2> 754 T302 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> 755 T302 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4] 2> 762 T302 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> 762 T302 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4] 2> 770 T302 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> 771 T302 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4] 2> 777 T302 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> 778 T302 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4] 2> 784 T302 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> 786 T302 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4] 2> 800 T302 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> 801 T302 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4] 2> 811 T302 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> 812 T302 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4] 2> 821 T302 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> 821 T302 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4] 2> 834 T307 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c4fcf63140001
[junit4:junit4] 2> 841 T311 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 841 T302 oaz.ZooKeeper.close Session: 0x13c4fcf63140001 closed
[junit4:junit4] 2> 843 T304 oazs.NIOServerCnxn.doIO WARNING caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x13c4fcf63140001, 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:662)
[junit4:junit4] 2>
[junit4:junit4] 2> 843 T304 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:52653 which had sessionid 0x13c4fcf63140001
[junit4:junit4] 2> 1175 T302 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 1181 T302 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:52654
[junit4:junit4] 2> 1182 T302 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 1182 T302 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 1182 T302 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-1358548657528
[junit4:junit4] 2> 1184 T302 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-1358548657528\solr.xml
[junit4:junit4] 2> 1184 T302 oasc.CoreContainer.<init> New CoreContainer 1106760625
[junit4:junit4] 2> 1185 T302 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-1358548657528\'
[junit4:junit4] 2> 1186 T302 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-1358548657528\'
[junit4:junit4] 2> 1233 T302 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 60000
[junit4:junit4] 2> 1233 T302 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 1234 T302 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 1234 T302 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 1234 T302 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 1235 T302 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 1235 T302 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 1235 T302 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 1236 T302 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 1236 T302 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=60000&connTimeout=15000&retry=false
[junit4:junit4] 2> 1255 T302 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 1279 T302 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:52647/solr
[junit4:junit4] 2> 1302 T302 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 1302 T302 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52647 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@2a72cf60
[junit4:junit4] 2> 1305 T302 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 1306 T321 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:52647. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4] 2> 1307 T321 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:52647, initiating session
[junit4:junit4] 2> 1307 T304 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:52659
[junit4:junit4] 2> 1307 T304 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:52659
[junit4:junit4] 2> 1324 T306 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c4fcf63140002 with negotiated timeout 20000 for client /127.0.0.1:52659
[junit4:junit4] 2> 1324 T321 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:52647, sessionid = 0x13c4fcf63140002, negotiated timeout = 20000
[junit4:junit4] 2> 1324 T322 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2a72cf60 name:ZooKeeperConnection Watcher:127.0.0.1:52647 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 1325 T302 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 1327 T307 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c4fcf63140002
[junit4:junit4] 2> 1329 T302 oaz.ZooKeeper.close Session: 0x13c4fcf63140002 closed
[junit4:junit4] 2> 1329 T322 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 1330 T304 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:52659 which had sessionid 0x13c4fcf63140002
[junit4:junit4] 2> 1334 T302 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=60000&connTimeout=15000
[junit4:junit4] 2> 1345 T302 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52647/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@2de7753a
[junit4:junit4] 2> 1347 T302 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 1348 T323 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:52647. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4] 2> 1349 T323 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:52647, initiating session
[junit4:junit4] 2> 1349 T304 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:52662
[junit4:junit4] 2> 1349 T304 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:52662
[junit4:junit4] 2> 1351 T306 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c4fcf63140003 with negotiated timeout 20000 for client /127.0.0.1:52662
[junit4:junit4] 2> 1351 T323 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:52647, sessionid = 0x13c4fcf63140003, negotiated timeout = 20000
[junit4:junit4] 2> 1352 T324 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2de7753a name:ZooKeeperConnection Watcher:127.0.0.1:52647/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 1352 T302 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 1371 T307 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c4fcf63140003 type:create cxid:0x2 zxid:0x1a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 1381 T302 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4] 2> 1386 T302 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52654__o
[junit4:junit4] 2> 1392 T307 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c4fcf63140003 type:delete cxid:0x7 zxid:0x1c txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:52654__o Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:52654__o
[junit4:junit4] 2> 1394 T302 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:52654__o
[junit4:junit4] 2> 1401 T302 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4] 2> 1427 T307 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c4fcf63140003 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> 1431 T302 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4] 2> 1438 T302 oasc.Overseer.start Overseer (id=89033844779646979-127.0.0.1:52654__o-n_0000000000) starting
[junit4:junit4] 2> 1439 T307 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c4fcf63140003 type:create cxid:0x1a zxid:0x23 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 1462 T307 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c4fcf63140003 type:create cxid:0x1b zxid:0x24 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 1466 T307 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c4fcf63140003 type:create cxid:0x1c zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 1472 T307 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c4fcf63140003 type:create cxid:0x1d zxid:0x26 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 1488 T326 oasc.OverseerCollectionProcessor.run Process current queue of collection messages
[junit4:junit4] 2> 1489 T302 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4] 2> 1497 T302 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 1513 T325 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4] 2> 1526 T327 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-1358548657528\collection1
[junit4:junit4] 2> 1526 T327 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4] 2> 1528 T327 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 1528 T327 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4] 2> 1531 T327 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-1358548657528\collection1\'
[junit4:junit4] 2> 1533 T327 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-1358548657528/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 1534 T327 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-1358548657528/collection1/lib/README' to classloader
[junit4:junit4] 2> 1610 T327 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 1698 T327 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 1700 T327 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 1709 T327 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 2443 T327 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 2455 T327 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 2462 T327 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 2492 T327 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 2498 T327 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 2506 T327 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 2509 T327 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 2511 T327 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-1358548657528\collection1\, dataDir=.\org.apache.solr.cloud.RecoveryZkTest-1358548656690/control/data\
[junit4:junit4] 2> 2512 T327 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@c73f0d8
[junit4:junit4] 2> 2512 T327 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 2513 T327 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-1358548656690\control\data forceNew:false
[junit4:junit4] 2> 2513 T327 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358548656690\control\data
[junit4:junit4] 2> 2513 T327 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.RecoveryZkTest-1358548656690/control/data\index/
[junit4:junit4] 2> 2514 T327 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.RecoveryZkTest-1358548656690\control\data\index' doesn't exist. Creating new index...
[junit4:junit4] 2> 2515 T327 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-1358548656690\control\data\index forceNew:false
[junit4:junit4] 2> 2520 T327 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@262e9e80 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1517225f; maxCacheMB=0.9404296875 maxMergeSizeMB=0.501953125))),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 2520 T327 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 2522 T327 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358548656690\control\data\index
[junit4:junit4] 2> 2522 T327 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4] 2> 2525 T327 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 2525 T327 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4] 2> 2525 T327 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 2526 T327 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4] 2> 2526 T327 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 2526 T327 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 2526 T327 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 2528 T327 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 2528 T327 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 2529 T327 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4] 2> 2529 T327 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4] 2> 2530 T327 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 2530 T327 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 2530 T327 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 2531 T327 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 2531 T327 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 2531 T327 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 2532 T327 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 2532 T327 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 2533 T327 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 2534 T327 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 2534 T327 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 2535 T327 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 2535 T327 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 2536 T327 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4] 2> 2536 T327 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4] 2> 2544 T327 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 2550 T327 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4] 2> 2552 T327 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> 2555 T327 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358548656690\control\data
[junit4:junit4] 2> 2555 T327 oass.SolrIndexSearcher.<init> Opening Searcher@d809bd3 main
[junit4:junit4] 2> 2557 T327 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 2557 T327 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 2557 T327 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4] 2> 2573 T327 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4] 2> 2641 T328 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d809bd3 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 2647 T327 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 2647 T327 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 2649 T307 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c4fcf63140003 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> 3137 T325 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 3139 T325 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:52654__o",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:52654/_o"}
[junit4:junit4] 2> 3139 T325 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with numShards 1
[junit4:junit4] 2> 3167 T325 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 3168 T307 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c4fcf63140003 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> 3207 T324 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> 3735 T327 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 3735 T327 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:52654/_o collection:control_collection shard:shard1
[junit4:junit4] 2> 3748 T327 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4] 2> 3759 T327 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4:junit4] 2> 3790 T307 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c4fcf63140003 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> 3792 T327 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4] 2> 3793 T307 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c4fcf63140003 type:create cxid:0x73 zxid:0x37 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 3799 T327 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4] 2> 3799 T327 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4] 2> 3805 T327 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:52654/_o/collection1/
[junit4:junit4] 2> 3805 T327 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4] 2> 3805 T327 oasc.SyncStrategy.syncToMe http://127.0.0.1:52654/_o/collection1/ has no replicas
[junit4:junit4] 2> 3805 T327 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:52654/_o/collection1/
[junit4:junit4] 2> 3805 T327 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4:junit4] 2> 3823 T307 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c4fcf63140003 type:create cxid:0x7d zxid:0x3b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 4839 T325 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 4873 T324 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> 4924 T327 oasc.ZkController.register We are http://127.0.0.1:52654/_o/collection1/ and leader is http://127.0.0.1:52654/_o/collection1/
[junit4:junit4] 2> 4924 T327 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:52654/_o
[junit4:junit4] 2> 4924 T327 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4] 2> 4924 T327 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4] 2> 4924 T327 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 4929 T327 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 4930 T302 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4] 2> 4931 T302 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 4931 T302 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 4938 T302 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4] 2> 4946 T302 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52647/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1c458c1e
[junit4:junit4] 2> 4948 T302 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 4949 T329 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:52647. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4] 2> 4949 T329 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:52647, initiating session
[junit4:junit4] 2> 4950 T304 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:52665
[junit4:junit4] 2> 4950 T304 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:52665
[junit4:junit4] 2> 4953 T306 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c4fcf63140004 with negotiated timeout 10000 for client /127.0.0.1:52665
[junit4:junit4] 2> 4953 T329 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:52647, sessionid = 0x13c4fcf63140004, negotiated timeout = 10000
[junit4:junit4] 2> 4953 T330 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c458c1e name:ZooKeeperConnection Watcher:127.0.0.1:52647/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 4954 T302 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 4956 T302 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 4960 T302 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4] 2> 5306 T302 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 5310 T302 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:52666
[junit4:junit4] 2> 5312 T302 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 5313 T302 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 5313 T302 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-1358548661647
[junit4:junit4] 2> 5313 T302 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-1358548661647\solr.xml
[junit4:junit4] 2> 5314 T302 oasc.CoreContainer.<init> New CoreContainer 228520805
[junit4:junit4] 2> 5314 T302 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-1358548661647\'
[junit4:junit4] 2> 5315 T302 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-1358548661647\'
[junit4:junit4] 2> 5363 T302 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 60000
[junit4:junit4] 2> 5363 T302 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 5363 T302 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 5363 T302 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 5363 T302 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 5364 T302 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 5364 T302 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 5365 T302 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 5365 T302 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 5366 T302 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=60000&connTimeout=15000&retry=false
[junit4:junit4] 2> 5382 T302 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 5403 T302 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:52647/solr
[junit4:junit4] 2> 5404 T302 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 5405 T302 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52647 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@3ec1eecd
[junit4:junit4] 2> 5407 T302 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 5408 T340 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:52647. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4] 2> 5409 T340 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:52647, initiating session
[junit4:junit4] 2> 5409 T304 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:52671
[junit4:junit4] 2> 5409 T304 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:52671
[junit4:junit4] 2> 5419 T306 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c4fcf63140005 with negotiated timeout 20000 for client /127.0.0.1:52671
[junit4:junit4] 2> 5420 T340 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:52647, sessionid = 0x13c4fcf63140005, negotiated timeout = 20000
[junit4:junit4] 2> 5420 T341 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3ec1eecd name:ZooKeeperConnection Watcher:127.0.0.1:52647 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 5420 T302 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 5422 T307 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c4fcf63140005
[junit4:junit4] 2> 5424 T341 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 5424 T304 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:52671 which had sessionid 0x13c4fcf63140005
[junit4:junit4] 2> 5424 T302 oaz.ZooKeeper.close Session: 0x13c4fcf63140005 closed
[junit4:junit4] 2> 5425 T302 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=60000&connTimeout=15000
[junit4:junit4] 2> 5429 T302 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52647/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@70413ea6
[junit4:junit4] 2> 5433 T302 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 5434 T342 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:52647. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4] 2> 5435 T342 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:52647, initiating session
[junit4:junit4] 2> 5435 T304 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:52674
[junit4:junit4] 2> 5435 T304 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:52674
[junit4:junit4] 2> 5438 T306 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c4fcf63140006 with negotiated timeout 20000 for client /127.0.0.1:52674
[junit4:junit4] 2> 5438 T342 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:52647, sessionid = 0x13c4fcf63140006, negotiated timeout = 20000
[junit4:junit4] 2> 5438 T343 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@70413ea6 name:ZooKeeperConnection Watcher:127.0.0.1:52647/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 5438 T302 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 5442 T307 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c4fcf63140006 type:create cxid:0x1 zxid:0x49 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 5444 T307 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c4fcf63140006 type:create cxid:0x2 zxid:0x4a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 5447 T302 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52666__o
[junit4:junit4] 2> 5448 T307 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c4fcf63140006 type:delete cxid:0x4 zxid:0x4b txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:52666__o Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:52666__o
[junit4:junit4] 2> 5449 T302 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:52666__o
[junit4:junit4] 2> 5455 T324 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> 5456 T330 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4] 2> 5460 T324 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4] 2> 5465 T302 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 5475 T344 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-1358548661647\collection1
[junit4:junit4] 2> 5475 T344 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4] 2> 5476 T344 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 5476 T344 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4] 2> 5481 T344 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-1358548661647\collection1\'
[junit4:junit4] 2> 5482 T344 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-1358548661647/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 5483 T344 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-1358548661647/collection1/lib/README' to classloader
[junit4:junit4] 2> 5540 T344 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 5617 T344 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 5619 T344 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 5626 T344 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 6336 T344 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 6349 T344 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 6354 T344 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 6384 T344 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 6391 T344 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 6398 T344 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 6401 T344 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 6403 T344 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-1358548661647\collection1\, dataDir=.\org.apache.solr.cloud.RecoveryZkTest-1358548656690/jetty1\
[junit4:junit4] 2> 6405 T344 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@c73f0d8
[junit4:junit4] 2> 6405 T344 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 6406 T344 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-1358548656690\jetty1 forceNew:false
[junit4:junit4] 2> 6406 T344 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358548656690\jetty1
[junit4:junit4] 2> 6406 T344 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.RecoveryZkTest-1358548656690/jetty1\index/
[junit4:junit4] 2> 6407 T344 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.RecoveryZkTest-1358548656690\jetty1\index' doesn't exist. Creating new index...
[junit4:junit4] 2> 6409 T344 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-1358548656690\jetty1\index forceNew:false
[junit4:junit4] 2> 6413 T344 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@266bd4a9 lockFactory=org.apache.lucene.store.NativeFSLockFactory@12c50438; maxCacheMB=0.9404296875 maxMergeSizeMB=0.501953125))),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 6413 T344 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 6415 T344 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358548656690\jetty1\index
[junit4:junit4] 2> 6415 T344 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4] 2> 6417 T344 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 6418 T344 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4] 2> 6418 T344 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 6419 T344 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4] 2> 6419 T344 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 6419 T344 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 6420 T344 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 6420 T344 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 6421 T344 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 6421 T344 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4] 2> 6422 T344 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4] 2> 6422 T344 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 6423 T344 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 6423 T344 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 6423 T344 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 6424 T344 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 6424 T344 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 6425 T344 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 6425 T344 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 6426 T344 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 6426 T344 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 6427 T344 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 6429 T344 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 6429 T344 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 6430 T344 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4] 2> 6430 T344 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4] 2> 6438 T344 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 6443 T344 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4] 2> 6445 T344 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> 6449 T344 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358548656690\jetty1
[junit4:junit4] 2> 6449 T344 oass.SolrIndexSearcher.<init> Opening Searcher@10d58f09 main
[junit4:junit4] 2> 6451 T344 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 6451 T344 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 6451 T344 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4] 2> 6463 T344 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4] 2> 6503 T325 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 6504 T325 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:52654__o",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:52654/_o"}
[junit4:junit4] 2> 6515 T343 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> 6516 T324 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> 6516 T330 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> 6539 T345 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@10d58f09 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 6551 T344 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 6551 T344 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 8146 T325 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 8147 T325 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:52666__o",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:52666/_o"}
[junit4:junit4] 2> 8147 T325 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 1
[junit4:junit4] 2> 8148 T325 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 8158 T330 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> 8159 T324 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> 8159 T343 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> 8710 T344 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 8710 T344 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:52666/_o collection:collection1 shard:shard1
[junit4:junit4] 2> 8712 T344 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4] 2> 8815 T307 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c4fcf63140006 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> 8833 T344 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4] 2> 8835 T307 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c4fcf63140006 type:create cxid:0x46 zxid:0x5c txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 8869 T344 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4] 2> 8869 T344 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4] 2> 8870 T344 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:52666/_o/collection1/
[junit4:junit4] 2> 8870 T344 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4] 2> 8870 T344 oasc.SyncStrategy.syncToMe http://127.0.0.1:52666/_o/collection1/ has no replicas
[junit4:junit4] 2> 8871 T344 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:52666/_o/collection1/
[junit4:junit4] 2> 8871 T344 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4] 2> 8883 T307 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c4fcf63140006 type:create cxid:0x50 zxid:0x60 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 9788 T325 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 9916 T343 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> 9916 T330 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> 9920 T324 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> 9930 T344 oasc.ZkController.register We are http://127.0.0.1:52666/_o/collection1/ and leader is http://127.0.0.1:52666/_o/collection1/
[junit4:junit4] 2> 9930 T344 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:52666/_o
[junit4:junit4] 2> 9930 T344 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4] 2> 9931 T344 oasc.ZkController.publish publishing core=collection1 state=active
[junit4:junit4] 2> 9931 T344 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 9938 T344 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 9941 T302 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4] 2> 9941 T302 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 9942 T302 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 10310 T302 oejs.Server.doStart jetty-8.1.8.v20121106
[junit4:junit4] 2> 10314 T302 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:52675
[junit4:junit4] 2> 10315 T302 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4] 2> 10315 T302 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4] 2> 10317 T302 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-1358548666636
[junit4:junit4] 2> 10317 T302 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-1358548666636\solr.xml
[junit4:junit4] 2> 10318 T302 oasc.CoreContainer.<init> New CoreContainer 195112228
[junit4:junit4] 2> 10318 T302 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-1358548666636\'
[junit4:junit4] 2> 10319 T302 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-1358548666636\'
[junit4:junit4] 2> 10359 T302 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 60000
[junit4:junit4] 2> 10359 T302 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4] 2> 10360 T302 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4:junit4] 2> 10360 T302 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4] 2> 10360 T302 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4] 2> 10361 T302 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4] 2> 10361 T302 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4] 2> 10362 T302 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4] 2> 10362 T302 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4] 2> 10362 T302 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=60000&connTimeout=15000&retry=false
[junit4:junit4] 2> 10377 T302 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4] 2> 10399 T302 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:52647/solr
[junit4:junit4] 2> 10400 T302 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4:junit4] 2> 10400 T302 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52647 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@7495435a
[junit4:junit4] 2> 10402 T302 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 10403 T355 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:52647. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4] 2> 10404 T355 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:52647, initiating session
[junit4:junit4] 2> 10404 T304 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:52680
[junit4:junit4] 2> 10404 T304 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:52680
[junit4:junit4] 2> 10417 T306 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c4fcf63140007 with negotiated timeout 20000 for client /127.0.0.1:52680
[junit4:junit4] 2> 10417 T355 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:52647, sessionid = 0x13c4fcf63140007, negotiated timeout = 20000
[junit4:junit4] 2> 10418 T356 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7495435a name:ZooKeeperConnection Watcher:127.0.0.1:52647 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 10418 T302 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 10420 T307 oazs.PrepRequestProcessor.pRequest2Txn Processed session termination for sessionid: 0x13c4fcf63140007
[junit4:junit4] 2> 10422 T302 oaz.ZooKeeper.close Session: 0x13c4fcf63140007 closed
[junit4:junit4] 2> 10423 T304 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:52680 which had sessionid 0x13c4fcf63140007
[junit4:junit4] 2> 10423 T302 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=60000&connTimeout=15000
[junit4:junit4] 2> 10423 T356 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 10429 T302 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:52647/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@417e9329
[junit4:junit4] 2> 10432 T302 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4] 2> 10434 T357 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:52647. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4] 2> 10434 T357 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to 127.0.0.1/127.0.0.1:52647, initiating session
[junit4:junit4] 2> 10436 T304 oazs.NIOServerCnxnFactory.run Accepted socket connection from /127.0.0.1:52683
[junit4:junit4] 2> 10437 T304 oazs.ZooKeeperServer.processConnectRequest Client attempting to establish new session at /127.0.0.1:52683
[junit4:junit4] 2> 10463 T306 oazs.ZooKeeperServer.finishSessionInit Established session 0x13c4fcf63140008 with negotiated timeout 20000 for client /127.0.0.1:52683
[junit4:junit4] 2> 10463 T357 oaz.ClientCnxn$SendThread.onConnected Session establishment complete on server 127.0.0.1/127.0.0.1:52647, sessionid = 0x13c4fcf63140008, negotiated timeout = 20000
[junit4:junit4] 2> 10463 T358 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@417e9329 name:ZooKeeperConnection Watcher:127.0.0.1:52647/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4] 2> 10464 T302 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4] 2> 10466 T307 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c4fcf63140008 type:create cxid:0x1 zxid:0x6d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 10469 T307 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c4fcf63140008 type:create cxid:0x2 zxid:0x6e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4] 2> 10484 T302 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:52675__o
[junit4:junit4] 2> 10485 T307 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13c4fcf63140008 type:delete cxid:0x4 zxid:0x6f txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:52675__o Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:52675__o
[junit4:junit4] 2> 10486 T302 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:52675__o
[junit4:junit4] 2> 10513 T330 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> 10514 T324 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> 10518 T343 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 10519 T343 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4] 2> 10527 T324 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 10528 T330 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4] 2> 10558 T302 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4:junit4] 2> 10568 T359 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-1358548666636\collection1
[junit4:junit4] 2> 10568 T359 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4] 2> 10569 T359 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4] 2> 10570 T359 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4] 2> 10573 T359 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-1358548666636\collection1\'
[junit4:junit4] 2> 10574 T359 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-1358548666636/collection1/lib/classes/' to classloader
[junit4:junit4] 2> 10574 T359 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-1358548666636/collection1/lib/README' to classloader
[junit4:junit4] 2> 10632 T359 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4] 2> 10712 T359 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4] 2> 10715 T359 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4] 2> 10725 T359 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4] 2> 11534 T325 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 11535 T325 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:52666__o",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:52666/_o"}
[junit4:junit4] 2> 11548 T359 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4] 2> 11558 T359 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4] 2> 11563 T343 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> 11564 T330 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> 11564 T359 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4] 2> 11565 T358 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> 11566 T324 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> 11594 T359 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 11599 T359 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4] 2> 11604 T359 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 11607 T359 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4] 2> 11609 T359 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-1358548666636\collection1\, dataDir=.\org.apache.solr.cloud.RecoveryZkTest-1358548656690/jetty2\
[junit4:junit4] 2> 11609 T359 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@c73f0d8
[junit4:junit4] 2> 11609 T359 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4] 2> 11610 T359 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-1358548656690\jetty2 forceNew:false
[junit4:junit4] 2> 11610 T359 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358548656690\jetty2
[junit4:junit4] 2> 11610 T359 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.RecoveryZkTest-1358548656690/jetty2\index/
[junit4:junit4] 2> 11611 T359 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory '.\org.apache.solr.cloud.RecoveryZkTest-1358548656690\jetty2\index' doesn't exist. Creating new index...
[junit4:junit4] 2> 11611 T359 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-1358548656690\jetty2\index forceNew:false
[junit4:junit4] 2> 11615 T359 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@4704532e lockFactory=org.apache.lucene.store.NativeFSLockFactory@36b4361a; maxCacheMB=0.9404296875 maxMergeSizeMB=0.501953125))),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 11616 T359 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 11617 T359 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358548656690\jetty2\index
[junit4:junit4] 2> 11618 T359 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4] 2> 11620 T359 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4] 2> 11620 T359 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4] 2> 11620 T359 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4] 2> 11621 T359 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4] 2> 11621 T359 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4] 2> 11621 T359 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4] 2> 11621 T359 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4] 2> 11623 T359 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4] 2> 11623 T359 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4] 2> 11624 T359 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4] 2> 11624 T359 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4] 2> 11625 T359 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4] 2> 11625 T359 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4] 2> 11625 T359 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4] 2> 11626 T359 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4] 2> 11626 T359 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4] 2> 11627 T359 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 11627 T359 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 11629 T359 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 11629 T359 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 11630 T359 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 11630 T359 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 11631 T359 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 11631 T359 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4] 2> 11632 T359 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4] 2> 11632 T359 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4] 2> 11639 T359 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4] 2> 11644 T359 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4] 2> 11646 T359 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> 11649 T359 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358548656690\jetty2
[junit4:junit4] 2> 11649 T359 oass.SolrIndexSearcher.<init> Opening Searcher@47f70bc1 main
[junit4:junit4] 2> 11651 T359 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4] 2> 11651 T359 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4] 2> 11652 T359 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4] 2> 11665 T359 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4] 2> 11730 T360 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@47f70bc1 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4] 2> 11734 T359 oasc.ZkController.publish publishing core=collection1 state=down
[junit4:junit4] 2> 11735 T359 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 13078 T325 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 13079 T325 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:52675__o",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:52675/_o"}
[junit4:junit4] 2> 13079 T325 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=1
[junit4:junit4] 2> 13079 T325 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4:junit4] 2> 13096 T343 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> 13096 T358 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> 13097 T324 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> 13096 T330 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> 13739 T359 oasc.CoreContainer.registerCore registering core: collection1
[junit4:junit4] 2> 13739 T359 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:52675/_o collection:collection1 shard:shard1
[junit4:junit4] 2> 13747 T359 oasc.ZkController.register We are http://127.0.0.1:52675/_o/collection1/ and leader is http://127.0.0.1:52666/_o/collection1/
[junit4:junit4] 2> 13747 T359 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:52675/_o
[junit4:junit4] 2> 13748 T359 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4] 2> 13748 T359 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4] 2> ASYNC NEW_CORE C32 name=collection1 org.apache.solr.core.SolrCore@1b490621 url=http://127.0.0.1:52675/_o/collection1 node=127.0.0.1:52675__o C32_STATE=coll:collection1 core:collection1 props:{shard=null, roles=null, state=down, core=collection1, collection=collection1, node_name=127.0.0.1:52675__o, base_url=http://127.0.0.1:52675/_o}
[junit4:junit4] 2> 13756 T361 C32 P52675 oasc.RecoveryStrategy.run Starting recovery process. core=collection1 recoveringAfterStartup=true
[junit4:junit4] 2> 13759 T359 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 13760 T361 C32 P52675 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4] 2> 13761 T361 C32 P52675 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4:junit4] 2> 13761 T361 C32 P52675 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4] 2> 13767 T302 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4:junit4] 2> 13768 T302 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4] 2> 13769 T302 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 13769 T361 C32 P52675 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4] 2> 13787 T302 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> ASYNC NEW_CORE C33 name=collection1 org.apache.solr.core.SolrCore@4c782678 url=http://127.0.0.1:52654/_o/collection1 node=127.0.0.1:52654__o C33_STATE=coll:control_collection core:collection1 props:{shard=shard1, roles=null, state=active, core=collection1, collection=control_collection, node_name=127.0.0.1:52654__o, base_url=http://127.0.0.1:52654/_o, leader=true}
[junit4:junit4] 2> 13835 T317 C33 P52654 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358548656690\control\data
[junit4:junit4] 2> 13841 T317 C33 P52654 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@262e9e80 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1517225f; maxCacheMB=0.9404296875 maxMergeSizeMB=0.501953125))),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 13842 T317 C33 P52654 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 13848 T318 C33 P52654 /update {wt=javabin&version=2} {add=[10001 (1424541530740228097)]} 0 38
[junit4:junit4] 2> 13848 T317 C33 P52654 /update {wt=javabin&version=2} {add=[1 (1424541530740228096)]} 0 39
[junit4:junit4] 2> ASYNC NEW_CORE C34 name=collection1 org.apache.solr.core.SolrCore@55936c40 url=http://127.0.0.1:52666/_o/collection1 node=127.0.0.1:52666__o C34_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:52666__o, base_url=http://127.0.0.1:52666/_o, leader=true}
[junit4:junit4] 2> 13874 T335 C34 P52666 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358548656690\jetty1
[junit4:junit4] 2> 13880 T335 C34 P52666 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@266bd4a9 lockFactory=org.apache.lucene.store.NativeFSLockFactory@12c50438; maxCacheMB=0.9404296875 maxMergeSizeMB=0.501953125))),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 13881 T335 C34 P52666 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 13888 T336 C34 P52666 /update {wt=javabin&version=2} {add=[10001 (1424541530781122560)]} 0 15
[junit4:junit4] 2> 13888 T335 C34 P52666 /update {wt=javabin&version=2} {add=[1 (1424541530780073984)]} 0 16
[junit4:junit4] 2> 13896 T315 C33 P52654 /update {wt=javabin&version=2} {add=[10002 (1424541530799996928)]} 0 4
[junit4:junit4] 2> 13896 T320 C33 P52654 /update {wt=javabin&version=2} {add=[2 (1424541530799996929)]} 0 4
[junit4:junit4] 2> 13904 T337 C34 P52666 /update {wt=javabin&version=2} {add=[10002 (1424541530808385536)]} 0 5
[junit4:junit4] 2> 13905 T338 C34 P52666 /update {wt=javabin&version=2} {add=[2 (1424541530808385537)]} 0 5
[junit4:junit4] 2> 13909 T316 C33 P52654 /update {wt=javabin&version=2} {delete=[10002 (-1424541530816774144)]} 0 2
[junit4:junit4] 2> 13910 T319 C33 P52654 /update {wt=javabin&version=2} {delete=[2 (-1424541530817822720)]} 0 1
[junit4:junit4] 2> 13925 T339 C34 P52666 /update {wt=javabin&version=2} {delete=[10002 (-1424541530834599936)]} 0 1
[junit4:junit4] 2> 13927 T336 C34 P52666 /update {wt=javabin&version=2} {delete=[2 (-1424541530836697088)]} 0 2
[junit4:junit4] 2> 13932 T317 C33 P52654 /update {wt=javabin&version=2} {add=[10003 (1424541530839842817)]} 0 2
[junit4:junit4] 2> 13933 T318 C33 P52654 /update {wt=javabin&version=2} {add=[3 (1424541530839842816)]} 0 3
[junit4:junit4] 2> 13937 T335 C34 P52666 /update {wt=javabin&version=2} {add=[10003 (1424541530846134272)]} 0 2
[junit4:junit4] 2> 13939 T337 C34 P52666 /update {wt=javabin&version=2} {add=[3 (1424541530847182848)]} 0 2
[junit4:junit4] 2> 13944 T315 C33 P52654 /update {wt=javabin&version=2} {add=[10004 (1424541530852425728)]} 0 3
[junit4:junit4] 2> 13945 T320 C33 P52654 /update {wt=javabin&version=2} {add=[4 (1424541530852425729)]} 0 3
[junit4:junit4] 2> 13949 T338 C34 P52666 /update {wt=javabin&version=2} {add=[10004 (1424541530857668608)]} 0 2
[junit4:junit4] 2> 13950 T339 C34 P52666 /update {wt=javabin&version=2} {add=[4 (1424541530859765760)]} 0 1
[junit4:junit4] 2> 13953 T316 C33 P52654 /update {wt=javabin&version=2} {delete=[10004 (-1424541530863960064)]} 0 1
[junit4:junit4] 2> 13955 T319 C33 P52654 /update {wt=javabin&version=2} {delete=[4 (-1424541530866057216)]} 0 1
[junit4:junit4] 2> 13957 T336 C34 P52666 /update {wt=javabin&version=2} {delete=[10004 (-1424541530868154368)]} 0 1
[junit4:junit4] 2> 13959 T335 C34 P52666 /update {wt=javabin&version=2} {delete=[4 (-1424541530870251520)]} 0 1
[junit4:junit4] 2> 13962 T317 C33 P52654 /update {wt=javabin&version=2} {add=[10005 (1424541530871300096)]} 0 2
[junit4:junit4] 2> 13967 T337 C34 P52666 /update {wt=javabin&version=2} {add=[10005 (1424541530877591552)]} 0 2
[junit4:junit4] 2> 13970 T318 C33 P52654 /update {wt=javabin&version=2} {add=[5 (1424541530873397248)]} 0 8
[junit4:junit4] 2> 13971 T315 C33 P52654 /update {wt=javabin&version=2} {delete=[10005 (-1424541530882834432)]} 0 0
[junit4:junit4] 2> 13977 T339 C34 P52666 /update {wt=javabin&version=2} {delete=[10005 (-1424541530889125888)]} 0 2
[junit4:junit4] 2> 13982 T338 C34 P52666 /update {wt=javabin&version=2} {add=[5 (1424541530884931584)]} 0 9
[junit4:junit4] 2> 13984 T320 C33 P52654 /update {wt=javabin&version=2} {add=[10006 (1424541530892271616)]} 0 4
[junit4:junit4] 2> 13987 T316 C33 P52654 /update {wt=javabin&version=2} {delete=[5 (-1424541530898563072)]} 0 1
[junit4:junit4] 2> 13990 T335 C34 P52666 /update {wt=javabin&version=2} {delete=[5 (-1424541530902757376)]} 0 0
[junit4:junit4] 2> 13991 T336 C34 P52666 /update {wt=javabin&version=2} {add=[10006 (1424541530899611648)]} 0 4
[junit4:junit4] 2> 13996 T319 C33 P52654 /update {wt=javabin&version=2} {add=[6 (1424541530906951680)]} 0 3
[junit4:junit4] 2> 13999 T317 C33 P52654 /update {wt=javabin&version=2} {add=[10007 (1424541530910097408)]} 0 3
[junit4:junit4] 2> 14001 T337 C34 P52666 /update {wt=javabin&version=2} {add=[6 (1424541530913243136)]} 0 2
[junit4:junit4] 2> 14004 T339 C34 P52666 /update {wt=javabin&version=2} {add=[10007 (1424541530915340288)]} 0 2
[junit4:junit4] 2> 14006 T318 C33 P52654 /update {wt=javabin&version=2} {add=[7 (1424541530918486016)]} 0 1
[junit4:junit4] 2> 14013 T338 C34 P52666 /update {wt=javabin&version=2} {add=[7 (1424541530924777472)]} 0 2
[junit4:junit4] 2> 14014 T315 C33 P52654 /update {wt=javabin&version=2} {delete=[10006 (-1424541530927923200)]} 0 5
[junit4:junit4] 2> 14019 T320 C33 P52654 /update {wt=javabin&version=2} {delete=[6 (-1424541530933166080)]} 0 3
[junit4:junit4] 2> 14020 T335 C34 P52666 /update {wt=javabin&version=2} {delete=[10006 (-1424541530934214656)]} 0 2
[junit4:junit4] 2> 14023 T336 C34 P52666 /update {wt=javabin&version=2} {delete=[6 (-1424541530937360384)]} 0 1
[junit4:junit4] 2> 14028 T316 C33 P52654 /update {wt=javabin&version=2} {add=[8 (1424541530941554688)]} 0 2
[junit4:junit4] 2> 14034 T319 C33 P52654 /update {wt=javabin&version=2} {add=[10008 (1424541530945748992)]} 0 3
[junit4:junit4] 2> 14059 T337 C34 P52666 /update {wt=javabin&version=2} {add=[8 (1424541530950991872)]} 0 23
[junit4:junit4] 2> 14064 T337 C34 P52666 /update {wt=javabin&version=2} {add=[10008 (1424541530977206272)]} 0 3
[junit4:junit4] 2> 14079 T317 C33 P52654 /update {wt=javabin&version=2} {add=[9 (1424541530982449152)]} 0 14
[junit4:junit4] 2> 14087 T318 C33 P52654 /update {wt=javabin&version=2} {add=[10009 (1424541530999226368)]} 0 5
[junit4:junit4] 2> 14090 T338 C34 P52666 /update {wt=javabin&version=2} {add=[9 (1424541531005517824)]} 0 2
[junit4:junit4] 2> 14098 T335 C34 P52666 /update {wt=javabin&version=2} {add=[10009 (1424541531013906432)]} 0 1
[junit4:junit4] 2> 14104 T315 C33 P52654 /update {wt=javabin&version=2} {add=[10 (1424541531020197888)]} 0 3
[junit4:junit4] 2> 14112 T336 C34 P52666 /update {wt=javabin&version=2} {add=[10 (1424541531029635072)]} 0 2
[junit4:junit4] 2> 14128 T320 C33 P52654 /update {wt=javabin&version=2} {add=[10010 (1424541531025440768)]} 0 21
[junit4:junit4] 2> 14130 T316 C33 P52654 /update {wt=javabin&version=2} {delete=[10 (-1424541531049558016)]} 0 0
[junit4:junit4] 2> 14143 T337 C34 P52666 /update {wt=javabin&version=2} {add=[10010 (1424541531053752320)]} 0 9
[junit4:junit4] 2> 14145 T337 C34 P52666 /update {wt=javabin&version=2} {delete=[10 (-1424541531065286656)]} 0 0
[junit4:junit4] 2> 14152 T319 C33 P52654 /update {wt=javabin&version=2} {add=[11 (1424541531069480960)]} 0 4
[junit4:junit4] 2> 14156 T317 C33 P52654 /update {wt=javabin&version=2} {delete=[10010 (-1424541531075772416)]} 0 2
[junit4:junit4] 2> 14173 T338 C34 P52666 /update {wt=javabin&version=2} {add=[11 (1424541531077869568)]} 0 16
[junit4:junit4] 2> 14180 T318 C33 P52654 /update {wt=javabin&version=2} {add=[12 (1424541531098841088)]} 0 4
[junit4:junit4] 2> 14183 T336 C34 P52666 /update {wt=javabin&version=2} {delete=[10010 (-1424541531105132544)]} 0 1
[junit4:junit4] 2> 14186 T336 C34 P52666 /update {wt=javabin&version=2} {add=[12 (1424541531106181120)]} 0 2
[junit4:junit4] 2> 14198 T315 C33 P52654 /update {wt=javabin&version=2} {add=[13 (1424541531115618304)]} 0 6
[junit4:junit4] 2> 14206 T315 C33 P52654 /update {wt=javabin&version=2} {add=[10011 (1424541531122958336)]} 0 6
[junit4:junit4] 2> 14212 T339 C34 P52666 /update {wt=javabin&version=2} {add=[13 (1424541531132395520)]} 0 3
[junit4:junit4] 2> 14217 T338 C34 P52666 /update {wt=javabin&version=2} {add=[10011 (1424541531138686976)]} 0 1
[junit4:junit4] 2> 14232 T319 C33 P52654 /update {wt=javabin&version=2} {add=[14 (1424541531142881280)]} 0 13
[junit4:junit4] 2> 14236 T317 C33 P52654 /update {wt=javabin&version=2} {add=[10012 (1424541531159658496)]} 0 2
[junit4:junit4] 2> 14240 T335 C34 P52666 /update {wt=javabin&version=2} {add=[14 (1424541531162804224)]} 0 2
[junit4:junit4] 2> 14249 T336 C34 P52666 /update {wt=javabin&version=2} {add=[10012 (1424541531172241408)]} 0 2
[junit4:junit4] 2> 14252 T318 C33 P52654 /update {wt=javabin&version=2} {delete=[12 (-1424541531176435712)]} 0 1
[junit4:junit4] 2> 14257 T337 C34 P52666 /update {wt=javabin&version=2} {delete=[12 (-1424541531182727168)]} 0 1
[junit4:junit4] 2> 14262 T315 C33 P52654 /update {wt=javabin&version=2} {add=[10013 (1424541531186921472)]} 0 3
[junit4:junit4] 2> 14264 T320 C33 P52654 /update {wt=javabin&version=2} {add=[15 (1424541531189018624)]} 0 1
[junit4:junit4] 2> 14268 T339 C34 P52666 /update {wt=javabin&version=2} {add=[15 (1424541531193212928)]} 0 1
[junit4:junit4] 2> 14271 T338 C34 P52666 /update {wt=javabin&version=2} {add=[10013 (1424541531196358656)]} 0 1
[junit4:junit4] 2> 14276 T316 C33 P52654 /update {wt=javabin&version=2} {delete=[13 (-1424541531202650112)]} 0 2
[junit4:junit4] 2> 14281 T335 C34 P52666 /update {wt=javabin&version=2} {delete=[13 (-1424541531207892992)]} 0 1
[junit4:junit4] 2> 14285 T319 C33 P52654 /update {wt=javabin&version=2} {add=[10014 (1424541531209990144)]} 0 3
[junit4:junit4] 2> 14294 T317 C33 P52654 /update {wt=javabin&version=2} {add=[16 (1424541531213135872)]} 0 8
[junit4:junit4] 2> 14299 T336 C34 P52666 /update {wt=javabin&version=2} {add=[10014 (1424541531223621632)]} 0 3
[junit4:junit4] 2> 14724 T318 C33 P52654 /update {wt=javabin&version=2} {delete=[10012 (-1424541531672412160)]} 0 0
[junit4:junit4] 2> 14737 T336 C34 P52666 /update {wt=javabin&version=2} {add=[16 (1424541531228864512)]} 0 437
[junit4:junit4] 2> 14741 T320 C33 P52654 /update {wt=javabin&version=2} {delete=[15 (-1424541531690237952)]} 0 1
[junit4:junit4] 2> 14743 T338 C34 P52666 /update {wt=javabin&version=2} {delete=[10012 (-1424541531692335104)]} 0 0
[junit4:junit4] 2> 14745 T338 C34 P52666 /update {wt=javabin&version=2} {delete=[15 (-1424541531694432256)]} 0 1
[junit4:junit4] 2> 14749 T316 C33 P52654 /update {wt=javabin&version=2} {add=[17 (1424541531697577984)]} 0 2
[junit4:junit4] 2> 14754 T319 C33 P52654 /update {wt=javabin&version=2} {add=[10015 (1424541531701772288)]} 0 2
[junit4:junit4] 2> 14757 T336 C34 P52666 /update {wt=javabin&version=2} {add=[17 (1424541531705966592)]} 0 2
[junit4:junit4] 2> 14762 T337 C34 P52666 /update {wt=javabin&version=2} {add=[10015 (1424541531711209472)]} 0 2
[junit4:junit4] 2> 14764 T317 C33 P52654 /update {wt=javabin&version=2} {add=[18 (1424541531713306624)]} 0 1
[junit4:junit4] 2> 14766 T318 C33 P52654 /update {wt=javabin&version=2} {delete=[10013 (-1424541531716452352)]} 0 0
[junit4:junit4] 2> 14770 T339 C34 P52666 /update {wt=javabin&version=2} {add=[18 (1424541531718549504)]} 0 2
[junit4:junit4] 2> 14772 T338 C34 P52666 /update {wt=javabin&version=2} {delete=[10013 (-1424541531722743808)]} 0 0
[junit4:junit4] 2> 14775 T315 C33 P52654 /update {wt=javabin&version=2} {add=[19 (1424541531724840960)]} 0 2
[junit4:junit4] 2> 14777 T320 C33 P52654 /update {wt=javabin&version=2} {add=[10016 (1424541531726938112)]} 0 1
[junit4:junit4] 2> 14780 T335 C34 P52666 /update {wt=javabin&version=2} {add=[19 (1424541531730083840)]} 0 1
[junit4:junit4] 2> 14782 T325 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4:junit4] 2> 14785 T336 C34 P52666 /update {wt=javabin&version=2} {add=[10016 (1424541531735326720)]} 0 1
[junit4:junit4] 2> 14787 T316 C33 P52654 /update {wt=javabin&version=2} {delete=[18 (-1424541531737423872)]} 0 1
[junit4:junit4] 2> 14789 T325 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:52675__o",
[junit4:junit4] 2> "base_url":"http://127.0.0.1:52675/_o"}
[junit4:junit4] 2> 14793 T337 C34 P52666 /update {wt=javabin&version=2} {delete=[18 (-1424541531744763904)]} 0 1
[junit4:junit4] 2> 14795 T319 C33 P52654 /update {wt=javabin&version=2} {delete=[10015 (-1424541531746861056)]} 0 0
[junit4:junit4] 2> 14799 T317 C33 P52654 /update {wt=javabin&version=2} {add=[20 (1424541531750006784)]} 0 1
[junit4:junit4] 2> 14802 T339 C34 P52666 /update {wt=javabin&version=2} {delete=[10015 (-1424541531754201088)]} 0 1
[junit4:junit4] 2> 14805 T339 C34 P52666 /update {wt=javabin&version=2} {add=[20 (1424541531756298240)]} 0 1
[junit4:junit4] 2> 14809 T318 C33 P52654 /update {wt=javabin&version=2} {add=[21 (1424541531760492544)]} 0 2
[junit4:junit4] 2> 14811 T318 C33 P52654 /update {wt=javabin&version=2} {add=[10017 (1424541531762589696)]} 0 1
[junit4:junit4] 2> 14814 T335 C34 P52666 /update {wt=javabin&version=2} {add=[21 (1424541531765735424)]} 0 1
[junit4:junit4] 2> 14819 T336 C34 P52666 /update {wt=javabin&version=2} {add=[10017 (1424541531770978304)]} 0 1
[junit4:junit4] 2> 14821 T320 C33 P52654 /update {wt=javabin&version=2} {add=[22 (1424541531773075456)]} 0 1
[junit4:junit4] 2> 14826 T337 C34 P52666 /update {wt=javabin&version=2} {add=[22 (1424541531778318336)]} 0 2
[junit4:junit4] 2> 14831 T316 C33 P52654 /update {wt=javabin&version=2} {add=[10018 (1424541531780415488)]} 0 4
[junit4:junit4] 2> 14835 T319 C33 P52654 /update {wt=javabin&version=2} {add=[23 (1424541531787755520)]} 0 1
[junit4:junit4] 2> 14850 T338 C34 P52666 /update {wt=javabin&version=2} {add=[23 (1424541531795095552)]} 0 9
[junit4:junit4] 2> 14855 T339 C34 P52666 /update {wt=javabin&version=2} {add=[10018 (1424541531791949824)]} 0 17
[junit4:junit4] 2> 14856 T330 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> 14856 T358 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> 14857 T324 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> 14858 T343 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> 14863 T317 C33 P52654 /update {wt=javabin&version=2} {add=[24 (1424541531815018496)]} 0 4
[junit4:junit4] 2> 14869 T335 C34 P52666 /update {wt=javabin&version=2} {add=[24 (1424541531822358528)]} 0 2
[junit4:junit4] 2> 14872 T318 C33 P52654 /update {wt=javabin&version=2} {add=[10019 (1424541531826552832)]} 0 2
[junit4:junit4] 2> 14879 T336 C34 P52666 /update {wt=javabin&version=2} {add=[10019 (1424541531833892864)]} 0 1
[junit4:junit4] 2> 14884 T315 C33 P52654 /update {wt=javabin&version=2} {add=[25 (1424541531839135744)]} 0 2
[junit4:junit4] 2> 14886 T320 C33 P52654 /update {wt=javabin&version=2} {delete=[10018 (-1424541531842281472)]} 0 0
[junit4:junit4] 2> C32_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:52675__o, base_url=http://127.0.0.1:52675/_o}
[junit4:junit4] 2> 14997 T350 C32 P52675 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358548656690\jetty2
[junit4:junit4] 2> 15015 T350 C32 P52675 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@4704532e lockFactory=org.apache.lucene.store.NativeFSLockFactory@36b4361a; maxCacheMB=0.9404296875 maxMergeSizeMB=0.501953125))),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4] 2> 15017 T350 C32 P52675 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4] 2> 15020 T352 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10018 (-1424541531913584640)]} 0 7
[junit4:junit4] 2> 15023 T350 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[25 (1424541531852767232)]} 0 27
[junit4:junit4] 2> 15024 T338 C34 P52666 /update {wt=javabin&version=2} {delete=[10018 (-1424541531913584640)]} 0 87
[junit4:junit4] 2> 15025 T337 C34 P52666 /update {wt=javabin&version=2} {add=[25 (1424541531852767232)]} 0 137
[junit4:junit4] 2> 15029 T316 C33 P52654 /update {wt=javabin&version=2} {add=[10020 (1424541531991179264)]} 0 1
[junit4:junit4] 2> 15032 T319 C33 P52654 /update {wt=javabin&version=2} {add=[26 (1424541531994324992)]} 0 2
[junit4:junit4] 2> 15051 T353 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10020 (1424541531997470720)]} 0 2
[junit4:junit4] 2> 15054 T353 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[26 (1424541531999567872)]} 0 2
[junit4:junit4] 2> 15055 T335 C34 P52666 /update {wt=javabin&version=2} {add=[26 (1424541531999567872)]} 0 19
[junit4:junit4] 2> 15055 T339 C34 P52666 /update {wt=javabin&version=2} {add=[10020 (1424541531997470720)]} 0 21
[junit4:junit4] 2> 15058 T317 C33 P52654 /update {wt=javabin&version=2} {add=[27 (1424541532021587968)]} 0 2
[junit4:junit4] 2> 15060 T318 C33 P52654 /update {wt=javabin&version=2} {add=[10021 (1424541532023685120)]} 0 1
[junit4:junit4] 2> 15071 T349 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[27 (1424541532026830848)]} 0 3
[junit4:junit4] 2> 15073 T336 C34 P52666 /update {wt=javabin&version=2} {add=[27 (1424541532026830848)]} 0 11
[junit4:junit4] 2> 15077 T351 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10021 (1424541532031025152)]} 0 3
[junit4:junit4] 2> 15078 T338 C34 P52666 /update {wt=javabin&version=2} {add=[10021 (1424541532031025152)]} 0 12
[junit4:junit4] 2> 15081 T315 C33 P52654 /update {wt=javabin&version=2} {delete=[21 (-1424541532046753792)]} 0 2
[junit4:junit4] 2> 15085 T320 C33 P52654 /update {wt=javabin&version=2} {add=[10022 (1424541532048850944)]} 0 3
[junit4:junit4] 2> 15092 T352 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[21 (-1424541532054093824)]} 0 1
[junit4:junit4] 2> 15094 T350 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10022 (1424541532055142400)]} 0 1
[junit4:junit4] 2> 15096 T335 C34 P52666 /update {wt=javabin&version=2} {add=[10022 (1424541532055142400)]} 0 8
[junit4:junit4] 2> 15096 T337 C34 P52666 /update {wt=javabin&version=2} {delete=[21 (-1424541532054093824)]} 0 9
[junit4:junit4] 2> 15101 T316 C33 P52654 /update {wt=javabin&version=2} {add=[10023 (1424541532063531008)]} 0 4
[junit4:junit4] 2> 15105 T319 C33 P52654 /update {wt=javabin&version=2} {add=[28 (1424541532069822464)]} 0 2
[junit4:junit4] 2> 15115 T353 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[28 (1424541532076113920)]} 0 1
[junit4:junit4] 2> 15118 T354 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10023 (1424541532074016768)]} 0 1
[junit4:junit4] 2> 15119 T336 C34 P52666 /update {wt=javabin&version=2} {add=[28 (1424541532076113920)]} 0 11
[junit4:junit4] 2> 15121 T339 C34 P52666 /update {wt=javabin&version=2} {add=[10023 (1424541532074016768)]} 0 14
[junit4:junit4] 2> 15123 T317 C33 P52654 /update {wt=javabin&version=2} {delete=[23 (-1424541532090793984)]} 0 1
[junit4:junit4] 2> 15126 T318 C33 P52654 /update {wt=javabin&version=2} {add=[10024 (1424541532091842560)]} 0 2
[junit4:junit4] 2> 15132 T349 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[23 (-1424541532094988288)]} 0 0
[junit4:junit4] 2> 15134 T338 C34 P52666 /update {wt=javabin&version=2} {delete=[23 (-1424541532094988288)]} 0 7
[junit4:junit4] 2> 15137 T351 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10024 (1424541532098134016)]} 0 1
[junit4:junit4] 2> 15138 T315 C33 P52654 /update {wt=javabin&version=2} {add=[29 (1424541532106522624)]} 0 0
[junit4:junit4] 2> 15139 T337 C34 P52666 /update {wt=javabin&version=2} {add=[10024 (1424541532098134016)]} 0 9
[junit4:junit4] 2> 15145 T320 C33 P52654 /update {wt=javabin&version=2} {add=[10025 (1424541532112814080)]} 0 2
[junit4:junit4] 2> 15150 T352 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[29 (1424541532109668352)]} 0 4
[junit4:junit4] 2> 15151 T336 C34 P52666 /update {wt=javabin&version=2} {add=[29 (1424541532109668352)]} 0 10
[junit4:junit4] 2> 15157 T319 C33 P52654 /update {wt=javabin&version=2} {delete=[24 (-1424541532126445568)]} 0 1
[junit4:junit4] 2> 15164 T350 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10025 (1424541532124348416)]} 0 5
[junit4:junit4] 2> 15166 T339 C34 P52666 /update {wt=javabin&version=2} {add=[10025 (1424541532124348416)]} 0 11
[junit4:junit4] 2> 15169 T353 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[24 (-1424541532131688448)]} 0 0
[junit4:junit4] 2> 15170 T335 C34 P52666 /update {wt=javabin&version=2} {delete=[24 (-1424541532131688448)]} 0 9
[junit4:junit4] 2> 15174 T317 C33 P52654 /update {wt=javabin&version=2} {add=[10026 (1424541532142174208)]} 0 3
[junit4:junit4] 2> 15176 T318 C33 P52654 /update {wt=javabin&version=2} {add=[30 (1424541532145319936)]} 0 1
[junit4:junit4] 2> 15185 T354 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10026 (1424541532150562816)]} 0 2
[junit4:junit4] 2> 15186 T338 C34 P52666 /update {wt=javabin&version=2} {add=[10026 (1424541532150562816)]} 0 7
[junit4:junit4] 2> 15201 T349 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[30 (1424541532151611392)]} 0 13
[junit4:junit4] 2> 15203 T337 C34 P52666 /update {wt=javabin&version=2} {add=[30 (1424541532151611392)]} 0 23
[junit4:junit4] 2> 15207 T315 C33 P52654 /update {wt=javabin&version=2} {add=[10027 (1424541532176777216)]} 0 2
[junit4:junit4] 2> 15208 T320 C33 P52654 /update {wt=javabin&version=2} {delete=[25 (-1424541532179922944)]} 0 1
[junit4:junit4] 2> 15217 T351 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10027 (1424541532184117248)]} 0 1
[junit4:junit4] 2> 15219 T351 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[25 (-1424541532186214400)]} 0 1
[junit4:junit4] 2> 15219 T336 C34 P52666 /update {wt=javabin&version=2} {add=[10027 (1424541532184117248)]} 0 7
[junit4:junit4] 2> 15221 T339 C34 P52666 /update {wt=javabin&version=2} {delete=[25 (-1424541532186214400)]} 0 8
[junit4:junit4] 2> 15224 T316 C33 P52654 /update {wt=javabin&version=2} {add=[31 (1424541532195651584)]} 0 1
[junit4:junit4] 2> 15226 T316 C33 P52654 /update {wt=javabin&version=2} {delete=[10021 (-1424541532198797312)]} 0 1
[junit4:junit4] 2> 15235 T350 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[31 (1424541532199845888)]} 0 1
[junit4:junit4] 2> 15236 T335 C34 P52666 /update {wt=javabin&version=2} {add=[31 (1424541532199845888)]} 0 9
[junit4:junit4] 2> 15238 T353 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10021 (-1424541532205088768)]} 0 1
[junit4:junit4] 2> 15240 T338 C34 P52666 /update {wt=javabin&version=2} {delete=[10021 (-1424541532205088768)]} 0 8
[junit4:junit4] 2> 15242 T317 C33 P52654 /update {wt=javabin&version=2} {add=[32 (1424541532214525952)]} 0 1
[junit4:junit4] 2> 15248 T318 C33 P52654 /update {wt=javabin&version=2} {add=[10028 (1424541532217671680)]} 0 5
[junit4:junit4] 2> 15295 T354 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[32 (1424541532222914560)]} 0 1
[junit4:junit4] 2> 15296 T337 C34 P52666 /update {wt=javabin&version=2} {add=[32 (1424541532222914560)]} 0 47
[junit4:junit4] 2> 15299 T349 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10028 (1424541532226060288)]} 0 1
[junit4:junit4] 2> 15300 T336 C34 P52666 /update {wt=javabin&version=2} {add=[10028 (1424541532226060288)]} 0 48
[junit4:junit4] 2> 15300 T315 C33 P52654 /update {wt=javabin&version=2} {add=[33 (1424541532275343360)]} 0 1
[junit4:junit4] 2> 15303 T320 C33 P52654 /update {wt=javabin&version=2} {delete=[10023 (-1424541532279537664)]} 0 1
[junit4:junit4] 2> 15307 T351 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[33 (1424541532280586240)]} 0 1
[junit4:junit4] 2> 15308 T339 C34 P52666 /update {wt=javabin&version=2} {add=[33 (1424541532280586240)]} 0 3
[junit4:junit4] 2> 15309 T352 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10023 (-1424541532281634816)]} 0 1
[junit4:junit4] 2> 15309 T335 C34 P52666 /update {wt=javabin&version=2} {delete=[10023 (-1424541532281634816)]} 0 4
[junit4:junit4] 2> 15311 T316 C33 P52654 /update {wt=javabin&version=2} {delete=[28 (-1424541532287926272)]} 0 0
[junit4:junit4] 2> 15313 T319 C33 P52654 /update {wt=javabin&version=2} {add=[10029 (1424541532288974848)]} 0 1
[junit4:junit4] 2> 15316 T350 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[28 (-1424541532291072000)]} 0 0
[junit4:junit4] 2> 15317 T338 C34 P52666 /update {wt=javabin&version=2} {delete=[28 (-1424541532291072000)]} 0 3
[junit4:junit4] 2> 15320 T353 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10029 (1424541532293169152)]} 0 1
[junit4:junit4] 2> 15321 T337 C34 P52666 /update {wt=javabin&version=2} {add=[10029 (1424541532293169152)]} 0 5
[junit4:junit4] 2> 15321 T317 C33 P52654 /update {wt=javabin&version=2} {add=[34 (1424541532297363456)]} 0 1
[junit4:junit4] 2> 15323 T318 C33 P52654 /update {wt=javabin&version=2} {delete=[10024 (-1424541532300509184)]} 0 0
[junit4:junit4] 2> 15329 T349 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10024 (-1424541532303654912)]} 0 0
[junit4:junit4] 2> 15331 T339 C34 P52666 /update {wt=javabin&version=2} {delete=[10024 (-1424541532303654912)]} 0 5
[junit4:junit4] 2> 15332 T354 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[34 (1424541532301557760)]} 0 5
[junit4:junit4] 2> 15333 T336 C34 P52666 /update {wt=javabin&version=2} {add=[34 (1424541532301557760)]} 0 9
[junit4:junit4] 2> 15336 T315 C33 P52654 /update {wt=javabin&version=2} {add=[10030 (1424541532313092096)]} 0 2
[junit4:junit4] 2> 15337 T320 C33 P52654 /update {wt=javabin&version=2} {add=[35 (1424541532314140672)]} 0 1
[junit4:junit4] 2> 15344 T351 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10030 (1424541532317286400)]} 0 2
[junit4:junit4] 2> 15345 T335 C34 P52666 /update {wt=javabin&version=2} {add=[10030 (1424541532317286400)]} 0 7
[junit4:junit4] 2> 15345 T352 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[35 (1424541532319383552)]} 0 1
[junit4:junit4] 2> 15346 T338 C34 P52666 /update {wt=javabin&version=2} {add=[35 (1424541532319383552)]} 0 5
[junit4:junit4] 2> 15347 T316 C33 P52654 /update {wt=javabin&version=2} {delete=[10025 (-1424541532325675008)]} 0 0
[junit4:junit4] 2> 15349 T316 C33 P52654 /update {wt=javabin&version=2} {add=[36 (1424541532326723584)]} 0 1
[junit4:junit4] 2> 15353 T350 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10025 (-1424541532329869312)]} 0 0
[junit4:junit4] 2> 15355 T337 C34 P52666 /update {wt=javabin&version=2} {delete=[10025 (-1424541532329869312)]} 0 5
[junit4:junit4] 2> 15359 T353 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[36 (1424541532333015040)]} 0 2
[junit4:junit4] 2> 15361 T317 C33 P52654 /update {wt=javabin&version=2} {add=[10031 (1424541532339306496)]} 0 1
[junit4:junit4] 2> 15362 T339 C34 P52666 /update {wt=javabin&version=2} {add=[36 (1424541532333015040)]} 0 8
[junit4:junit4] 2> 15366 T318 C33 P52654 /update {wt=javabin&version=2} {delete=[29 (-1424541532345597952)]} 0 0
[junit4:junit4] 2> 15368 T349 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10031 (1424541532343500800)]} 0 0
[junit4:junit4] 2> 15370 T336 C34 P52666 /update {wt=javabin&version=2} {add=[10031 (1424541532343500800)]} 0 6
[junit4:junit4] 2> 15372 T354 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[29 (-1424541532349792256)]} 0 0
[junit4:junit4] 2> 15373 T335 C34 P52666 /update {wt=javabin&version=2} {delete=[29 (-1424541532349792256)]} 0 3
[junit4:junit4] 2> 15374 T315 C33 P52654 /update {wt=javabin&version=2} {add=[10032 (1424541532352937984)]} 0 1
[junit4:junit4] 2> 15376 T320 C33 P52654 /update {wt=javabin&version=2} {add=[37 (1424541532356083712)]} 0 0
[junit4:junit4] 2> 15382 T351 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10032 (1424541532358180864)]} 0 1
[junit4:junit4] 2> 15383 T338 C34 P52666 /update {wt=javabin&version=2} {add=[10032 (1424541532358180864)]} 0 5
[junit4:junit4] 2> 15384 T350 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[37 (1424541532360278016)]} 0 1
[junit4:junit4] 2> 15385 T337 C34 P52666 /update {wt=javabin&version=2} {add=[37 (1424541532360278016)]} 0 5
[junit4:junit4] 2> 15389 T319 C33 P52654 /update {wt=javabin&version=2} {add=[10033 (1424541532365520896)]} 0 4
[junit4:junit4] 2> 15390 T316 C33 P52654 /update {wt=javabin&version=2} {delete=[30 (-1424541532370763776)]} 0 1
[junit4:junit4] 2> 15398 T353 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[30 (-1424541532372860928)]} 0 0
[junit4:junit4] 2> 15399 T339 C34 P52666 /update {wt=javabin&version=2} {delete=[30 (-1424541532372860928)]} 0 7
[junit4:junit4] 2> 15402 T349 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10033 (1424541532373909504)]} 0 1
[junit4:junit4] 2> 15403 T336 C34 P52666 /update {wt=javabin&version=2} {add=[10033 (1424541532373909504)]} 0 10
[junit4:junit4] 2> 15411 T317 C33 P52654 /update {wt=javabin&version=2} {add=[38 (1424541532385443840)]} 0 7
[junit4:junit4] 2> 15413 T317 C33 P52654 /update {wt=javabin&version=2} {delete=[10028 (-1424541532394881024)]} 0 1
[junit4:junit4] 2> 15418 T352 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10028 (-1424541532396978176)]} 0 0
[junit4:junit4] 2> 15420 T335 C34 P52666 /update {wt=javabin&version=2} {delete=[10028 (-1424541532396978176)]} 0 5
[junit4:junit4] 2> 15421 T354 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[38 (1424541532398026752)]} 0 1
[junit4:junit4] 2> 15422 T338 C34 P52666 /update {wt=javabin&version=2} {add=[38 (1424541532398026752)]} 0 6
[junit4:junit4] 2> 15423 T315 C33 P52654 /update {wt=javabin&version=2} {add=[10034 (1424541532404318208)]} 0 1
[junit4:junit4] 2> 15428 T320 C33 P52654 /update {wt=javabin&version=2} {add=[39 (1424541532408512512)]} 0 2
[junit4:junit4] 2> 15433 T351 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10034 (1424541532408512512)]} 0 1
[junit4:junit4] 2> 15438 T351 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[39 (1424541532412706816)]} 0 4
[junit4:junit4] 2> 15439 T339 C34 P52666 /update {wt=javabin&version=2} {add=[39 (1424541532412706816)]} 0 9
[junit4:junit4] 2> 15440 T337 C34 P52666 /update {wt=javabin&version=2} {add=[10034 (1424541532408512512)]} 0 14
[junit4:junit4] 2> 15443 T319 C33 P52654 /update {wt=javabin&version=2} {delete=[32 (-1424541532426338304)]} 0 0
[junit4:junit4] 2> 15445 T319 C33 P52654 /update {wt=javabin&version=2} {add=[10035 (1424541532427386880)]} 0 1
[junit4:junit4] 2> 15451 T353 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[32 (-1424541532432629760)]} 0 0
[junit4:junit4] 2> 15452 T335 C34 P52666 /update {wt=javabin&version=2} {delete=[32 (-1424541532432629760)]} 0 3
[junit4:junit4] 2> 15456 T318 C33 P52654 /update {wt=javabin&version=2} {add=[40 (1424541532438921216)]} 0 1
[junit4:junit4] 2> 15456 T349 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10035 (1424541532431581184)]} 0 1
[junit4:junit4] 2> 15457 T336 C34 P52666 /update {wt=javabin&version=2} {add=[10035 (1424541532431581184)]} 0 9
[junit4:junit4] 2> 15463 T317 C33 P52654 /update {wt=javabin&version=2} {add=[10036 (1424541532446261248)]} 0 1
[junit4:junit4] 2> 15464 T352 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[40 (1424541532443115520)]} 0 1
[junit4:junit4] 2> 15465 T338 C34 P52666 /update {wt=javabin&version=2} {add=[40 (1424541532443115520)]} 0 6
[junit4:junit4] 2> 15468 T315 C33 P52654 /update {wt=javabin&version=2} {delete=[33 (-1424541532452552704)]} 0 0
[junit4:junit4] 2> 15470 T354 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10036 (1424541532449406976)]} 0 1
[junit4:junit4] 2> 15471 T339 C34 P52666 /update {wt=javabin&version=2} {add=[10036 (1424541532449406976)]} 0 6
[junit4:junit4] 2> 15472 T351 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[33 (-1424541532455698432)]} 0 0
[junit4:junit4] 2> 15473 T337 C34 P52666 /update {wt=javabin&version=2} {delete=[33 (-1424541532455698432)]} 0 2
[junit4:junit4] 2> 15473 T320 C33 P52654 /update {wt=javabin&version=2} {delete=[10029 (-1424541532457795584)]} 0 0
[junit4:junit4] 2> 15477 T319 C33 P52654 /update {wt=javabin&version=2} {add=[41 (1424541532460941312)]} 0 1
[junit4:junit4] 2> 15479 T350 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10029 (-1424541532460941312)]} 0 1
[junit4:junit4] 2> 15479 T335 C34 P52666 /update {wt=javabin&version=2} {delete=[10029 (-1424541532460941312)]} 0 3
[junit4:junit4] 2> 15483 T316 C33 P52654 /update {wt=javabin&version=2} {add=[10037 (1424541532467232768)]} 0 1
[junit4:junit4] 2> 15485 T353 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[41 (1424541532465135616)]} 0 1
[junit4:junit4] 2> 15486 T336 C34 P52666 /update {wt=javabin&version=2} {add=[41 (1424541532465135616)]} 0 6
[junit4:junit4] 2> 15489 T318 C33 P52654 /update {wt=javabin&version=2} {add=[42 (1424541532473524224)]} 0 1
[junit4:junit4] 2> 15490 T349 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10037 (1424541532471427072)]} 0 0
[junit4:junit4] 2> 15491 T338 C34 P52666 /update {wt=javabin&version=2} {add=[10037 (1424541532471427072)]} 0 5
[junit4:junit4] 2> 15494 T317 C33 P52654 /update {wt=javabin&version=2} {delete=[10030 (-1424541532479815680)]} 0 0
[junit4:junit4] 2> 15496 T352 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[42 (1424541532477718528)]} 0 1
[junit4:junit4] 2> 15496 T339 C34 P52666 /update {wt=javabin&version=2} {add=[42 (1424541532477718528)]} 0 4
[junit4:junit4] 2> 15499 T354 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10030 (-1424541532482961408)]} 0 0
[junit4:junit4] 2> 15499 T315 C33 P52654 /update {wt=javabin&version=2} {delete=[34 (-1424541532485058560)]} 0 0
[junit4:junit4] 2> 15500 T337 C34 P52666 /update {wt=javabin&version=2} {delete=[10030 (-1424541532482961408)]} 0 3
[junit4:junit4] 2> 15505 T351 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[34 (-1424541532488204288)]} 0 0
[junit4:junit4] 2> 15506 T335 C34 P52666 /update {wt=javabin&version=2} {delete=[34 (-1424541532488204288)]} 0 4
[junit4:junit4] 2> 15515 T320 C33 P52654 /update {wt=javabin&version=2} {add=[10038 (1424541532489252864)]} 0 12
[junit4:junit4] 2> 15517 T319 C33 P52654 /update {wt=javabin&version=2} {add=[43 (1424541532495544320)]} 0 7
[junit4:junit4] 2> 15526 T350 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10038 (1424541532504981504)]} 0 1
[junit4:junit4] 2> 15527 T353 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[43 (1424541532507078656)]} 0 2
[junit4:junit4] 2> 15528 T336 C34 P52666 /update {wt=javabin&version=2} {add=[10038 (1424541532504981504)]} 0 10
[junit4:junit4] 2> 15528 T338 C34 P52666 /update {wt=javabin&version=2} {add=[43 (1424541532507078656)]} 0 8
[junit4:junit4] 2> 15532 T316 C33 P52654 /update {wt=javabin&version=2} {add=[10039 (1424541532517564416)]} 0 2
[junit4:junit4] 2> 15533 T316 C33 P52654 /update {wt=javabin&version=2} {add=[44 (1424541532520710144)]} 0 1
[junit4:junit4] 2> 15540 T349 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10039 (1424541532523855872)]} 0 1
[junit4:junit4] 2> 15541 T337 C34 P52666 /update {wt=javabin&version=2} {add=[10039 (1424541532523855872)]} 0 5
[junit4:junit4] 2> 15545 T317 C33 P52654 /update {wt=javabin&version=2} {delete=[10032 (-1424541532533293056)]} 0 0
[junit4:junit4] 2> 15575 T352 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10032 (-1424541532562653184)]} 0 0
[junit4:junit4] 2> 15577 T336 C34 P52666 /update {wt=javabin&version=2} {delete=[10032 (-1424541532562653184)]} 0 4
[junit4:junit4] 2> 15581 T320 C33 P52654 /update {wt=javabin&version=2} {add=[10040 (1424541532571041792)]} 0 0
[junit4:junit4] 2> 15586 T354 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[44 (1424541532562653185)]} 0 6
[junit4:junit4] 2> 15587 T335 C34 P52666 /update {wt=javabin&version=2} {add=[44 (1424541532562653185)]} 0 14
[junit4:junit4] 2> 15594 T319 C33 P52654 /update {wt=javabin&version=2} {add=[45 (1424541532581527552)]} 0 4
[junit4:junit4] 2> 15613 T351 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10040 (1424541532575236096)]} 0 17
[junit4:junit4] 2> 15615 T339 C34 P52666 /update {wt=javabin&version=2} {add=[10040 (1424541532575236096)]} 0 30
[junit4:junit4] 2> 15618 T316 C33 P52654 /update {wt=javabin&version=2} {delete=[10033 (-1424541532609839104)]} 0 0
[junit4:junit4] 2> 15619 T350 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[45 (1424541532586770432)]} 0 4
[junit4:junit4] 2> 15620 T338 C34 P52666 /update {wt=javabin&version=2} {add=[45 (1424541532586770432)]} 0 24
[junit4:junit4] 2> 15624 T318 C33 P52654 /update {wt=javabin&version=2} {delete=[38 (-1424541532616130560)]} 0 0
[junit4:junit4] 2> 15624 T353 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10033 (-1424541532614033408)]} 0 0
[junit4:junit4] 2> 15625 T337 C34 P52666 /update {wt=javabin&version=2} {delete=[10033 (-1424541532614033408)]} 0 3
[junit4:junit4] 2> 15629 T317 C33 P52654 /update {wt=javabin&version=2} {add=[10041 (1424541532620324864)]} 0 1
[junit4:junit4] 2> 15632 T349 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[38 (-1424541532619276288)]} 0 1
[junit4:junit4] 2> 15653 T336 C34 P52666 /update {wt=javabin&version=2} {delete=[38 (-1424541532619276288)]} 0 26
[junit4:junit4] 2> 15660 T352 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10041 (1424541532624519168)]} 0 6
[junit4:junit4] 2> 15661 T315 C33 P52654 /update {wt=javabin&version=2} {add=[46 (1424541532653879296)]} 0 1
[junit4:junit4] 2> 15662 T335 C34 P52666 /update {wt=javabin&version=2} {add=[10041 (1424541532624519168)]} 0 30
[junit4:junit4] 2> 15690 T320 C33 P52654 /update {wt=javabin&version=2} {add=[10042 (1424541532659122176)]} 0 25
[junit4:junit4] 2> 15705 T354 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[46 (1424541532658073600)]} 0 11
[junit4:junit4] 2> 15710 T339 C34 P52666 /update {wt=javabin&version=2} {add=[46 (1424541532658073600)]} 0 46
[junit4:junit4] 2> 15712 T351 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10042 (1424541532690579456)]} 0 0
[junit4:junit4] 2> 15715 T338 C34 P52666 /update {wt=javabin&version=2} {add=[10042 (1424541532690579456)]} 0 20
[junit4:junit4] 2> 15715 T319 C33 P52654 /update {wt=javabin&version=2} {add=[47 (1424541532711550976)]} 0 1
[junit4:junit4] 2> 15718 T316 C33 P52654 /update {wt=javabin&version=2} {delete=[10034 (-1424541532714696704)]} 0 0
[junit4:junit4] 2> 15722 T350 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[47 (1424541532714696704)]} 0 1
[junit4:junit4] 2> 15723 T353 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10034 (-1424541532717842432)]} 0 1
[junit4:junit4] 2> 15723 T336 C34 P52666 /update {wt=javabin&version=2} {delete=[10034 (-1424541532717842432)]} 0 2
[junit4:junit4] 2> 15724 T337 C34 P52666 /update {wt=javabin&version=2} {add=[47 (1424541532714696704)]} 0 6
[junit4:junit4] 2> 15727 T318 C33 P52654 /update {wt=javabin&version=2} {delete=[39 (-1424541532724133888)]} 0 0
[junit4:junit4] 2> 15732 T317 C33 P52654 /update {wt=javabin&version=2} {add=[10043 (1424541532724133889)]} 0 5
[junit4:junit4] 2> 15735 T349 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[39 (-1424541532730425344)]} 0 0
[junit4:junit4] 2> 15743 T335 C34 P52666 /update {wt=javabin&version=2} {delete=[39 (-1424541532730425344)]} 0 10
[junit4:junit4] 2> 15747 T315 C33 P52654 /update {wt=javabin&version=2} {add=[48 (1424541532744056832)]} 0 1
[junit4:junit4] 2> 15749 T352 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10043 (1424541532732522496)]} 0 1
[junit4:junit4] 2> 15750 T339 C34 P52666 /update {wt=javabin&version=2} {add=[10043 (1424541532732522496)]} 0 15
[junit4:junit4] 2> 15753 T320 C33 P52654 /update {wt=javabin&version=2} {add=[10044 (1424541532750348288)]} 0 1
[junit4:junit4] 2> 15755 T354 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[48 (1424541532748251136)]} 0 1
[junit4:junit4] 2> 15756 T338 C34 P52666 /update {wt=javabin&version=2} {add=[48 (1424541532748251136)]} 0 6
[junit4:junit4] 2> 15758 T319 C33 P52654 /update {wt=javabin&version=2} {delete=[40 (-1424541532756639744)]} 0 0
[junit4:junit4] 2> 15760 T351 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10044 (1424541532754542592)]} 0 1
[junit4:junit4] 2> 15761 T336 C34 P52666 /update {wt=javabin&version=2} {add=[10044 (1424541532754542592)]} 0 5
[junit4:junit4] 2> 15763 T353 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[40 (-1424541532759785472)]} 0 0
[junit4:junit4] 2> 15763 T337 C34 P52666 /update {wt=javabin&version=2} {delete=[40 (-1424541532759785472)]} 0 2
[junit4:junit4] 2> 15764 T316 C33 P52654 /update {wt=javabin&version=2} {add=[10045 (1424541532761882624)]} 0 1
[junit4:junit4] 2> 15768 T318 C33 P52654 /update {wt=javabin&version=2} {add=[49 (1424541532766076928)]} 0 1
[junit4:junit4] 2> 15773 T350 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10045 (1424541532766076928)]} 0 3
[junit4:junit4] 2> 15774 T335 C34 P52666 /update {wt=javabin&version=2} {add=[10045 (1424541532766076928)]} 0 7
[junit4:junit4] 2> 15776 T349 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[49 (1424541532770271232)]} 0 2
[junit4:junit4] 2> 15777 T339 C34 P52666 /update {wt=javabin&version=2} {add=[49 (1424541532770271232)]} 0 6
[junit4:junit4] 2> 15777 T317 C33 P52654 /update {wt=javabin&version=2} {delete=[10038 (-1424541532776562688)]} 0 0
[junit4:junit4] 2> 15779 T315 C33 P52654 /update {wt=javabin&version=2} {delete=[41 (-1424541532778659840)]} 0 0
[junit4:junit4] 2> 15783 T354 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10038 (-1424541532779708416)]} 0 0
[junit4:junit4] 2> 15784 T338 C34 P52666 /update {wt=javabin&version=2} {delete=[10038 (-1424541532779708416)]} 0 4
[junit4:junit4] 2> 15784 T354 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[41 (-1424541532781805568)]} 0 0
[junit4:junit4] 2> 15785 T336 C34 P52666 /update {wt=javabin&version=2} {delete=[41 (-1424541532781805568)]} 0 3
[junit4:junit4] 2> 15788 T320 C33 P52654 /update {wt=javabin&version=2} {add=[10046 (1424541532787048448)]} 0 1
[junit4:junit4] 2> 15789 T319 C33 P52654 /update {wt=javabin&version=2} {add=[50 (1424541532788097024)]} 0 1
[junit4:junit4] 2> 15796 T353 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10046 (1424541532791242752)]} 0 1
[junit4:junit4] 2> 15797 T350 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[50 (1424541532793339904)]} 0 1
[junit4:junit4] 2> 15797 T337 C34 P52666 /update {wt=javabin&version=2} {add=[10046 (1424541532791242752)]} 0 6
[junit4:junit4] 2> 15797 T335 C34 P52666 /update {wt=javabin&version=2} {add=[50 (1424541532793339904)]} 0 4
[junit4:junit4] 2> 15801 T316 C33 P52654 /update {wt=javabin&version=2} {add=[51 (1424541532800679936)]} 0 1
[junit4:junit4] 2> 15803 T318 C33 P52654 /update {wt=javabin&version=2} {add=[10047 (1424541532802777088)]} 0 3
[junit4:junit4] 2> 15809 T352 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[51 (1424541532805922816)]} 0 1
[junit4:junit4] 2> 15811 T339 C34 P52666 /update {wt=javabin&version=2} {add=[51 (1424541532805922816)]} 0 7
[junit4:junit4] 2> 15812 T349 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10047 (1424541532805922817)]} 0 3
[junit4:junit4] 2> 15812 T338 C34 P52666 /update {wt=javabin&version=2} {add=[10047 (1424541532805922817)]} 0 7
[junit4:junit4] 2> 15814 T317 C33 P52654 /update {wt=javabin&version=2} {delete=[43 (-1424541532815360000)]} 0 0
[junit4:junit4] 2> 15815 T315 C33 P52654 /update {wt=javabin&version=2} {delete=[10039 (-1424541532816408576)]} 0 0
[junit4:junit4] 2> 15819 T302 oasc.ChaosMonkey.monkeyLog monkey: stop shard! 52675
[junit4:junit4] 2> 15819 T302 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=195112228
[junit4:junit4] 2> 15819 T302 oasc.RecoveryStrategy.close WARNING Stopping recovery for zkNodeName=127.0.0.1:52675__o_collection1core=collection1
[junit4:junit4] 2> 15821 T351 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[43 (-1424541532819554304)]} 0 0
[junit4:junit4] 2> 15821 T354 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10039 (-1424541532819554305)]} 0 0
[junit4:junit4] 2> 15822 T336 C34 P52666 /update {wt=javabin&version=2} {delete=[43 (-1424541532819554304)]} 0 5
[junit4:junit4] 2> 15823 T334 C34 P52666 /update {wt=javabin&version=2} {delete=[10039 (-1424541532819554305)]} 0 5
[junit4:junit4] 2> 15826 T320 C33 P52654 /update {wt=javabin&version=2} {add=[52 (1424541532826894336)]} 0 1
[junit4:junit4] 2> 15829 T319 C33 P52654 /update {wt=javabin&version=2} {add=[10048 (1424541532826894337)]} 0 4
[junit4:junit4] 2> 15834 T353 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[52 (1424541532831088640)]} 0 1
[junit4:junit4] 2> 15835 T337 C34 P52666 /update {wt=javabin&version=2} {add=[52 (1424541532831088640)]} 0 6
[junit4:junit4] 2> 15839 T316 C33 P52654 /update {wt=javabin&version=2} {add=[53 (1424541532840525824)]} 0 1
[junit4:junit4] 2> 15841 T350 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10048 (1424541532835282944)]} 0 2
[junit4:junit4] 2> 15844 T335 C34 P52666 /update {wt=javabin&version=2} {add=[10048 (1424541532835282944)]} 0 11
[junit4:junit4] 2> 15846 T318 C33 P52654 /update {wt=javabin&version=2} {delete=[10040 (-1424541532848914432)]} 0 0
[junit4:junit4] 2> 15849 T352 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[53 (1424541532844720128)]} 0 3
[junit4:junit4] 2> 15850 T339 C34 P52666 /update {wt=javabin&version=2} {add=[53 (1424541532844720128)]} 0 8
[junit4:junit4] 2> 15853 T317 C33 P52654 /update {wt=javabin&version=2} {delete=[46 (-1424541532855205888)]} 0 1
[junit4:junit4] 2> 15853 T349 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10040 (-1424541532853108736)]} 0 0
[junit4:junit4] 2> 15855 T338 C34 P52666 /update {wt=javabin&version=2} {delete=[10040 (-1424541532853108736)]} 0 5
[junit4:junit4] 2> 15858 T315 C33 P52654 /update {wt=javabin&version=2} {add=[10049 (1424541532860448768)]} 0 1
[junit4:junit4] 2> 15859 T351 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[46 (-1424541532859400192)]} 0 1
[junit4:junit4] 2> 15860 T336 C34 P52666 /update {wt=javabin&version=2} {delete=[46 (-1424541532859400192)]} 0 4
[junit4:junit4] 2> 15864 T320 C33 P52654 /update {wt=javabin&version=2} {add=[54 (1424541532866740224)]} 0 1
[junit4:junit4] 2> 15866 T354 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10049 (1424541532864643072)]} 0 1
[junit4:junit4] 2> 15867 T334 C34 P52666 /update {wt=javabin&version=2} {add=[10049 (1424541532864643072)]} 0 6
[junit4:junit4] 2> 15870 T319 C33 P52654 /update {wt=javabin&version=2} {delete=[10041 (-1424541532874080256)]} 0 0
[junit4:junit4] 2> 15875 T353 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[54 (1424541532870934528)]} 0 3
[junit4:junit4] 2> 15877 T337 C34 P52666 /update {wt=javabin&version=2} {add=[54 (1424541532870934528)]} 0 10
[junit4:junit4] 2> 15878 T350 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10041 (-1424541532878274560)]} 0 1
[junit4:junit4] 2> 15879 T335 C34 P52666 /update {wt=javabin&version=2} {delete=[10041 (-1424541532878274560)]} 0 5
[junit4:junit4] 2> 15880 T316 C33 P52654 /update {wt=javabin&version=2} {delete=[51 (-1424541532884566016)]} 0 0
[junit4:junit4] 2> 15884 T318 C33 P52654 /update {wt=javabin&version=2} {add=[10050 (1424541532887711744)]} 0 1
[junit4:junit4] 2> 15888 T352 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[51 (-1424541532889808896)]} 0 1
[junit4:junit4] 2> 15888 T339 C34 P52666 /update {wt=javabin&version=2} {delete=[51 (-1424541532889808896)]} 0 3
[junit4:junit4] 2> 15892 T317 C33 P52654 /update {wt=javabin&version=2} {add=[55 (1424541532896100352)]} 0 1
[junit4:junit4] 2> 15892 T349 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10050 (1424541532892954624)]} 0 1
[junit4:junit4] 2> 15894 T338 C34 P52666 /update {wt=javabin&version=2} {add=[10050 (1424541532892954624)]} 0 6
[junit4:junit4] 2> 15897 T315 C33 P52654 /update {wt=javabin&version=2} {add=[10051 (1424541532901343232)]} 0 1
[junit4:junit4] 2> 15900 T351 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[55 (1424541532900294656)]} 0 2
[junit4:junit4] 2> 15901 T336 C34 P52666 /update {wt=javabin&version=2} {add=[55 (1424541532900294656)]} 0 6
[junit4:junit4] 2> 15904 T320 C33 P52654 /update {wt=javabin&version=2} {add=[56 (1424541532908683264)]} 0 1
[junit4:junit4] 2> 15905 T354 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10051 (1424541532906586112)]} 0 1
[junit4:junit4] 2> 15905 T334 C34 P52666 /update {wt=javabin&version=2} {add=[10051 (1424541532906586112)]} 0 5
[junit4:junit4] 2> 15908 T319 C33 P52654 /update {wt=javabin&version=2} {delete=[10043 (-1424541532913926144)]} 0 0
[junit4:junit4] 2> 15911 T353 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[56 (1424541532912877568)]} 0 1
[junit4:junit4] 2> 15913 T337 C34 P52666 /update {wt=javabin&version=2} {add=[56 (1424541532912877568)]} 0 6
[junit4:junit4] 2> 15913 T353 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10043 (-1424541532917071872)]} 0 0
[junit4:junit4] 2> 15914 T335 C34 P52666 /update {wt=javabin&version=2} {delete=[10043 (-1424541532917071872)]} 0 3
[junit4:junit4] 2> 15917 T316 C33 P52654 /update {wt=javabin&version=2} {add=[57 (1424541532922314752)]} 0 1
[junit4:junit4] 2> 15918 T318 C33 P52654 /update {wt=javabin&version=2} {add=[10052 (1424541532923363328)]} 0 1
[junit4:junit4] 2> 15924 T352 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10052 (1424541532926509056)]} 0 1
[junit4:junit4] 2> 15926 T339 C34 P52666 /update {wt=javabin&version=2} {add=[10052 (1424541532926509056)]} 0 6
[junit4:junit4] 2> 15926 T349 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[57 (1424541532926509057)]} 0 2
[junit4:junit4] 2> 15927 T338 C34 P52666 /update {wt=javabin&version=2} {add=[57 (1424541532926509057)]} 0 7
[junit4:junit4] 2> 15930 T315 C33 P52654 /update {wt=javabin&version=2} {add=[58 (1424541532935946240)]} 0 1
[junit4:junit4] 2> 15930 T317 C33 P52654 /update {wt=javabin&version=2} {add=[10053 (1424541532934897664)]} 0 2
[junit4:junit4] 2> 15937 T351 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10053 (1424541532940140544)]} 0 1
[junit4:junit4] 2> 15938 T337 C34 P52666 /update {wt=javabin&version=2} {add=[10053 (1424541532940140544)]} 0 5
[junit4:junit4] 2> 15939 T354 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[58 (1424541532939091968)]} 0 1
[junit4:junit4] 2> 15939 T336 C34 P52666 /update {wt=javabin&version=2} {add=[58 (1424541532939091968)]} 0 7
[junit4:junit4] 2> 15941 T320 C33 P52654 /update {wt=javabin&version=2} {delete=[10046 (-1424541532948529152)]} 0 0
[junit4:junit4] 2> 15943 T319 C33 P52654 /update {wt=javabin&version=2} {add=[59 (1424541532950626304)]} 0 0
[junit4:junit4] 2> 15949 T350 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10046 (-1424541532952723456)]} 0 0
[junit4:junit4] 2> 15951 T335 C34 P52666 /update {wt=javabin&version=2} {delete=[10046 (-1424541532952723456)]} 0 6
[junit4:junit4] 2> 15952 T353 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[59 (1424541532955869184)]} 0 0
[junit4:junit4] 2> 15953 T339 C34 P52666 /update {wt=javabin&version=2} {add=[59 (1424541532955869184)]} 0 5
[junit4:junit4] 2> 15955 T316 C33 P52654 /update {wt=javabin&version=2} {add=[10054 (1424541532962160640)]} 0 1
[junit4:junit4] 2> 15956 T315 C33 P52654 /update {wt=javabin&version=2} {add=[60 (1424541532964257792)]} 0 0
[junit4:junit4] 2> 15964 T352 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10054 (1424541532966354944)]} 0 3
[junit4:junit4] 2> 15967 T349 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[60 (1424541532968452096)]} 0 3
[junit4:junit4] 2> 15968 T338 C34 P52666 /update {wt=javabin&version=2} {add=[10054 (1424541532966354944)]} 0 10
[junit4:junit4] 2> 15968 T334 C34 P52666 /update {wt=javabin&version=2} {add=[60 (1424541532968452096)]} 0 8
[junit4:junit4] 2> 15970 T317 C33 P52654 /update {wt=javabin&version=2} {delete=[10051 (-1424541532978937856)]} 0 0
[junit4:junit4] 2> 15971 T320 C33 P52654 /update {wt=javabin&version=2} {delete=[53 (-1424541532979986432)]} 0 0
[junit4:junit4] 2> 15974 T351 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10051 (-1424541532981035008)]} 0 0
[junit4:junit4] 2> 15975 T337 C34 P52666 /update {wt=javabin&version=2} {delete=[10051 (-1424541532981035008)]} 0 3
[junit4:junit4] 2> 15975 T354 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[53 (-1424541532982083584)]} 0 0
[junit4:junit4] 2> 15976 T336 C34 P52666 /update {wt=javabin&version=2} {delete=[53 (-1424541532982083584)]} 0 3
[junit4:junit4] 2> 15980 T319 C33 P52654 /update {wt=javabin&version=2} {add=[10055 (1424541532987326464)]} 0 2
[junit4:junit4] 2> 15981 T318 C33 P52654 /update {wt=javabin&version=2} {add=[61 (1424541532989423616)]} 0 1
[junit4:junit4] 2> 15987 T350 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10055 (1424541532992569344)]} 0 1
[junit4:junit4] 2> 15988 T335 C34 P52666 /update {wt=javabin&version=2} {add=[10055 (1424541532992569344)]} 0 5
[junit4:junit4] 2> 15990 T353 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[61 (1424541532993617920)]} 0 1
[junit4:junit4] 2> 15992 T316 C33 P52654 /update {wt=javabin&version=2} {add=[10056 (1424541533000957952)]} 0 1
[junit4:junit4] 2> 15993 T339 C34 P52666 /update {wt=javabin&version=2} {add=[61 (1424541532993617920)]} 0 9
[junit4:junit4] 2> 15997 T315 C33 P52654 /update {wt=javabin&version=2} {delete=[54 (-1424541533007249408)]} 0 0
[junit4:junit4] 2> 16002 T352 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10056 (1424541533005152256)]} 0 3
[junit4:junit4] 2> 16004 T338 C34 P52666 /update {wt=javabin&version=2} {add=[10056 (1424541533005152256)]} 0 9
[junit4:junit4] 2> 16007 T349 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[54 (-1424541533014589440)]} 0 0
[junit4:junit4] 2> 16009 T334 C34 P52666 /update {wt=javabin&version=2} {delete=[54 (-1424541533014589440)]} 0 6
[junit4:junit4] 2> 16011 T317 C33 P52654 /update {wt=javabin&version=2} {add=[10057 (1424541533020880896)]} 0 1
[junit4:junit4] 2> 16012 T320 C33 P52654 /update {wt=javabin&version=2} {add=[62 (1424541533021929472)]} 0 1
[junit4:junit4] 2> 16018 T351 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[62 (1424541533024026625)]} 0 1
[junit4:junit4] 2> 16018 T354 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10057 (1424541533024026624)]} 0 1
[junit4:junit4] 2> 16019 T337 C34 P52666 /update {wt=javabin&version=2} {add=[62 (1424541533024026625)]} 0 6
[junit4:junit4] 2> 16019 T336 C34 P52666 /update {wt=javabin&version=2} {add=[10057 (1424541533024026624)]} 0 6
[junit4:junit4] 2> 16023 T319 C33 P52654 /update {wt=javabin&version=2} {add=[63 (1424541533033463809)]} 0 1
[junit4:junit4] 2> 16025 T318 C33 P52654 /update {wt=javabin&version=2} {add=[10058 (1424541533033463808)]} 0 3
[junit4:junit4] 2> 16034 T350 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10058 (1424541533039755264)]} 0 1
[junit4:junit4] 2> 16035 T353 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[63 (1424541533038706688)]} 0 1
[junit4:junit4] 2> 16035 T339 C34 P52666 /update {wt=javabin&version=2} {add=[10058 (1424541533039755264)]} 0 7
[junit4:junit4] 2> 16036 T335 C34 P52666 /update {wt=javabin&version=2} {add=[63 (1424541533038706688)]} 0 9
[junit4:junit4] 2> 16038 T316 C33 P52654 /update {wt=javabin&version=2} {delete=[55 (-1424541533050241024)]} 0 0
[junit4:junit4] 2> 16039 T315 C33 P52654 /update {wt=javabin&version=2} {add=[10059 (1424541533050241025)]} 0 1
[junit4:junit4] 2> 16044 T352 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[55 (-1424541533053386752)]} 0 0
[junit4:junit4] 2> 16046 T338 C34 P52666 /update {wt=javabin&version=2} {delete=[55 (-1424541533053386752)]} 0 5
[junit4:junit4] 2> 16047 T352 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10059 (1424541533054435328)]} 0 1
[junit4:junit4] 2> 16047 T334 C34 P52666 /update {wt=javabin&version=2} {add=[10059 (1424541533054435328)]} 0 5
[junit4:junit4] 2> 16049 T317 C33 P52654 /update {wt=javabin&version=2} {add=[64 (1424541533060726784)]} 0 1
[junit4:junit4] 2> 16050 T320 C33 P52654 /update {wt=javabin&version=2} {add=[10060 (1424541533062823936)]} 0 0
[junit4:junit4] 2> 16058 T351 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[64 (1424541533064921088)]} 0 3
[junit4:junit4] 2> 16059 T337 C34 P52666 /update {wt=javabin&version=2} {add=[64 (1424541533064921088)]} 0 7
[junit4:junit4] 2> 16066 T354 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10060 (1424541533067018240)]} 0 3
[junit4:junit4] 2> 16066 T319 C33 P52654 /update {wt=javabin&version=2} {add=[65 (1424541533078552576)]} 0 1
[junit4:junit4] 2> 16076 T336 C34 P52666 /update {wt=javabin&version=2} {add=[10060 (1424541533067018240)]} 0 22
[junit4:junit4] 2> 16080 T318 C33 P52654 /update {wt=javabin&version=2} {delete=[10053 (-1424541533094281216)]} 0 0
[junit4:junit4] 2> 16085 T350 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[65 (1424541533094281216)]} 0 1
[junit4:junit4] 2> 16085 T339 C34 P52666 /update {wt=javabin&version=2} {add=[65 (1424541533094281216)]} 0 5
[junit4:junit4] 2> 16088 T353 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10053 (-1424541533098475520)]} 0 1
[junit4:junit4] 2> 16089 T335 C34 P52666 /update {wt=javabin&version=2} {delete=[10053 (-1424541533098475520)]} 0 5
[junit4:junit4] 2> 16091 T316 C33 P52654 /update {wt=javabin&version=2} {add=[66 (1424541533103718400)]} 0 2
[junit4:junit4] 2> 16092 T315 C33 P52654 /update {wt=javabin&version=2} {add=[10061 (1424541533105815552)]} 0 1
[junit4:junit4] 2> 16097 T349 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[66 (1424541533107912704)]} 0 1
[junit4:junit4] 2> 16099 T338 C34 P52666 /update {wt=javabin&version=2} {add=[66 (1424541533107912704)]} 0 6
[junit4:junit4] 2> 16099 T352 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10061 (1424541533110009856)]} 0 1
[junit4:junit4] 2> 16100 T334 C34 P52666 /update {wt=javabin&version=2} {add=[10061 (1424541533110009856)]} 0 5
[junit4:junit4] 2> 16102 T317 C33 P52654 /update {wt=javabin&version=2} {delete=[56 (-1424541533117349888)]} 0 0
[junit4:junit4] 2> 16103 T317 C33 P52654 /update {wt=javabin&version=2} {delete=[10054 (-1424541533118398464)]} 0 0
[junit4:junit4] 2> 16114 T351 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[56 (-1424541533126787072)]} 0 0
[junit4:junit4] 2> 16114 T354 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10054 (-1424541533126787073)]} 0 0
[junit4:junit4] 2> 16116 T337 C34 P52666 /update {wt=javabin&version=2} {delete=[56 (-1424541533126787072)]} 0 4
[junit4:junit4] 2> 16116 T336 C34 P52666 /update {wt=javabin&version=2} {delete=[10054 (-1424541533126787073)]} 0 5
[junit4:junit4] 2> 16120 T319 C33 P52654 /update {wt=javabin&version=2} {add=[10062 (1424541533135175680)]} 0 1
[junit4:junit4] 2> 16122 T319 C33 P52654 /update {wt=javabin&version=2} {add=[67 (1424541533137272832)]} 0 1
[junit4:junit4] 2> 16128 T350 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[67 (1424541533140418560)]} 0 1
[junit4:junit4] 2> 16129 T353 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10062 (1424541533141467136)]} 0 1
[junit4:junit4] 2> 16130 T339 C34 P52666 /update {wt=javabin&version=2} {add=[67 (1424541533140418560)]} 0 6
[junit4:junit4] 2> 16130 T335 C34 P52666 /update {wt=javabin&version=2} {add=[10062 (1424541533141467136)]} 0 5
[junit4:junit4] 2> 16136 T315 C33 P52654 /update {wt=javabin&version=2} {add=[10063 (1424541533149855744)]} 0 3
[junit4:junit4] 2> 16138 T316 C33 P52654 /update {wt=javabin&version=2} {add=[68 (1424541533148807168)]} 0 6
[junit4:junit4] 2> 16147 T349 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10063 (1424541533156147200)]} 0 1
[junit4:junit4] 2> 16148 T338 C34 P52666 /update {wt=javabin&version=2} {add=[10063 (1424541533156147200)]} 0 9
[junit4:junit4] 2> 16150 T320 C33 P52654 /update {wt=javabin&version=2} {delete=[10055 (-1424541533167681536)]} 0 0
[junit4:junit4] 2> 16152 T352 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[68 (1424541533161390080)]} 0 1
[junit4:junit4] 2> 16155 T334 C34 P52666 /update {wt=javabin&version=2} {add=[68 (1424541533161390080)]} 0 11
[junit4:junit4] 2> 16156 T351 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[10055 (-1424541533171875840)]} 0 0
[junit4:junit4] 2> 16157 T337 C34 P52666 /update {wt=javabin&version=2} {delete=[10055 (-1424541533171875840)]} 0 3
[junit4:junit4] 2> 16159 T317 C33 P52654 /update {wt=javabin&version=2} {add=[69 (1424541533176070144)]} 0 1
[junit4:junit4] 2> 16162 T317 C33 P52654 /update {wt=javabin&version=2} {add=[10064 (1424541533179215872)]} 0 1
[junit4:junit4] 2> 16169 T354 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[69 (1424541533183410176)]} 0 1
[junit4:junit4] 2> 16171 T336 C34 P52666 /update {wt=javabin&version=2} {add=[69 (1424541533183410176)]} 0 6
[junit4:junit4] 2> 16174 T318 C33 P52654 /update {wt=javabin&version=2} {delete=[58 (-1424541533192847360)]} 0 0
[junit4:junit4] 2> 16176 T350 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10064 (1424541533185507328)]} 0 5
[junit4:junit4] 2> 16176 T335 C34 P52666 /update {wt=javabin&version=2} {add=[10064 (1424541533185507328)]} 0 9
[junit4:junit4] 2> 16180 T315 C33 P52654 /update {wt=javabin&version=2} {add=[10065 (1424541533198090240)]} 0 1
[junit4:junit4] 2> 16180 T349 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {delete=[58 (-1424541533195993088)]} 0 0
[junit4:junit4] 2> 16180 T339 C34 P52666 /update {wt=javabin&version=2} {delete=[58 (-1424541533195993088)]} 0 3
[junit4:junit4] 2> 16184 T316 C33 P52654 /update {wt=javabin&version=2} {add=[70 (1424541533203333120)]} 0 0
[junit4:junit4] 2> 16187 T352 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[10065 (1424541533201235968)]} 0 1
[junit4:junit4] 2> 16188 T338 C34 P52666 /update {wt=javabin&version=2} {add=[10065 (1424541533201235968)]} 0 6
[junit4:junit4] 2> 16192 T351 C32 P52675 /update {distrib.from=http://127.0.0.1:52666/_o/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} {add=[70 (1424541533206478848)]} 0 2
[junit4:junit4] 2> 16193 T320 C33 P52654 /update {wt=javabin&version=2} {add=[10066 (1424541533211721728)]} 0 1
[junit4:junit4] 2> 16193 T334 C34 P52666 /update {wt=javabin&version=2} {add=[70 (1424541533206478848)]} 0 6
[...truncated too long message...]
ore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4] 2> 80799 T302 oasc.CachingDirectoryFactory.close Releasing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358548656690\jetty2\index
[junit4:junit4] 2> 80799 T302 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-1358548656690\jetty2
[junit4:junit4] 2> 80800 T302 oasc.CachingDirectoryFactory.closeDirectory Closing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358548656690\jetty2
[junit4:junit4] 2> 80800 T302 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-1358548656690\jetty2\index
[junit4:junit4] 2> 80801 T302 oasc.CachingDirectoryFactory.closeDirectory Closing directory:C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.RecoveryZkTest-1358548656690\jetty2\index
[junit4:junit4] 2> 80820 T388 oaz.ClientCnxn$SendThread.logStartConnect Opening socket connection to server 127.0.0.1/127.0.0.1:52647. Will not attempt to authenticate using SASL (access denied (javax.security.auth.AuthPermission getLoginConfiguration))
[junit4:junit4] 2> 81925 T302 oaz.ZooKeeper.close Session: 0x13c4fcf6314000a closed
[junit4:junit4] 2> 81926 T389 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4] 2> 81942 T302 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/_o,null}
[junit4:junit4] 2> 82138 T302 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=32995DA31B4DD4C3 -Dtests.slow=true -Dtests.locale=mk_MK -Dtests.timezone=America/Sao_Paulo -Dtests.file.encoding=Cp1252
[junit4:junit4] FAILURE 82.1s | RecoveryZkTest.testDistribSearch <<<
[junit4:junit4] > Throwable #1: java.lang.AssertionError: expected:<133> but was:<2>
[junit4:junit4] > at __randomizedtesting.SeedInfo.seed([32995DA31B4DD4C3:B37FD3BB6C12B4FF]: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:39)
[junit4:junit4] > at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
[junit4:junit4] > at java.lang.reflect.Method.invoke(Method.java:597)
[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:662)
[junit4:junit4] 2> 82149 T302 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4] 2> NOTE: test params are: codec=DummyCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY, chunkSize=67)), sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=mk_MK, timezone=America/Sao_Paulo
[junit4:junit4] 2> NOTE: Windows 7 6.1 amd64/Sun Microsystems Inc. 1.6.0_38 (64-bit)/cpus=2,threads=1,free=69988992,total=230686720
[junit4:junit4] 2> NOTE: All tests run in this JVM: [TestGroupingSearch, TestReplicationHandler, FieldMutatingUpdateProcessorTest, ScriptEngineTest, TestFiltering, DisMaxRequestHandlerTest, TestSolr4Spatial, TestUpdate, TestDocumentBuilder, DateMathParserTest, StandardRequestHandlerTest, PingRequestHandlerTest, TestMergePolicyConfig, QueryElevationComponentTest, CopyFieldTest, SearchHandlerTest, RecoveryZkTest]
[junit4:junit4] Completed in 82.18s, 1 test, 1 failure <<< FAILURES!
[...truncated 715 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 40 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 64bit/jdk1.6.0_38 -XX:+UseParallelGC
Email was triggered for: Failure
Sending email for trigger: Failure