lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Apache Jenkins Server <jenk...@builds.apache.org>
Subject [JENKINS] Lucene-Solr-Tests-4.x-java7 - Build # 459 - Still Failing
Date Mon, 17 Sep 2012 01:00:29 GMT
Build: https://builds.apache.org/job/Lucene-Solr-Tests-4.x-java7/459/

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

Error Message:
Suite timeout exceeded (>= 7200000 msec).

Stack Trace:
java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
	at __randomizedtesting.SeedInfo.seed([F06A935AA5795696]:0)


FAILED:  org.apache.solr.cloud.BasicDistributedZkTest.testDistribSearch

Error Message:
Test abandoned because suite timeout was reached.

Stack Trace:
java.lang.Exception: Test abandoned because suite timeout was reached.
	at __randomizedtesting.SeedInfo.seed([F06A935AA5795696]:0)




Build Log:
[...truncated 8327 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZkTest
[junit4:junit4]   2> 5 T732 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4:junit4]   2> Creating dataDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-BasicDistributedZkTest-1347836320062
[junit4:junit4]   2> 17 T732 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4:junit4]   2> 139 T733 oaz.Environment.logEnv Server environment:zookeeper.version=3.3.6-1366786, built on 07/29/2012 06:22 GMT
[junit4:junit4]   2> 139 T733 oaz.Environment.logEnv Server environment:host.name=lucene.zones.apache.org
[junit4:junit4]   2> 140 T733 oaz.Environment.logEnv Server environment:java.version=1.7.0_06
[junit4:junit4]   2> 140 T733 oaz.Environment.logEnv Server environment:java.vendor=Oracle Corporation
[junit4:junit4]   2> 140 T733 oaz.Environment.logEnv Server environment:java.home=/usr/local/openjdk7/jre
[junit4:junit4]   2> 140 T733 oaz.Environment.logEnv Server environment:java.class.path=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/classes/test:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-test-framework/classes/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/codecs/classes/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/test-framework/classes/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-solrj/classes/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/classes/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/analysis/common/lucene-analyzers-common-4.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/analysis/kuromoji/lucene-analyzers-kuromoji-4.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/analysis/phonetic/lucene-analyzers-phonetic-4.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/highlighter/lucene-highlighter-4.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/memory/lucene-memory-4.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/misc/lucene-misc-4.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/spatial/lucene-spatial-4.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/suggest/lucene-suggest-4.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/grouping/lucene-grouping-4.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/queries/lucene-queries-4.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/queryparser/lucene-queryparser-4.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/commons-cli-1.2.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/commons-codec-1.6.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/commons-fileupload-1.2.1.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/commons-lang-2.6.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/easymock-2.2.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/guava-r05.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/javax.servlet-api-3.0.1.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/spatial4j-0.3.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/commons-io-2.1.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/httpclient-4.1.3.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/httpcore-4.1.4.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/httpmime-4.1.3.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/jcl-over-slf4j-1.6.4.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/log4j-over-slf4j-1.6.4.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/slf4j-api-1.6.4.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/slf4j-jdk14-1.6.4.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/wstx-asl-3.2.7.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/zookeeper-3.3.6.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-continuation-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-deploy-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-http-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-io-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-jmx-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-security-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-server-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-servlet-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-util-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-webapp-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-xml-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/servlet-api-3.0.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/example-DIH/solr/db/lib/hsqldb-1.8.0.10.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/core/classes/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/test-framework/lib/junit-4.10.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/test-framework/lib/randomizedtesting-runner-2.0.0.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-launcher.jar:/home/hudson/.ant/lib/ivy-2.2.0.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-apache-regexp.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-junit.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-apache-bsf.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-commons-net.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-antlr.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-apache-log4j.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-commons-logging.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-apache-xalan2.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-apache-oro.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-junit4.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-netrexx.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-swing.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-javamail.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-jsch.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-jmf.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-apache-bcel.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-jai.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-testutil.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-apache-resolver.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-jdepend.jar:/usr/local/openjdk7/lib/tools.jar:/home/hudson/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.0.0.jar
[junit4:junit4]   2> 142 T733 oaz.Environment.logEnv Server environment:java.library.path=/usr/local/openjdk7/jre/lib/amd64/server:/usr/local/openjdk7/jre/lib/amd64:/usr/local/openjdk7/jre/../lib/amd64:/usr/local/openjdk6/jre/lib/amd64/server:/usr/local/openjdk6/jre/lib/amd64:/usr/local/openjdk6/jre/../lib/amd64:/usr/java/packages/lib/amd64:/lib:/usr/lib:/usr/local/lib
[junit4:junit4]   2> 142 T733 oaz.Environment.logEnv Server environment:java.io.tmpdir=.
[junit4:junit4]   2> 142 T733 oaz.Environment.logEnv Server environment:java.compiler=<NA>
[junit4:junit4]   2> 142 T733 oaz.Environment.logEnv Server environment:os.name=FreeBSD
[junit4:junit4]   2> 142 T733 oaz.Environment.logEnv Server environment:os.arch=amd64
[junit4:junit4]   2> 142 T733 oaz.Environment.logEnv Server environment:os.version=9.0-RELEASE
[junit4:junit4]   2> 143 T733 oaz.Environment.logEnv Server environment:user.name=hudson
[junit4:junit4]   2> 143 T733 oaz.Environment.logEnv Server environment:user.home=/home/hudson
[junit4:junit4]   2> 143 T733 oaz.Environment.logEnv Server environment:user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0
[junit4:junit4]   2> 191 T733 oazs.ZooKeeperServer.setTickTime tickTime set to 1000
[junit4:junit4]   2> 231 T733 oazs.NIOServerCnxn$Factory.<init> binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   2> 309 T733 oazsp.FileTxnSnapLog.save Snapshotting: 0
[junit4:junit4]   2> 328 T732 oasc.ZkTestServer.run start zk server on port:32593
[junit4:junit4]   2> 430 T732 oaz.Environment.logEnv Client environment:zookeeper.version=3.3.6-1366786, built on 07/29/2012 06:22 GMT
[junit4:junit4]   2> 431 T732 oaz.Environment.logEnv Client environment:host.name=lucene.zones.apache.org
[junit4:junit4]   2> 431 T732 oaz.Environment.logEnv Client environment:java.version=1.7.0_06
[junit4:junit4]   2> 431 T732 oaz.Environment.logEnv Client environment:java.vendor=Oracle Corporation
[junit4:junit4]   2> 432 T732 oaz.Environment.logEnv Client environment:java.home=/usr/local/openjdk7/jre
[junit4:junit4]   2> 432 T732 oaz.Environment.logEnv Client environment:java.class.path=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/classes/test:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-test-framework/classes/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/codecs/classes/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/test-framework/classes/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-solrj/classes/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/classes/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/analysis/common/lucene-analyzers-common-4.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/analysis/kuromoji/lucene-analyzers-kuromoji-4.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/analysis/phonetic/lucene-analyzers-phonetic-4.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/highlighter/lucene-highlighter-4.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/memory/lucene-memory-4.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/misc/lucene-misc-4.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/spatial/lucene-spatial-4.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/suggest/lucene-suggest-4.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/grouping/lucene-grouping-4.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/queries/lucene-queries-4.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/queryparser/lucene-queryparser-4.0-SNAPSHOT.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/commons-cli-1.2.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/commons-codec-1.6.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/commons-fileupload-1.2.1.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/commons-lang-2.6.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/easymock-2.2.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/guava-r05.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/javax.servlet-api-3.0.1.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/core/lib/spatial4j-0.3.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/commons-io-2.1.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/httpclient-4.1.3.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/httpcore-4.1.4.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/httpmime-4.1.3.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/jcl-over-slf4j-1.6.4.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/log4j-over-slf4j-1.6.4.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/slf4j-api-1.6.4.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/slf4j-jdk14-1.6.4.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/wstx-asl-3.2.7.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/solrj/lib/zookeeper-3.3.6.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-continuation-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-deploy-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-http-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-io-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-jmx-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-security-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-server-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-servlet-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-util-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-webapp-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/jetty-xml-8.1.2.v20120308.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/lib/servlet-api-3.0.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/example/example-DIH/solr/db/lib/hsqldb-1.8.0.10.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/build/core/classes/java:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/test-framework/lib/junit-4.10.jar:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/test-framework/lib/randomizedtesting-runner-2.0.0.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-launcher.jar:/home/hudson/.ant/lib/ivy-2.2.0.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-apache-regexp.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-junit.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-apache-bsf.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-commons-net.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-antlr.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-apache-log4j.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-commons-logging.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-apache-xalan2.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-apache-oro.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-junit4.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-netrexx.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-swing.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-javamail.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-jsch.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-jmf.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-apache-bcel.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-jai.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-testutil.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-apache-resolver.jar:/home/hudson/hudson-slave/tools/Ant/ant-1.8.2/lib/ant-jdepend.jar:/usr/local/openjdk7/lib/tools.jar:/home/hudson/.ivy2/cache/com.carrotsearch.randomizedtesting/junit4-ant/jars/junit4-ant-2.0.0.jar
[junit4:junit4]   2> 434 T732 oaz.Environment.logEnv Client environment:java.library.path=/usr/local/openjdk7/jre/lib/amd64/server:/usr/local/openjdk7/jre/lib/amd64:/usr/local/openjdk7/jre/../lib/amd64:/usr/local/openjdk6/jre/lib/amd64/server:/usr/local/openjdk6/jre/lib/amd64:/usr/local/openjdk6/jre/../lib/amd64:/usr/java/packages/lib/amd64:/lib:/usr/lib:/usr/local/lib
[junit4:junit4]   2> 434 T732 oaz.Environment.logEnv Client environment:java.io.tmpdir=.
[junit4:junit4]   2> 435 T732 oaz.Environment.logEnv Client environment:java.compiler=<NA>
[junit4:junit4]   2> 435 T732 oaz.Environment.logEnv Client environment:os.name=FreeBSD
[junit4:junit4]   2> 436 T732 oaz.Environment.logEnv Client environment:os.arch=amd64
[junit4:junit4]   2> 436 T732 oaz.Environment.logEnv Client environment:os.version=9.0-RELEASE
[junit4:junit4]   2> 436 T732 oaz.Environment.logEnv Client environment:user.name=hudson
[junit4:junit4]   2> 437 T732 oaz.Environment.logEnv Client environment:user.home=/home/hudson
[junit4:junit4]   2> 437 T732 oaz.Environment.logEnv Client environment:user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0
[junit4:junit4]   2> 444 T732 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:32593 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@2288779a
[junit4:junit4]   2> 507 T738 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:32593
[junit4:junit4]   2> 507 T732 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 511 T738 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:32593, initiating session
[junit4:junit4]   2> 512 T734 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:32594
[junit4:junit4]   2> 530 T734 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:32594
[junit4:junit4]   2> 548 T736 oazsp.FileTxnLog.append Creating new log file: log.1
[junit4:junit4]   2> 610 T736 oazs.NIOServerCnxn.finishSessionInit Established session 0x139d14e1a9d0000 with negotiated timeout 10000 for client /140.211.11.196:32594
[junit4:junit4]   2> 610 T738 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:32593, sessionid = 0x139d14e1a9d0000, negotiated timeout = 10000
[junit4:junit4]   2> 618 T739 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2288779a name:ZooKeeperConnection Watcher:127.0.0.1:32593 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 619 T732 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 624 T732 oascc.SolrZkClient.makePath makePath: /solr
[junit4:junit4]   2> 724 T737 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139d14e1a9d0000
[junit4:junit4]   2> 726 T739 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 726 T732 oaz.ZooKeeper.close Session: 0x139d14e1a9d0000 closed
[junit4:junit4]   2> 727 T732 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:32593/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@69d5dcae
[junit4:junit4]   2> 727 T734 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:32594 which had sessionid 0x139d14e1a9d0000
[junit4:junit4]   2> 728 T740 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:32593
[junit4:junit4]   2> 728 T732 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 729 T740 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:32593, initiating session
[junit4:junit4]   2> 729 T734 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:32595
[junit4:junit4]   2> 729 T734 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:32595
[junit4:junit4]   2> 731 T736 oazs.NIOServerCnxn.finishSessionInit Established session 0x139d14e1a9d0001 with negotiated timeout 10000 for client /140.211.11.196:32595
[junit4:junit4]   2> 731 T740 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:32593, sessionid = 0x139d14e1a9d0001, negotiated timeout = 10000
[junit4:junit4]   2> 731 T741 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@69d5dcae name:ZooKeeperConnection Watcher:127.0.0.1:32593/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 732 T732 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 739 T732 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4:junit4]   2> 744 T732 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4:junit4]   2> 753 T732 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4:junit4]   2> 756 T732 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4:junit4]   2> 760 T732 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 763 T732 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 777 T732 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   2> 778 T732 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4:junit4]   2> 884 T732 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 884 T732 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   2> 902 T732 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   2> 903 T732 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   2> 907 T732 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   2> 908 T732 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4:junit4]   2> 912 T732 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   2> 912 T732 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4:junit4]   2> 917 T732 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 917 T732 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   2> 922 T732 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 922 T732 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   2> 926 T732 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 927 T732 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   2> 931 T732 oasc.AbstractZkTestCase.putConfig put /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   2> 932 T732 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   2> 936 T737 oazs.PrepRequestProcessor.pRequest Processed session termination for sessionid: 0x139d14e1a9d0001
[junit4:junit4]   2> 937 T741 oaz.ClientCnxn$EventThread.run EventThread shut down
[junit4:junit4]   2> 937 T732 oaz.ZooKeeper.close Session: 0x139d14e1a9d0001 closed
[junit4:junit4]   2> 937 T734 oazs.NIOServerCnxn.closeSock Closed socket connection for client /140.211.11.196:32595 which had sessionid 0x139d14e1a9d0001
[junit4:junit4]   2> 940 T732 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 945 T732 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:32596
[junit4:junit4]   2> 946 T732 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 946 T732 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 947 T732 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 994 T732 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 995 T732 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 995 T732 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr
[junit4:junit4]   2> 996 T732 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/solr.xml
[junit4:junit4]   2> 996 T732 oasc.CoreContainer.<init> New CoreContainer 653738291
[junit4:junit4]   2> 997 T732 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 997 T732 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/'
[junit4:junit4]   2> 1034 T732 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 1051 T732 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:32593/solr
[junit4:junit4]   2> 1085 T732 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:32593/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@5175ae7f
[junit4:junit4]   2> 1086 T751 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:32593
[junit4:junit4]   2> 1086 T732 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 1088 T751 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:32593, initiating session
[junit4:junit4]   2> 1089 T734 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:32597
[junit4:junit4]   2> 1089 T734 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:32597
[junit4:junit4]   2> 1091 T736 oazs.NIOServerCnxn.finishSessionInit Established session 0x139d14e1a9d0002 with negotiated timeout 5000 for client /140.211.11.196:32597
[junit4:junit4]   2> 1091 T751 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:32593, sessionid = 0x139d14e1a9d0002, negotiated timeout = 5000
[junit4:junit4]   2> 1091 T752 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5175ae7f name:ZooKeeperConnection Watcher:127.0.0.1:32593/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 1091 T732 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 1115 T737 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d14e1a9d0002 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1127 T732 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4:junit4]   2> 1130 T732 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:32596_solr
[junit4:junit4]   2> 1137 T737 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d14e1a9d0002 type:delete cxid:0x7 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:32596_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:32596_solr
[junit4:junit4]   2> 1138 T732 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:32596_solr
[junit4:junit4]   2> 1143 T732 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 1143 T732 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 1144 T732 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 1144 T732 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 1145 T732 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 1145 T732 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 1145 T732 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 1146 T732 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 1146 T732 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 1147 T732 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 1165 T732 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4:junit4]   2> 1188 T737 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d14e1a9d0002 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> 1189 T732 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4:junit4]   2> 1193 T732 oasc.Overseer.start Overseer (id=88331801094586370-127.0.0.1:32596_solr-n_0000000000) starting
[junit4:junit4]   2> 1194 T737 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d14e1a9d0002 type:create cxid:0x1a zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1213 T737 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d14e1a9d0002 type:create cxid:0x1b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1214 T737 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d14e1a9d0002 type:create cxid:0x1c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1220 T737 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d14e1a9d0002 type:create cxid:0x1d zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 1222 T754 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4:junit4]   2> 1222 T732 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4:junit4]   2> 1226 T732 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 1254 T753 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4:junit4]   2> 1266 T732 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1
[junit4:junit4]   2> 1266 T732 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4:junit4]   2> 1268 T732 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 1268 T732 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4:junit4]   2> 1271 T732 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/'
[junit4:junit4]   2> 1272 T732 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 1273 T732 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4:junit4]   2> 1351 T732 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 1422 T732 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 1524 T732 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 1530 T732 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 2175 T732 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 2188 T732 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 2192 T732 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 2210 T732 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2216 T732 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 2222 T732 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2223 T732 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 2225 T732 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test-files/solr/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/control/data/
[junit4:junit4]   2> 2226 T732 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@53b0a58b
[junit4:junit4]   2> 2262 T732 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/control/data/index/
[junit4:junit4]   2> 2263 T732 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 2264 T732 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/control/data/index forceNew:false
[junit4:junit4]   2> 2270 T732 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 2271 T732 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 2272 T732 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 2274 T732 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 2275 T732 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 2275 T732 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 2276 T732 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 2277 T732 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 2277 T732 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 2278 T732 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 2279 T732 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 2279 T732 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 2280 T732 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 2281 T732 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 2281 T732 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 2282 T732 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 2282 T732 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 2283 T732 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 2283 T732 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 2284 T732 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2285 T732 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2286 T732 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2286 T732 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2287 T732 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2287 T732 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2288 T732 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 2289 T732 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 2289 T732 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 2293 T732 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 2298 T732 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 2299 T732 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 2304 T732 oass.SolrIndexSearcher.<init> Opening Searcher@2e529768 main
[junit4:junit4]   2> 2305 T732 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 2306 T732 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 2306 T732 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 2315 T732 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 2369 T755 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2e529768 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 2375 T737 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d14e1a9d0002 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> 2387 T732 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 2388 T732 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:32596/solr shardId:control_shard
[junit4:junit4]   2> 2402 T732 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4]   2> 2418 T732 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/control_shard/election
[junit4:junit4]   2> 2445 T737 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d14e1a9d0002 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> 2446 T732 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 2447 T737 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d14e1a9d0002 type:create cxid:0x66 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 2764 T753 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 2782 T737 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d14e1a9d0002 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> 2810 T752 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 2956 T732 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 2957 T732 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 2957 T732 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:32596/solr/collection1/
[junit4:junit4]   2> 2958 T732 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 2959 T732 oasc.SyncStrategy.syncToMe http://127.0.0.1:32596/solr/collection1/ has no replicas
[junit4:junit4]   2> 2959 T732 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:32596/solr/collection1/
[junit4:junit4]   2> 2960 T732 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/control_shard
[junit4:junit4]   2> 2966 T737 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d14e1a9d0002 type:create cxid:0x8b zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3317 T753 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3330 T752 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3384 T732 oasc.ZkController.register We are http://127.0.0.1:32596/solr/collection1/ and leader is http://127.0.0.1:32596/solr/collection1/
[junit4:junit4]   2> 3384 T732 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:32596/solr
[junit4:junit4]   2> 3385 T732 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 3387 T732 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3388 T732 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0
[junit4:junit4]   2> 3389 T732 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 3389 T732 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 3560 T732 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 3563 T732 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:32602
[junit4:junit4]   2> 3564 T732 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3565 T732 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-jetty1-1347836323457
[junit4:junit4]   2> 3565 T732 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-jetty1-1347836323457/'
[junit4:junit4]   2> 3608 T732 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 3609 T732 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 3609 T732 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-jetty1-1347836323457
[junit4:junit4]   2> 3610 T732 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-jetty1-1347836323457/solr.xml
[junit4:junit4]   2> 3610 T732 oasc.CoreContainer.<init> New CoreContainer 1620717856
[junit4:junit4]   2> 3611 T732 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-jetty1-1347836323457/'
[junit4:junit4]   2> 3612 T732 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-jetty1-1347836323457/'
[junit4:junit4]   2> 3645 T732 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 3662 T732 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:32593/solr
[junit4:junit4]   2> 3663 T732 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:32593/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@551d1641
[junit4:junit4]   2> 3664 T765 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:32593
[junit4:junit4]   2> 3664 T732 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 3665 T765 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:32593, initiating session
[junit4:junit4]   2> 3666 T734 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:32603
[junit4:junit4]   2> 3666 T734 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:32603
[junit4:junit4]   2> 3692 T736 oazs.NIOServerCnxn.finishSessionInit Established session 0x139d14e1a9d0003 with negotiated timeout 5000 for client /140.211.11.196:32603
[junit4:junit4]   2> 3692 T765 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:32593, sessionid = 0x139d14e1a9d0003, negotiated timeout = 5000
[junit4:junit4]   2> 3692 T766 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@551d1641 name:ZooKeeperConnection Watcher:127.0.0.1:32593/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 3693 T732 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 3694 T737 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d14e1a9d0003 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3737 T737 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d14e1a9d0003 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 3766 T732 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:32602_solr
[junit4:junit4]   2> 3767 T737 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d14e1a9d0003 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:32602_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:32602_solr
[junit4:junit4]   2> 3786 T732 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:32602_solr
[junit4:junit4]   2> 3789 T752 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 3790 T732 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 3790 T732 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 3791 T732 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 3791 T732 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 3792 T732 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 3792 T732 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 3793 T732 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 3793 T732 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 3794 T732 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 3794 T732 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 3826 T732 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 3833 T732 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-jetty1-1347836323457/collection1
[junit4:junit4]   2> 3834 T732 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 3835 T732 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 3836 T732 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 3840 T753 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 3840 T732 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-jetty1-1347836323457/collection1/'
[junit4:junit4]   2> 3842 T732 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/solrtest-jetty1-1347836323457/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 3843 T732 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/solrtest-jetty1-1347836323457/collection1/lib/README' to classloader
[junit4:junit4]   2> 3853 T766 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3853 T752 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 3903 T732 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 3980 T732 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 4081 T732 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 4088 T732 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 4792 T732 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 4805 T732 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 4811 T732 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 4832 T732 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4839 T732 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 4846 T732 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4849 T732 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 4851 T732 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-jetty1-1347836323457/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/jetty1/
[junit4:junit4]   2> 4852 T732 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@53b0a58b
[junit4:junit4]   2> 4854 T732 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/jetty1/index/
[junit4:junit4]   2> 4856 T732 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 4857 T732 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/jetty1/index forceNew:false
[junit4:junit4]   2> 4891 T732 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 4892 T732 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 4893 T732 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 4898 T732 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 4899 T732 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 4900 T732 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 4900 T732 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 4902 T732 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 4903 T732 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 4904 T732 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 4905 T732 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 4906 T732 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 4907 T732 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 4909 T732 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 4910 T732 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 4911 T732 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 4911 T732 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 4913 T732 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 4914 T732 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 4915 T732 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4916 T732 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4917 T732 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4918 T732 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4919 T732 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4920 T732 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4922 T732 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 4923 T732 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 4924 T732 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 4930 T732 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 4938 T732 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 4940 T732 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 4946 T732 oass.SolrIndexSearcher.<init> Opening Searcher@4dbc369c main
[junit4:junit4]   2> 4949 T732 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 4950 T732 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 4951 T732 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 4962 T732 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 5021 T767 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4dbc369c main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 5365 T753 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5384 T752 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5384 T766 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5531 T732 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 5531 T732 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:32602/solr shardId:shard1
[junit4:junit4]   2> 5532 T732 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   2> 5549 T737 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d14e1a9d0003 type:delete cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   2> 5550 T732 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 5551 T737 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d14e1a9d0003 type:create cxid:0x44 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5553 T732 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 5553 T732 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 5553 T732 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:32602/solr/collection1/
[junit4:junit4]   2> 5554 T732 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 5554 T732 oasc.SyncStrategy.syncToMe http://127.0.0.1:32602/solr/collection1/ has no replicas
[junit4:junit4]   2> 5554 T732 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:32602/solr/collection1/
[junit4:junit4]   2> 5555 T732 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   2> 5559 T737 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d14e1a9d0003 type:create cxid:0x4e zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 5895 T753 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5915 T752 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5915 T766 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 5918 T732 oasc.ZkController.register We are http://127.0.0.1:32602/solr/collection1/ and leader is http://127.0.0.1:32602/solr/collection1/
[junit4:junit4]   2> 5918 T732 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:32602/solr
[junit4:junit4]   2> 5919 T732 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 5929 T732 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 5930 T732 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0
[junit4:junit4]   2> 5930 T732 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 5931 T732 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 6051 T732 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 6054 T732 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:32614
[junit4:junit4]   2> 6054 T732 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6055 T732 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-jetty2-1347836325997
[junit4:junit4]   2> 6055 T732 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-jetty2-1347836325997/'
[junit4:junit4]   2> 6090 T732 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 6091 T732 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 6091 T732 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-jetty2-1347836325997
[junit4:junit4]   2> 6091 T732 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-jetty2-1347836325997/solr.xml
[junit4:junit4]   2> 6092 T732 oasc.CoreContainer.<init> New CoreContainer 896362048
[junit4:junit4]   2> 6092 T732 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-jetty2-1347836325997/'
[junit4:junit4]   2> 6093 T732 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-jetty2-1347836325997/'
[junit4:junit4]   2> 6119 T732 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 6131 T732 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:32593/solr
[junit4:junit4]   2> 6132 T732 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:32593/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@fdc078e
[junit4:junit4]   2> 6133 T777 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:32593
[junit4:junit4]   2> 6133 T732 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 6134 T777 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:32593, initiating session
[junit4:junit4]   2> 6134 T734 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:32615
[junit4:junit4]   2> 6134 T734 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:32615
[junit4:junit4]   2> 6136 T736 oazs.NIOServerCnxn.finishSessionInit Established session 0x139d14e1a9d0004 with negotiated timeout 5000 for client /140.211.11.196:32615
[junit4:junit4]   2> 6136 T777 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:32593, sessionid = 0x139d14e1a9d0004, negotiated timeout = 5000
[junit4:junit4]   2> 6137 T778 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@fdc078e name:ZooKeeperConnection Watcher:127.0.0.1:32593/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 6137 T732 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 6138 T737 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d14e1a9d0004 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6155 T737 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d14e1a9d0004 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 6156 T732 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:32614_solr
[junit4:junit4]   2> 6157 T737 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d14e1a9d0004 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:32614_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:32614_solr
[junit4:junit4]   2> 6158 T732 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:32614_solr
[junit4:junit4]   2> 6161 T752 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 6162 T766 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 6164 T732 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 6164 T732 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 6164 T732 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 6165 T732 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 6165 T732 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 6165 T732 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 6166 T732 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 6166 T732 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 6166 T732 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 6167 T732 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 6181 T732 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 6187 T732 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-jetty2-1347836325997/collection1
[junit4:junit4]   2> 6188 T732 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 6189 T732 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 6189 T732 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 6191 T732 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-jetty2-1347836325997/collection1/'
[junit4:junit4]   2> 6192 T732 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/solrtest-jetty2-1347836325997/collection1/lib/README' to classloader
[junit4:junit4]   2> 6192 T732 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/solrtest-jetty2-1347836325997/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 6237 T732 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 6292 T732 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 6394 T732 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 6399 T732 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 6424 T753 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 6438 T752 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6438 T778 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 6438 T766 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7019 T732 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 7031 T732 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 7036 T732 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 7056 T732 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 7062 T732 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 7068 T732 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 7070 T732 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 7072 T732 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-jetty2-1347836325997/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/jetty2/
[junit4:junit4]   2> 7073 T732 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@53b0a58b
[junit4:junit4]   2> 7074 T732 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/jetty2/index/
[junit4:junit4]   2> 7075 T732 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 7076 T732 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/jetty2/index forceNew:false
[junit4:junit4]   2> 7096 T732 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 7097 T732 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 7098 T732 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 7101 T732 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 7102 T732 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 7102 T732 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 7103 T732 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 7104 T732 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 7104 T732 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 7105 T732 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 7106 T732 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 7106 T732 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 7107 T732 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 7108 T732 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 7109 T732 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 7109 T732 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 7110 T732 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 7111 T732 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 7112 T732 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 7113 T732 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7113 T732 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7114 T732 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7115 T732 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7116 T732 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7116 T732 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7117 T732 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 7118 T732 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 7119 T732 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 7123 T732 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 7128 T732 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 7130 T732 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 7134 T732 oass.SolrIndexSearcher.<init> Opening Searcher@5bddefc7 main
[junit4:junit4]   2> 7136 T732 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 7136 T732 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 7137 T732 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 7150 T732 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 7212 T779 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5bddefc7 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 7448 T753 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 7455 T752 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7455 T766 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7455 T778 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 7720 T732 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 7720 T732 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:32614/solr shardId:shard2
[junit4:junit4]   2> 7722 T732 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   2> 7740 T737 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d14e1a9d0004 type:delete cxid:0x42 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard2 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard2
[junit4:junit4]   2> 7741 T732 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process.
[junit4:junit4]   2> 7743 T737 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d14e1a9d0004 type:create cxid:0x43 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7754 T732 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4:junit4]   2> 7755 T732 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4:junit4]   2> 7755 T732 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:32614/solr/collection1/
[junit4:junit4]   2> 7756 T732 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4:junit4]   2> 7756 T732 oasc.SyncStrategy.syncToMe http://127.0.0.1:32614/solr/collection1/ has no replicas
[junit4:junit4]   2> 7756 T732 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:32614/solr/collection1/
[junit4:junit4]   2> 7757 T732 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   2> 7762 T737 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d14e1a9d0004 type:create cxid:0x4c zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 7969 T753 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8009 T752 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8009 T778 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8009 T766 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8026 T732 oasc.ZkController.register We are http://127.0.0.1:32614/solr/collection1/ and leader is http://127.0.0.1:32614/solr/collection1/
[junit4:junit4]   2> 8026 T732 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:32614/solr
[junit4:junit4]   2> 8027 T732 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4:junit4]   2> 8036 T732 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8037 T732 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0
[junit4:junit4]   2> 8037 T732 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 8038 T732 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 8201 T732 oejs.Server.doStart jetty-8.1.2.v20120308
[junit4:junit4]   2> 8204 T732 oejs.AbstractConnector.doStart Started SocketConnector@0.0.0.0:32619
[junit4:junit4]   2> 8205 T732 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 8206 T732 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-jetty3-1347836328109
[junit4:junit4]   2> 8206 T732 oasc.SolrResourceLoader.<init> new SolrResourceLoader for deduced Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-jetty3-1347836328109/'
[junit4:junit4]   2> 8247 T732 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4:junit4]   2> 8248 T732 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   2> 8248 T732 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-jetty3-1347836328109
[junit4:junit4]   2> 8249 T732 oasc.CoreContainer$Initializer.initialize looking for solr.xml: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-jetty3-1347836328109/solr.xml
[junit4:junit4]   2> 8249 T732 oasc.CoreContainer.<init> New CoreContainer 1218312165
[junit4:junit4]   2> 8250 T732 oasc.CoreContainer.load Loading CoreContainer using Solr Home: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-jetty3-1347836328109/'
[junit4:junit4]   2> 8250 T732 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-jetty3-1347836328109/'
[junit4:junit4]   2> 8282 T732 oasc.CoreContainer.load Registering Log Listener
[junit4:junit4]   2> 8298 T732 oasc.CoreContainer.initZooKeeper Zookeeper client=127.0.0.1:32593/solr
[junit4:junit4]   2> 8299 T732 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:32593/solr sessionTimeout=5000 watcher=org.apache.solr.common.cloud.ConnectionManager@2bea0fa0
[junit4:junit4]   2> 8300 T789 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:32593
[junit4:junit4]   2> 8300 T732 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 8301 T789 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:32593, initiating session
[junit4:junit4]   2> 8302 T734 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:32620
[junit4:junit4]   2> 8302 T734 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:32620
[junit4:junit4]   2> 8307 T736 oazs.NIOServerCnxn.finishSessionInit Established session 0x139d14e1a9d0005 with negotiated timeout 5000 for client /140.211.11.196:32620
[junit4:junit4]   2> 8307 T789 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:32593, sessionid = 0x139d14e1a9d0005, negotiated timeout = 5000
[junit4:junit4]   2> 8308 T790 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2bea0fa0 name:ZooKeeperConnection Watcher:127.0.0.1:32593/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 8308 T732 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 8309 T737 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d14e1a9d0005 type:create cxid:0x1 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8316 T737 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d14e1a9d0005 type:create cxid:0x2 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   2> 8325 T732 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:32619_solr
[junit4:junit4]   2> 8325 T737 oazs.PrepRequestProcessor.pRequest Got user-level KeeperException when processing sessionid:0x139d14e1a9d0005 type:delete cxid:0x4 zxid:0xfffffffffffffffe txntype:unknown reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:32619_solr Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:32619_solr
[junit4:junit4]   2> 8332 T732 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:32619_solr
[junit4:junit4]   2> 8340 T752 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 8340 T778 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 8340 T766 oascc.ZkStateReader$3.process Updating live nodes
[junit4:junit4]   2> 8341 T732 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4:junit4]   2> 8342 T732 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4:junit4]   2> 8342 T732 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4:junit4]   2> 8343 T732 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4:junit4]   2> 8343 T732 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4:junit4]   2> 8344 T732 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4:junit4]   2> 8344 T732 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4:junit4]   2> 8345 T732 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4:junit4]   2> 8345 T732 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4:junit4]   2> 8346 T732 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4:junit4]   2> 8370 T732 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 8377 T732 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-jetty3-1347836328109/collection1
[junit4:junit4]   2> 8377 T732 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4:junit4]   2> 8378 T732 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4:junit4]   2> 8379 T732 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4:junit4]   2> 8381 T732 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-jetty3-1347836328109/collection1/'
[junit4:junit4]   2> 8382 T732 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/solrtest-jetty3-1347836328109/collection1/lib/README' to classloader
[junit4:junit4]   2> 8383 T732 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/solrtest-jetty3-1347836328109/collection1/lib/classes/' to classloader
[junit4:junit4]   2> 8435 T732 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_40
[junit4:junit4]   2> 8508 T732 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   2> 8529 T753 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 8609 T752 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8609 T732 oass.IndexSchema.readSchema Reading Solr Schema
[junit4:junit4]   2> 8609 T778 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8609 T790 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8609 T766 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 8617 T732 oass.IndexSchema.readSchema Schema name=test
[junit4:junit4]   2> 9286 T732 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   2> 9298 T732 oass.IndexSchema.readSchema default search field in schema is text
[junit4:junit4]   2> 9302 T732 oass.IndexSchema.readSchema unique key field: id
[junit4:junit4]   2> 9322 T732 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 9328 T732 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4:junit4]   2> 9334 T732 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 9336 T732 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   2> 9338 T732 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./solrtest-jetty3-1347836328109/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/jetty3/
[junit4:junit4]   2> 9338 T732 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@53b0a58b
[junit4:junit4]   2> 9340 T732 oasc.SolrCore.getNewIndexDir WARNING New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/jetty3/index/
[junit4:junit4]   2> 9341 T732 oasc.SolrCore.initIndex WARNING [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/jetty3/index' doesn't exist. Creating new index...
[junit4:junit4]   2> 9342 T732 oasc.CachingDirectoryFactory.get return new directory for /usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/./org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/jetty3/index forceNew:false
[junit4:junit4]   2> 9353 T732 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/jetty3/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 9354 T732 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 9355 T732 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4:junit4]   2> 9358 T732 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   2> 9359 T732 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4:junit4]   2> 9359 T732 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   2> 9360 T732 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4:junit4]   2> 9361 T732 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   2> 9361 T732 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   2> 9362 T732 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4:junit4]   2> 9363 T732 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4:junit4]   2> 9364 T732 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4:junit4]   2> 9364 T732 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4:junit4]   2> 9365 T732 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4:junit4]   2> 9366 T732 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   2> 9367 T732 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4:junit4]   2> 9367 T732 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4:junit4]   2> 9368 T732 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4:junit4]   2> 9369 T732 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4:junit4]   2> 9370 T732 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9370 T732 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9371 T732 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9372 T732 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9373 T732 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9373 T732 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9374 T732 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4:junit4]   2> 9375 T732 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4:junit4]   2> 9376 T732 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4:junit4]   2> 9380 T732 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4:junit4]   2> 9385 T732 oasc.SolrCore.initDeprecatedSupport WARNING solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4:junit4]   2> 9387 T732 oasc.SolrCore.initDeprecatedSupport WARNING adding ShowFileRequestHandler with hidden files: [SCHEMA.XML, OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT]
[junit4:junit4]   2> 9391 T732 oass.SolrIndexSearcher.<init> Opening Searcher@a81054b main
[junit4:junit4]   2> 9393 T732 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4:junit4]   2> 9393 T732 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4:junit4]   2> 9394 T732 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4:junit4]   2> 9403 T732 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4:junit4]   2> 9456 T791 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@a81054b main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 9619 T753 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9636 T766 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9636 T752 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9636 T778 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9636 T790 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 9966 T732 oasc.CoreContainer.register registering core: collection1
[junit4:junit4]   2> 9966 T732 oasc.ZkController.register Register shard - core:collection1 address:http://127.0.0.1:32619/solr shardId:shard1
[junit4:junit4]   2> 9977 T732 oasc.ZkController.register We are http://127.0.0.1:32619/solr/collection1/ and leader is http://127.0.0.1:32602/solr/collection1/
[junit4:junit4]   2> 9977 T732 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:32619/solr
[junit4:junit4]   2> 9978 T732 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4:junit4]   2> 9978 T732 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4:junit4]   2> ASYNC  NEW_CORE C106 name=collection1 org.apache.solr.core.SolrCore@62803a8f url=http://127.0.0.1:32619/solr/collection1 node=127.0.0.1:32619_solr
[junit4:junit4]   2> 9986 T792 C106 P32619 oasc.RecoveryStrategy.run Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   2> 9987 T792 C106 P32619 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4:junit4]   2> 9987 T732 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 9989 T792 C106 P32619 oasc.RecoveryStrategy.doRecovery Attempting to PeerSync from http://127.0.0.1:32602/solr/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   2> 9990 T732 oass.SolrDispatchFilter.init user.dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0
[junit4:junit4]   2> 9990 T732 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4:junit4]   2> 9991 T732 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 10001 T792 C106 P32619 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&connTimeout=30000&socketTimeout=30000&retry=false
[junit4:junit4]   2> 10015 T732 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:32593/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@1cbe810b
[junit4:junit4]   2> 10016 T793 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:32593
[junit4:junit4]   2> 10016 T732 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 10017 T793 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:32593, initiating session
[junit4:junit4]   2> 10017 T734 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:32623
[junit4:junit4]   2> 10018 T734 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:32623
[junit4:junit4]   2> 10020 T736 oazs.NIOServerCnxn.finishSessionInit Established session 0x139d14e1a9d0006 with negotiated timeout 10000 for client /140.211.11.196:32623
[junit4:junit4]   2> 10020 T793 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:32593, sessionid = 0x139d14e1a9d0006, negotiated timeout = 10000
[junit4:junit4]   2> 10020 T794 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1cbe810b name:ZooKeeperConnection Watcher:127.0.0.1:32593/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 10021 T732 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 10022 T732 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 10026 T792 C106 P32619 oasu.PeerSync.sync PeerSync: core=collection1 url=http://127.0.0.1:32619/solr START replicas=[http://127.0.0.1:32602/solr/collection1/] nUpdates=100
[junit4:junit4]   2> 10027 T732 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   2> 10027 T792 C106 P32619 oasu.PeerSync.sync WARNING no frame of reference to tell of we've missed updates
[junit4:junit4]   2> 10028 T792 C106 P32619 oasc.RecoveryStrategy.doRecovery PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   2> 10028 T792 C106 P32619 oasc.RecoveryStrategy.doRecovery Starting Replication Recovery. core=collection1
[junit4:junit4]   2> 10028 T792 C106 P32619 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 10028 T732 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> ASYNC  NEW_CORE C107 name=collection1 org.apache.solr.core.SolrCore@669fbf5e url=http://127.0.0.1:32602/solr/collection1 node=127.0.0.1:32602_solr
[junit4:junit4]   2> 10042 T758 C107 P32602 REQ /get {distrib=false&qt=/get&wt=javabin&version=2&getVersions=100} status=0 QTime=1 
[junit4:junit4]   2> 10042 T732 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10045 T732 oasc.AbstractDistribZkTestBase.waitForRecoveriesToFinish Wait for recoveries to finish - collection: collection1 failOnTimeout:true timeout (sec):1800
[junit4:junit4]   2> 10046 T732 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10143 T753 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 10157 T778 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10157 T790 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10157 T794 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10157 T752 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 10157 T766 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 11048 T732 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 12051 T732 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2>  C106_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=recovering, core=collection1, collection=collection1, node_name=127.0.0.1:32619_solr, base_url=http://127.0.0.1:32619/solr}
[junit4:junit4]   2> 13051 T792 C106 P32619 oasc.RecoveryStrategy.doRecovery Begin buffering updates. core=collection1
[junit4:junit4]   2> 13051 T792 C106 P32619 oasu.UpdateLog.bufferUpdates Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   2> 13051 T792 C106 P32619 oasc.RecoveryStrategy.replicate Attempting to replicate from http://127.0.0.1:32602/solr/collection1/. core=collection1
[junit4:junit4]   2> 13052 T792 C106 P32619 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   2> 13053 T732 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 13072 T760 C107 P32602 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 13077 T760 C107 P32602 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 13078 T760 C107 P32602 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 13079 T760 C107 P32602 oass.SolrIndexSearcher.<init> Opening Searcher@573e1f44 main
[junit4:junit4]   2> 13080 T760 C107 P32602 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 13082 T767 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@573e1f44 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   2> 13083 T760 C107 P32602 /update {waitSearcher=true&commit=true&commit_end_point=true&wt=javabin&softCommit=false&version=2} {commit=} 0 12
[junit4:junit4]   2> 13084 T792 C106 P32619 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 13085 T792 C106 P32619 oascsi.HttpClientUtil.createClient Creating new http client, config:connTimeout=5000&socketTimeout=20000&allowCompression=false&maxConnections=10000&maxConnectionsPerHost=10000
[junit4:junit4]   2> 13120 T792 C106 P32619 oash.SnapPuller.<init>  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   2> 13124 T761 C107 P32602 oash.ReplicationHandler.inform Commits will be reserved for  10000
[junit4:junit4]   2> 13125 T761 C107 P32602 REQ /replication {command=indexversion&wt=javabin&qt=/replication&version=2} status=0 QTime=2 
[junit4:junit4]   2> 13131 T792 C106 P32619 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/jetty3/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 13132 T792 C106 P32619 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 13133 T792 C106 P32619 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 13139 T792 C106 P32619 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/jetty3/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/jetty3/index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 13139 T792 C106 P32619 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 13144 T792 C106 P32619 oass.SolrIndexSearcher.<init> Opening Searcher@511a3154 main
[junit4:junit4]   2> 13145 T792 C106 P32619 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 13147 T791 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@511a3154 main{StandardDirectoryReader(segments_2:2)}
[junit4:junit4]   2> 13147 T792 C106 P32619 oasc.RecoveryStrategy.replay No replay needed. core=collection1
[junit4:junit4]   2> 13147 T792 C106 P32619 oasc.RecoveryStrategy.doRecovery Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   2> 13150 T792 C106 P32619 oasc.RecoveryStrategy.doRecovery Finished recovery process. core=collection1
[junit4:junit4]   2> 13178 T753 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> 13192 T778 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 13192 T766 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 13192 T752 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 13192 T790 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 13192 T794 oascc.ZkStateReader$2.process A cluster state change has occurred - updating...
[junit4:junit4]   2> 14056 T732 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper... 
[junit4:junit4]   2> ASYNC  NEW_CORE C108 name=collection1 org.apache.solr.core.SolrCore@71f68c1b url=http://127.0.0.1:32596/solr/collection1 node=127.0.0.1:32596_solr
[junit4:junit4]   2> 14073 T744 C108 P32596 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 14074 T744 C108 P32596 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 14076 T744 C108 P32596 oass.SolrIndexSearcher.<init> Opening Searcher@3f446c69 realtime
[junit4:junit4]   2> 14077 T744 C108 P32596 /update {wt=javabin&version=2} {deleteByQuery=*:* (-1413308831891456000)} 0 9
[junit4:junit4]   2> 14078 T732 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:32593/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@252981f2
[junit4:junit4]   2> 14079 T796 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:32593
[junit4:junit4]   2> 14079 T732 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 14080 T796 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:32593, initiating session
[junit4:junit4]   2> 14080 T734 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:32633
[junit4:junit4]   2> 14081 T734 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:32633
[junit4:junit4]   2> 14085 T736 oazs.NIOServerCnxn.finishSessionInit Established session 0x139d14e1a9d0007 with negotiated timeout 10000 for client /140.211.11.196:32633
[junit4:junit4]   2> 14085 T796 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:32593, sessionid = 0x139d14e1a9d0007, negotiated timeout = 10000
[junit4:junit4]   2> 14085 T797 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@252981f2 name:ZooKeeperConnection Watcher:127.0.0.1:32593/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 14085 T732 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 14087 T732 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 14093 T762 C107 P32602 oass.SolrIndexSearcher.<init> Opening Searcher@33d61e61 realtime
[junit4:junit4]   2>  C106_STATE=coll:collection1 core:collection1 props:{shard=shard1, roles=null, state=active, core=collection1, collection=collection1, node_name=127.0.0.1:32619_solr, base_url=http://127.0.0.1:32619/solr}
[junit4:junit4]   2> 14103 T782 C106 P32619 oass.SolrIndexSearcher.<init> Opening Searcher@1b551f84 realtime
[junit4:junit4]   2> 14104 T782 C106 P32619 REQ /update {update.distrib=FROMLEADER&_version_=-1413308831914524672&update.from=http://127.0.0.1:32602/solr/collection1/&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> ASYNC  NEW_CORE C109 name=collection1 org.apache.solr.core.SolrCore@26c054cf url=http://127.0.0.1:32614/solr/collection1 node=127.0.0.1:32614_solr
[junit4:junit4]   2> 14106 T770 C109 P32614 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 14107 T770 C109 P32614 oasc.SolrDeletionPolicy.updateCommits newest commit = 1
[junit4:junit4]   2> 14109 T770 C109 P32614 oass.SolrIndexSearcher.<init> Opening Searcher@364e96e7 realtime
[junit4:junit4]   2> 14109 T770 C109 P32614 REQ /update {update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=8 
[junit4:junit4]   2> 14110 T762 C107 P32602 /update {wt=javabin&version=2} {deleteByQuery=*:* (-1413308831914524672)} 0 19
[junit4:junit4]   2> 14121 T744 C108 P32596 /update {wt=javabin&version=2} {add=[1 (1413308831941787648)]} 0 5
[junit4:junit4]   2> 14142 T782 C106 P32619 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:32602/solr/collection1/&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 14143 T763 C107 P32602 REQ /update {distrib.from=http://127.0.0.1:32614/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=12 
[junit4:junit4]   2> 14144 T771 C109 P32614 /update {wt=javabin&version=2} {add=[1]} 0 20
[junit4:junit4]   2> 14149 T744 C108 P32596 /update {wt=javabin&version=2} {add=[2 (1413308831974293504)]} 0 2
[junit4:junit4]   2> 14156 T771 C109 P32614 /update {wt=javabin&version=2} {add=[2 (1413308831978487808)]} 0 4
[junit4:junit4]   2> 14161 T744 C108 P32596 /update {wt=javabin&version=2} {add=[3 (1413308831985827840)]} 0 2
[junit4:junit4]   2> 14169 T770 C109 P32614 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14170 T762 C107 P32602 /update {wt=javabin&version=2} {add=[3]} 0 6
[junit4:junit4]   2> 14175 T744 C108 P32596 /update {wt=javabin&version=2} {add=[4 (1413308832000507904)]} 0 2
[junit4:junit4]   2> 14186 T782 C106 P32619 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14187 T762 C107 P32602 /update {wt=javabin&version=2} {add=[4 (1413308832006799360)]} 0 9
[junit4:junit4]   2> 14192 T744 C108 P32596 /update {wt=javabin&version=2} {add=[5 (1413308832018333696)]} 0 2
[junit4:junit4]   2> 14200 T770 C109 P32614 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14201 T762 C107 P32602 /update {wt=javabin&version=2} {add=[5]} 0 6
[junit4:junit4]   2> 14206 T744 C108 P32596 /update {wt=javabin&version=2} {add=[6 (1413308832033013760)]} 0 2
[junit4:junit4]   2> 14211 T771 C109 P32614 /update {wt=javabin&version=2} {add=[6 (1413308832038256640)]} 0 2
[junit4:junit4]   2> 14216 T744 C108 P32596 /update {wt=javabin&version=2} {add=[7 (1413308832043499520)]} 0 2
[junit4:junit4]   2> 14221 T771 C109 P32614 /update {wt=javabin&version=2} {add=[7 (1413308832048742400)]} 0 2
[junit4:junit4]   2> 14226 T744 C108 P32596 /update {wt=javabin&version=2} {add=[8 (1413308832053985280)]} 0 2
[junit4:junit4]   2> 14239 T782 C106 P32619 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:32602/solr/collection1/&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14240 T763 C107 P32602 REQ /update {distrib.from=http://127.0.0.1:32614/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=8 
[junit4:junit4]   2> 14241 T771 C109 P32614 /update {wt=javabin&version=2} {add=[8]} 0 12
[junit4:junit4]   2> 14246 T744 C108 P32596 /update {wt=javabin&version=2} {add=[9 (1413308832074956800)]} 0 2
[junit4:junit4]   2> 14251 T771 C109 P32614 /update {wt=javabin&version=2} {add=[9 (1413308832081248256)]} 0 2
[junit4:junit4]   2> 14290 T744 C108 P32596 /update {wt=javabin&version=2} {add=[10 (1413308832086491136)]} 0 36
[junit4:junit4]   2> 14302 T782 C106 P32619 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:32602/solr/collection1/&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14303 T763 C107 P32602 REQ /update {distrib.from=http://127.0.0.1:32614/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 14303 T771 C109 P32614 /update {wt=javabin&version=2} {add=[10]} 0 10
[junit4:junit4]   2> 14310 T744 C108 P32596 /update {wt=javabin&version=2} {add=[11 (1413308832139968512)]} 0 4
[junit4:junit4]   2> 14322 T782 C106 P32619 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:32602/solr/collection1/&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14323 T763 C107 P32602 REQ /update {distrib.from=http://127.0.0.1:32614/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 14323 T771 C109 P32614 /update {wt=javabin&version=2} {add=[11]} 0 10
[junit4:junit4]   2> 14329 T744 C108 P32596 /update {wt=javabin&version=2} {add=[12 (1413308832163037184)]} 0 2
[junit4:junit4]   2> 14338 T782 C106 P32619 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14339 T762 C107 P32602 /update {wt=javabin&version=2} {add=[12 (1413308832167231488)]} 0 7
[junit4:junit4]   2> 14344 T744 C108 P32596 /update {wt=javabin&version=2} {add=[13 (1413308832177717248)]} 0 2
[junit4:junit4]   2> 14354 T782 C106 P32619 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14355 T762 C107 P32602 /update {wt=javabin&version=2} {add=[13 (1413308832182960128)]} 0 8
[junit4:junit4]   2> 14360 T744 C108 P32596 /update {wt=javabin&version=2} {add=[14 (1413308832194494464)]} 0 2
[junit4:junit4]   2> 14369 T782 C106 P32619 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14370 T762 C107 P32602 /update {wt=javabin&version=2} {add=[14 (1413308832199737344)]} 0 7
[junit4:junit4]   2> 14375 T744 C108 P32596 /update {wt=javabin&version=2} {add=[15 (1413308832210223104)]} 0 2
[junit4:junit4]   2> 14384 T782 C106 P32619 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14385 T762 C107 P32602 /update {wt=javabin&version=2} {add=[15 (1413308832215465984)]} 0 7
[junit4:junit4]   2> 14390 T744 C108 P32596 /update {wt=javabin&version=2} {add=[16 (1413308832225951744)]} 0 2
[junit4:junit4]   2> 14452 T782 C106 P32619 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=29 
[junit4:junit4]   2> 14453 T762 C107 P32602 /update {wt=javabin&version=2} {add=[16 (1413308832231194624)]} 0 60
[junit4:junit4]   2> 14461 T744 C108 P32596 /update {wt=javabin&version=2} {add=[17 (1413308832298303488)]} 0 5
[junit4:junit4]   2> 14473 T770 C109 P32614 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=5 
[junit4:junit4]   2> 14473 T762 C107 P32602 /update {wt=javabin&version=2} {add=[17]} 0 9
[junit4:junit4]   2> 14479 T744 C108 P32596 /update {wt=javabin&version=2} {add=[100 (1413308832319275008)]} 0 2
[junit4:junit4]   2> 14491 T782 C106 P32619 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 14492 T762 C107 P32602 /update {wt=javabin&version=2} {add=[100 (1413308832324517888)]} 0 10
[junit4:junit4]   2> 14497 T744 C108 P32596 /update {wt=javabin&version=2} {add=[101 (1413308832339197952)]} 0 2
[junit4:junit4]   2> 14509 T782 C106 P32619 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:32602/solr/collection1/&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14509 T763 C107 P32602 REQ /update {distrib.from=http://127.0.0.1:32614/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 14510 T771 C109 P32614 /update {wt=javabin&version=2} {add=[101]} 0 10
[junit4:junit4]   2> 14539 T744 C108 P32596 /update {wt=javabin&version=2} {add=[102 (1413308832357023744)]} 0 26
[junit4:junit4]   2> 14551 T782 C106 P32619 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:32602/solr/collection1/&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14552 T763 C107 P32602 REQ /update {distrib.from=http://127.0.0.1:32614/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 14552 T771 C109 P32614 /update {wt=javabin&version=2} {add=[102]} 0 10
[junit4:junit4]   2> 14558 T744 C108 P32596 /update {wt=javabin&version=2} {add=[103 (1413308832401063936)]} 0 3
[junit4:junit4]   2> 14563 T771 C109 P32614 /update {wt=javabin&version=2} {add=[103 (1413308832407355392)]} 0 2
[junit4:junit4]   2> 14568 T744 C108 P32596 /update {wt=javabin&version=2} {add=[104 (1413308832412598272)]} 0 2
[junit4:junit4]   2> 14573 T771 C109 P32614 /update {wt=javabin&version=2} {add=[104 (1413308832417841152)]} 0 2
[junit4:junit4]   2> 14578 T744 C108 P32596 /update {wt=javabin&version=2} {add=[105 (1413308832423084032)]} 0 2
[junit4:junit4]   2> 14608 T771 C109 P32614 /update {wt=javabin&version=2} {add=[105 (1413308832428326912)]} 0 27
[junit4:junit4]   2> 14613 T744 C108 P32596 /update {wt=javabin&version=2} {add=[106 (1413308832460832768)]} 0 2
[junit4:junit4]   2> 14620 T771 C109 P32614 /update {wt=javabin&version=2} {add=[106 (1413308832466075648)]} 0 4
[junit4:junit4]   2> 14625 T744 C108 P32596 /update {wt=javabin&version=2} {add=[107 (1413308832472367104)]} 0 2
[junit4:junit4]   2> 14637 T782 C106 P32619 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:32602/solr/collection1/&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14638 T763 C107 P32602 REQ /update {distrib.from=http://127.0.0.1:32614/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 14639 T771 C109 P32614 /update {wt=javabin&version=2} {add=[107]} 0 11
[junit4:junit4]   2> 14644 T744 C108 P32596 /update {wt=javabin&version=2} {add=[108 (1413308832492290048)]} 0 2
[junit4:junit4]   2> 14662 T782 C106 P32619 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:32602/solr/collection1/&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14663 T763 C107 P32602 REQ /update {distrib.from=http://127.0.0.1:32614/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=13 
[junit4:junit4]   2> 14663 T771 C109 P32614 /update {wt=javabin&version=2} {add=[108]} 0 16
[junit4:junit4]   2> 14668 T744 C108 P32596 /update {wt=javabin&version=2} {add=[109 (1413308832518504448)]} 0 2
[junit4:junit4]   2> 14681 T782 C106 P32619 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:32602/solr/collection1/&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14681 T763 C107 P32602 REQ /update {distrib.from=http://127.0.0.1:32614/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 14682 T771 C109 P32614 /update {wt=javabin&version=2} {add=[109]} 0 11
[junit4:junit4]   2> 14687 T744 C108 P32596 /update {wt=javabin&version=2} {add=[110 (1413308832537378816)]} 0 2
[junit4:junit4]   2> 14696 T782 C106 P32619 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14696 T762 C107 P32602 /update {wt=javabin&version=2} {add=[110 (1413308832542621696)]} 0 6
[junit4:junit4]   2> 14701 T744 C108 P32596 /update {wt=javabin&version=2} {add=[111 (1413308832552058880)]} 0 2
[junit4:junit4]   2> 14712 T782 C106 P32619 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:32602/solr/collection1/&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14712 T763 C107 P32602 REQ /update {distrib.from=http://127.0.0.1:32614/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 14713 T771 C109 P32614 /update {wt=javabin&version=2} {add=[111]} 0 10
[junit4:junit4]   2> 14735 T744 C108 P32596 /update {wt=javabin&version=2} {add=[112 (1413308832569884672)]} 0 19
[junit4:junit4]   2> 14740 T771 C109 P32614 /update {wt=javabin&version=2} {add=[112 (1413308832592953344)]} 0 2
[junit4:junit4]   2> 14746 T744 C108 P32596 /update {wt=javabin&version=2} {add=[113 (1413308832598196224)]} 0 3
[junit4:junit4]   2> 14758 T782 C106 P32619 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:32602/solr/collection1/&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14758 T763 C107 P32602 REQ /update {distrib.from=http://127.0.0.1:32614/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 14759 T771 C109 P32614 /update {wt=javabin&version=2} {add=[113]} 0 10
[junit4:junit4]   2> 14764 T744 C108 P32596 /update {wt=javabin&version=2} {add=[114 (1413308832618119168)]} 0 2
[junit4:junit4]   2> 14807 T782 C106 P32619 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=19 
[junit4:junit4]   2> 14808 T762 C107 P32602 /update {wt=javabin&version=2} {add=[114 (1413308832623362048)]} 0 41
[junit4:junit4]   2> 14813 T744 C108 P32596 /update {wt=javabin&version=2} {add=[115 (1413308832669499392)]} 0 2
[junit4:junit4]   2> 14818 T771 C109 P32614 /update {wt=javabin&version=2} {add=[115 (1413308832674742272)]} 0 2
[junit4:junit4]   2> 14823 T744 C108 P32596 /update {wt=javabin&version=2} {add=[116 (1413308832679985152)]} 0 2
[junit4:junit4]   2> 14827 T771 C109 P32614 /update {wt=javabin&version=2} {add=[116 (1413308832684179456)]} 0 2
[junit4:junit4]   2> 14832 T744 C108 P32596 /update {wt=javabin&version=2} {add=[117 (1413308832689422336)]} 0 2
[junit4:junit4]   2> 14837 T771 C109 P32614 /update {wt=javabin&version=2} {add=[117 (1413308832694665216)]} 0 2
[junit4:junit4]   2> 14842 T744 C108 P32596 /update {wt=javabin&version=2} {add=[118 (1413308832699908096)]} 0 2
[junit4:junit4]   2> 14854 T782 C106 P32619 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 14855 T762 C107 P32602 /update {wt=javabin&version=2} {add=[118 (1413308832705150976)]} 0 10
[junit4:junit4]   2> 14860 T744 C108 P32596 /update {wt=javabin&version=2} {add=[119 (1413308832719831040)]} 0 2
[junit4:junit4]   2> 14865 T771 C109 P32614 /update {wt=javabin&version=2} {add=[119 (1413308832724025344)]} 0 2
[junit4:junit4]   2> 14870 T744 C108 P32596 /update {wt=javabin&version=2} {add=[120 (1413308832729268224)]} 0 2
[junit4:junit4]   2> 14877 T770 C109 P32614 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14878 T762 C107 P32602 /update {wt=javabin&version=2} {add=[120]} 0 6
[junit4:junit4]   2> 14883 T744 C108 P32596 /update {wt=javabin&version=2} {add=[121 (1413308832742899712)]} 0 2
[junit4:junit4]   2> 14888 T771 C109 P32614 /update {wt=javabin&version=2} {add=[121 (1413308832748142592)]} 0 2
[junit4:junit4]   2> 14911 T744 C108 P32596 /update {wt=javabin&version=2} {add=[122 (1413308832753385472)]} 0 20
[junit4:junit4]   2> 14924 T782 C106 P32619 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:32602/solr/collection1/&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14924 T763 C107 P32602 REQ /update {distrib.from=http://127.0.0.1:32614/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 14925 T771 C109 P32614 /update {wt=javabin&version=2} {add=[122]} 0 11
[junit4:junit4]   2> 14932 T744 C108 P32596 /update {wt=javabin&version=2} {add=[123 (1413308832792182784)]} 0 4
[junit4:junit4]   2> 14945 T782 C106 P32619 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:32602/solr/collection1/&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14946 T763 C107 P32602 REQ /update {distrib.from=http://127.0.0.1:32614/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=8 
[junit4:junit4]   2> 14947 T771 C109 P32614 /update {wt=javabin&version=2} {add=[123]} 0 12
[junit4:junit4]   2> 14952 T744 C108 P32596 /update {wt=javabin&version=2} {add=[124 (1413308832815251456)]} 0 2
[junit4:junit4]   2> 14965 T782 C106 P32619 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:32602/solr/collection1/&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14965 T763 C107 P32602 REQ /update {distrib.from=http://127.0.0.1:32614/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 14966 T771 C109 P32614 /update {wt=javabin&version=2} {add=[124]} 0 11
[junit4:junit4]   2> 14971 T744 C108 P32596 /update {wt=javabin&version=2} {add=[125 (1413308832835174400)]} 0 2
[junit4:junit4]   2> 14984 T782 C106 P32619 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:32602/solr/collection1/&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14984 T763 C107 P32602 REQ /update {distrib.from=http://127.0.0.1:32614/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=7 
[junit4:junit4]   2> 14985 T771 C109 P32614 /update {wt=javabin&version=2} {add=[125]} 0 11
[junit4:junit4]   2> 14990 T744 C108 P32596 /update {wt=javabin&version=2} {add=[126 (1413308832855097344)]} 0 2
[junit4:junit4]   2> 14998 T770 C109 P32614 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 14998 T762 C107 P32602 /update {wt=javabin&version=2} {add=[126]} 0 5
[junit4:junit4]   2> 15003 T744 C108 P32596 /update {wt=javabin&version=2} {add=[127 (1413308832868728832)]} 0 2
[junit4:junit4]   2> 15028 T770 C109 P32614 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=20 
[junit4:junit4]   2> 15029 T762 C107 P32602 /update {wt=javabin&version=2} {add=[127]} 0 24
[junit4:junit4]   2> 15034 T744 C108 P32596 /update {wt=javabin&version=2} {add=[128 (1413308832902283264)]} 0 2
[junit4:junit4]   2> 15044 T770 C109 P32614 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=4 
[junit4:junit4]   2> 15044 T762 C107 P32602 /update {wt=javabin&version=2} {add=[128]} 0 7
[junit4:junit4]   2> 15049 T744 C108 P32596 /update {wt=javabin&version=2} {add=[129 (1413308832916963328)]} 0 2
[junit4:junit4]   2> 15058 T782 C106 P32619 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 15059 T762 C107 P32602 /update {wt=javabin&version=2} {add=[129 (1413308832923254784)]} 0 7
[junit4:junit4]   2> 15064 T744 C108 P32596 /update {wt=javabin&version=2} {add=[130 (1413308832933740544)]} 0 2
[junit4:junit4]   2> 15070 T771 C109 P32614 /update {wt=javabin&version=2} {add=[130 (1413308832938983424)]} 0 3
[junit4:junit4]   2> 15075 T744 C108 P32596 /update {wt=javabin&version=2} {add=[131 (1413308832945274880)]} 0 2
[junit4:junit4]   2> 15083 T770 C109 P32614 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 15084 T762 C107 P32602 /update {wt=javabin&version=2} {add=[131]} 0 6
[junit4:junit4]   2> 15108 T744 C108 P32596 /update {wt=javabin&version=2} {add=[132 (1413308832959954944)]} 0 21
[junit4:junit4]   2> 15120 T782 C106 P32619 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:32602/solr/collection1/&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 15120 T763 C107 P32602 REQ /update {distrib.from=http://127.0.0.1:32614/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 15121 T771 C109 P32614 /update {wt=javabin&version=2} {add=[132]} 0 10
[junit4:junit4]   2> 15127 T744 C108 P32596 /update {wt=javabin&version=2} {add=[133 (1413308832997703680)]} 0 3
[junit4:junit4]   2> 15139 T782 C106 P32619 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:32602/solr/collection1/&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 15139 T763 C107 P32602 REQ /update {distrib.from=http://127.0.0.1:32614/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 15140 T771 C109 P32614 /update {wt=javabin&version=2} {add=[133]} 0 10
[junit4:junit4]   2> 15145 T744 C108 P32596 /update {wt=javabin&version=2} {add=[134 (1413308833017626624)]} 0 2
[junit4:junit4]   2> 15152 T770 C109 P32614 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 15152 T762 C107 P32602 /update {wt=javabin&version=2} {add=[134]} 0 5
[junit4:junit4]   2> 15156 T744 C108 P32596 /update {wt=javabin&version=2} {add=[135 (1413308833030209536)]} 0 1
[junit4:junit4]   2> 15168 T782 C106 P32619 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:32602/solr/collection1/&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 15168 T763 C107 P32602 REQ /update {distrib.from=http://127.0.0.1:32614/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=6 
[junit4:junit4]   2> 15169 T771 C109 P32614 /update {wt=javabin&version=2} {add=[135]} 0 10
[junit4:junit4]   2> 15174 T744 C108 P32596 /update {wt=javabin&version=2} {add=[136 (1413308833048035328)]} 0 2
[junit4:junit4]   2> 15178 T771 C109 P32614 /update {wt=javabin&version=2} {add=[136 (1413308833052229632)]} 0 2
[junit4:junit4]   2> 15183 T744 C108 P32596 /update {wt=javabin&version=2} {add=[137 (1413308833057472512)]} 0 2
[junit4:junit4]   2> 15190 T770 C109 P32614 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 15190 T762 C107 P32602 /update {wt=javabin&version=2} {add=[137]} 0 5
[junit4:junit4]   2> 15195 T744 C108 P32596 /update {wt=javabin&version=2} {add=[138 (1413308833070055424)]} 0 2
[junit4:junit4]   2> 15237 T782 C106 P32619 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=19 
[junit4:junit4]   2> 15238 T762 C107 P32602 /update {wt=javabin&version=2} {add=[138 (1413308833075298304)]} 0 41
[junit4:junit4]   2> 15243 T744 C108 P32596 /update {wt=javabin&version=2} {add=[139 (1413308833121435648)]} 0 2
[junit4:junit4]   2> 15248 T771 C109 P32614 /update {wt=javabin&version=2} {add=[139 (1413308833126678528)]} 0 2
[junit4:junit4]   2> 15253 T744 C108 P32596 /update {wt=javabin&version=2} {add=[140 (1413308833130872832)]} 0 2
[junit4:junit4]   2> 15259 T770 C109 P32614 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15260 T762 C107 P32602 /update {wt=javabin&version=2} {add=[140]} 0 5
[junit4:junit4]   2> 15264 T744 C108 P32596 /update {wt=javabin&version=2} {add=[141 (1413308833143455744)]} 0 1
[junit4:junit4]   2> 15269 T771 C109 P32614 /update {wt=javabin&version=2} {add=[141 (1413308833147650048)]} 0 2
[junit4:junit4]   2> 15291 T744 C108 P32596 /update {wt=javabin&version=2} {add=[142 (1413308833152892928)]} 0 19
[junit4:junit4]   2> 15314 T770 C109 P32614 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=17 
[junit4:junit4]   2> 15315 T762 C107 P32602 /update {wt=javabin&version=2} {add=[142]} 0 21
[junit4:junit4]   2> 15321 T744 C108 P32596 /update {wt=javabin&version=2} {add=[143 (1413308833201127424)]} 0 3
[junit4:junit4]   2> 15327 T771 C109 P32614 /update {wt=javabin&version=2} {add=[143 (1413308833208467456)]} 0 3
[junit4:junit4]   2> 15333 T744 C108 P32596 /update {wt=javabin&version=2} {add=[144 (1413308833214758912)]} 0 2
[junit4:junit4]   2> 15340 T770 C109 P32614 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 15340 T762 C107 P32602 /update {wt=javabin&version=2} {add=[144]} 0 5
[junit4:junit4]   2> 15345 T744 C108 P32596 /update {wt=javabin&version=2} {add=[145 (1413308833228390400)]} 0 2
[junit4:junit4]   2> 15350 T771 C109 P32614 /update {wt=javabin&version=2} {add=[145 (1413308833233633280)]} 0 2
[junit4:junit4]   2> 15355 T744 C108 P32596 /update {wt=javabin&version=2} {add=[146 (1413308833237827584)]} 0 2
[junit4:junit4]   2> 15370 T782 C106 P32619 REQ /update {update.distrib=FROMLEADER&distrib.from=http://127.0.0.1:32602/solr/collection1/&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 15371 T763 C107 P32602 REQ /update {distrib.from=http://127.0.0.1:32614/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=10 
[junit4:junit4]   2> 15371 T771 C109 P32614 /update {wt=javabin&version=2} {add=[146]} 0 13
[junit4:junit4]   2> 15377 T744 C108 P32596 /update {wt=javabin&version=2} {add=[147 (1413308833260896256)]} 0 2
[junit4:junit4]   2> 15384 T770 C109 P32614 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 15384 T762 C107 P32602 /update {wt=javabin&version=2} {add=[147]} 0 5
[junit4:junit4]   2> 15388 T744 C108 P32596 /update {wt=javabin&version=2} {add=[148 (1413308833273479168)]} 0 1
[junit4:junit4]   2> 15396 T782 C106 P32619 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=FROMLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15397 T762 C107 P32602 /update {wt=javabin&version=2} {add=[148 (1413308833277673472)]} 0 6
[junit4:junit4]   2> 15402 T744 C108 P32596 /update {wt=javabin&version=2} {add=[149 (1413308833287110656)]} 0 2
[junit4:junit4]   2> 15409 T770 C109 P32614 REQ /update {distrib.from=http://127.0.0.1:32602/solr/collection1/&update.distrib=TOLEADER&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 15410 T762 C107 P32602 /update {wt=javabin&version=2} {add=[149]} 0 5
[junit4:junit4]   2> 15413 T744 C108 P32596 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 15927 T744 C108 P32596 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/control/data/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/control/data/index,segFN=segments_2,generation=2,filenames=[_5_nrm.cfe, _4_TestBloomFilteredLucene40Postings_0.frq, _6_MockFixedIntBlock_0.pyl, _2_SimpleText_0.pst, _3_TestBloomFilteredLucene40Postings_0.prx, _6_MockFixedIntBlock_0.frq, _2_TestBloomFilteredLucene40Postings_0.blm, _3.fdt, _0_MockFixedIntBlock_0.tib, _6_MockFixedIntBlock_0.skp, _0_MockFixedIntBlock_0.tii, _0_SimpleText_0.pst, _0_TestBloomFilteredLucene40Postings_0.frq, _3_TestBloomFilteredLucene40Postings_0.frq, _3_TestBloomFilteredLucene40Postings_0.tim, _0_TestBloomFilteredLucene40Postings_0.blm, _4_MockFixedIntBlock_0.pos, _2.fdt, _1_MockRandom_0.sd, _3_TestBloomFilteredLucene40Postings_0.tip, _2.fdx, _1_MockRandom_0.tii, _0_MockRandom_0.skp, _5_TestBloomFilteredLucene40Postings_0.frq, _1.fdx, _0_MockRandom_0.frq, _1_MockRandom_0.tib, _5_nrm.cfs, _1.fdt, _4_MockFixedIntBlock_0.frq, _2_MockFixedIntBlock_0.doc, _3.si, _1_MockFixedIntBlock_0.pos, _6.fdt, _3_TestBloomFilteredLucene40Postings_0.blm, _1_SimpleText_0.pst, _2.si, _3_MockFixedIntBlock_0.frq, _6.fdx, _2_MockFixedIntBlock_0.skp, _6_MockFixedIntBlock_0.tib, _3.fnm, _6_MockFixedIntBlock_0.tii, _4_TestBloomFilteredLucene40Postings_0.prx, _2_nrm.cfe, _0_MockFixedIntBlock_0.pyl, _5_TestBloomFilteredLucene40Postings_0.blm, _2_nrm.cfs, _2_TestBloomFilteredLucene40Postings_0.frq, _6_MockFixedIntBlock_0.doc, _4_TestBloomFilteredLucene40Postings_0.blm, _5_MockFixedIntBlock_0.frq, _5_MockFixedIntBlock_0.tib, _3_MockFixedIntBlock_0.pos, _2.fnm, _3.fdx, _0_MockFixedIntBlock_0.doc, _1.si, _4_TestBloomFilteredLucene40Postings_0.tim, _6.si, _6.fnm, _4_TestBloomFilteredLucene40Postings_0.tip, _5_MockFixedIntBlock_0.tii, _3_nrm.cfe, _5.fdt, _3_MockFixedIntBlock_0.skp, _4_MockFixedIntBlock_0.tib, _2_TestBloomFilteredLucene40Postings_0.prx, _1_MockFixedIntBlock_0.pyl, _4_nrm.cfe, _4.fnm, _1.fnm, _2_MockFixedIntBlock_0.tii, _4.fdx, _3_MockFixedIntBlock_0.pyl, _2_MockFixedIntBlock_0.tib, _2_MockFixedIntBlock_0.pyl, _3_SimpleText_0.pst, _4_MockFixedIntBlock_0.tii, _2_MockFixedIntBlock_0.frq, _5_TestBloomFilteredLucene40Postings_0.prx, _0_TestBloomFilteredLucene40Postings_0.tim, _2_TestBloomFilteredLucene40Postings_0.tim, _0_TestBloomFilteredLucene40Postings_0.tip, _0.fnm, _1_MockFixedIntBlock_0.frq, _5_MockFixedIntBlock_0.pyl, _4_MockFixedIntBlock_0.skp, _0_MockFixedIntBlock_0.pos, _1_TestBloomFilteredLucene40Postings_0.frq, _2_TestBloomFilteredLucene40Postings_0.tip, _5.si, _3_nrm.cfs, _6_MockFixedIntBlock_0.pos, _5_MockFixedIntBlock_0.skp, _1_MockFixedIntBlock_0.skp, _2_MockFixedIntBlock_0.pos, _4_MockFixedIntBlock_0.doc, _0_MockRandom_0.doc, _4_nrm.cfs, _0_MockRandom_0.pos, _1_MockRandom_0.frq, _1_MockFixedIntBlock_0.doc, _0_TestBloomFilteredLucene40Postings_0.prx, _1_TestBloomFilteredLucene40Postings_0.blm, _3_MockFixedIntBlock_0.doc, _1_TestBloomFilteredLucene40Postings_0.tip, _6_nrm.cfs, _0_MockFixedIntBlock_0.skp, _6_TestBloomFilteredLucene40Postings_0.tip, _4_MockFixedIntBlock_0.pyl, _1_TestBloomFilteredLucene40Postings_0.tim, _0.si, _6_TestBloomFilteredLucene40Postings_0.tim, _6_TestBloomFilteredLucene40Postings_0.frq, _5_SimpleText_0.pst, _1_MockFixedIntBlock_0.tii, _1_nrm.cfs, _0_MockRandom_0.tib, _5.fnm, _1_TestBloomFilteredLucene40Postings_0.prx, _6_nrm.cfe, _5_MockFixedIntBlock_0.pos, _3_MockFixedIntBlock_0.tib, _5_MockFixedIntBlock_0.doc, _4.si, _4.fdt, _0_nrm.cfs, _6_SimpleText_0.pst, _1_nrm.cfe, _0_MockFixedIntBlock_0.frq, _4_SimpleText_0.pst, _1_MockRandom_0.prx, _0_MockRandom_0.sd, _0_nrm.cfe, _5.fdx, _0_MockRandom_0.tii, segments_2, _1_MockFixedIntBlock_0.tib, _6_TestBloomFilteredLucene40Postings_0.blm, _0.fdx, _6_TestBloomFilteredLucene40Postings_0.prx, _5_TestBloomFilteredLucene40Postings_0.tim, _0_MockRandom_0.pyl, _5_TestBloomFilteredLucene40Postings_0.tip, _0.fdt, _3_MockFixedIntBlock_0.tii]
[junit4:junit4]   2> 15928 T744 C108 P32596 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 15939 T744 C108 P32596 oass.SolrIndexSearcher.<init> Opening Searcher@19620a92 main
[junit4:junit4]   2> 15940 T744 C108 P32596 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 15942 T755 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@19620a92 main{StandardDirectoryReader(segments_2:15:nrt _0(4.0.0.2):C10 _1(4.0.0.2):C10 _2(4.0.0.2):C10 _3(4.0.0.2):C10 _4(4.0.0.2):C10 _5(4.0.0.2):C10 _6(4.0.0.2):C7)}
[junit4:junit4]   2> 15942 T744 C108 P32596 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 529
[junit4:junit4]   2> 15945 T771 C109 P32614 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 16267 T771 C109 P32614 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/jetty2/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/jetty2/index,segFN=segments_2,generation=2,filenames=[_3_nrm.cfe, _3_MockFixedIntBlock_0.skp, _3_TestBloomFilteredLucene40Postings_0.prx, _2_SimpleText_0.pst, _2_TestBloomFilteredLucene40Postings_0.prx, _2_TestBloomFilteredLucene40Postings_0.blm, _1_MockFixedIntBlock_0.pyl, _3.fdt, _0_MockFixedIntBlock_0.tib, _2_MockFixedIntBlock_0.tii, _1.fnm, _3_MockFixedIntBlock_0.pyl, _2_MockFixedIntBlock_0.tib, _2_MockFixedIntBlock_0.pyl, _3_SimpleText_0.pst, _0_MockFixedIntBlock_0.tii, _0_SimpleText_0.pst, _2_MockFixedIntBlock_0.frq, _0_TestBloomFilteredLucene40Postings_0.frq, _3_TestBloomFilteredLucene40Postings_0.frq, _3_TestBloomFilteredLucene40Postings_0.tim, _2_TestBloomFilteredLucene40Postings_0.tim, _0_TestBloomFilteredLucene40Postings_0.tim, _0_TestBloomFilteredLucene40Postings_0.tip, _0_TestBloomFilteredLucene40Postings_0.blm, _0.fnm, _1_MockFixedIntBlock_0.frq, _2.fdt, _0_MockFixedIntBlock_0.pos, _3_TestBloomFilteredLucene40Postings_0.tip, _2.fdx, _2_TestBloomFilteredLucene40Postings_0.tip, _0_MockRandom_0.skp, _1_TestBloomFilteredLucene40Postings_0.frq, _3_nrm.cfs, _1.fdx, _0_MockRandom_0.frq, _1_MockFixedIntBlock_0.skp, _2_MockFixedIntBlock_0.pos, _1.fdt, _0_MockRandom_0.doc, _0_MockRandom_0.pos, _3.si, _2_MockFixedIntBlock_0.doc, _1_MockFixedIntBlock_0.pos, _3_TestBloomFilteredLucene40Postings_0.blm, _1_SimpleText_0.pst, _2.si, _3_MockFixedIntBlock_0.frq, _1_MockFixedIntBlock_0.doc, _2_MockFixedIntBlock_0.skp, _0_TestBloomFilteredLucene40Postings_0.prx, _3_MockFixedIntBlock_0.doc, _1_TestBloomFilteredLucene40Postings_0.blm, _3.fnm, _1_TestBloomFilteredLucene40Postings_0.tip, _2_nrm.cfe, _0_MockFixedIntBlock_0.skp, _0_MockFixedIntBlock_0.pyl, _2_nrm.cfs, _0.si, _1_TestBloomFilteredLucene40Postings_0.tim, _2_TestBloomFilteredLucene40Postings_0.frq, _1_nrm.cfs, _1_MockFixedIntBlock_0.tii, _1_TestBloomFilteredLucene40Postings_0.prx, _3_MockFixedIntBlock_0.tib, _0_nrm.cfs, _3_MockFixedIntBlock_0.pos, _2.fnm, _1_nrm.cfe, _3.fdx, _0_MockFixedIntBlock_0.frq, _0_MockRandom_0.tip, _0_MockRandom_0.sd, _0_MockFixedIntBlock_0.doc, _1.si, _0_nrm.cfe, segments_2, _1_MockFixedIntBlock_0.tib, _0.fdx, _0_MockRandom_0.pyl, _0_MockRandom_0.tim, _0.fdt, _3_MockFixedIntBlock_0.tii]
[junit4:junit4]   2> 16268 T771 C109 P32614 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 16278 T771 C109 P32614 oass.SolrIndexSearcher.<init> Opening Searcher@d83116a main
[junit4:junit4]   2> 16279 T771 C109 P32614 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 16280 T779 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@d83116a main{StandardDirectoryReader(segments_2:9:nrt _0(4.0.0.2):C10 _1(4.0.0.2):C10 _2(4.0.0.2):C10 _3(4.0.0.2):C5)}
[junit4:junit4]   2> 16281 T771 C109 P32614 oasu.SolrCmdDistributor.distribCommit Distrib commit to:[StdNode: http://127.0.0.1:32602/solr/collection1/, StdNode: http://127.0.0.1:32619/solr/collection1/]
[junit4:junit4]   2> 16284 T763 C107 P32602 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 16284 T782 C106 P32619 oasu.DirectUpdateHandler2.commit start commit{flags=0,_version_=0,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false}
[junit4:junit4]   2> 16709 T782 C106 P32619 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/jetty3/index,segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/jetty3/index,segFN=segments_3,generation=3,filenames=[_3_nrm.cfe, _3_MockFixedIntBlock_0.skp, _3_TestBloomFilteredLucene40Postings_0.prx, _2_SimpleText_0.pst, _2_TestBloomFilteredLucene40Postings_0.prx, _2_TestBloomFilteredLucene40Postings_0.blm, _0_MockRandom_0.prx, _1_MockFixedIntBlock_0.pyl, _3.fdt, _0_MockFixedIntBlock_0.tib, _2_MockFixedIntBlock_0.tii, _1.fnm, _3_MockFixedIntBlock_0.pyl, _2_MockFixedIntBlock_0.tib, _2_MockFixedIntBlock_0.pyl, _3_SimpleText_0.pst, _0_MockFixedIntBlock_0.tii, _0_SimpleText_0.pst, _2_MockFixedIntBlock_0.frq, _0_TestBloomFilteredLucene40Postings_0.frq, _3_TestBloomFilteredLucene40Postings_0.frq, _3_TestBloomFilteredLucene40Postings_0.tim, _0_TestBloomFilteredLucene40Postings_0.tim, _2_TestBloomFilteredLucene40Postings_0.tim, _0_TestBloomFilteredLucene40Postings_0.tip, _0_TestBloomFilteredLucene40Postings_0.blm, _0.fnm, _1_MockFixedIntBlock_0.frq, _2.fdt, _0_MockFixedIntBlock_0.pos, _3_TestBloomFilteredLucene40Postings_0.tip, _2.fdx, _2_TestBloomFilteredLucene40Postings_0.tip, _1_TestBloomFilteredLucene40Postings_0.frq, _3_nrm.cfs, _1.fdx, _0_MockRandom_0.frq, _1_MockFixedIntBlock_0.skp, _2_MockFixedIntBlock_0.pos, _1.fdt, _3.si, _2_MockFixedIntBlock_0.doc, _1_MockFixedIntBlock_0.pos, _3_TestBloomFilteredLucene40Postings_0.blm, _1_SimpleText_0.pst, _2.si, _3_MockFixedIntBlock_0.frq, _1_MockFixedIntBlock_0.doc, _2_MockFixedIntBlock_0.skp, _0_TestBloomFilteredLucene40Postings_0.prx, _3_MockFixedIntBlock_0.doc, _1_TestBloomFilteredLucene40Postings_0.blm, _3.fnm, _1_TestBloomFilteredLucene40Postings_0.tip, _2_nrm.cfe, _0_MockFixedIntBlock_0.pyl, _0_MockFixedIntBlock_0.skp, _2_nrm.cfs, _0.si, _1_TestBloomFilteredLucene40Postings_0.tim, _2_TestBloomFilteredLucene40Postings_0.frq, _0_MockRandom_0.tib, _1_nrm.cfs, _1_MockFixedIntBlock_0.tii, _1_TestBloomFilteredLucene40Postings_0.prx, _3_MockFixedIntBlock_0.tib, _0_nrm.cfs, _3_MockFixedIntBlock_0.pos, _2.fnm, _1_nrm.cfe, _3.fdx, _0_MockFixedIntBlock_0.frq, _0_MockRandom_0.sd, _0_MockRandom_0.tiv, _0_MockFixedIntBlock_0.doc, _1.si, _0_nrm.cfe, _1_MockFixedIntBlock_0.tib, _0.fdx, segments_3, _0.fdt, _3_MockFixedIntBlock_0.tii]
[junit4:junit4]   2> 16710 T782 C106 P32619 oasc.SolrDeletionPolicy.updateCommits newest commit = 3
[junit4:junit4]   2> 16719 T763 C107 P32602 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/jetty1/index,segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   2> 		commit{dir=/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build/solr-core/test/J0/org.apache.solr.cloud.BasicDistributedZkTest-1347836320061/jetty1/index,segFN=segments_2,generation=2,filenames=[_3_nrm.cfe, _3_MockFixedIntBlock_0.skp, _3_TestBloomFilteredLucene40Postings_0.prx, _2_SimpleText_0.pst, _2_TestBloomFilteredLucene40Postings_0.prx, _2_TestBloomFilteredLucene40Postings_0.blm, _1_MockFixedIntBlock_0.pyl, _3.fdt, _0_MockFixedIntBlock_0.tib, _2_MockFixedIntBlock_0.tii, _1.fnm, _3_MockFixedIntBlock_0.pyl, _2_MockFixedIntBlock_0.tib, _2_MockFixedIntBlock_0.pyl, _3_SimpleText_0.pst, _0_MockFixedIntBlock_0.tii, _0_SimpleText_0.pst, _2_MockFixedIntBlock_0.frq, _0_TestBloomFilteredLucene40Postings_0.frq, _3_TestBloomFilteredLucene40Postings_0.frq, _3_TestBloomFilteredLucene40Postings_0.tim, _2_TestBloomFilteredLucene40Postings_0.tim, _0_TestBloomFilteredLucene40Postings_0.tim, _0_TestBloomFilteredLucene40Postings_0.tip, _0_TestBloomFilteredLucene40Postings_0.blm, _0.fnm, _1_MockFixedIntBlock_0.frq, _2.fdt, _0_MockFixedIntBlock_0.pos, _3_TestBloomFilteredLucene40Postings_0.tip, _2.fdx, _2_TestBloomFilteredLucene40Postings_0.tip, _0_MockRandom_0.skp, _1_TestBloomFilteredLucene40Postings_0.frq, _3_nrm.cfs, _1.fdx, _0_MockRandom_0.frq, _1_MockFixedIntBlock_0.skp, _2_MockFixedIntBlock_0.pos, _1.fdt, _0_MockRandom_0.doc, _0_MockRandom_0.pos, _3.si, _2_MockFixedIntBlock_0.doc, _1_MockFixedIntBlock_0.pos, _3_TestBloomFilteredLucene40Postings_0.blm, _1_SimpleText_0.pst, _2.si, _3_MockFixedIntBlock_0.frq, _1_MockFixedIntBlock_0.doc, _2_MockFixedIntBlock_0.skp, _0_TestBloomFilteredLucene40Postings_0.prx, _3_MockFixedIntBlock_0.doc, _1_TestBloomFilteredLucene40Postings_0.blm, _3.fnm, _1_TestBloomFilteredLucene40Postings_0.tip, _2_nrm.cfe, _0_MockFixedIntBlock_0.skp, _0_MockFixedIntBlock_0.pyl, _2_nrm.cfs, _0.si, _1_TestBloomFilteredLucene40Postings_0.tim, _2_TestBloomFilteredLucene40Postings_0.frq, _1_nrm.cfs, _1_MockFixedIntBlock_0.tii, _1_TestBloomFilteredLucene40Postings_0.prx, _3_MockFixedIntBlock_0.tib, _0_nrm.cfs, _3_MockFixedIntBlock_0.pos, _2.fnm, _1_nrm.cfe, _3.fdx, _0_MockFixedIntBlock_0.frq, _0_MockRandom_0.tip, _0_MockRandom_0.sd, _0_MockFixedIntBlock_0.doc, _1.si, _0_nrm.cfe, segments_2, _1_MockFixedIntBlock_0.tib, _0.fdx, _0_MockRandom_0.pyl, _0_MockRandom_0.tim, _0.fdt, _3_MockFixedIntBlock_0.tii]
[junit4:junit4]   2> 16721 T763 C107 P32602 oasc.SolrDeletionPolicy.updateCommits newest commit = 2
[junit4:junit4]   2> 16722 T782 C106 P32619 oass.SolrIndexSearcher.<init> Opening Searcher@7e1d50b9 main
[junit4:junit4]   2> 16723 T782 C106 P32619 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 16724 T791 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7e1d50b9 main{StandardDirectoryReader(segments_3:10:nrt _0(4.0.0.2):C10 _1(4.0.0.2):C10 _2(4.0.0.2):C10 _3(4.0.0.2):C2)}
[junit4:junit4]   2> 16725 T782 C106 P32619 /update {waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&expungeDeletes=false&softCommit=false&version=2} {commit=} 0 441
[junit4:junit4]   2> 16731 T763 C107 P32602 oass.SolrIndexSearcher.<init> Opening Searcher@f79067e main
[junit4:junit4]   2> 16733 T763 C107 P32602 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4:junit4]   2> 16734 T767 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f79067e main{StandardDirectoryReader(segments_2:9:nrt _0(4.0.0.2):C10 _1(4.0.0.2):C10 _2(4.0.0.2):C10 _3(4.0.0.2):C2)}
[junit4:junit4]   2> 16735 T763 C107 P32602 /update {waitSearcher=true&commit=true&wt=javabin&commit_end_point=true&expungeDeletes=false&softCommit=false&version=2} {commit=} 0 451
[junit4:junit4]   2> 16736 T771 C109 P32614 /update {waitSearcher=true&wt=javabin&commit=true&softCommit=false&version=2} {commit=} 0 791
[junit4:junit4]   2> 16747 T744 C108 P32596 REQ /select {distrib=false&sort=n_ti1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=8 
[junit4:junit4]   2> 16752 T732 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4:junit4]   2> 16764 T732 oaz.ZooKeeper.<init> Initiating client connection, connectString=127.0.0.1:32593/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@4885df1b
[junit4:junit4]   2> 16765 T802 oaz.ClientCnxn$SendThread.startConnect Opening socket connection to server /127.0.0.1:32593
[junit4:junit4]   2> 16765 T732 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4:junit4]   2> 16766 T802 oaz.ClientCnxn$SendThread.primeConnection Socket connection established to localhost/127.0.0.1:32593, initiating session
[junit4:junit4]   2> 16767 T734 oazs.NIOServerCnxn$Factory.run Accepted socket connection from /140.211.11.196:32639
[junit4:junit4]   2> 16767 T734 oazs.NIOServerCnxn.readConnectRequest Client attempting to establish new session at /140.211.11.196:32639
[junit4:junit4]   2> 16770 T736 oazs.NIOServerCnxn.finishSessionInit Established session 0x139d14e1a9d0008 with negotiated timeout 10000 for client /140.211.11.196:32639
[junit4:junit4]   2> 16770 T802 oaz.ClientCnxn$SendThread.readConnectResult Session establishment complete on server localhost/127.0.0.1:32593, sessionid = 0x139d14e1a9d0008, negotiated timeout = 10000
[junit4:junit4]   2> 16770 T803 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4885df1b name:ZooKeeperConnection Watcher:127.0.0.1:32593/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   2> 16770 T732 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4:junit4]   2> 16772 T732 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... 
[junit4:junit4]   2> 16789 T764 C107 P32602 REQ /select {sort=n_ti1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347836336837&shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=5 
[junit4:junit4]   2> 16789 T773 C109 P32614 REQ /select {sort=n_ti1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347836336837&shard.url=127.0.0.1:32614/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=5 
[junit4:junit4]   2> 16796 T764 C107 P32602 REQ /select {shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836336837&q=*:*&ids=133,8,13&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16797 T773 C109 P32614 REQ /select {shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836336837&q=*:*&ids=126,7,139,6,137,104,103&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16800 T772 C109 P32614 REQ /select {collection=collection1&sort=n_ti1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=19 
[junit4:junit4]   2> 16805 T744 C108 P32596 REQ /select {distrib=false&sort=n_ti1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 16813 T759 C107 P32602 REQ /select {sort=n_ti1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836336866&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=0 
[junit4:junit4]   2> 16813 T774 C109 P32614 REQ /select {sort=n_ti1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836336866&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=0 
[junit4:junit4]   2> 16821 T774 C109 P32614 REQ /select {shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836336866&q=*:*&ids=116,145,149,105,142&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16821 T759 C107 P32602 REQ /select {shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836336866&q=*:*&ids=135,10,138,148,124&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16823 T783 C106 P32619 REQ /select {sort=n_ti1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=13 
[junit4:junit4]   2> 16831 T744 C108 P32596 REQ /select {distrib=false&sort=n_f1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=3 
[junit4:junit4]   2> 16839 T784 C106 P32619 REQ /select {sort=n_f1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836336891&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=2 
[junit4:junit4]   2> 16839 T773 C109 P32614 REQ /select {sort=n_f1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836336891&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 16846 T773 C109 P32614 REQ /select {shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836336891&q=*:*&ids=117,2,7,104,103&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16846 T784 C106 P32619 REQ /select {shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836336891&q=*:*&ids=122,148,109,113,123&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16848 T772 C109 P32614 REQ /select {sort=n_f1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=13 
[junit4:junit4]   2> 16854 T744 C108 P32596 REQ /select {distrib=false&sort=n_f1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 16863 T774 C109 P32614 REQ /select {sort=n_f1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347836336915&shard.url=127.0.0.1:32614/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 16864 T759 C107 P32602 REQ /select {sort=n_f1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347836336915&shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=2 
[junit4:junit4]   2> 16868 T759 C107 P32602 REQ /select {shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836336915&q=*:*&ids=132,16,13,11&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16869 T774 C109 P32614 REQ /select {shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836336915&q=*:*&ids=116,145,115,5,17,105&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16871 T785 C106 P32619 REQ /select {collection=collection1&sort=n_f1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=12 
[junit4:junit4]   2> 16880 T744 C108 P32596 REQ /select {distrib=false&sort=n_tf1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=5 
[junit4:junit4]   2> 16891 T773 C109 P32614 REQ /select {sort=n_tf1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836336941&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=3 
[junit4:junit4]   2> 16892 T764 C107 P32602 REQ /select {sort=n_tf1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836336941&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=4 
[junit4:junit4]   2> 16897 T773 C109 P32614 REQ /select {shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836336941&q=*:*&ids=144,7,136,140&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16898 T764 C107 P32602 REQ /select {shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836336941&q=*:*&ids=109,108,124,129,101,12&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16901 T771 C109 P32614 REQ /select {collection=collection1&sort=n_tf1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=16 
[junit4:junit4]   2> 16906 T744 C108 P32596 REQ /select {distrib=false&sort=n_tf1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 16916 T760 C107 P32602 REQ /select {sort=n_tf1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347836336967&shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 16916 T775 C109 P32614 REQ /select {sort=n_tf1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347836336967&shard.url=127.0.0.1:32614/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 16921 T775 C109 P32614 REQ /select {shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836336967&q=*:*&ids=126,130&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16923 T760 C107 P32602 REQ /select {shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836336967&q=*:*&ids=132,113,148,107,111,13,11,118&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16926 T762 C107 P32602 REQ /select {collection=collection1&sort=n_tf1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=15 
[junit4:junit4]   2> 16933 T744 C108 P32596 REQ /select {distrib=false&sort=n_d1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=3 
[junit4:junit4]   2> 16943 T760 C107 P32602 REQ /select {sort=n_d1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836336994&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=2 
[junit4:junit4]   2> 16944 T775 C109 P32614 REQ /select {sort=n_d1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836336994&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=2 
[junit4:junit4]   2> 16950 T775 C109 P32614 REQ /select {shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836336994&q=*:*&ids=139,104,131,141&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16951 T760 C107 P32602 REQ /select {shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836336994&q=*:*&ids=146,133,138,4,15,101&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 16955 T762 C107 P32602 REQ /select {collection=collection1&sort=n_d1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=17 
[junit4:junit4]   2> 16960 T744 C108 P32596 REQ /select {distrib=false&sort=n_d1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 16968 T764 C107 P32602 REQ /select {sort=n_d1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836337020&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 16968 T773 C109 P32614 REQ /select {sort=n_d1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836337020&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 16974 T764 C107 P32602 REQ /select {shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836337020&q=*:*&ids=148,11&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 16975 T773 C109 P32614 REQ /select {shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836337020&q=*:*&ids=143,116,144,147,6,149,137,120&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=3 
[junit4:junit4]   2> 16978 T772 C109 P32614 REQ /select {collection=collection1&sort=n_d1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=14 
[junit4:junit4]   2> 16984 T744 C108 P32596 REQ /select {distrib=false&sort=n_td1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=2 
[junit4:junit4]   2> 16993 T775 C109 P32614 REQ /select {sort=n_td1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836337044&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=2 
[junit4:junit4]   2> 16993 T760 C107 P32602 REQ /select {sort=n_td1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836337044&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=2 
[junit4:junit4]   2> 16999 T775 C109 P32614 REQ /select {shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836337044&q=*:*&ids=144,145,128,147,142&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 16999 T760 C107 P32602 REQ /select {shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836337044&q=*:*&ids=113,109,123,108,118&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 17003 T810 C107 P32602 REQ /select {collection=collection1&sort=n_td1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=15 
[junit4:junit4]   2> 17008 T744 C108 P32596 REQ /select {distrib=false&sort=n_td1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 17016 T773 C109 P32614 REQ /select {sort=n_td1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836337068&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 17017 T784 C106 P32619 REQ /select {sort=n_td1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347836337068&shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 17022 T773 C109 P32614 REQ /select {shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836337068&q=*:*&ids=139,149,104,130,141&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17023 T784 C106 P32619 REQ /select {shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836337068&q=*:*&ids=10,1,114,107,101&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 17026 T772 C109 P32614 REQ /select {collection=collection1&sort=n_td1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=14 
[junit4:junit4]   2> 17053 T744 C108 P32596 REQ /select {distrib=false&sort=n_l1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=24 
[junit4:junit4]   2> 17062 T774 C109 P32614 REQ /select {sort=n_l1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836337113&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=2 
[junit4:junit4]   2> 17062 T759 C107 P32602 REQ /select {sort=n_l1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836337113&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=2 
[junit4:junit4]   2> 17068 T759 C107 P32602 REQ /select {shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836337113&q=*:*&ids=135,1,133,124,8&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 17068 T774 C109 P32614 REQ /select {shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836337113&q=*:*&ids=143,127,105,103,119&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 17071 T783 C106 P32619 REQ /select {sort=n_l1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=14 
[junit4:junit4]   2> 17075 T744 C108 P32596 REQ /select {distrib=false&sort=n_l1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 17083 T773 C109 P32614 REQ /select {sort=n_l1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836337135&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 17083 T764 C107 P32602 REQ /select {sort=n_l1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836337135&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 17089 T773 C109 P32614 REQ /select {shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836337135&q=*:*&ids=117,128,17,9,142&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17089 T764 C107 P32602 REQ /select {shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836337135&q=*:*&ids=113,109,110,129,101&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 17091 T771 C109 P32614 REQ /select {sort=n_l1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=12 
[junit4:junit4]   2> 17097 T744 C108 P32596 REQ /select {distrib=false&sort=n_tl1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=2 
[junit4:junit4]   2> 17106 T786 C106 P32619 REQ /select {sort=n_tl1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&NOW=1347836337158&shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&fl=id&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=2 
[junit4:junit4]   2> 17107 T775 C109 P32614 REQ /select {sort=n_tl1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836337158&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=2 
[junit4:junit4]   2> 17113 T775 C109 P32614 REQ /select {shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836337158&q=*:*&ids=117,127,147,121,9&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 17113 T786 C106 P32619 REQ /select {shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836337158&q=*:*&ids=132,113,124,15,16&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 17115 T762 C107 P32602 REQ /select {collection=collection1&sort=n_tl1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=13 
[junit4:junit4]   2> 17120 T744 C108 P32596 REQ /select {distrib=false&sort=n_tl1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 17128 T775 C109 P32614 REQ /select {sort=n_tl1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836337180&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 17128 T786 C106 P32619 REQ /select {sort=n_tl1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836337180&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=0 
[junit4:junit4]   2> 17134 T786 C106 P32619 REQ /select {shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836337180&q=*:*&ids=125,10,101&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17135 T775 C109 P32614 REQ /select {shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836337180&q=*:*&ids=143,7,5,149,105,103,142&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 17137 T810 C107 P32602 REQ /select {collection=collection1&sort=n_tl1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=13 
[junit4:junit4]   2> 17142 T744 C108 P32596 REQ /select {distrib=false&sort=n_dt1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=2 
[junit4:junit4]   2> 17150 T773 C109 P32614 REQ /select {sort=n_dt1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836337202&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 17150 T764 C107 P32602 REQ /select {sort=n_dt1+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836337202&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 17156 T764 C107 P32602 REQ /select {shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836337202&q=*:*&ids=135,114,133,102,118&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 17156 T773 C109 P32614 REQ /select {shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836337202&q=*:*&ids=6,139,17,9,130&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 17157 T772 C109 P32614 REQ /select {sort=n_dt1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=11 
[junit4:junit4]   2> 17162 T744 C108 P32596 REQ /select {distrib=false&sort=n_dt1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 17170 T759 C107 P32602 REQ /select {sort=n_dt1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836337222&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 17170 T774 C109 P32614 REQ /select {sort=n_dt1+asc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836337222&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 17175 T774 C109 P32614 REQ /select {shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836337222&q=*:*&ids=143,3,140,131&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17176 T759 C107 P32602 REQ /select {shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836337222&q=*:*&ids=10,1,109,122,148,110&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=2 
[junit4:junit4]   2> 17177 T783 C106 P32619 REQ /select {collection=collection1&sort=n_dt1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=11 
[junit4:junit4]   2> 17184 T744 C108 P32596 REQ /select {distrib=false&sort=n_tdt1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=3 
[junit4:junit4]   2> 17193 T774 C109 P32614 REQ /select {sort=n_tdt1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836337244&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 17193 T759 C107 P32602 REQ /select {sort=n_tdt1+desc&distrib=false&collection=collection1&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836337244&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=2 
[junit4:junit4]   2> 17198 T774 C109 P32614 REQ /select {shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836337244&q=*:*&ids=2,128,136,149,141,119&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17198 T759 C107 P32602 REQ /select {shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836337244&q=*:*&ids=133,109,101,11&distrib=false&collection=collection1&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17200 T783 C106 P32619 REQ /select {collection=collection1&sort=n_tdt1+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=12 
[junit4:junit4]   2> 17204 T744 C108 P32596 REQ /select {distrib=false&sort=n_tdt1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=1 
[junit4:junit4]   2> 17211 T774 C109 P32614 REQ /select {sort=n_tdt1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836337263&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 17211 T759 C107 P32602 REQ /select {sort=n_tdt1+asc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836337263&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 17215 T774 C109 P32614 REQ /select {shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836337263&q=*:*&ids=116,143,121,5,9,130&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17216 T759 C107 P32602 REQ /select {shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836337263&q=*:*&ids=10,102,129,12&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17217 T783 C106 P32619 REQ /select {sort=n_tdt1+asc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=10 
[junit4:junit4]   2> 17223 T744 C108 P32596 REQ /select {distrib=false&sort=a_si+desc&wt=javabin&q=*:*&version=2} hits=67 status=0 QTime=2 
[junit4:junit4]   2> 17230 T775 C109 P32614 REQ /select {sort=a_si+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836337282&start=0&q=*:*&isShard=true&fsv=true} hits=35 status=0 QTime=1 
[junit4:junit4]   2> 17230 T760 C107 P32602 REQ /select {sort=a_si+desc&distrib=false&wt=javabin&rows=10&version=2&fl=id&shard.url=127.0.0.1:32602/solr/collection1/|127.0.0.1:32619/solr/collection1/&NOW=1347836337282&start=0&q=*:*&isShard=true&fsv=true} hits=32 status=0 QTime=1 
[junit4:junit4]   2> 17235 T775 C109 P32614 REQ /select {shard.url=127.0.0.1:32614/solr/collection1/&NOW=1347836337282&q=*:*&ids=3,2,7,5,9&distrib=false&isShard=true&wt=javabin&version=2} status=0 QTime=1 
[junit4:junit4]   2> 17235 T760 C107 P32602 REQ /select {shard.url=

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

etty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:534)
[junit4:junit4]   2> 	        at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 	   7) Thread[id=768, name=HashSessionScavenger-28, state=TIMED_WAITING, group=TGRP-BasicDistributedZkTest]
[junit4:junit4]   2> 	        at java.lang.Object.wait(Native Method)
[junit4:junit4]   2> 	        at java.util.TimerThread.mainLoop(Timer.java:552)
[junit4:junit4]   2> 	        at java.util.TimerThread.run(Timer.java:505)
[junit4:junit4]   2> 	   8) Thread[id=780, name=HashSessionScavenger-29, state=TIMED_WAITING, group=TGRP-BasicDistributedZkTest]
[junit4:junit4]   2> 	        at java.lang.Object.wait(Native Method)
[junit4:junit4]   2> 	        at java.util.TimerThread.mainLoop(Timer.java:552)
[junit4:junit4]   2> 	        at java.util.TimerThread.run(Timer.java:505)
[junit4:junit4]   2> 	   9) Thread[id=789, name=TEST-BasicDistributedZkTest.testDistribSearch-seed#[F06A935AA5795696]-SendThread(localhost:32593), state=RUNNABLE, group=TGRP-BasicDistributedZkTest]
[junit4:junit4]   2> 	        at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:82)
[junit4:junit4]   2> 	        at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:98)
[junit4:junit4]   2> 	        at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1131)
[junit4:junit4]   2> 	  10) Thread[id=831, name=searcherExecutor-651-thread-1, state=WAITING, group=TGRP-BasicDistributedZkTest]
[junit4:junit4]   2> 	        at sun.misc.Unsafe.park(Native Method)
[junit4:junit4]   2> 	        at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4:junit4]   2> 	        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4:junit4]   2> 	        at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4:junit4]   2> 	        at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1043)
[junit4:junit4]   2> 	        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1103)
[junit4:junit4]   2> 	        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
[junit4:junit4]   2> 	        at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 	  11) Thread[id=822, name=searcherExecutor-635-thread-1, state=WAITING, group=TGRP-BasicDistributedZkTest]
[junit4:junit4]   2> 	        at sun.misc.Unsafe.park(Native Method)
[junit4:junit4]   2> 	        at java.util.concurrent.locks.LockSupport.park(LockSupport.java:186)
[junit4:junit4]   2> 	        at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.await(AbstractQueuedSynchronizer.java:2043)
[junit4:junit4]   2> 	        at java.util.concurrent.LinkedBlockingQueue.take(LinkedBlockingQueue.java:442)
[junit4:junit4]   2> 	        at java.util.concurrent.ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:1043)
[junit4:junit4]   2> 	        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1103)
[junit4:junit4]   2> 	        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603)
[junit4:junit4]   2> 	        at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> 	  12) Thread[id=802, name=TEST-BasicDistributedZkTest.testDistribSearch-seed#[F06A935AA5795696]-SendThread(localhost:32593), state=RUNNABLE, group=TGRP-BasicDistributedZkTest]
[junit4:junit4]   2> 	        at sun.nio.ch.KQueueArrayWrapper.interrupt(Native Method)
[junit4:junit4]   2> 	        at sun.nio.ch.KQueueArrayWrapper.interrupt(KQueueArrayWrapper.java:164)
[junit4:junit4]   2> 	        at sun.nio.ch.KQueueSelectorImpl.wakeup(KQueueSelectorImpl.java:245)
[junit4:junit4]   2> 	        at java.nio.channels.spi.AbstractSelector$1.interrupt(AbstractSelector.java:210)
[junit4:junit4]   2> 	        at java.nio.channels.spi.AbstractSelector.begin(AbstractSelector.java:216)
[junit4:junit4]   2> 	        at sun.nio.ch.KQueueSelectorImpl.doSelect(KQueueSelectorImpl.java:102)
[junit4:junit4]   2> 	        at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:87)
[junit4:junit4]   2> 	        at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:98)
[junit4:junit4]   2> 	        at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1131)
[junit4:junit4]   2> 	  13) Thread[id=781, name=qtp1569115546-781 Acceptor0 SocketConnector@0.0.0.0:32619, state=RUNNABLE, group=TGRP-BasicDistributedZkTest]
[junit4:junit4]   2> 	        at java.net.PlainSocketImpl.socketAccept(Native Method)
[junit4:junit4]   2> 	        at java.net.AbstractPlainSocketImpl.accept(AbstractPlainSocketImpl.java:398)
[junit4:junit4]   2> 	        at java.net.ServerSocket.implAccept(ServerSocket.java:522)
[junit4:junit4]   2> 	        at java.net.ServerSocket.accept(ServerSocket.java:490)
[junit4:junit4]   2> 	        at org.eclipse.jetty.server.bio.SocketConnector.accept(SocketConnector.java:112)
[junit4:junit4]   2> 	        at org.eclipse.jetty.server.AbstractConnector$Acceptor.run(AbstractConnector.java:933)
[junit4:junit4]   2> 	        at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:599)
[junit4:junit4]   2> 	        at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:534)
[junit4:junit4]   2> 	        at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   2> NOTE: test params are: codec=Lucene40: {n_f1=PostingsFormat(name=TestBloomFilteredLucene40Postings), foo_b=PostingsFormat(name=MockRandom), foo_d=PostingsFormat(name=SimpleText), foo_f=PostingsFormat(name=MockRandom), n_tl1=PostingsFormat(name=TestBloomFilteredLucene40Postings), n_d1=MockFixedIntBlock(blockSize=1648), rnd_b=PostingsFormat(name=SimpleText), intDefault=PostingsFormat(name=TestBloomFilteredLucene40Postings), n_td1=PostingsFormat(name=TestBloomFilteredLucene40Postings), timestamp=MockFixedIntBlock(blockSize=1648), id=PostingsFormat(name=SimpleText), range_facet_sl=PostingsFormat(name=SimpleText), range_facet_si=MockFixedIntBlock(blockSize=1648), oddField_s=PostingsFormat(name=SimpleText), foo_i=PostingsFormat(name=TestBloomFilteredLucene40Postings), multiDefault=MockFixedIntBlock(blockSize=1648), n_tf1=MockFixedIntBlock(blockSize=1648), n_dt1=PostingsFormat(name=TestBloomFilteredLucene40Postings), n_ti1=PostingsFormat(name=SimpleText), range_facet_l=MockFixedIntBlock(blockSize=1648), text=PostingsFormat(name=MockRandom), _version_=PostingsFormat(name=TestBloomFilteredLucene40Postings), val_i=PostingsFormat(name=SimpleText), SubjectTerms_mfacet=PostingsFormat(name=TestBloomFilteredLucene40Postings), a_t=MockFixedIntBlock(blockSize=1648), n_tdt1=PostingsFormat(name=TestBloomFilteredLucene40Postings), other_tl1=PostingsFormat(name=TestBloomFilteredLucene40Postings), n_l1=MockFixedIntBlock(blockSize=1648), a_si=PostingsFormat(name=TestBloomFilteredLucene40Postings)}, sim=RandomSimilarityProvider(queryNorm=false,coord=crazy): {}, locale=es_VE, timezone=Etc/Greenwich
[junit4:junit4]   2> NOTE: FreeBSD 9.0-RELEASE amd64/Oracle Corporation 1.7.0_06 (64-bit)/cpus=16,threads=16,free=236306352,total=346030080
[junit4:junit4]   2> NOTE: All tests run in this JVM: [CoreContainerCoreInitFailuresTest, TestJmxMonitoredMap, TestReload, TestFaceting, PluginInfoTest, SystemInfoHandlerTest, TestQueryTypes, TestBinaryResponseWriter, HighlighterConfigTest, SpellCheckCollatorTest, TestWordDelimiterFilterFactory, TestQuerySenderNoQuery, CacheHeaderTest, DateMathParserTest, MinimalSchemaTest, SampleTest, TestCollationField, AnalysisAfterCoreReloadTest, SolrCmdDistributorTest, TestLFUCache, QueryElevationComponentTest, SortByFunctionTest, TestLMJelinekMercerSimilarityFactory, CopyFieldTest, TestSolrQueryParser, TestStressReorder, DistributedTermsComponentTest, SimplePostToolTest, NoCacheHeaderTest, TestBadConfig, TestNumberUtils, SearchHandlerTest, DefaultValueUpdateProcessorTest, LoggingHandlerTest, TestDefaultSimilarityFactory, SpellPossibilityIteratorTest, SpatialFilterTest, TestLMDirichletSimilarityFactory, TestSystemIdResolver, FileBasedSpellCheckerTest, SolrPluginUtilsTest, TestExtendedDismaxParser, TestFastOutputStream, JsonLoaderTest, TestIBSimilarityFactory, TestFastLRUCache, NotRequiredUniqueKeyTest, SolrRequestParserTest, CurrencyFieldTest, TermVectorComponentDistributedTest, CSVRequestHandlerTest, MoreLikeThisHandlerTest, CoreAdminHandlerTest, BasicDistributedZkTest]
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=BasicDistributedZkTest -Dtests.seed=F06A935AA5795696 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es_VE -Dtests.timezone=Etc/Greenwich -Dtests.file.encoding=ISO-8859-1
[junit4:junit4] ERROR   0.00s J0 | BasicDistributedZkTest (suite) <<<
[junit4:junit4]    > Throwable #1: java.lang.Exception: Suite timeout exceeded (>= 7200000 msec).
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([F06A935AA5795696]:0)
[junit4:junit4] Completed on J0 in 7223.75s, 1 test, 2 errors <<< FAILURES!

[...truncated 6 lines...]
BUILD FAILED
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/build.xml:32: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/solr/build.xml:146: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/module-build.xml:63: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/common-build.xml:1133: The following error occurred while executing this line:
/usr/home/hudson/hudson-slave/workspace/Lucene-Solr-Tests-4.x-java7/lucene/common-build.xml:810: There were test failures: 229 suites, 927 tests, 1 suite-level error, 1 error, 6 ignored (1 assumption)

Total time: 141 minutes 10 seconds
Build step 'Invoke Ant' marked build as failure
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message