lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@sd-datasolutions.de>
Subject [JENKINS] Lucene-Solr-trunk-Linux (64bit/ibm-j9-jdk6) - Build # 2068 - Failure!
Date Mon, 29 Oct 2012 08:03:02 GMT
Build: http://jenkins.sd-datasolutions.de/job/Lucene-Solr-trunk-Linux/2068/
Java: 64bit/ibm-j9-jdk6 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}

2 tests failed.
FAILED:  junit.framework.TestSuite.org.apache.solr.cloud.RecoveryZkTest

Error Message:
Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=223, name=RecoveryThread, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.

Stack Trace:
com.carrotsearch.randomizedtesting.ResourceDisposalError: Resource in scope SUITE failed to close. Resource was registered from thread Thread[id=223, name=RecoveryThread, state=RUNNABLE, group=TGRP-RecoveryZkTest], registration stack trace below.
	at __randomizedtesting.SeedInfo.seed([2B623C77D0FC2A92]:0)
	at java.lang.Thread.getStackTraceImpl(Native Method)
	at java.lang.Thread.getStackTrace(Thread.java:1047)
	at com.carrotsearch.randomizedtesting.RandomizedContext.closeAtEnd(RandomizedContext.java:150)
	at org.apache.lucene.util.LuceneTestCase.closeAfterSuite(LuceneTestCase.java:514)
	at org.apache.lucene.util.LuceneTestCase.wrapDirectory(LuceneTestCase.java:958)
	at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:872)
	at org.apache.lucene.util.LuceneTestCase.newDirectory(LuceneTestCase.java:864)
	at org.apache.solr.core.MockDirectoryFactory.create(MockDirectoryFactory.java:33)
	at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:232)
	at org.apache.solr.core.CachingDirectoryFactory.get(CachingDirectoryFactory.java:192)
	at org.apache.solr.handler.SnapPuller.fetchLatestIndex(SnapPuller.java:373)
	at org.apache.solr.handler.ReplicationHandler.doFetch(ReplicationHandler.java:273)
	at org.apache.solr.cloud.RecoveryStrategy.replicate(RecoveryStrategy.java:152)
	at org.apache.solr.cloud.RecoveryStrategy.doRecovery(RecoveryStrategy.java:407)
	at org.apache.solr.cloud.RecoveryStrategy.run(RecoveryStrategy.java:222)
Caused by: java.lang.AssertionError: Directory not closed: MockDirWrapper(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/index5633856341tmp lockFactory=org.apache.lucene.store.SimpleFSLockFactory@3b4f3b4f)
	at org.junit.Assert.fail(Assert.java:93)
	at org.apache.lucene.util.CloseableDirectory.close(CloseableDirectory.java:47)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:602)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$2$1.apply(RandomizedRunner.java:599)
	at com.carrotsearch.randomizedtesting.RandomizedContext.closeResources(RandomizedContext.java:167)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$2.afterAlways(RandomizedRunner.java:615)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:43)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
	at java.lang.Thread.run(Thread.java:738)


REGRESSION:  org.apache.solr.update.SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds

Error Message:
searcher529 wasn't soon enough after soft529: 1351497191238 !< 1351497191024 + 200 (fudge)

Stack Trace:
java.lang.AssertionError: searcher529 wasn't soon enough after soft529: 1351497191238 !< 1351497191024 + 200 (fudge)
	at __randomizedtesting.SeedInfo.seed([2B623C77D0FC2A92:7AB6C5F7618F1A35]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.assertTrue(Assert.java:43)
	at org.apache.solr.update.SoftAutoCommitTest.testSoftAndHardCommitMaxTimeMixedAdds(SoftAutoCommitTest.java:137)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:60)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:37)
	at java.lang.reflect.Method.invoke(Method.java:611)
	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:45)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
	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:45)
	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:738)




Build Log:
[...truncated 7793 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.RecoveryZkTest
[junit4:junit4]   2> 4 T171 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-RecoveryZkTest-1351497108784
[junit4:junit4]   2> 14 T171 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 47 T172 oaz.Environment.logEnv Server environment:zookeeper.version=3.3.6-1366786, built on 07/29/2012 06:22 GMT
[junit4:junit4]   2> 48 T172 oaz.Environment.logEnv Server environment:host.name=serv1
[junit4:junit4]   2> 48 T172 oaz.Environment.logEnv Server environment:java.version=1.6.0
[junit4:junit4]   2> 49 T172 oaz.Environment.logEnv Server environment:java.vendor=IBM Corporation
[junit4:junit4]   2> 49 T172 oaz.Environment.logEnv Server environment:java.home=/opt/ibm/java-x86_64-60/jre
[junit4:junit4]   2> 50 T172 oaz.Environment.logEnv Server environment:java.class.path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/classes/test:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-test-framework/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/test-framework/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/codecs/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/analysis/common/lucene-analyzers-common-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/analysis/kuromoji/lucene-analyzers-kuromoji-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/analysis/phonetic/lucene-analyzers-phonetic-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/highlighter/lucene-highlighter-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/memory/lucene-memory-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/misc/lucene-misc-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/spatial/lucene-spatial-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/suggest/lucene-suggest-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/grouping/lucene-grouping-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/queries/lucene-queries-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/queryparser/lucene-queryparser-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-cli-1.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-codec-1.7.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-fileupload-1.2.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-lang-2.6.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/easymock-2.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/guava-r05.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/javax.servlet-api-3.0.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/spatial4j-0.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/commons-io-2.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/httpclient-4.1.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/httpcore-4.1.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/httpmime-4.1.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/jcl-over-slf4j-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/log4j-over-slf4j-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/slf4j-api-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/slf4j-jdk14-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/wstx-asl-3.2.7.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/zookeeper-3.3.6.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-continuation-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-deploy-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-http-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-io-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-jmx-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-security-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-server-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-servlet-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-util-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-webapp-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-xml-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/servlet-api-3.0.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/example-DIH/solr/db/lib/hsqldb-1.8.0.10.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/test-framework/lib/junit-4.10.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/test-framework/lib/randomizedtesting-runner-2.0.4.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-launcher.jar:/var/lib/jenkins/.ant/lib/ivy-2.2.0.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-jdepend.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-netrexx.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-antlr.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-commons-net.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-javamail.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-regexp.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-jsch.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-xalan2.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-junit4.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-jmf.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-junit.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-bcel.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-jai.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-commons-logging.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-resolver.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-oro.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-swing.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-bsf.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-log4j.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-testutil.jar:/opt/ibm/java-x86_64-60/lib/tools.jar:/var/lib/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.0.4.jar
[junit4:junit4]   2> 84 T172 oaz.Environment.logEnv Server environment:java.library.path=/opt/ibm/java-x86_64-60/jre/lib/amd64/default:/opt/ibm/java-x86_64-60/jre/lib/amd64:/usr/lib/jvm/j2sdk1.6-sun/jre/lib/amd64/server:/usr/lib/jvm/j2sdk1.6-sun/jre/lib/amd64:/usr/lib/jvm/j2sdk1.6-sun/jre/../lib/amd64:/usr/lib
[junit4:junit4]   2> 85 T172 oaz.Environment.logEnv Server environment:java.io.tmpdir=.
[junit4:junit4]   2> 85 T172 oaz.Environment.logEnv Server environment:java.compiler=j9jit24
[junit4:junit4]   2> 86 T172 oaz.Environment.logEnv Server environment:os.name=Linux
[junit4:junit4]   2> 86 T172 oaz.Environment.logEnv Server environment:os.arch=amd64
[junit4:junit4]   2> 87 T172 oaz.Environment.logEnv Server environment:os.version=3.2.0-32-generic
[junit4:junit4]   2> 87 T172 oaz.Environment.logEnv Server environment:user.name=jenkins
[junit4:junit4]   2> 88 T172 oaz.Environment.logEnv Server environment:user.home=/var/lib/jenkins
[junit4:junit4]   2> 88 T172 oaz.Environment.logEnv Server environment:user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 106 T172 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 114 T172 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 118 T171 oasc.ZkTestServer.run start zk server on port:53055
[junit4:junit4]   2> 135 T172 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 141 T171 oaz.Environment.logEnv Client environment:zookeeper.version=3.3.6-1366786, built on 07/29/2012 06:22 GMT
[junit4:junit4]   2> 142 T171 oaz.Environment.logEnv Client environment:host.name=serv1
[junit4:junit4]   2> 142 T171 oaz.Environment.logEnv Client environment:java.version=1.6.0
[junit4:junit4]   2> 143 T171 oaz.Environment.logEnv Client environment:java.vendor=IBM Corporation
[junit4:junit4]   2> 143 T171 oaz.Environment.logEnv Client environment:java.home=/opt/ibm/java-x86_64-60/jre
[junit4:junit4]   2> 144 T171 oaz.Environment.logEnv Client environment:java.class.path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/classes/test:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-test-framework/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/test-framework/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/codecs/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-solrj/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/analysis/common/lucene-analyzers-common-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/analysis/kuromoji/lucene-analyzers-kuromoji-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/analysis/phonetic/lucene-analyzers-phonetic-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/highlighter/lucene-highlighter-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/memory/lucene-memory-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/misc/lucene-misc-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/spatial/lucene-spatial-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/suggest/lucene-suggest-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/grouping/lucene-grouping-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/queries/lucene-queries-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/queryparser/lucene-queryparser-5.0-SNAPSHOT.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-cli-1.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-codec-1.7.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-fileupload-1.2.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/commons-lang-2.6.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/easymock-2.2.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/guava-r05.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/javax.servlet-api-3.0.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/lib/spatial4j-0.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/commons-io-2.1.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/httpclient-4.1.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/httpcore-4.1.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/httpmime-4.1.3.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/jcl-over-slf4j-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/log4j-over-slf4j-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/slf4j-api-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/slf4j-jdk14-1.6.4.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/wstx-asl-3.2.7.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/solrj/lib/zookeeper-3.3.6.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-continuation-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-deploy-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-http-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-io-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-jmx-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-security-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-server-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-servlet-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-util-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-webapp-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/jetty-xml-8.1.2.v20120308.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/lib/servlet-api-3.0.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/example/example-DIH/solr/db/lib/hsqldb-1.8.0.10.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/build/core/classes/java:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/test-framework/lib/junit-4.10.jar:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/test-framework/lib/randomizedtesting-runner-2.0.4.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-launcher.jar:/var/lib/jenkins/.ant/lib/ivy-2.2.0.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-jdepend.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-netrexx.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-antlr.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-commons-net.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-javamail.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-regexp.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-jsch.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-xalan2.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-junit4.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-jmf.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-junit.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-bcel.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-jai.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-commons-logging.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-resolver.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-oro.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-swing.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-bsf.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-apache-log4j.jar:/var/lib/jenkins/tools/Ant/ANT_1.8.2/lib/ant-testutil.jar:/opt/ibm/java-x86_64-60/lib/tools.jar:/var/lib/jenkins/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.0.4.jar
[junit4:junit4]   2> 145 T171 oaz.Environment.logEnv Client environment:java.library.path=/opt/ibm/java-x86_64-60/jre/lib/amd64/default:/opt/ibm/java-x86_64-60/jre/lib/amd64:/usr/lib/jvm/j2sdk1.6-sun/jre/lib/amd64/server:/usr/lib/jvm/j2sdk1.6-sun/jre/lib/amd64:/usr/lib/jvm/j2sdk1.6-sun/jre/../lib/amd64:/usr/lib
[junit4:junit4]   2> 146 T171 oaz.Environment.logEnv Client environment:java.io.tmpdir=.
[junit4:junit4]   2> 146 T171 oaz.Environment.logEnv Client environment:java.compiler=j9jit24
[junit4:junit4]   2> 147 T171 oaz.Environment.logEnv Client environment:os.name=Linux
[junit4:junit4]   2> 147 T171 oaz.Environment.logEnv Client environment:os.arch=amd64
[junit4:junit4]   2> 148 T171 oaz.Environment.logEnv Client environment:os.version=3.2.0-32-generic
[junit4:junit4]   2> 148 T171 oaz.Environment.logEnv Client environment:user.name=jenkins
[junit4:junit4]   2> 149 T171 oaz.Environment.logEnv Client environment:user.home=/var/lib/jenkins
[junit4:junit4]   2> 150 T171 oaz.Environment.logEnv Client environment:user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 152 T171 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:53055 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@62126212
[junit4:junit4]   2> 164 T177 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:53055
[junit4:junit4]   2> 164 T171 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 167 T177 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:53055, initiating session
[junit4:junit4]   2> 167 T173 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51800
[junit4:junit4]   2> 175 T173 oazs.NIOServerCnxn.doIO WARNING Exception causing close of session 0x0 due to java.io.IOException: ZooKeeperServer not running
[junit4:junit4]   2> 175 T173 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:51800 (no session established for client)
[junit4:junit4]   2> 176 T177 oaz.ClientCnxn$SendThread.run Unable to read additional data from server sessionid 0x0, likely server has closed socket, closing socket connection and attempting reconnect
[junit4:junit4]   2> 2039 T177 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server localhost.localdomain/127.0.0.1:53055
[junit4:junit4]   2> 2039 T177 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:53055, initiating session
[junit4:junit4]   2> 2040 T173 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51802
[junit4:junit4]   2> 2041 T173 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51802
[junit4:junit4]   2> 2043 T175 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 2145 T175 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aab814db90000 with negotiated timeout 10000 for client /127.0.0.1:51802
[junit4:junit4]   2> 2145 T177 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:53055, sessionid = 0x13aab814db90000, negotiated timeout = 10000
[junit4:junit4]   2> 2146 T178 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@62126212 name:ZooKeeperConnection Watcher:127.0.0.1:53055 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2147 T171 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2148 T171 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 2162 T176 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13aab814db90000
[junit4:junit4]   2> 2164 T178 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 2164 T171 oaz.ZooKeeper.close Session: 0x13aab814db90000 closed
[junit4:junit4]   2> 2164 T173 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:51802 which had sessionid 0x13aab814db90000
[junit4:junit4]   2> 2164 T171 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:53055/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@71b071b0
[junit4:junit4]   2> 2166 T181 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:53055
[junit4:junit4]   2> 2166 T171 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2167 T181 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:53055, initiating session
[junit4:junit4]   2> 2167 T173 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51803
[junit4:junit4]   2> 2168 T173 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51803
[junit4:junit4]   2> 2169 T175 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aab814db90001 with negotiated timeout 10000 for client /127.0.0.1:51803
[junit4:junit4]   2> 2169 T181 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:53055, sessionid = 0x13aab814db90001, negotiated timeout = 10000
[junit4:junit4]   2> 2170 T182 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@71b071b0 name:ZooKeeperConnection Watcher:127.0.0.1:53055/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2170 T171 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2173 T171 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 2179 T171 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 2182 T171 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 2185 T171 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 2188 T171 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 2192 T171 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 2197 T171 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 2198 T171 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 2201 T171 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 2202 T171 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 2213 T171 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 2214 T171 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 2216 T171 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 2217 T171 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 2220 T171 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 2221 T171 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 2228 T171 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 2228 T171 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 2238 T171 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 2239 T171 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 2242 T171 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 2243 T171 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 2245 T171 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 2246 T171 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 2249 T176 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x13aab814db90001
[junit4:junit4]   2> 2250 T173 oazs.NIOServerCnxn.closeSock Closed socket connection for client /127.0.0.1:51803 which had sessionid 0x13aab814db90001
[junit4:junit4]   2> 2250 T182 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 2251 T171 oaz.ZooKeeper.close Session: 0x13aab814db90001 closed
[junit4:junit4]   2> 2377 T171 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 2380 T171 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:41033
[junit4:junit4]   2> 2447 T171 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2448 T171 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1351497111030
[junit4:junit4]   2> 2455 T171 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1351497111030/'
[junit4:junit4]   2> 2469 T171 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 2470 T171 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 2470 T171 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1351497111030
[junit4:junit4]   2> 2471 T171 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1351497111030/solr.xml
[junit4:junit4]   2> 2471 T171 oasc.CoreContainer.<init> New CoreContainer 63439816
[junit4:junit4]   2> 2472 T171 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1351497111030/'
[junit4:junit4]   2> 2473 T171 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1351497111030/'
[junit4:junit4]   2> 2487 T171 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 2510 T171 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:53055/solr
[junit4:junit4]   2> 2516 T171 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:53055/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@29bb29bb
[junit4:junit4]   2> 2517 T192 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:53055
[junit4:junit4]   2> 2517 T171 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 2518 T192 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:53055, initiating session
[junit4:junit4]   2> 2518 T173 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51805
[junit4:junit4]   2> 2519 T173 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51805
[junit4:junit4]   2> 2521 T175 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aab814db90002 with negotiated timeout 5000 for client /127.0.0.1:51805
[junit4:junit4]   2> 2521 T192 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:53055, sessionid = 0x13aab814db90002, negotiated timeout = 5000
[junit4:junit4]   2> 2522 T193 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@29bb29bb name:ZooKeeperConnection Watcher:127.0.0.1:53055/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 2523 T171 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 2527 T176 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aab814db90002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2530 T171 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 2532 T171 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:41033_solr
[junit4:junit4]   2> 2534 T176 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aab814db90002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:41033_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:41033_solr
[junit4:junit4]   2> 2535 T171 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:41033_solr
[junit4:junit4]   2> 2538 T171 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 2538 T171 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 2539 T171 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 2540 T171 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 2540 T171 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 2541 T171 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 2541 T171 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 2542 T171 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 2543 T171 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 2544 T171 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 2551 T171 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 2558 T176 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aab814db90002 type:delete cxid:0x15 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   2> 2559 T171 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 2561 T171 oasc.Overseer.start Overseer (id=88571714530246658-127.0.0.1:41033_solr-n_0000000000) starting
[junit4:junit4]   2> 2562 T176 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aab814db90002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2565 T176 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aab814db90002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2567 T176 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aab814db90002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2570 T176 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aab814db90002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2571 T195 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 2571 T171 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 2574 T171 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 2584 T194 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 2587 T171 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1351497111030/collection1
[junit4:junit4]   2> 2587 T171 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 2588 T171 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 2589 T171 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 2590 T171 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1351497111030/collection1/'
[junit4:junit4]   2> 2591 T171 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-controljetty-1351497111030/collection1/lib/README' to classloader
[junit4:junit4]   2> 2592 T171 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-controljetty-1351497111030/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 2632 T171 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 2690 T171 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 2692 T171 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 2697 T171 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 3575 T171 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 3580 T171 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 3584 T171 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 3600 T171 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3606 T171 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 3612 T171 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3614 T171 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 3619 T171 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-controljetty-1351497111030/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1351497108784/control/data/
[junit4:junit4]   2> 3621 T171 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d7e2d7e
[junit4:junit4]   2> 3623 T171 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 3626 T171 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(org.apache.lucene.store.RAMDirectory@b790b79 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@cc20cc2) assuming 'simple'
[junit4:junit4]   2> 3626 T171 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351497108784/control/data forceNew:false
[junit4:junit4]   2> 3627 T171 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351497108784/control/data
[junit4:junit4]   2> 3627 T171 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1351497108784/control/data/index/
[junit4:junit4]   2> 3628 T171 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1351497108784/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 3630 T171 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351497108784/control/data/index forceNew:false
[junit4:junit4]   2> 3651 T171 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/index5633856340tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@301a301a; maxCacheMB=0.4072265625 maxMergeSizeMB=0.00390625)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 3652 T171 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 3653 T171 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351497108784/control/data/index
[junit4:junit4]   2> 3653 T171 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 3657 T171 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 3658 T171 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 3658 T171 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 3659 T171 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 3660 T171 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 3660 T171 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 3672 T171 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 3674 T171 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 3675 T171 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 3677 T171 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 3678 T171 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 3679 T171 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 3680 T171 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 3680 T171 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 3681 T171 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 3682 T171 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 3684 T171 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3685 T171 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3686 T171 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3687 T171 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3688 T171 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3689 T171 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3690 T171 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 3691 T171 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 3692 T171 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 3694 T171 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 3695 T171 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 3697 T171 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, PROTWORDS.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML, SCHEMA.XML, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 3699 T171 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351497108784/control/data
[junit4:junit4]   2> 3701 T171 oass.SolrIndexSearcher.<init> Opening Searcher@7b9d7b9d main
[junit4:junit4]   2> 3726 T171 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 3727 T171 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 3728 T171 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 3737 T171 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 3793 T196 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7b9d7b9d main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 3823 T171 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 3824 T176 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aab814db90002 type:create cxid:0x56 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue Error:KeeperErrorCode = NoNode for /solr/overseer/queue
[junit4:junit4]   2> 3826 T171 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 3827 T171 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:41033/solr shardId:control_shard
[junit4:junit4]   2> 3829 T171 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4]   2> 3835 T171 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 3842 T176 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aab814db90002 type:delete cxid:0x65 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/control_collection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/control_collection/leaders
[junit4:junit4]   2> 3843 T171 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 3844 T176 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aab814db90002 type:create cxid:0x66 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4090 T194 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4091 T194 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:41033/solr",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:41033_solr",
[junit4:junit4]   2> 	  "numShards":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "shard":"control_shard",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 4093 T176 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aab814db90002 type:create cxid:0x6d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer/queue-work Error:KeeperErrorCode = NoNode for /solr/overseer/queue-work
[junit4:junit4]   2> 4102 T193 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 4347 T171 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 4349 T171 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 4350 T171 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:41033/solr/collection1/
[junit4:junit4]   2> 4352 T171 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 4353 T171 oasc.SyncStrategy.syncToMe http://127.0.0.1:41033/solr/collection1/ has no replicas
[junit4:junit4]   2> 4355 T171 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:41033/solr/collection1/
[junit4:junit4]   2> 4356 T171 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 4362 T176 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aab814db90002 type:create cxid:0x8c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4607 T194 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4612 T193 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 4615 T171 oasc.ZkController.register We are http://127.0.0.1:41033/solr/collection1/ and leader is http://127.0.0.1:41033/solr/collection1/
[junit4:junit4]   2> 4616 T171 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:41033/solr
[junit4:junit4]   2> 4616 T171 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 4617 T171 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 4620 T171 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 4621 T171 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 4622 T171 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 4622 T171 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 4627 T171 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 4633 T171 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:53055/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@655f655f
[junit4:junit4]   2> 4634 T197 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:53055
[junit4:junit4]   2> 4634 T171 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4638 T197 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:53055, initiating session
[junit4:junit4]   2> 4638 T173 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51806
[junit4:junit4]   2> 4639 T173 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51806
[junit4:junit4]   2> 4641 T175 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aab814db90003 with negotiated timeout 10000 for client /127.0.0.1:51806
[junit4:junit4]   2> 4641 T197 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:53055, sessionid = 0x13aab814db90003, negotiated timeout = 10000
[junit4:junit4]   2> 4641 T198 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@655f655f name:ZooKeeperConnection Watcher:127.0.0.1:53055/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4642 T171 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4643 T171 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4644 T171 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 4782 T171 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 4785 T171 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:48470
[junit4:junit4]   2> 4787 T171 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4788 T171 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1351497113423
[junit4:junit4]   2> 4788 T171 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1351497113423/'
[junit4:junit4]   2> 4805 T171 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 4806 T171 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 4807 T171 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1351497113423
[junit4:junit4]   2> 4808 T171 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1351497113423/solr.xml
[junit4:junit4]   2> 4809 T171 oasc.CoreContainer.<init> New CoreContainer 118425359
[junit4:junit4]   2> 4810 T171 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1351497113423/'
[junit4:junit4]   2> 4810 T171 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1351497113423/'
[junit4:junit4]   2> 4836 T171 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 4850 T171 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:53055/solr
[junit4:junit4]   2> 4851 T171 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:53055/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@72687268
[junit4:junit4]   2> 4852 T208 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:53055
[junit4:junit4]   2> 4852 T171 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 4855 T208 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:53055, initiating session
[junit4:junit4]   2> 4855 T173 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51807
[junit4:junit4]   2> 4859 T173 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51807
[junit4:junit4]   2> 4861 T175 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aab814db90004 with negotiated timeout 5000 for client /127.0.0.1:51807
[junit4:junit4]   2> 4861 T208 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:53055, sessionid = 0x13aab814db90004, negotiated timeout = 5000
[junit4:junit4]   2> 4862 T209 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@72687268 name:ZooKeeperConnection Watcher:127.0.0.1:53055/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 4863 T171 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 4865 T176 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aab814db90004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4867 T176 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aab814db90004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 4868 T171 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:48470_solr
[junit4:junit4]   2> 4869 T176 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aab814db90004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:48470_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:48470_solr
[junit4:junit4]   2> 4870 T171 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:48470_solr
[junit4:junit4]   2> 4872 T193 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (1)
[junit4:junit4]   2> 4873 T198 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4874 T193 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4:junit4]   2> 4874 T171 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 4875 T171 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 4876 T171 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 4877 T171 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 4877 T171 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 4878 T171 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 4879 T171 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 4880 T171 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 4881 T171 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 4882 T171 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 4897 T171 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 4902 T171 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1351497113423/collection1
[junit4:junit4]   2> 4903 T171 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 4904 T171 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 4904 T171 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 4906 T171 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1351497113423/collection1/'
[junit4:junit4]   2> 4907 T171 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty1-1351497113423/collection1/lib/README' to classloader
[junit4:junit4]   2> 4908 T171 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty1-1351497113423/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 4957 T171 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 5014 T171 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 5016 T171 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 5023 T171 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 5115 T194 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5116 T194 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:41033/solr",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:41033_solr",
[junit4:junit4]   2> 	  "numShards":null,
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"control_collection",
[junit4:junit4]   2> 	  "shard":"control_shard",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 5120 T198 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 5120 T209 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 5120 T193 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 5473 T171 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 5478 T171 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 5481 T171 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 5497 T171 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5501 T171 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 5505 T171 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5507 T171 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 5508 T171 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty1-1351497113423/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1351497108784/jetty1/
[junit4:junit4]   2> 5509 T171 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d7e2d7e
[junit4:junit4]   2> 5510 T171 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 5511 T171 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(org.apache.lucene.store.RAMDirectory@618e618e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@62d762d7) assuming 'simple'
[junit4:junit4]   2> 5512 T171 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351497108784/jetty1 forceNew:false
[junit4:junit4]   2> 5512 T171 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351497108784/jetty1
[junit4:junit4]   2> 5513 T171 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1351497108784/jetty1/index/
[junit4:junit4]   2> 5514 T171 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1351497108784/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 5514 T171 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351497108784/jetty1/index forceNew:false
[junit4:junit4]   2> 5523 T171 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2f912f91 lockFactory=org.apache.lucene.store.NativeFSLockFactory@446d446d),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 5524 T171 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 5526 T171 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351497108784/jetty1/index
[junit4:junit4]   2> 5526 T171 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 5530 T171 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 5531 T171 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 5532 T171 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 5532 T171 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 5533 T171 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 5534 T171 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 5535 T171 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 5536 T171 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 5537 T171 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 5538 T171 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 5539 T171 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 5540 T171 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 5542 T171 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 5542 T171 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 5543 T171 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 5545 T171 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 5547 T171 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5548 T171 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5549 T171 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5550 T171 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5552 T171 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5553 T171 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5554 T171 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 5555 T171 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 5556 T171 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 5558 T171 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 5559 T171 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 5561 T171 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, PROTWORDS.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML, SCHEMA.XML, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 5563 T171 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351497108784/jetty1
[junit4:junit4]   2> 5564 T171 oass.SolrIndexSearcher.<init> Opening Searcher@3ae43ae4 main
[junit4:junit4]   2> 5565 T171 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 5566 T171 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 5567 T171 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 5576 T171 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 5633 T210 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3ae43ae4 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 5658 T171 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 6126 T194 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6127 T194 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:48470/solr",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:48470_solr",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 6128 T194 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with numShards 1
[junit4:junit4]   2> 6133 T209 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 6133 T198 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 6133 T193 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 6161 T171 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 6162 T171 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:48470/solr shardId:shard1
[junit4:junit4]   2> 6163 T171 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 6168 T176 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aab814db90004 type:delete cxid:0x45 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 6169 T171 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 6170 T176 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aab814db90004 type:create cxid:0x46 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6172 T171 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 6173 T171 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 6174 T171 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:48470/solr/collection1/
[junit4:junit4]   2> 6175 T171 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 6176 T171 oasc.SyncStrategy.syncToMe http://127.0.0.1:48470/solr/collection1/ has no replicas
[junit4:junit4]   2> 6177 T171 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:48470/solr/collection1/
[junit4:junit4]   2> 6177 T171 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 6182 T176 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aab814db90004 type:create cxid:0x50 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6637 T194 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6644 T198 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 6645 T193 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 6645 T209 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 6685 T171 oasc.ZkController.register We are http://127.0.0.1:48470/solr/collection1/ and leader is http://127.0.0.1:48470/solr/collection1/
[junit4:junit4]   2> 6686 T171 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:48470/solr
[junit4:junit4]   2> 6686 T171 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 6687 T171 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 6689 T171 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6690 T171 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 6690 T171 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 6691 T171 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 6840 T171 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 6843 T171 oejs.AbstractConnector.doStart Started SelectChannelConnector@0.0.0.0:60311
[junit4:junit4]   2> 6844 T171 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6845 T171 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1351497115474
[junit4:junit4]   2> 6846 T171 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1351497115474/'
[junit4:junit4]   2> 6861 T171 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 6866 T171 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6866 T171 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1351497115474
[junit4:junit4]   2> 6867 T171 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1351497115474/solr.xml
[junit4:junit4]   2> 6868 T171 oasc.CoreContainer.<init> New CoreContainer 613950616
[junit4:junit4]   2> 6869 T171 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1351497115474/'
[junit4:junit4]   2> 6870 T171 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1351497115474/'
[junit4:junit4]   2> 6891 T171 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 6917 T171 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:53055/solr
[junit4:junit4]   2> 6918 T171 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:53055/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@31d231d2
[junit4:junit4]   2> 6919 T220 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:53055
[junit4:junit4]   2> 6919 T171 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 6920 T220 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost.localdomain/127.0.0.1:53055, initiating session
[junit4:junit4]   2> 6921 T173 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /127.0.0.1:51809
[junit4:junit4]   2> 6921 T173 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /127.0.0.1:51809
[junit4:junit4]   2> 6923 T175 oazs.NIOServerCnxn.finishSessionInit Established session 0x13aab814db90005 with negotiated timeout 5000 for client /127.0.0.1:51809
[junit4:junit4]   2> 6923 T220 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost.localdomain/127.0.0.1:53055, sessionid = 0x13aab814db90005, negotiated timeout = 5000
[junit4:junit4]   2> 6924 T221 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@31d231d2 name:ZooKeeperConnection Watcher:127.0.0.1:53055/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 6924 T171 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 6926 T176 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aab814db90005 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6927 T176 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aab814db90005 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6928 T171 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60311_solr
[junit4:junit4]   2> 6929 T176 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x13aab814db90005 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:60311_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:60311_solr
[junit4:junit4]   2> 6930 T171 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60311_solr
[junit4:junit4]   2> 6934 T198 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 6934 T209 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 6934 T171 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 6934 T193 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (2)
[junit4:junit4]   2> 6935 T198 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 6935 T171 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 6936 T209 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 6937 T171 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 6937 T193 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4:junit4]   2> 6938 T171 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 6939 T171 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 6940 T171 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 6941 T171 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 6941 T171 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 6942 T171 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 6943 T171 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 6953 T171 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 6960 T171 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1351497115474/collection1
[junit4:junit4]   2> 6961 T171 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 6962 T171 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 6963 T171 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 6968 T171 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1351497115474/collection1/'
[junit4:junit4]   2> 6970 T171 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty2-1351497115474/collection1/lib/README' to classloader
[junit4:junit4]   2> 6971 T171 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.RecoveryZkTest-jetty2-1351497115474/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 7018 T171 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   2> 7066 T171 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 7068 T171 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 7073 T171 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 7149 T194 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7150 T194 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:48470/solr",
[junit4:junit4]   2> 	  "state":"active",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:48470_solr",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 7154 T221 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 7154 T198 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 7154 T193 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 7154 T209 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 7478 T171 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 7486 T171 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 7489 T171 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 7499 T171 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 7504 T171 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 7516 T171 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 7518 T171 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 7519 T171 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-jetty2-1351497115474/collection1/, dataDir=./org.apache.solr.cloud.RecoveryZkTest-1351497108784/jetty2/
[junit4:junit4]   2> 7520 T171 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2d7e2d7e
[junit4:junit4]   2> 7521 T171 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   2> 7522 T171 oasc.CachingDirectoryFactory.injectLockFactory WARNING No lockType configured for MockDirWrapper(org.apache.lucene.store.RAMDirectory@191a191a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1a631a63) assuming 'simple'
[junit4:junit4]   2> 7523 T171 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351497108784/jetty2 forceNew:false
[junit4:junit4]   2> 7523 T171 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351497108784/jetty2
[junit4:junit4]   2> 7524 T171 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=./org.apache.solr.cloud.RecoveryZkTest-1351497108784/jetty2/index/
[junit4:junit4]   2> 7525 T171 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.RecoveryZkTest-1351497108784/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 7525 T171 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351497108784/jetty2/index forceNew:false
[junit4:junit4]   2> 7528 T171 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6e856e85 lockFactory=org.apache.lucene.store.NativeFSLockFactory@afb0afb),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 7529 T171 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 7531 T171 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351497108784/jetty2/index
[junit4:junit4]   2> 7532 T171 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 7536 T171 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 7537 T171 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 7538 T171 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 7539 T171 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 7540 T171 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 7540 T171 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 7541 T171 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 7542 T171 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 7543 T171 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 7544 T171 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 7545 T171 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 7546 T171 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 7547 T171 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 7548 T171 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 7549 T171 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 7550 T171 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 7551 T171 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7552 T171 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7553 T171 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7554 T171 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7555 T171 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7556 T171 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7557 T171 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7558 T171 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 7559 T171 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 7560 T171 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 7562 T171 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 7564 T171 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, PROTWORDS.TXT, STOPWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, CURRENCY.XML, SCHEMA.XML, SYNONYMS.TXT, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 7565 T171 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351497108784/jetty2
[junit4:junit4]   2> 7566 T171 oass.SolrIndexSearcher.<init> Opening Searcher@25672567 main
[junit4:junit4]   2> 7568 T171 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 7568 T171 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 7570 T171 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 7579 T171 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 7642 T222 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@25672567 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 7665 T171 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 8161 T194 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8162 T194 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:60311/solr",
[junit4:junit4]   2> 	  "state":"down",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:60311_solr",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":null,
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 8165 T193 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 8165 T209 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 8167 T198 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 8167 T221 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 8169 T171 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 8170 T171 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:60311/solr shardId:shard1
[junit4:junit4]   2> 8173 T171 oasc.ZkController.register We are http://127.0.0.1:60311/solr/collection1/ and leader is http://127.0.0.1:48470/solr/collection1/
[junit4:junit4]   2> 8175 T171 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:60311/solr
[junit4:junit4]   2> 8176 T171 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 8177 T171 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C13 name=collection1 org.apache.solr.core.SolrCore@18681868 url=http://127.0.0.1:60311/solr/collection1 node=127.0.0.1:60311_solr
[junit4:junit4]   2> 8180 T223 C13 P60311 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 8181 T171 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8181 T223 C13 P60311 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 8182 T223 C13 P60311 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4:junit4]   2> 8182 T171 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   2> 8182 T171 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 8183 T223 C13 P60311 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:48470/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 8183 T171 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 8186 T223 C13 P60311 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4]   2> 8189 T171 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8191 T223 C13 P60311 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:60311/solr START replicas=[http://127.0.0.1:48470/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 8192 T223 C13 P60311 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 8192 T223 C13 P60311 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 8193 T223 C13 P60311 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 8193 T223 C13 P60311 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> ASYNC  NEW_CORE C14 name=collection1 org.apache.solr.core.SolrCore@2030203 url=http://127.0.0.1:48470/solr/collection1 node=127.0.0.1:48470_solr
[junit4:junit4]   2> 8198 T205 C14 P48470 REQ /get {version=2&getVersions=100&qt=/get&wt=javabin&distrib=false} status=0 QTime=0 
[junit4:junit4]   2> ASYNC  NEW_CORE C15 name=collection1 org.apache.solr.core.SolrCore@229a229a url=http://127.0.0.1:41033/solr/collection1 node=127.0.0.1:41033_solr
[junit4:junit4]   2> 8199 T191 C15 P41033 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16
[junit4:junit4]   2> 8205 T191 C15 P41033 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351497108784/control/data
[junit4:junit4]   2> 8209 T191 C15 P41033 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.NIOFSDirectory@/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/index5633856340tmp lockFactory=org.apache.lucene.store.NativeFSLockFactory@301a301a; maxCacheMB=0.4072265625 maxMergeSizeMB=0.00390625)),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 8210 T191 C15 P41033 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 8271 T189 C15 P41033 /update {version=2&wt=javabin} {add=[1 (1417147440937566209)]} 0 74
[junit4:junit4]   2> 8271 T191 C15 P41033 /update {version=2&wt=javabin} {add=[10001 (1417147440937566208)]} 0 75
[junit4:junit4]   2> 8309 T204 C14 P48470 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351497108784/jetty1
[junit4:junit4]   2> 8312 T204 C14 P48470 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@2f912f91 lockFactory=org.apache.lucene.store.NativeFSLockFactory@446d446d),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 8313 T204 C14 P48470 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 8315 T204 C14 P48470 /update {version=2&wt=javabin} {add=[1 (1417147441046618112)]} 0 6
[junit4:junit4]   2> 8315 T205 C14 P48470 /update {version=2&wt=javabin} {add=[10001 (1417147441048715264)]} 0 4
[junit4:junit4]   2> 8318 T188 C15 P41033 /update {version=2&wt=javabin} {add=[2 (1417147441056055296)]} 0 0
[junit4:junit4]   2> 8319 T190 C15 P41033 /update {version=2&wt=javabin} {add=[10002 (1417147441056055297)]} 0 1
[junit4:junit4]   2> 8322 T206 C14 P48470 /update {version=2&wt=javabin} {add=[2 (1417147441059201024)]} 0 1
[junit4:junit4]   2> 8322 T204 C14 P48470 /update {version=2&wt=javabin} {add=[10002 (1417147441059201025)]} 0 1
[junit4:junit4]   2> 8325 T189 C15 P41033 /update {version=2&wt=javabin} {add=[3 (1417147441062346752)]} 0 1
[junit4:junit4]   2> 8325 T191 C15 P41033 /update {version=2&wt=javabin} {add=[10003 (1417147441062346753)]} 0 1
[junit4:junit4]   2> 8328 T205 C14 P48470 /update {version=2&wt=javabin} {add=[3 (1417147441065492480)]} 0 1
[junit4:junit4]   2> 8330 T206 C14 P48470 /update {version=2&wt=javabin} {add=[10003 (1417147441066541056)]} 0 3
[junit4:junit4]   2> 8332 T188 C15 P41033 /update {version=2&wt=javabin} {add=[4 (1417147441069686784)]} 0 1
[junit4:junit4]   2> 8334 T190 C15 P41033 /update {version=2&wt=javabin} {add=[10004 (1417147441071783936)]} 0 1
[junit4:junit4]   2> 8335 T204 C14 P48470 /update {version=2&wt=javabin} {add=[4 (1417147441072832512)]} 0 1
[junit4:junit4]   2> 8336 T205 C14 P48470 /update {version=2&wt=javabin} {add=[10004 (1417147441074929664)]} 0 0
[junit4:junit4]   2> 8337 T189 C15 P41033 /update {version=2&wt=javabin} {delete=[3 (-1417147441075978240)]} 0 0
[junit4:junit4]   2> 8338 T191 C15 P41033 /update {version=2&wt=javabin} {delete=[10003 (-1417147441077026816)]} 0 0
[junit4:junit4]   2> 8339 T206 C14 P48470 /update {version=2&wt=javabin} {delete=[3 (-1417147441078075392)]} 0 0
[junit4:junit4]   2> 8341 T204 C14 P48470 /update {version=2&wt=javabin} {delete=[10003 (-1417147441080172544)]} 0 0
[junit4:junit4]   2> 8342 T188 C15 P41033 /update {version=2&wt=javabin} {add=[5 (1417147441081221120)]} 0 1
[junit4:junit4]   2> 8345 T205 C14 P48470 /update {version=2&wt=javabin} {add=[5 (1417147441083318272)]} 0 1
[junit4:junit4]   2> 8349 T189 C15 P41033 /update {version=2&wt=javabin} {add=[6 (1417147441087512576)]} 0 2
[junit4:junit4]   2> 8355 T190 C15 P41033 /update {version=2&wt=javabin} {add=[10005 (1417147441082269696)]} 0 12
[junit4:junit4]   2> 8357 T206 C14 P48470 /update {version=2&wt=javabin} {add=[6 (1417147441090658304)]} 0 6
[junit4:junit4]   2> 8358 T204 C14 P48470 /update {version=2&wt=javabin} {add=[10005 (1417147441096949760)]} 0 1
[junit4:junit4]   2> 8360 T191 C15 P41033 /update {version=2&wt=javabin} {add=[7 (1417147441100095488)]} 0 1
[junit4:junit4]   2> 8361 T188 C15 P41033 /update {version=2&wt=javabin} {add=[10006 (1417147441100095489)]} 0 1
[junit4:junit4]   2> 8372 T205 C14 P48470 /update {version=2&wt=javabin} {add=[7 (1417147441102192640)]} 0 10
[junit4:junit4]   2> 8372 T206 C14 P48470 /update {version=2&wt=javabin} {add=[10006 (1417147441112678400)]} 0 0
[junit4:junit4]   2> 8374 T189 C15 P41033 /update {version=2&wt=javabin} {delete=[6 (-1417147441114775552)]} 0 0
[junit4:junit4]   2> 8375 T190 C15 P41033 /update {version=2&wt=javabin} {add=[10007 (1417147441114775553)]} 0 1
[junit4:junit4]   2> 8377 T204 C14 P48470 /update {version=2&wt=javabin} {delete=[6 (-1417147441117921280)]} 0 1
[junit4:junit4]   2> 8378 T205 C14 P48470 /update {version=2&wt=javabin} {add=[10007 (1417147441117921281)]} 0 1
[junit4:junit4]   2> 8380 T191 C15 P41033 /update {version=2&wt=javabin} {add=[8 (1417147441120018432)]} 0 1
[junit4:junit4]   2> 8380 T188 C15 P41033 /update {version=2&wt=javabin} {delete=[10006 (-1417147441121067008)]} 0 0
[junit4:junit4]   2> 8383 T204 C14 P48470 /update {version=2&wt=javabin} {delete=[10006 (-1417147441123164161)]} 0 1
[junit4:junit4]   2> 8383 T206 C14 P48470 /update {version=2&wt=javabin} {add=[8 (1417147441123164160)]} 0 1
[junit4:junit4]   2> 8385 T189 C15 P41033 /update {version=2&wt=javabin} {add=[10008 (1417147441126309888)]} 0 0
[junit4:junit4]   2> 8386 T190 C15 P41033 /update {version=2&wt=javabin} {add=[9 (1417147441126309889)]} 0 1
[junit4:junit4]   2> 8388 T205 C14 P48470 /update {version=2&wt=javabin} {add=[10008 (1417147441129455616)]} 0 1
[junit4:junit4]   2> 8390 T205 C14 P48470 /update {version=2&wt=javabin} {add=[9 (1417147441130504192)]} 0 1
[junit4:junit4]   2> 8392 T191 C15 P41033 /update {version=2&wt=javabin} {add=[10009 (1417147441132601344)]} 0 1
[junit4:junit4]   2> 8396 T188 C15 P41033 /update {version=2&wt=javabin} {add=[10 (1417147441136795648)]} 0 1
[junit4:junit4]   2> 8396 T206 C14 P48470 /update {version=2&wt=javabin} {add=[10009 (1417147441136795648)]} 0 1
[junit4:junit4]   2> 8399 T204 C14 P48470 /update {version=2&wt=javabin} {add=[10 (1417147441139941376)]} 0 1
[junit4:junit4]   2> 8404 T190 C15 P41033 /update {version=2&wt=javabin} {add=[11 (1417147441143087105)]} 0 3
[junit4:junit4]   2> 8416 T189 C15 P41033 /update {version=2&wt=javabin} {add=[10010 (1417147441143087104)]} 0 15
[junit4:junit4]   2> 8416 T205 C14 P48470 /update {version=2&wt=javabin} {add=[11 (1417147441149378560)]} 0 9
[junit4:junit4]   2> 8420 T191 C15 P41033 /update {version=2&wt=javabin} {delete=[11 (-1417147441163010048)]} 0 1
[junit4:junit4]   2> 8420 T206 C14 P48470 /update {version=2&wt=javabin} {add=[10010 (1417147441161961472)]} 0 1
[junit4:junit4]   2> 8423 T204 C14 P48470 /update {version=2&wt=javabin} {delete=[11 (-1417147441166155776)]} 0 1
[junit4:junit4]   2> 8424 T188 C15 P41033 /update {version=2&wt=javabin} {add=[10011 (1417147441166155776)]} 0 1
[junit4:junit4]   2> 8426 T190 C15 P41033 /update {version=2&wt=javabin} {add=[12 (1417147441169301504)]} 0 1
[junit4:junit4]   2> 8428 T205 C14 P48470 /update {version=2&wt=javabin} {add=[10011 (1417147441171398656)]} 0 0
[junit4:junit4]   2> 8430 T206 C14 P48470 /update {version=2&wt=javabin} {add=[12 (1417147441172447232)]} 0 1
[junit4:junit4]   2> 8431 T189 C15 P41033 /update {version=2&wt=javabin} {delete=[10011 (-1417147441174544384)]} 0 0
[junit4:junit4]   2> 8433 T191 C15 P41033 /update {version=2&wt=javabin} {add=[13 (1417147441175592960)]} 0 1
[junit4:junit4]   2> 8434 T204 C14 P48470 /update {version=2&wt=javabin} {delete=[10011 (-1417147441177690112)]} 0 0
[junit4:junit4]   2> 8436 T206 C14 P48470 /update {version=2&wt=javabin} {add=[13 (1417147441178738688)]} 0 1
[junit4:junit4]   2> 8438 T188 C15 P41033 /update {version=2&wt=javabin} {add=[10012 (1417147441180835840)]} 0 1
[junit4:junit4]   2> 8439 T190 C15 P41033 /update {version=2&wt=javabin} {delete=[13 (-1417147441182932992)]} 0 0
[junit4:junit4]   2> 8442 T206 C14 P48470 /update {version=2&wt=javabin} {delete=[13 (-1417147441185030145)]} 0 1
[junit4:junit4]   2> 8443 T205 C14 P48470 /update {version=2&wt=javabin} {add=[10012 (1417147441185030144)]} 0 2
[junit4:junit4]   2> 8446 T189 C15 P41033 /update {version=2&wt=javabin} {add=[14 (1417147441189224448)]} 0 1
[junit4:junit4]   2> 8446 T191 C15 P41033 /update {version=2&wt=javabin} {add=[10013 (1417147441190273024)]} 0 0
[junit4:junit4]   2> 8459 T204 C14 P48470 /update {version=2&wt=javabin} {add=[14 (1417147441203904512)]} 0 1
[junit4:junit4]   2> 8462 T206 C14 P48470 /update {version=2&wt=javabin} {add=[10013 (1417147441204953088)]} 0 2
[junit4:junit4]   2> 8464 T188 C15 P41033 /update {version=2&wt=javabin} {add=[15 (1417147441208098816)]} 0 1
[junit4:junit4]   2> 8465 T190 C15 P41033 /update {version=2&wt=javabin} {delete=[10013 (-1417147441209147392)]} 0 1
[junit4:junit4]   2> 8468 T204 C14 P48470 /update {version=2&wt=javabin} {delete=[10013 (-1417147441213341696)]} 0 0
[junit4:junit4]   2> 8468 T205 C14 P48470 /update {version=2&wt=javabin} {add=[15 (1417147441212293120)]} 0 1
[junit4:junit4]   2> 8472 T189 C15 P41033 /update {version=2&wt=javabin} {add=[10014 (1417147441216487424)]} 0 1
[junit4:junit4]   2> 8476 T206 C14 P48470 /update {version=2&wt=javabin} {add=[10014 (1417147441220681728)]} 0 1
[junit4:junit4]   2> 8490 T188 C15 P41033 /update {version=2&wt=javabin} {add=[10015 (1417147441230118912)]} 0 6
[junit4:junit4]   2> 8492 T191 C15 P41033 /update {version=2&wt=javabin} {add=[16 (1417147441217536000)]} 0 20
[junit4:junit4]   2> 8495 T205 C14 P48470 /update {version=2&wt=javabin} {add=[16 (1417147441241653248)]} 0 1
[junit4:junit4]   2> 8496 T204 C14 P48470 /update {version=2&wt=javabin} {add=[10015 (1417147441239556096)]} 0 3
[junit4:junit4]   2> 8499 T190 C15 P41033 /update {version=2&wt=javabin} {delete=[16 (-1417147441244798976)]} 0 1
[junit4:junit4]   2> 8499 T189 C15 P41033 /update {version=2&wt=javabin} {add=[10016 (1417147441245847552)]} 0 1
[junit4:junit4]   2> 8501 T206 C14 P48470 /update {version=2&wt=javabin} {delete=[16 (-1417147441247944704)]} 0 0
[junit4:junit4]   2> 8502 T204 C14 P48470 /update {version=2&wt=javabin} {add=[10016 (1417147441247944705)]} 0 1
[junit4:junit4]   2> 8505 T191 C15 P41033 /update {version=2&wt=javabin} {delete=[10016 (-1417147441251090433)]} 0 1
[junit4:junit4]   2> 8505 T188 C15 P41033 /update {version=2&wt=javabin} {add=[17 (1417147441251090432)]} 0 1
[junit4:junit4]   2> 8507 T205 C14 P48470 /update {version=2&wt=javabin} {delete=[10016 (-1417147441254236160)]} 0 0
[junit4:junit4]   2> 8508 T206 C14 P48470 /update {version=2&wt=javabin} {add=[17 (1417147441255284736)]} 0 1
[junit4:junit4]   2> 8510 T190 C15 P41033 /update {version=2&wt=javabin} {add=[10017 (1417147441257381888)]} 0 0
[junit4:junit4]   2> 8511 T189 C15 P41033 /update {version=2&wt=javabin} {add=[18 (1417147441258430464)]} 0 0
[junit4:junit4]   2> 8514 T204 C14 P48470 /update {version=2&wt=javabin} {add=[10017 (1417147441260527616)]} 0 1
[junit4:junit4]   2> 8514 T205 C14 P48470 /update {version=2&wt=javabin} {add=[18 (1417147441260527617)]} 0 1
[junit4:junit4]   2> 8517 T191 C15 P41033 /update {version=2&wt=javabin} {add=[10018 (1417147441263673344)]} 0 1
[junit4:junit4]   2> 8517 T188 C15 P41033 /update {version=2&wt=javabin} {add=[19 (1417147441264721920)]} 0 0
[junit4:junit4]   2> 8520 T206 C14 P48470 /update {version=2&wt=javabin} {add=[10018 (1417147441266819072)]} 0 1
[junit4:junit4]   2> 8520 T204 C14 P48470 /update {version=2&wt=javabin} {add=[19 (1417147441267867648)]} 0 0
[junit4:junit4]   2> 8522 T190 C15 P41033 /update {version=2&wt=javabin} {add=[10019 (1417147441269964800)]} 0 1
[junit4:junit4]   2> 8523 T189 C15 P41033 /update {version=2&wt=javabin} {add=[20 (1417147441271013376)]} 0 1
[junit4:junit4]   2> 8530 T206 C14 P48470 /update {version=2&wt=javabin} {add=[20 (1417147441278353408)]} 0 0
[junit4:junit4]   2> 8531 T205 C14 P48470 /update {version=2&wt=javabin} {add=[10019 (1417147441278353409)]} 0 1
[junit4:junit4]   2> 8535 T188 C15 P41033 /update {version=2&wt=javabin} {add=[10020 (1417147441282547712)]} 0 2
[junit4:junit4]   2> 8550 T204 C14 P48470 /update {version=2&wt=javabin} {add=[10020 (1417147441296179200)]} 0 4
[junit4:junit4]   2> 8553 T189 C15 P41033 /update {version=2&wt=javabin} {add=[10021 (1417147441302470656)]} 0 1
[junit4:junit4]   2> 8553 T191 C15 P41033 /update {version=2&wt=javabin} {add=[21 (1417147441281499136)]} 0 20
[junit4:junit4]   2> 8557 T206 C14 P48470 /update {version=2&wt=javabin} {add=[10021 (1417147441305616384)]} 0 1
[junit4:junit4]   2> 8557 T205 C14 P48470 /update {version=2&wt=javabin} {add=[21 (1417147441305616385)]} 0 1
[junit4:junit4]   2> 8560 T190 C15 P41033 /update {version=2&wt=javabin} {delete=[10018 (-1417147441308762112)]} 0 1
[junit4:junit4]   2> 8560 T188 C15 P41033 /update {version=2&wt=javabin} {delete=[18 (-1417147441309810688)]} 0 0
[junit4:junit4]   2> 8562 T204 C14 P48470 /update {version=2&wt=javabin} {delete=[10018 (-1417147441311907840)]} 0 0
[junit4:junit4]   2> 8563 T206 C14 P48470 /update {version=2&wt=javabin} {delete=[18 (-1417147441312956416)]} 0 0
[junit4:junit4]   2> 8565 T189 C15 P41033 /update {version=2&wt=javabin} {add=[10022 (1417147441314004992)]} 0 1
[junit4:junit4]   2> 8568 T205 C14 P48470 /update {version=2&wt=javabin} {add=[10022 (1417147441317150720)]} 0 1
[junit4:junit4]   2> 8570 T191 C15 P41033 /update {version=2&wt=javabin} {add=[22 (1417147441315053568)]} 0 5
[junit4:junit4]   2> 8571 T190 C15 P41033 /update {version=2&wt=javabin} {delete=[10019 (-1417147441321345024)]} 0 0
[junit4:junit4]   2> 8573 T204 C14 P48470 /update {version=2&wt=javabin} {add=[22 (1417147441323442176)]} 0 0
[junit4:junit4]   2> 8573 T206 C14 P48470 /update {version=2&wt=javabin} {delete=[10019 (-1417147441323442177)]} 0 0
[junit4:junit4]   2> 8576 T188 C15 P41033 /update {version=2&wt=javabin} {delete=[19 (-1417147441326587904)]} 0 0
[junit4:junit4]   2> 8578 T189 C15 P41033 /update {version=2&wt=javabin} {add=[10023 (1417147441327636480)]} 0 1
[junit4:junit4]   2> 8579 T205 C14 P48470 /update {version=2&wt=javabin} {delete=[19 (-1417147441329733632)]} 0 1
[junit4:junit4]   2> 8580 T204 C14 P48470 /update {version=2&wt=javabin} {add=[10023 (1417147441330782208)]} 0 0
[junit4:junit4]   2> 8582 T190 C15 P41033 /update {version=2&wt=javabin} {add=[23 (1417147441331830784)]} 0 1
[junit4:junit4]   2> 8583 T191 C15 P41033 /update {version=2&wt=javabin} {add=[10024 (1417147441333927936)]} 0 1
[junit4:junit4]   2> 8585 T206 C14 P48470 /update {version=2&wt=javabin} {add=[23 (1417147441334976512)]} 0 1
[junit4:junit4]   2> 8586 T205 C14 P48470 /update {version=2&wt=javabin} {add=[10024 (1417147441337073664)]} 0 0
[junit4:junit4]   2> 8588 T188 C15 P41033 /update {version=2&wt=javabin} {add=[24 (1417147441339170816)]} 0 0
[junit4:junit4]   2> 8589 T189 C15 P41033 /update {version=2&wt=javabin} {add=[10025 (1417147441340219392)]} 0 1
[junit4:junit4]   2> 8592 T204 C14 P48470 /update {version=2&wt=javabin} {add=[24 (1417147441342316544)]} 0 1
[junit4:junit4]   2> 8592 T206 C14 P48470 /update {version=2&wt=javabin} {add=[10025 (1417147441343365120)]} 0 1
[junit4:junit4]   2> 8595 T188 C15 P41033 /update {version=2&wt=javabin} {delete=[10020 (-1417147441346510848)]} 0 1
[junit4:junit4]   2> 8598 T205 C14 P48470 /update {version=2&wt=javabin} {delete=[10020 (-1417147441349656576)]} 0 1
[junit4:junit4]   2> 8603 T191 C15 P41033 /update {version=2&wt=javabin} {add=[10026 (1417147441352802304)]} 0 3
[junit4:junit4]   2> 8616 T190 C15 P41033 /update {version=2&wt=javabin} {add=[25 (1417147441345462272)]} 0 22
[junit4:junit4]   2> 8619 T206 C14 P48470 /update {version=2&wt=javabin} {add=[10026 (1417147441366433792)]} 0 5
[junit4:junit4]   2> 8619 T205 C14 P48470 /update {version=2&wt=javabin} {add=[25 (1417147441371676672)]} 0 1
[junit4:junit4]   2> 8622 T189 C15 P41033 /update {version=2&wt=javabin} {add=[10027 (1417147441374822400)]} 0 1
[junit4:junit4]   2> 8622 T188 C15 P41033 /update {version=2&wt=javabin} {delete=[20 (-1417147441374822401)]} 0 0
[junit4:junit4]   2> 8625 T206 C14 P48470 /update {version=2&wt=javabin} {delete=[20 (-1417147441377968129)]} 0 0
[junit4:junit4]   2> 8625 T204 C14 P48470 /update {version=2&wt=javabin} {add=[10027 (1417147441377968128)]} 0 1
[junit4:junit4]   2> 8628 T191 C15 P41033 /update {version=2&wt=javabin} {add=[26 (1417147441380065280)]} 0 1
[junit4:junit4]   2> 8629 T191 C15 P41033 /update {version=2&wt=javabin} {delete=[10021 (-1417147441382162432)]} 0 0
[junit4:junit4]   2> 8631 T205 C14 P48470 /update {version=2&wt=javabin} {add=[26 (1417147441383211008)]} 0 1
[junit4:junit4]   2> 8631 T206 C14 P48470 /update {version=2&wt=javabin} {delete=[10021 (-1417147441384259584)]} 0 0
[junit4:junit4]   2> 8634 T189 C15 P41033 /update {version=2&wt=javabin} {add=[27 (1417147441386356736)]} 0 1
[junit4:junit4]   2> 8634 T188 C15 P41033 /update {version=2&wt=javabin} {add=[10028 (1417147441387405312)]} 0 0
[junit4:junit4]   2> 8640 T205 C14 P48470 /update {version=2&wt=javabin} {add=[27 (1417147441392648192)]} 0 1
[junit4:junit4]   2> 8640 T204 C14 P48470 /update {version=2&wt=javabin} {add=[10028 (1417147441392648193)]} 0 1
[junit4:junit4]   2> 8642 T191 C15 P41033 /update {version=2&wt=javabin} {delete=[21 (-1417147441395793920)]} 0 0
[junit4:junit4]   2> 8645 T190 C15 P41033 /update {version=2&wt=javabin} {delete=[10028 (-1417147441398939648)]} 0 0
[junit4:junit4]   2> 8646 T206 C14 P48470 /update {version=2&wt=javabin} {delete=[21 (-1417147441398939648)]} 0 1
[junit4:junit4]   2> 8648 T205 C14 P48470 /update {version=2&wt=javabin} {delete=[10028 (-1417147441402085376)]} 0 0
[junit4:junit4]   2> 8649 T189 C15 P41033 /update {version=2&wt=javabin} {add=[28 (1417147441402085376)]} 0 1
[junit4:junit4]   2> 8651 T188 C15 P41033 /update {version=2&wt=javabin} {add=[10029 (1417147441405231104)]} 0 1
[junit4:junit4]   2> 8652 T204 C14 P48470 /update {version=2&wt=javabin} {add=[28 (1417147441405231104)]} 0 1
[junit4:junit4]   2> 8654 T191 C15 P41033 /update {version=2&wt=javabin} {delete=[28 (-1417147441408376832)]} 0 0
[junit4:junit4]   2> 8655 T206 C14 P48470 /update {version=2&wt=javabin} {add=[10029 (1417147441409425408)]} 0 1
[junit4:junit4]   2> 8657 T205 C14 P48470 /update {version=2&wt=javabin} {delete=[28 (-1417147441411522560)]} 0 0
[junit4:junit4]   2> 8658 T190 C15 P41033 /update {version=2&wt=javabin} {add=[10030 (1417147441411522560)]} 0 1
[junit4:junit4]   2> 8661 T189 C15 P41033 /update {version=2&wt=javabin} {add=[29 (1417147441414668288)]} 0 1
[junit4:junit4]   2> 8661 T204 C14 P48470 /update {version=2&wt=javabin} {add=[10030 (1417147441414668288)]} 0 1
[junit4:junit4]   2> 8664 T206 C14 P48470 /update {version=2&wt=javabin} {add=[29 (1417147441417814016)]} 0 1
[junit4:junit4]   2> 8669 T191 C15 P41033 /update {version=2&wt=javabin} {add=[30 (1417147441422008320)]} 0 2
[junit4:junit4]   2> 8670 T194 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8672 T194 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4:junit4]   2> 	  "base_url":"http://127.0.0.1:60311/solr",
[junit4:junit4]   2> 	  "state":"recovering",
[junit4:junit4]   2> 	  "operation":"state",
[junit4:junit4]   2> 	  "node_name":"127.0.0.1:60311_solr",
[junit4:junit4]   2> 	  "numShards":"1",
[junit4:junit4]   2> 	  "roles":null,
[junit4:junit4]   2> 	  "collection":"collection1",
[junit4:junit4]   2> 	  "shard":"shard1",
[junit4:junit4]   2> 	  "core":"collection1"}
[junit4:junit4]   2> 8674 T188 C15 P41033 /update {version=2&wt=javabin} {add=[10031 (1417147441418862592)]} 0 11
[junit4:junit4]   2> 8676 T221 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 8676 T193 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 8676 T209 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 8676 T198 oascc.ZkStateReader$2.process A cluster state change has occurred - updating... (3)
[junit4:junit4]   2> 8687 T205 C14 P48470 /update {version=2&wt=javabin} {add=[30 (1417147441428299776)]} 0 15
[junit4:junit4]   2> 8691 T190 C15 P41033 /update {version=2&wt=javabin} {add=[31 (1417147441446125568)]} 0 1
[junit4:junit4]   2>  C13_STATE=coll:collection1 core:collection1 props:{base_url=http://127.0.0.1:60311/solr, state=recovering, node_name=127.0.0.1:60311_solr, roles=null, collection=collection1, shard=shard1, core=collection1}
[junit4:junit4]   2> 8704 T217 C13 P60311 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.RecoveryZkTest-1351497108784/jetty2
[junit4:junit4]   2> 8708 T217 C13 P60311 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6e856e85 lockFactory=org.apache.lucene.store.NativeFSLockFactory@afb0afb),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 8709 T217 C13 P60311 oasc.SolrDeletionPolicy.updateCommits newest commit = 1[segments_1]
[junit4:junit4]   2> 8711 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=13 
[junit4:junit4]   2> 8711 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=12 
[junit4:junit4]   2> 8713 T204 C14 P48470 /update {version=2&wt=javabin} {add=[10031 (1417147441442979840)]} 0 27
[junit4:junit4]   2> 8713 T206 C14 P48470 /update {version=2&wt=javabin} {add=[31 (1417147441449271296)]} 0 20
[junit4:junit4]   2> 8717 T189 C15 P41033 /update {version=2&wt=javabin} {add=[10032 (1417147441474437120)]} 0 1
[junit4:junit4]   2> 8719 T191 C15 P41033 /update {version=2&wt=javabin} {add=[32 (1417147441474437121)]} 0 2
[junit4:junit4]   2> 8725 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 8726 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 8726 T206 C14 P48470 /update {version=2&wt=javabin} {add=[32 (1417147441479680000)]} 0 4
[junit4:junit4]   2> 8727 T205 C14 P48470 /update {version=2&wt=javabin} {add=[10032 (1417147441477582848)]} 0 7
[junit4:junit4]   2> 8730 T188 C15 P41033 /update {version=2&wt=javabin} {add=[33 (1417147441487020032)]} 0 1
[junit4:junit4]   2> 8731 T190 C15 P41033 /update {version=2&wt=javabin} {add=[10033 (1417147441489117184)]} 0 0
[junit4:junit4]   2> 8735 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 8736 T204 C14 P48470 /update {version=2&wt=javabin} {add=[33 (1417147441490165760)]} 0 4
[junit4:junit4]   2> 8737 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 8738 T206 C14 P48470 /update {version=2&wt=javabin} {add=[10033 (1417147441492262912)]} 0 4
[junit4:junit4]   2> 8739 T189 C15 P41033 /update {version=2&wt=javabin} {add=[34 (1417147441497505792)]} 0 0
[junit4:junit4]   2> 8741 T191 C15 P41033 /update {version=2&wt=javabin} {add=[10034 (1417147441499602944)]} 0 1
[junit4:junit4]   2> 8744 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 8745 T205 C14 P48470 /update {version=2&wt=javabin} {add=[34 (1417147441500651520)]} 0 3
[junit4:junit4]   2> 8746 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 8747 T204 C14 P48470 /update {version=2&wt=javabin} {add=[10034 (1417147441502748672)]} 0 4
[junit4:junit4]   2> 8748 T188 C15 P41033 /update {version=2&wt=javabin} {add=[35 (1417147441506942976)]} 0 0
[junit4:junit4]   2> 8759 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 8760 T206 C14 P48470 /update {version=2&wt=javabin} {add=[35 (1417147441510088704)]} 0 9
[junit4:junit4]   2> 8764 T189 C15 P41033 /update {version=2&wt=javabin} {add=[36 (1417147441522671616)]} 0 2
[junit4:junit4]   2> 8767 T190 C15 P41033 /update {version=2&wt=javabin} {add=[10035 (1417147441509040128)]} 0 18
[junit4:junit4]   2> 8773 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 8774 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 8775 T204 C14 P48470 /update {version=2&wt=javabin} {add=[10035 (1417147441530011648)]} 0 5
[junit4:junit4]   2> 8776 T205 C14 P48470 /update {version=2&wt=javabin} {add=[36 (1417147441526865920)]} 0 9
[junit4:junit4]   2> 8779 T188 C15 P41033 /update {version=2&wt=javabin} {delete=[34 (-1417147441538400257)]} 0 1
[junit4:junit4]   2> 8780 T191 C15 P41033 /update {version=2&wt=javabin} {add=[10036 (1417147441538400256)]} 0 3
[junit4:junit4]   2> 8784 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 8785 T206 C14 P48470 /update {version=2&wt=javabin} {delete=[34 (-1417147441541545984)]} 0 4
[junit4:junit4]   2> 8785 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 8786 T204 C14 P48470 /update {version=2&wt=javabin} {add=[10036 (1417147441543643136)]} 0 4
[junit4:junit4]   2> 8788 T189 C15 P41033 /update {version=2&wt=javabin} {add=[37 (1417147441547837440)]} 0 1
[junit4:junit4]   2> 8788 T190 C15 P41033 /update {version=2&wt=javabin} {delete=[10034 (-1417147441548886016)]} 0 0
[junit4:junit4]   2> 8792 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 8793 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 8793 T205 C14 P48470 /update {version=2&wt=javabin} {add=[37 (1417147441550983168)]} 0 3
[junit4:junit4]   2> 8794 T206 C14 P48470 /update {version=2&wt=javabin} {delete=[10034 (-1417147441552031744)]} 0 3
[junit4:junit4]   2> 8797 T188 C15 P41033 /update {version=2&wt=javabin} {add=[38 (1417147441557274624)]} 0 1
[junit4:junit4]   2> 8797 T191 C15 P41033 /update {version=2&wt=javabin} {add=[10037 (1417147441558323200)]} 0 1
[junit4:junit4]   2> 8802 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 8803 T206 C14 P48470 /update {version=2&wt=javabin} {add=[10037 (1417147441560420353)]} 0 4
[junit4:junit4]   2> 8804 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 8805 T204 C14 P48470 /update {version=2&wt=javabin} {add=[38 (1417147441560420352)]} 0 6
[junit4:junit4]   2> 8806 T189 C15 P41033 /update {version=2&wt=javabin} {add=[10038 (1417147441567760384)]} 0 1
[junit4:junit4]   2> 8807 T190 C15 P41033 /update {version=2&wt=javabin} {add=[39 (1417147441568808960)]} 0 0
[junit4:junit4]   2> 8811 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 8812 T205 C14 P48470 /update {version=2&wt=javabin} {add=[10038 (1417147441569857536)]} 0 4
[junit4:junit4]   2> 8812 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 8821 T206 C14 P48470 /update {version=2&wt=javabin} {add=[39 (1417147441571954688)]} 0 11
[junit4:junit4]   2> 8823 T188 C15 P41033 /update {version=2&wt=javabin} {add=[10039 (1417147441584537600)]} 0 1
[junit4:junit4]   2> 8823 T191 C15 P41033 /update {version=2&wt=javabin} {delete=[35 (-1417147441585586176)]} 0 0
[junit4:junit4]   2> 8827 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 8828 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 8828 T205 C14 P48470 /update {version=2&wt=javabin} {delete=[35 (-1417147441588731904)]} 0 3
[junit4:junit4]   2> 8829 T204 C14 P48470 /update {version=2&wt=javabin} {add=[10039 (1417147441587683328)]} 0 4
[junit4:junit4]   2> 8832 T189 C15 P41033 /update {version=2&wt=javabin} {add=[40 (1417147441593974784)]} 0 1
[junit4:junit4]   2> 8832 T190 C15 P41033 /update {version=2&wt=javabin} {delete=[10035 (-1417147441593974785)]} 0 1
[junit4:junit4]   2> 8837 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 8837 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 8838 T205 C14 P48470 /update {version=2&wt=javabin} {delete=[10035 (-1417147441598169089)]} 0 3
[junit4:junit4]   2> 8839 T206 C14 P48470 /update {version=2&wt=javabin} {add=[40 (1417147441598169088)]} 0 5
[junit4:junit4]   2> 8841 T191 C15 P41033 /update {version=2&wt=javabin} {delete=[37 (-1417147441604460545)]} 0 0
[junit4:junit4]   2> 8846 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 8847 T204 C14 P48470 /update {version=2&wt=javabin} {delete=[37 (-1417147441607606272)]} 0 3
[junit4:junit4]   2> 8851 T189 C15 P41033 /update {version=2&wt=javabin} {add=[41 (1417147441612849152)]} 0 2
[junit4:junit4]   2> 8881 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 8882 T205 C14 P48470 /update {version=2&wt=javabin} {add=[41 (1417147441618092032)]} 0 29
[junit4:junit4]   2> 8885 T190 C15 P41033 /update {version=2&wt=javabin} {delete=[41 (-1417147441650597888)]} 0 0
[junit4:junit4]   2> 8891 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 8892 T206 C14 P48470 /update {version=2&wt=javabin} {delete=[41 (-1417147441653743616)]} 0 4
[junit4:junit4]   2> 8896 T191 C15 P41033 /update {version=2&wt=javabin} {add=[42 (1417147441661083648)]} 0 1
[junit4:junit4]   2> 8902 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 8902 T204 C14 P48470 /update {version=2&wt=javabin} {add=[42 (1417147441664229376)]} 0 4
[junit4:junit4]   2> 8906 T189 C15 P41033 /update {version=2&wt=javabin} {add=[43 (1417147441671569408)]} 0 1
[junit4:junit4]   2> 8908 T188 C15 P41033 /update {version=2&wt=javabin} {add=[10040 (1417147441604460544)]} 0 67
[junit4:junit4]   2> 8922 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 8923 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 8923 T205 C14 P48470 /update {version=2&wt=javabin} {add=[43 (1417147441675763712)]} 0 15
[junit4:junit4]   2> 8924 T206 C14 P48470 /update {version=2&wt=javabin} {add=[10040 (1417147441687298048)]} 0 5
[junit4:junit4]   2> 8927 T190 C15 P41033 /update {version=2&wt=javabin} {delete=[43 (-1417147441693589504)]} 0 1
[junit4:junit4]   2> 8927 T191 C15 P41033 /update {version=2&wt=javabin} {delete=[10037 (-1417147441694638080)]} 0 0
[junit4:junit4]   2> 8932 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 8933 T204 C14 P48470 /update {version=2&wt=javabin} {delete=[43 (-1417147441697783808)]} 0 4
[junit4:junit4]   2> 8933 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 8935 T205 C14 P48470 /update {version=2&wt=javabin} {delete=[10037 (-1417147441697783809)]} 0 5
[junit4:junit4]   2> 8936 T189 C15 P41033 /update {version=2&wt=javabin} {add=[44 (1417147441704075264)]} 0 1
[junit4:junit4]   2> 8938 T188 C15 P41033 /update {version=2&wt=javabin} {add=[10041 (1417147441706172416)]} 0 1
[junit4:junit4]   2> 8943 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 8944 T206 C14 P48470 /update {version=2&wt=javabin} {add=[44 (1417147441707220992)]} 0 5
[junit4:junit4]   2> 8948 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 8949 T204 C14 P48470 /update {version=2&wt=javabin} {add=[10041 (1417147441709318144)]} 0 8
[junit4:junit4]   2> 8950 T191 C15 P41033 /update {version=2&wt=javabin} {add=[45 (1417147441718755328)]} 0 0
[junit4:junit4]   2> 8952 T190 C15 P41033 /update {version=2&wt=javabin} {delete=[10041 (-1417147441720852480)]} 0 0
[junit4:junit4]   2> 8956 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 8957 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 8966 T206 C14 P48470 /update {version=2&wt=javabin} {delete=[10041 (-1417147441723998208)]} 0 11
[junit4:junit4]   2> 8967 T205 C14 P48470 /update {version=2&wt=javabin} {add=[45 (1417147441721901056)]} 0 14
[junit4:junit4]   2> 8970 T189 C15 P41033 /update {version=2&wt=javabin} {add=[10042 (1417147441738678272)]} 0 1
[junit4:junit4]   2> 8971 T188 C15 P41033 /update {version=2&wt=javabin} {add=[46 (1417147441739726848)]} 0 1
[junit4:junit4]   2> 8975 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 8976 T204 C14 P48470 /update {version=2&wt=javabin} {add=[10042 (1417147441741824000)]} 0 4
[junit4:junit4]   2> 8976 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 8978 T206 C14 P48470 /update {version=2&wt=javabin} {add=[46 (1417147441743921152)]} 0 5
[junit4:junit4]   2> 8979 T191 C15 P41033 /update {version=2&wt=javabin} {add=[10043 (1417147441749164032)]} 0 1
[junit4:junit4]   2> 8985 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 8986 T205 C14 P48470 /update {version=2&wt=javabin} {add=[10043 (1417147441752309760)]} 0 4
[junit4:junit4]   2> 8989 T189 C15 P41033 /update {version=2&wt=javabin} {delete=[10043 (-1417147441759649792)]} 0 1
[junit4:junit4]   2> 8993 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 8996 T190 C15 P41033 /update {version=2&wt=javabin} {add=[47 (1417147441751261184)]} 0 16
[junit4:junit4]   2> 8997 T204 C14 P48470 /update {version=2&wt=javabin} {delete=[10043 (-1417147441762795520)]} 0 6
[junit4:junit4]   2> 9003 T191 C15 P41033 /update {version=2&wt=javabin} {add=[10044 (1417147441771184128)]} 0 3
[junit4:junit4]   2> 9005 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9007 T206 C14 P48470 /update {version=2&wt=javabin} {add=[47 (1417147441770135552)]} 0 8
[junit4:junit4]   2> 9010 T189 C15 P41033 /update {version=2&wt=javabin} {delete=[46 (-1417147441781669888)]} 0 1
[junit4:junit4]   2> 9011 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9012 T205 C14 P48470 /update {version=2&wt=javabin} {add=[10044 (1417147441779572736)]} 0 5
[junit4:junit4]   2> 9015 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9016 T204 C14 P48470 /update {version=2&wt=javabin} {delete=[46 (-1417147441784815616)]} 0 4
[junit4:junit4]   2> 9016 T190 C15 P41033 /update {version=2&wt=javabin} {add=[10045 (1417147441786912768)]} 0 1
[junit4:junit4]   2> 9020 T188 C15 P41033 /update {version=2&wt=javabin} {add=[48 (1417147441790058496)]} 0 2
[junit4:junit4]   2> 9030 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9031 T206 C14 P48470 /update {version=2&wt=javabin} {add=[10045 (1417147441791107072)]} 0 12
[junit4:junit4]   2> 9032 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9034 T205 C14 P48470 /update {version=2&wt=javabin} {add=[48 (1417147441802641408)]} 0 5
[junit4:junit4]   2> 9035 T189 C15 P41033 /update {version=2&wt=javabin} {add=[10046 (1417147441806835712)]} 0 1
[junit4:junit4]   2> 9040 T191 C15 P41033 /update {version=2&wt=javabin} {add=[49 (1417147441812078592)]} 0 1
[junit4:junit4]   2> 9041 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9042 T204 C14 P48470 /update {version=2&wt=javabin} {add=[10046 (1417147441811030016)]} 0 4
[junit4:junit4]   2> 9045 T190 C15 P41033 /update {version=2&wt=javabin} {add=[10047 (1417147441817321472)]} 0 1
[junit4:junit4]   2> 9046 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9047 T206 C14 P48470 /update {version=2&wt=javabin} {add=[49 (1417147441816272896)]} 0 5
[junit4:junit4]   2> 9050 T188 C15 P41033 /update {version=2&wt=javabin} {delete=[49 (-1417147441822564352)]} 0 1
[junit4:junit4]   2> 9050 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9051 T205 C14 P48470 /update {version=2&wt=javabin} {add=[10047 (1417147441820467200)]} 0 4
[junit4:junit4]   2> 9054 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9054 T189 C15 P41033 /update {version=2&wt=javabin} {delete=[10046 (-1417147441827807232)]} 0 0
[junit4:junit4]   2> 9055 T204 C14 P48470 /update {version=2&wt=javabin} {delete=[49 (-1417147441825710080)]} 0 3
[junit4:junit4]   2> 9058 T191 C15 P41033 /update {version=2&wt=javabin} {add=[50 (1417147441830952960)]} 0 1
[junit4:junit4]   2> 9059 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9060 T206 C14 P48470 /update {version=2&wt=javabin} {delete=[10046 (-1417147441830952960)]} 0 3
[junit4:junit4]   2> 9063 T190 C15 P41033 /update {version=2&wt=javabin} {add=[10048 (1417147441836195840)]} 0 1
[junit4:junit4]   2> 9066 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 9067 T205 C14 P48470 /update {version=2&wt=javabin} {add=[50 (1417147441835147264)]} 0 7
[junit4:junit4]   2> 9068 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9069 T204 C14 P48470 /update {version=2&wt=javabin} {add=[10048 (1417147441839341568)]} 0 4
[junit4:junit4]   2> 9070 T188 C15 P41033 /update {version=2&wt=javabin} {add=[51 (1417147441844584448)]} 0 1
[junit4:junit4]   2> 9076 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9077 T206 C14 P48470 /update {version=2&wt=javabin} {add=[51 (1417147441847730176)]} 0 4
[junit4:junit4]   2> 9082 T191 C15 P41033 /update {version=2&wt=javabin} {add=[52 (1417147441855070208)]} 0 2
[junit4:junit4]   2> 9083 T189 C15 P41033 /update {version=2&wt=javabin} {add=[10049 (1417147441845633024)]} 0 12
[junit4:junit4]   2> 9090 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9091 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9091 T204 C14 P48470 /update {version=2&wt=javabin} {add=[52 (1417147441862410240)]} 0 5
[junit4:junit4]   2> 9092 T205 C14 P48470 /update {version=2&wt=javabin} {add=[10049 (1417147441861361664)]} 0 6
[junit4:junit4]   2> 9094 T190 C15 P41033 /update {version=2&wt=javabin} {delete=[52 (-1417147441869750272)]} 0 0
[junit4:junit4]   2> 9095 T188 C15 P41033 /update {version=2&wt=javabin} {delete=[10049 (-1417147441870798848)]} 0 1
[junit4:junit4]   2> 9099 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9099 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9100 T206 C14 P48470 /update {version=2&wt=javabin} {delete=[52 (-1417147441872896000)]} 0 4
[junit4:junit4]   2> 9100 T204 C14 P48470 /update {version=2&wt=javabin} {delete=[10049 (-1417147441872896001)]} 0 3
[junit4:junit4]   2> 9103 T191 C15 P41033 /update {version=2&wt=javabin} {add=[53 (1417147441879187456)]} 0 0
[junit4:junit4]   2> 9104 T189 C15 P41033 /update {version=2&wt=javabin} {add=[10050 (1417147441879187457)]} 0 1
[junit4:junit4]   2> 9109 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9110 T205 C14 P48470 /update {version=2&wt=javabin} {add=[53 (1417147441882333184)]} 0 4
[junit4:junit4]   2> 9110 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9111 T206 C14 P48470 /update {version=2&wt=javabin} {add=[10050 (1417147441883381760)]} 0 4
[junit4:junit4]   2> 9113 T190 C15 P41033 /update {version=2&wt=javabin} {add=[54 (1417147441888624640)]} 0 1
[junit4:junit4]   2> 9114 T188 C15 P41033 /update {version=2&wt=javabin} {add=[10051 (1417147441890721792)]} 0 0
[junit4:junit4]   2> 9118 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9119 T204 C14 P48470 /update {version=2&wt=javabin} {add=[54 (1417147441891770368)]} 0 4
[junit4:junit4]   2> 9119 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9121 T205 C14 P48470 /update {version=2&wt=javabin} {add=[10051 (1417147441893867520)]} 0 5
[junit4:junit4]   2> 9122 T191 C15 P41033 /update {version=2&wt=javabin} {add=[55 (1417147441899110400)]} 0 0
[junit4:junit4]   2> 9124 T189 C15 P41033 /update {version=2&wt=javabin} {add=[10052 (1417147441901207552)]} 0 1
[junit4:junit4]   2> 9127 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9131 T206 C14 P48470 /update {version=2&wt=javabin} {add=[55 (1417147441901207552)]} 0 7
[junit4:junit4]   2> 9132 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9134 T190 C15 P41033 /update {version=2&wt=javabin} {delete=[54 (-1417147441911693312)]} 0 1
[junit4:junit4]   2> 9134 T204 C14 P48470 /update {version=2&wt=javabin} {add=[10052 (1417147441904353280)]} 0 7
[junit4:junit4]   2> 9149 T188 C15 P41033 /update {version=2&wt=javabin} {delete=[10052 (-1417147441927421952)]} 0 0
[junit4:junit4]   2> 9151 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9151 T205 C14 P48470 /update {version=2&wt=javabin} {delete=[54 (-1417147441927421952)]} 0 2
[junit4:junit4]   2> 9153 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9154 T191 C15 P41033 /update {version=2&wt=javabin} {add=[56 (1417147441932664832)]} 0 0
[junit4:junit4]   2> 9155 T206 C14 P48470 /update {version=2&wt=javabin} {delete=[10052 (-1417147441930567680)]} 0 4
[junit4:junit4]   2> 9158 T189 C15 P41033 /update {version=2&wt=javabin} {add=[10053 (1417147441935810560)]} 0 1
[junit4:junit4]   2> 9159 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9160 T204 C14 P48470 /update {version=2&wt=javabin} {add=[56 (1417147441935810560)]} 0 4
[junit4:junit4]   2> 9165 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 9167 T205 C14 P48470 /update {version=2&wt=javabin} {add=[10053 (1417147441940004864)]} 0 7
[junit4:junit4]   2> 9172 T188 C15 P41033 /update {version=2&wt=javabin} {add=[10054 (1417147441949442048)]} 0 3
[junit4:junit4]   2> 9172 T190 C15 P41033 /update {version=2&wt=javabin} {add=[57 (1417147441941053440)]} 0 10
[junit4:junit4]   2> 9180 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 9181 T204 C14 P48470 /update {version=2&wt=javabin} {add=[57 (1417147441954684929)]} 0 6
[junit4:junit4]   2> 9181 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9182 T206 C14 P48470 /update {version=2&wt=javabin} {add=[10054 (1417147441954684928)]} 0 8
[junit4:junit4]   2> 9183 T191 C15 P41033 /update {version=2&wt=javabin} {delete=[56 (-1417147441963073536)]} 0 0
[junit4:junit4]   2> 9186 T189 C15 P41033 /update {version=2&wt=javabin} {add=[10055 (1417147441965170688)]} 0 1
[junit4:junit4]   2> 9187 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9188 T205 C14 P48470 /update {version=2&wt=javabin} {delete=[56 (-1417147441965170688)]} 0 3
[junit4:junit4]   2> 9191 T188 C15 P41033 /update {version=2&wt=javabin} {add=[58 (1417147441971462144)]} 0 1
[junit4:junit4]   2> 9191 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9193 T204 C14 P48470 /update {version=2&wt=javabin} {add=[10055 (1417147441968316416)]} 0 5
[junit4:junit4]   2> 9195 T190 C15 P41033 /update {version=2&wt=javabin} {delete=[10054 (-1417147441975656448)]} 0 0
[junit4:junit4]   2> 9196 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9197 T206 C14 P48470 /update {version=2&wt=javabin} {add=[58 (1417147441973559296)]} 0 4
[junit4:junit4]   2> 9201 T191 C15 P41033 /update {version=2&wt=javabin} {add=[59 (1417147441981947904)]} 0 0
[junit4:junit4]   2> 9203 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9204 T205 C14 P48470 /update {version=2&wt=javabin} {delete=[10054 (-1417147441979850752)]} 0 5
[junit4:junit4]   2> 9206 T189 C15 P41033 /update {version=2&wt=javabin} {add=[10056 (1417147441987190784)]} 0 0
[junit4:junit4]   2> 9208 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9209 T205 C14 P48470 /update {version=2&wt=javabin} {add=[59 (1417147441987190784)]} 0 4
[junit4:junit4]   2> 9212 T188 C15 P41033 /update {version=2&wt=javabin} {add=[60 (1417147441992433664)]} 0 1
[junit4:junit4]   2> 9212 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9213 T204 C14 P48470 /update {version=2&wt=javabin} {add=[10056 (1417147441990336512)]} 0 4
[junit4:junit4]   2> 9216 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9217 T190 C15 P41033 /update {version=2&wt=javabin} {add=[10057 (1417147441997676544)]} 0 1
[junit4:junit4]   2> 9217 T206 C14 P48470 /update {version=2&wt=javabin} {add=[60 (1417147441995579392)]} 0 3
[junit4:junit4]   2> 9221 T191 C15 P41033 /update {version=2&wt=javabin} {add=[61 (1417147442002919424)]} 0 1
[junit4:junit4]   2> 9223 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9224 T207 C14 P48470 /update {version=2&wt=javabin} {add=[10057 (1417147442001870848)]} 0 5
[junit4:junit4]   2> 9226 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9226 T189 C15 P41033 /update {version=2&wt=javabin} {delete=[10056 (-1417147442008162304)]} 0 0
[junit4:junit4]   2> 9226 T205 C14 P48470 /update {version=2&wt=javabin} {add=[61 (1417147442005016576)]} 0 3
[junit4:junit4]   2> 9230 T188 C15 P41033 /update {version=2&wt=javabin} {add=[62 (1417147442011308032)]} 0 1
[junit4:junit4]   2> 9231 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9232 T204 C14 P48470 /update {version=2&wt=javabin} {delete=[10056 (-1417147442011308032)]} 0 3
[junit4:junit4]   2> 9235 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9236 T206 C14 P48470 /update {version=2&wt=javabin} {add=[62 (1417147442014453760)]} 0 4
[junit4:junit4]   2> 9267 T191 C15 P41033 /update {version=2&wt=javabin} {delete=[60 (-1417147442051153920)]} 0 0
[junit4:junit4]   2> 9271 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9272 T207 C14 P48470 /update {version=2&wt=javabin} {delete=[60 (-1417147442054299648)]} 0 3
[junit4:junit4]   2> 9275 T190 C15 P41033 /update {version=2&wt=javabin} {add=[10058 (1417147442016550912)]} 0 41
[junit4:junit4]   2> 9276 T189 C15 P41033 /update {version=2&wt=javabin} {add=[63 (1417147442059542528)]} 0 1
[junit4:junit4]   2> 9281 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9282 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9282 T204 C14 P48470 /update {version=2&wt=javabin} {add=[63 (1417147442062688256)]} 0 4
[junit4:junit4]   2> 9282 T205 C14 P48470 /update {version=2&wt=javabin} {add=[10058 (1417147442061639680)]} 0 5
[junit4:junit4]   2> 9285 T188 C15 P41033 /update {version=2&wt=javabin} {delete=[61 (-1417147442070028288)]} 0 1
[junit4:junit4]   2> 9285 T191 C15 P41033 /update {version=2&wt=javabin} {add=[10059 (1417147442070028289)]} 0 0
[junit4:junit4]   2> 9289 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9289 T206 C14 P48470 /update {version=2&wt=javabin} {delete=[61 (-1417147442072125440)]} 0 2
[junit4:junit4]   2> 9290 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9291 T207 C14 P48470 /update {version=2&wt=javabin} {add=[10059 (1417147442073174016)]} 0 4
[junit4:junit4]   2> 9292 T190 C15 P41033 /update {version=2&wt=javabin} {add=[64 (1417147442077368320)]} 0 0
[junit4:junit4]   2> 9293 T189 C15 P41033 /update {version=2&wt=javabin} {add=[10060 (1417147442078416896)]} 0 0
[junit4:junit4]   2> 9298 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 9299 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9299 T204 C14 P48470 /update {version=2&wt=javabin} {add=[64 (1417147442079465472)]} 0 5
[junit4:junit4]   2> 9300 T205 C14 P48470 /update {version=2&wt=javabin} {add=[10060 (1417147442081562624)]} 0 4
[junit4:junit4]   2> 9303 T188 C15 P41033 /update {version=2&wt=javabin} {add=[65 (1417147442087854080)]} 0 1
[junit4:junit4]   2> 9303 T191 C15 P41033 /update {version=2&wt=javabin} {add=[10061 (1417147442087854081)]} 0 1
[junit4:junit4]   2> 9307 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9308 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9309 T206 C14 P48470 /update {version=2&wt=javabin} {add=[65 (1417147442090999808)]} 0 4
[junit4:junit4]   2> 9309 T207 C14 P48470 /update {version=2&wt=javabin} {add=[10061 (1417147442092048384)]} 0 4
[junit4:junit4]   2> 9311 T190 C15 P41033 /update {version=2&wt=javabin} {delete=[63 (-1417147442097291264)]} 0 0
[junit4:junit4]   2> 9312 T189 C15 P41033 /update {version=2&wt=javabin} {add=[10062 (1417147442098339840)]} 0 0
[junit4:junit4]   2> 9315 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9316 T204 C14 P48470 /update {version=2&wt=javabin} {delete=[63 (-1417147442100436992)]} 0 2
[junit4:junit4]   2> 9317 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9317 T205 C14 P48470 /update {version=2&wt=javabin} {add=[10062 (1417147442100436993)]} 0 3
[junit4:junit4]   2> 9319 T188 C15 P41033 /update {version=2&wt=javabin} {add=[66 (1417147442104631296)]} 0 1
[junit4:junit4]   2> 9320 T191 C15 P41033 /update {version=2&wt=javabin} {delete=[10060 (-1417147442106728448)]} 0 1
[junit4:junit4]   2> 9323 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9323 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9324 T206 C14 P48470 /update {version=2&wt=javabin} {add=[66 (1417147442107777024)]} 0 3
[junit4:junit4]   2> 9325 T207 C14 P48470 /update {version=2&wt=javabin} {delete=[10060 (-1417147442108825600)]} 0 3
[junit4:junit4]   2> 9327 T190 C15 P41033 /update {version=2&wt=javabin} {delete=[64 (-1417147442114068480)]} 0 0
[junit4:junit4]   2> 9328 T189 C15 P41033 /update {version=2&wt=javabin} {add=[10063 (1417147442115117056)]} 0 1
[junit4:junit4]   2> 9331 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9333 T204 C14 P48470 /update {version=2&wt=javabin} {delete=[64 (-1417147442117214208)]} 0 4
[junit4:junit4]   2> 9334 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9335 T205 C14 P48470 /update {version=2&wt=javabin} {add=[10063 (1417147442118262784)]} 0 4
[junit4:junit4]   2> 9338 T191 C15 P41033 /update {version=2&wt=javabin} {delete=[10061 (-1417147442125602816)]} 0 1
[junit4:junit4]   2> 9344 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9345 T206 C14 P48470 /update {version=2&wt=javabin} {delete=[10061 (-1417147442128748544)]} 0 5
[junit4:junit4]   2> 9347 T188 C15 P41033 /update {version=2&wt=javabin} {add=[67 (1417147442122457088)]} 0 12
[junit4:junit4]   2> 9359 T190 C15 P41033 /update {version=2&wt=javabin} {add=[10064 (1417147442135040000)]} 0 12
[junit4:junit4]   2> 9364 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9364 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9365 T207 C14 P48470 /update {version=2&wt=javabin} {add=[67 (1417147442137137152)]} 0 16
[junit4:junit4]   2> 9365 T204 C14 P48470 /update {version=2&wt=javabin} {add=[10064 (1417147442149720064)]} 0 4
[junit4:junit4]   2> 9368 T189 C15 P41033 /update {version=2&wt=javabin} {add=[68 (1417147442157060096)]} 0 1
[junit4:junit4]   2> 9369 T191 C15 P41033 /update {version=2&wt=javabin} {add=[10065 (1417147442157060097)]} 0 1
[junit4:junit4]   2> 9374 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9374 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9375 T205 C14 P48470 /update {version=2&wt=javabin} {add=[68 (1417147442160205824)]} 0 4
[junit4:junit4]   2> 9375 T206 C14 P48470 /update {version=2&wt=javabin} {add=[10065 (1417147442161254400)]} 0 3
[junit4:junit4]   2> 9378 T190 C15 P41033 /update {version=2&wt=javabin} {delete=[10063 (-1417147442167545857)]} 0 0
[junit4:junit4]   2> 9378 T188 C15 P41033 /update {version=2&wt=javabin} {add=[69 (1417147442167545856)]} 0 1
[junit4:junit4]   2> 9382 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9383 T207 C14 P48470 /update {version=2&wt=javabin} {delete=[10063 (-1417147442170691584)]} 0 3
[junit4:junit4]   2> 9384 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9384 T204 C14 P48470 /update {version=2&wt=javabin} {add=[69 (1417147442170691585)]} 0 3
[junit4:junit4]   2> 9386 T189 C15 P41033 /update {version=2&wt=javabin} {add=[10066 (1417147442175934464)]} 0 1
[junit4:junit4]   2> 9387 T191 C15 P41033 /update {version=2&wt=javabin} {add=[70 (1417147442176983040)]} 0 1
[junit4:junit4]   2> 9391 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9392 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9393 T205 C14 P48470 /update {version=2&wt=javabin} {add=[10066 (1417147442179080192)]} 0 4
[junit4:junit4]   2> 9393 T206 C14 P48470 /update {version=2&wt=javabin} {add=[70 (1417147442179080193)]} 0 4
[junit4:junit4]   2> 9396 T190 C15 P41033 /update {version=2&wt=javabin} {delete=[10064 (-1417147442186420224)]} 0 1
[junit4:junit4]   2> 9396 T188 C15 P41033 /update {version=2&wt=javabin} {delete=[66 (-1417147442186420225)]} 0 0
[junit4:junit4]   2> 9401 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9401 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9402 T207 C14 P48470 /update {version=2&wt=javabin} {delete=[10064 (-1417147442189565952)]} 0 4
[junit4:junit4]   2> 9402 T204 C14 P48470 /update {version=2&wt=javabin} {delete=[66 (-1417147442189565953)]} 0 3
[junit4:junit4]   2> 9406 T189 C15 P41033 /update {version=2&wt=javabin} {add=[10067 (1417147442195857408)]} 0 1
[junit4:junit4]   2> 9406 T191 C15 P41033 /update {version=2&wt=javabin} {add=[71 (1417147442195857409)]} 0 1
[junit4:junit4]   2> 9416 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=4 
[junit4:junit4]   2> 9417 T205 C14 P48470 /update {version=2&wt=javabin} {add=[10067 (1417147442200051712)]} 0 9
[junit4:junit4]   2> 9418 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9419 T207 C14 P48470 /update {version=2&wt=javabin} {add=[71 (1417147442205294592)]} 0 5
[junit4:junit4]   2> 9420 T188 C15 P41033 /update {version=2&wt=javabin} {add=[10068 (1417147442211586048)]} 0 0
[junit4:junit4]   2> 9422 T190 C15 P41033 /update {version=2&wt=javabin} {delete=[67 (-1417147442212634624)]} 0 1
[junit4:junit4]   2> 9426 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9426 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9427 T204 C14 P48470 /update {version=2&wt=javabin} {add=[10068 (1417147442214731776)]} 0 4
[junit4:junit4]   2> 9428 T206 C14 P48470 /update {version=2&wt=javabin} {delete=[67 (-1417147442215780352)]} 0 4
[junit4:junit4]   2> 9433 T189 C15 P41033 /update {version=2&wt=javabin} {add=[72 (1417147442223120384)]} 0 2
[junit4:junit4]   2> 9440 T191 C15 P41033 /update {version=2&wt=javabin} {add=[10069 (1417147442222071808)]} 0 10
[junit4:junit4]   2> 9442 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9444 T205 C14 P48470 /update {version=2&wt=javabin} {add=[72 (1417147442228363264)]} 0 8
[junit4:junit4]   2> 9445 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9446 T188 C15 P41033 /update {version=2&wt=javabin} {delete=[69 (-1417147442238849024)]} 0 0
[junit4:junit4]   2> 9446 T207 C14 P48470 /update {version=2&wt=javabin} {add=[10069 (1417147442234654720)]} 0 4
[junit4:junit4]   2> 9450 T190 C15 P41033 /update {version=2&wt=javabin} {add=[10070 (1417147442241994752)]} 0 1
[junit4:junit4]   2> 9450 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9451 T204 C14 P48470 /update {version=2&wt=javabin} {delete=[69 (-1417147442240946176)]} 0 3
[junit4:junit4]   2> 9477 T189 C15 P41033 /update {version=2&wt=javabin} {add=[73 (1417147442247237632)]} 0 24
[junit4:junit4]   2> 9477 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9478 T206 C14 P48470 /update {version=2&wt=javabin} {add=[10070 (1417147442245140480)]} 0 26
[junit4:junit4]   2> 9481 T191 C15 P41033 /update {version=2&wt=javabin} {delete=[10066 (-1417147442275549184)]} 0 1
[junit4:junit4]   2> 9482 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9483 T205 C14 P48470 /update {version=2&wt=javabin} {add=[73 (1417147442273452032)]} 0 4
[junit4:junit4]   2> 9486 T188 C15 P41033 /update {version=2&wt=javabin} {add=[74 (1417147442280792064)]} 0 1
[junit4:junit4]   2> 9487 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9488 T207 C14 P48470 /update {version=2&wt=javabin} {delete=[10066 (-1417147442279743488)]} 0 5
[junit4:junit4]   2> 9491 T190 C15 P41033 /update {version=2&wt=javabin} {add=[10071 (1417147442286034944)]} 0 1
[junit4:junit4]   2> 9491 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9493 T204 C14 P48470 /update {version=2&wt=javabin} {add=[74 (1417147442283937792)]} 0 4
[junit4:junit4]   2> 9497 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9498 T206 C14 P48470 /update {version=2&wt=javabin} {add=[10071 (1417147442289180672)]} 0 4
[junit4:junit4]   2> 9499 T189 C15 P41033 /update {version=2&wt=javabin} {add=[75 (1417147442293374976)]} 0 1
[junit4:junit4]   2> 9501 T191 C15 P41033 /update {version=2&wt=javabin} {delete=[10067 (-1417147442296520704)]} 0 1
[junit4:junit4]   2> 9504 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9506 T205 C14 P48470 /update {version=2&wt=javabin} {add=[75 (1417147442297569280)]} 0 5
[junit4:junit4]   2> 9506 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9507 T207 C14 P48470 /update {version=2&wt=javabin} {delete=[10067 (-1417147442298617856)]} 0 4
[junit4:junit4]   2> 9509 T188 C15 P41033 /update {version=2&wt=javabin} {add=[76 (1417147442303860736)]} 0 1
[junit4:junit4]   2> 9510 T190 C15 P41033 /update {version=2&wt=javabin} {add=[10072 (1417147442305957888)]} 0 0
[junit4:junit4]   2> 9514 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9515 T204 C14 P48470 /update {version=2&wt=javabin} {add=[76 (1417147442307006464)]} 0 4
[junit4:junit4]   2> 9516 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9516 T206 C14 P48470 /update {version=2&wt=javabin} {add=[10072 (1417147442309103616)]} 0 3
[junit4:junit4]   2> 9518 T189 C15 P41033 /update {version=2&wt=javabin} {add=[77 (1417147442313297920)]} 0 1
[junit4:junit4]   2> 9519 T191 C15 P41033 /update {version=2&wt=javabin} {delete=[10069 (-1417147442315395072)]} 0 0
[junit4:junit4]   2> 9523 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9524 T207 C14 P48470 /update {version=2&wt=javabin} {delete=[10069 (-1417147442317492225)]} 0 3
[junit4:junit4]   2> 9535 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=12 
[junit4:junit4]   2> 9536 T205 C14 P48470 /update {version=2&wt=javabin} {add=[77 (1417147442317492224)]} 0 15
[junit4:junit4]   2> 9540 T190 C15 P41033 /update {version=2&wt=javabin} {delete=[70 (-1417147442337415168)]} 0 1
[junit4:junit4]   2> 9552 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9553 T204 C14 P48470 /update {version=2&wt=javabin} {delete=[70 (-1417147442347900928)]} 0 4
[junit4:junit4]   2> 9574 T189 C15 P41033 /update {version=2&wt=javabin} {add=[78 (1417147442370969600)]} 0 3
[junit4:junit4]   2> 9602 T188 C15 P41033 /update {version=2&wt=javabin} {add=[10073 (1417147442323783680)]} 0 75
[junit4:junit4]   2> 9603 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=2 
[junit4:junit4]   2> 9604 T206 C14 P48470 /update {version=2&wt=javabin} {add=[78 (1417147442375163904)]} 0 28
[junit4:junit4]   2> 9608 T191 C15 P41033 /update {version=2&wt=javabin} {add=[79 (1417147442406621184)]} 0 2
[junit4:junit4]   2> 9610 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 9611 T207 C14 P48470 /update {version=2&wt=javabin} {add=[10073 (1417147442405572608)]} 0 7
[junit4:junit4]   2> 9613 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9614 T190 C15 P41033 /update {version=2&wt=javabin} {add=[10074 (1417147442413961216)]} 0 1
[junit4:junit4]   2> 9614 T204 C14 P48470 /update {version=2&wt=javabin} {add=[79 (1417147442410815488)]} 0 4
[junit4:junit4]   2> 9617 T189 C15 P41033 /update {version=2&wt=javabin} {delete=[71 (-1417147442418155520)]} 0 0
[junit4:junit4]   2> 9621 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9639 T206 C14 P48470 /update {version=2&wt=javabin} {add=[10074 (1417147442418155520)]} 0 23
[junit4:junit4]   2> 9640 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9641 T205 C14 P48470 /update {version=2&wt=javabin} {delete=[71 (-1417147442440175616)]} 0 20
[junit4:junit4]   2> 9642 T188 C15 P41033 /update {version=2&wt=javabin} {add=[10075 (1417147442443321344)]} 0 1
[junit4:junit4]   2> 9644 T191 C15 P41033 /update {version=2&wt=javabin} {add=[80 (1417147442446467072)]} 0 1
[junit4:junit4]   2> 9648 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9649 T205 C14 P48470 /update {version=2&wt=javabin} {add=[10075 (1417147442447515648)]} 0 4
[junit4:junit4]   2> 9649 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9650 T204 C14 P48470 /update {version=2&wt=javabin} {add=[80 (1417147442449612800)]} 0 4
[junit4:junit4]   2> 9652 T190 C15 P41033 /update {version=2&wt=javabin} {add=[10076 (1417147442453807104)]} 0 1
[junit4:junit4]   2> 9653 T189 C15 P41033 /update {version=2&wt=javabin} {delete=[72 (-1417147442455904256)]} 0 0
[junit4:junit4]   2> 9657 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9658 T206 C14 P48470 /update {version=2&wt=javabin} {add=[10076 (1417147442456952832)]} 0 4
[junit4:junit4]   2> 9660 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9661 T207 C14 P48470 /update {version=2&wt=javabin} {delete=[72 (-1417147442459049984)]} 0 6
[junit4:junit4]   2> 9662 T191 C15 P41033 /update {version=2&wt=javabin} {add=[10077 (1417147442465341440)]} 0 1
[junit4:junit4]   2> 9665 T188 C15 P41033 /update {version=2&wt=javabin} {add=[81 (1417147442468487168)]} 0 1
[junit4:junit4]   2> 9669 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9670 T204 C14 P48470 /update {version=2&wt=javabin} {add=[10077 (1417147442468487168)]} 0 5
[junit4:junit4]   2> 9671 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9672 T205 C14 P48470 /update {version=2&wt=javabin} {add=[81 (1417147442471632896)]} 0 4
[junit4:junit4]   2> 9673 T190 C15 P41033 /update {version=2&wt=javabin} {delete=[10070 (-1417147442475827200)]} 0 1
[junit4:junit4]   2> 9675 T189 C15 P41033 /update {version=2&wt=javabin} {add=[82 (1417147442478972928)]} 0 0
[junit4:junit4]   2> 9678 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9679 T206 C14 P48470 /update {version=2&wt=javabin} {delete=[10070 (-1417147442480021504)]} 0 4
[junit4:junit4]   2> 9681 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9682 T207 C14 P48470 /update {version=2&wt=javabin} {add=[82 (1417147442482118656)]} 0 4
[junit4:junit4]   2> 9685 T188 C15 P41033 /update {version=2&wt=javabin} {delete=[77 (-1417147442489458688)]} 0 0
[junit4:junit4]   2> 9690 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9691 T204 C14 P48470 /update {version=2&wt=javabin} {delete=[77 (-1417147442492604416)]} 0 4
[junit4:junit4]   2> 9696 T191 C15 P41033 /update {version=2&wt=javabin} {add=[10078 (1417147442486312960)]} 0 14
[junit4:junit4]   2> 9712 T190 C15 P41033 /update {version=2&wt=javabin} {add=[83 (1417147442505187328)]} 0 13
[junit4:junit4]   2> 9715 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9716 T205 C14 P48470 /update {version=2&wt=javabin} {add=[10078 (1417147442505187328)]} 0 17
[junit4:junit4]   2> 9718 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9719 T206 C14 P48470 /update {version=2&wt=javabin} {add=[83 (1417147442520915968)]} 0 4
[junit4:junit4]   2> 9720 T189 C15 P41033 /update {version=2&wt=javabin} {add=[10079 (1417147442525110272)]} 0 2
[junit4:junit4]   2> 9722 T188 C15 P41033 /update {version=2&wt=javabin} {add=[84 (1417147442527207424)]} 0 1
[junit4:junit4]   2> 9727 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 9727 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9728 T207 C14 P48470 /update {version=2&wt=javabin} {add=[10079 (1417147442528256000)]} 0 6
[junit4:junit4]   2> 9729 T204 C14 P48470 /update {version=2&wt=javabin} {add=[84 (1417147442531401728)]} 0 5
[junit4:junit4]   2> 9732 T191 C15 P41033 /update {version=2&wt=javabin} {delete=[10071 (-1417147442538741760)]} 0 1
[junit4:junit4]   2> 9734 T190 C15 P41033 /update {version=2&wt=javabin} {add=[85 (1417147442538741761)]} 0 2
[junit4:junit4]   2> 9737 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9738 T205 C14 P48470 /update {version=2&wt=javabin} {delete=[10071 (-1417147442540838912)]} 0 4
[junit4:junit4]   2> 9739 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9740 T206 C14 P48470 /update {version=2&wt=javabin} {add=[85 (1417147442542936064)]} 0 4
[junit4:junit4]   2> 9742 T189 C15 P41033 /update {version=2&wt=javabin} {add=[10080 (1417147442547130368)]} 0 2
[junit4:junit4]   2> 9744 T188 C15 P41033 /update {version=2&wt=javabin} {add=[86 (1417147442550276096)]} 0 2
[junit4:junit4]   2> 9747 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9748 T207 C14 P48470 /update {version=2&wt=javabin} {add=[10080 (1417147442551324672)]} 0 4
[junit4:junit4]   2> 9750 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9750 T190 C15 P41033 /update {version=2&wt=javabin} {delete=[10072 (-1417147442557616128)]} 0 0
[junit4:junit4]   2> 9751 T204 C14 P48470 /update {version=2&wt=javabin} {add=[86 (1417147442554470400)]} 0 4
[junit4:junit4]   2> 9757 T191 C15 P41033 /update {version=2&wt=javabin} {delete=[78 (-1417147442564956160)]} 0 0
[junit4:junit4]   2> 9759 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9760 T206 C14 P48470 /update {version=2&wt=javabin} {delete=[10072 (-1417147442564956160)]} 0 3
[junit4:junit4]   2> 9761 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9762 T205 C14 P48470 /update {version=2&wt=javabin} {delete=[78 (-1417147442568101888)]} 0 2
[junit4:junit4]   2> 9763 T189 C15 P41033 /update {version=2&wt=javabin} {add=[10081 (1417147442570199040)]} 0 1
[junit4:junit4]   2> 9765 T188 C15 P41033 /update {version=2&wt=javabin} {add=[87 (1417147442573344768)]} 0 0
[junit4:junit4]   2> 9768 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9769 T207 C14 P48470 /update {version=2&wt=javabin} {add=[10081 (1417147442573344768)]} 0 4
[junit4:junit4]   2> 9770 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9771 T204 C14 P48470 /update {version=2&wt=javabin} {add=[87 (1417147442576490496)]} 0 3
[junit4:junit4]   2> 9772 T190 C15 P41033 /update {version=2&wt=javabin} {add=[10082 (1417147442579636224)]} 0 1
[junit4:junit4]   2> 9777 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9810 T206 C14 P48470 /update {version=2&wt=javabin} {add=[10082 (1417147442582781952)]} 0 36
[junit4:junit4]   2> 9813 T191 C15 P41033 /update {version=2&wt=javabin} {add=[88 (1417147442581733376)]} 0 40
[junit4:junit4]   2> 9813 T189 C15 P41033 /update {version=2&wt=javabin} {delete=[10077 (-1417147442623676416)]} 0 0
[junit4:junit4]   2> 9818 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9819 T204 C14 P48470 /update {version=2&wt=javabin} {delete=[10077 (-1417147442626822144)]} 0 3
[junit4:junit4]   2> 9820 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9821 T207 C14 P48470 /update {version=2&wt=javabin} {add=[88 (1417147442625773568)]} 0 6
[junit4:junit4]   2> 9823 T191 C15 P41033 /update {version=2&wt=javabin} {delete=[79 (-1417147442634162176)]} 0 0
[junit4:junit4]   2> 9824 T190 C15 P41033 /update {version=2&wt=javabin} {add=[10083 (1417147442633113600)]} 0 2
[junit4:junit4]   2> 9827 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9828 T206 C14 P48470 /update {version=2&wt=javabin} {delete=[79 (-1417147442637307904)]} 0 2
[junit4:junit4]   2> 9830 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9830 T205 C14 P48470 /update {version=2&wt=javabin} {add=[10083 (1417147442637307905)]} 0 4
[junit4:junit4]   2> 9832 T189 C15 P41033 /update {version=2&wt=javabin} {add=[89 (1417147442642550784)]} 0 1
[junit4:junit4]   2> 9833 T191 C15 P41033 /update {version=2&wt=javabin} {add=[10084 (1417147442644647936)]} 0 0
[junit4:junit4]   2> 9837 T218 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9838 T204 C14 P48470 /update {version=2&wt=javabin} {add=[89 (1417147442645696512)]} 0 4
[junit4:junit4]   2> 9838 T217 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=0 
[junit4:junit4]   2> 9840 T207 C14 P48470 /update {version=2&wt=javabin} {add=[10084 (1417147442647793664)]} 0 4
[junit4:junit4]   2> 9841 T190 C15 P41033 /update {version=2&wt=javabin} {add=[90 (1417147442653036544)]} 0 0
[junit4:junit4]   2> 9843 T188 C15 P41033 /update {version=2&wt=javabin} {add=[10085 (1417147442654085120)]} 0 1
[junit4:junit4]   2> 9848 T216 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=1 
[junit4:junit4]   2> 9849 T219 C13 P60311 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:48470/solr/collection1/&version=2&wt=javabin} status=0 QTime=3 
[junit4:junit4]   2> 9849 T205 C14 P48470 /update {version=2&wt=javabin} {add=[10085 (1417147442657230848)]} 0 4
[junit4:junit4]   2> 9850 T206 C14 P48470 /update {version=2&wt=javabin} {add=[90 (1417147442656182272)]} 0 6
[junit4:junit4]   2> 9855 T189 C15 P41033 /update {vers

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

ocommits=2,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=2,cumulative_deletesById=1,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 10519 T462 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 10519 T462 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-SoftAutoCommitTest-1351497187391/index
[junit4:junit4]   2> ASYNC  NEW_CORE C31 name=collection1 org.apache.solr.core.SolrCore@402a402a
[junit4:junit4]   2> 10525 T462 C31 UPDATE [collection1] webapp=null path=null params={} {add=[5000]} 0 1
[junit4:junit4]   2> 10565 T462 C31 UPDATE [collection1] webapp=null path=null params={} {add=[5001]} 0 0
[junit4:junit4]   2> 10594 T462 C31 UPDATE [collection1] webapp=null path=null params={} {add=[5002]} 0 2
[junit4:junit4]   2> 10608 T462 C31 UPDATE [collection1] webapp=null path=null params={} {add=[5003]} 0 0
[junit4:junit4]   2> 10613 T462 C31 UPDATE [collection1] webapp=null path=null params={} {add=[5004]} 0 1
[junit4:junit4]   2> 10614 T462 oas.SolrTestCaseJ4.tearDown ###Ending testSoftAndHardCommitMaxTimeRapidAdds
[junit4:junit4]   2> 10643 T462 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4:junit4]   2> 10643 T462 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=468851698
[junit4:junit4]   2> 10644 T462 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@402a402a
[junit4:junit4]   2> 10671 T462 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommit maxTime=1200ms,autocommits=0,soft autocommit maxTime=500ms,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=5,adds=5,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=5,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4:junit4]   2> 10672 T462 oasc.SolrCore.decrefSolrCoreState Closing SolrCoreState
[junit4:junit4]   2> 10672 T462 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4:junit4]   2> 10673 T462 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4:junit4]   2> 10675 T462 C31 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@77f877f8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8230823; maxCacheMB=0.5693359375 maxMergeSizeMB=0.091796875)),segFN=segments_4,generation=4,filenames=[_3.fdx, _1_MockVariableIntBlock_0.tib, _1_Pulsing41_0.tim, _3_Lucene41_0.pos, _3_Pulsing41_0.tim, _1_Lucene41_0.pos, _1_Pulsing41_0.tip, _3_Lucene41_0.tim, _3_MockVariableIntBlock_0.tii, _3_MockVariableIntBlock_0.pos, _3_Pulsing41_0.tip, _1_Lucene41_0.tim, _3_Lucene41_0.tip, _1_MockVariableIntBlock_0.tii, _1_MockVariableIntBlock_0.pos, _3_MockVariableIntBlock_0.skp, _1_Lucene41_0.tip, _1_MockVariableIntBlock_0.skp, _3.si, _1_nrm.cfe, _3_nrm.cfe, _1.si, segments_4, _3_MockVariableIntBlock_0.frq, _1_MockVariableIntBlock_0.frq, _1_nrm.cfs, _3_nrm.cfs, _1_Pulsing41_0.doc, _3_Pulsing41_0.doc, _3_Lucene41_0.doc, _1_Lucene41_0.doc, _1.fnm, _3_MockVariableIntBlock_0.doc, _3.fnm, _1_MockVariableIntBlock_0.doc, _3_Memory_0.ram, _1_Memory_0.ram, _3_MockVariableIntBlock_0.pyl, _1_MockVariableIntBlock_0.pyl, _1.fdt, _3.fdt, _1_Pulsing41_0.pos, _1.fdx, _3_MockVariableIntBlock_0.tib, _3_Pulsing41_0.pos]
[junit4:junit4]   2> 		commit{dir=MockDirWrapper(NRTCachingDirectory(org.apache.lucene.store.RAMDirectory@77f877f8 lockFactory=org.apache.lucene.store.NativeFSLockFactory@8230823; maxCacheMB=0.5693359375 maxMergeSizeMB=0.091796875)),segFN=segments_5,generation=5,filenames=[_3_Lucene41_0.pos, _1_Pulsing41_0.tim, _3_Pulsing41_0.tim, _1_Pulsing41_0.tip, _4_Pulsing41_0.tim, _3_Pulsing41_0.tip, _3_MockVariableIntBlock_0.tii, _4_Pulsing41_0.tip, _1_Lucene41_0.tim, _1_MockVariableIntBlock_0.pos, _4_MockVariableIntBlock_0.doc, _1_Lucene41_0.tip, _3_MockVariableIntBlock_0.skp, _4_Memory_0.ram, _1_nrm.cfe, _1.si, _3_nrm.cfe, _4_nrm.cfe, _3_MockVariableIntBlock_0.frq, _1_nrm.cfs, _3_nrm.cfs, _4_nrm.cfs, _1_Pulsing41_0.doc, _3_Pulsing41_0.doc, _4_Pulsing41_0.doc, _4_Lucene41_0.tim, _4_MockVariableIntBlock_0.pos, _4_Lucene41_0.tip, _1_Lucene41_0.doc, _3_MockVariableIntBlock_0.doc, _4.si, _1_Memory_0.ram, _1_MockVariableIntBlock_0.pyl, _1.fdt, _3.fdt, _4.fdt, _1_Pulsing41_0.pos, _1.fdx, _3_Pulsing41_0.pos, _3.fdx, _4_Pulsing41_0.pos, _1_MockVariableIntBlock_0.tib, _4.fdx, _1_Lucene41_0.pos, _4_Lucene41_0.doc, _3_Lucene41_0.tim, _3_MockVariableIntBlock_0.pos, _1_MockVariableIntBlock_0.tii, _3_Lucene41_0.tip, _1_MockVariableIntBlock_0.skp, _3.si, _4_MockVariableIntBlock_0.pyl, segments_5, _1_MockVariableIntBlock_0.frq, _4_MockVariableIntBlock_0.tib, _4_Lucene41_0.pos, _4_MockVariableIntBlock_0.tii, _3_Lucene41_0.doc, _4_MockVariableIntBlock_0.skp, _1.fnm, _3.fnm, _4.fnm, _1_MockVariableIntBlock_0.doc, _3_Memory_0.ram, _3_MockVariableIntBlock_0.pyl, _4_MockVariableIntBlock_0.frq, _3_MockVariableIntBlock_0.tib]
[junit4:junit4]   2> 10684 T462 C31 oasc.SolrDeletionPolicy.updateCommits newest commit = 5[_3_Lucene41_0.pos, _1_Pulsing41_0.tim, _3_Pulsing41_0.tim, _1_Pulsing41_0.tip, _4_Pulsing41_0.tim, _3_Pulsing41_0.tip, _3_MockVariableIntBlock_0.tii, _4_Pulsing41_0.tip, _1_Lucene41_0.tim, _1_MockVariableIntBlock_0.pos, _4_MockVariableIntBlock_0.doc, _1_Lucene41_0.tip, _3_MockVariableIntBlock_0.skp, _4_Memory_0.ram, _1_nrm.cfe, _1.si, _3_nrm.cfe, _4_nrm.cfe, _3_MockVariableIntBlock_0.frq, _1_nrm.cfs, _3_nrm.cfs, _4_nrm.cfs, _1_Pulsing41_0.doc, _3_Pulsing41_0.doc, _4_Pulsing41_0.doc, _4_Lucene41_0.tim, _4_MockVariableIntBlock_0.pos, _4_Lucene41_0.tip, _1_Lucene41_0.doc, _3_MockVariableIntBlock_0.doc, _4.si, _1_Memory_0.ram, _1_MockVariableIntBlock_0.pyl, _1.fdt, _3.fdt, _4.fdt, _1_Pulsing41_0.pos, _1.fdx, _3_Pulsing41_0.pos, _3.fdx, _4_Pulsing41_0.pos, _1_MockVariableIntBlock_0.tib, _4.fdx, _1_Lucene41_0.pos, _4_Lucene41_0.doc, _3_Lucene41_0.tim, _3_MockVariableIntBlock_0.pos, _1_MockVariableIntBlock_0.tii, _3_Lucene41_0.tip, _1_MockVariableIntBlock_0.skp, _3.si, _4_MockVariableIntBlock_0.pyl, segments_5, _1_MockVariableIntBlock_0.frq, _4_MockVariableIntBlock_0.tib, _4_Lucene41_0.pos, _4_MockVariableIntBlock_0.tii, _3_Lucene41_0.doc, _4_MockVariableIntBlock_0.skp, _1.fnm, _3.fnm, _4.fnm, _1_MockVariableIntBlock_0.doc, _3_Memory_0.ram, _3_MockVariableIntBlock_0.pyl, _4_MockVariableIntBlock_0.frq, _3_MockVariableIntBlock_0.tib]
[junit4:junit4]   2> 10685 T462 C31 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-SoftAutoCommitTest-1351497187391/index
[junit4:junit4]   2> 10687 T462 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4:junit4]   2> 10707 T462 oasc.CachingDirectoryFactory.close Releasing directory:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-SoftAutoCommitTest-1351497187391/index
[junit4:junit4]   2> NOTE: test params are: codec=Lucene41: {range_facet_si=PostingsFormat(name=Memory doPackFST= false), range_facet_l=PostingsFormat(name=Memory doPackFST= false), intDefault=MockVariableIntBlock(baseBlockSize=42), range_facet_sl=Pulsing41(freqCutoff=18 minBlockSize=97 maxBlockSize=236), timestamp=PostingsFormat(name=Memory doPackFST= false), multiDefault=PostingsFormat(name=Memory doPackFST= false), id=Pulsing41(freqCutoff=18 minBlockSize=97 maxBlockSize=236), subject=PostingsFormat(name=Memory doPackFST= false), text=Lucene41(blocksize=128)}, sim=DefaultSimilarity, locale=en_ZA, timezone=Pacific/Gambier
[junit4:junit4]   2> NOTE: Linux 3.2.0-32-generic amd64/IBM Corporation 1.6.0 (64-bit)/cpus=8,threads=1,free=21484584,total=36449280
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TermVectorComponentTest, TestCodecSupport, TestDistributedSearch, TestLRUCache, OpenExchangeRatesOrgProviderTest, BadIndexSchemaTest, TestPhraseSuggestions, DisMaxRequestHandlerTest, XmlUpdateRequestHandlerTest, ScriptEngineTest, RecoveryZkTest, ConvertedLegacyTest, QueryParsingTest, TestUpdate, EchoParamsTest, HighlighterTest, MultiTermTest, TestCSVResponseWriter, PingRequestHandlerTest, TestWriterPerf, TestOmitPositions, TestMultiCoreConfBootstrap, TestStressLucene, LukeRequestHandlerTest, TestSolrXMLSerializer, FileUtilsTest, TestFunctionQuery, UpdateParamsTest, SolrIndexConfigTest, SuggesterTest, TestValueSourceCache, TestDocSet, DebugComponentTest, CoreContainerCoreInitFailuresTest, SoftAutoCommitTest]
[junit4:junit4] Completed on J1 in 10.81s, 3 tests, 1 failure <<< FAILURES!

[...truncated 595 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:348: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:178: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/module-build.xml:61: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1159: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:827: There were test failures: 232 suites, 961 tests, 1 suite-level error, 1 failure, 7 ignored (2 assumptions)

Total time: 29 minutes 12 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 64bit/ibm-j9-jdk6 -Xjit:exclude={org/apache/lucene/util/fst/FST.pack(IIF)Lorg/apache/lucene/util/fst/FST;}
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message